builder: graphics_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-055 starttime: 1482386641.15 results: success (0) buildid: 20161221210953 builduid: 698872a1fa494048ba5e1f52229232ee revision: 27c8866e112f61feb69b5c7bd96627b03fbeb62e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-21 22:04:01.151101) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-21 22:04:01.151499) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-21 22:04:01.151776) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-21 22:04:01.194789) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 22:04:01.195059) ========= 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=bb9b54edd4a927dc735d112f000001d9-1482386417.927915-1480998484 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-21 22:04:01-- 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: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 224M=0s 2016-12-21 22:04:01 (224 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.110318 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 22:04:01.319046) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 22:04:01.319326) ========= 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=bb9b54edd4a927dc735d112f000001d9-1482386417.927915-1480998484 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020330 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 22:04:01.368393) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 22:04:01.368707) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 27c8866e112f61feb69b5c7bd96627b03fbeb62e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev 27c8866e112f61feb69b5c7bd96627b03fbeb62e --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=bb9b54edd4a927dc735d112f000001d9-1482386417.927915-1480998484 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-21 22:04:01,439 truncating revision to first 12 chars 2016-12-21 22:04:01,439 Setting DEBUG logging. 2016-12-21 22:04:01,439 attempt 1/10 2016-12-21 22:04:01,439 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/27c8866e112f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-21 22:04:01,900 unpacking tar archive at: graphics-27c8866e112f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.754316 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 22:04:02.135860) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-21 22:04:02.136144) ========= script_repo_revision: 27c8866e112f61feb69b5c7bd96627b03fbeb62e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-21 22:04:02.136511) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-21 22:04:02.136774) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-21 22:04:02.147066) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-12-21 22:04:02.147334) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 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', 'Graphics', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] 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=bb9b54edd4a927dc735d112f000001d9-1482386417.927915-1480998484 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:04:02 INFO - MultiFileLogger online at 20161221 22:04:02 in /builds/slave/test 22:04:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Graphics --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 22:04:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:04:02 INFO - {'append_to_log': False, 22:04:02 INFO - 'base_work_dir': '/builds/slave/test', 22:04:02 INFO - 'blob_upload_branch': 'Graphics', 22:04:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:04:02 INFO - 'branch': 'Graphics', 22:04:02 INFO - 'buildbot_json_path': 'buildprops.json', 22:04:02 INFO - 'config_files': ('talos/linux_config.py',), 22:04:02 INFO - 'default_actions': ('clobber', 22:04:02 INFO - 'read-buildbot-config', 22:04:02 INFO - 'download-and-extract', 22:04:02 INFO - 'populate-webroot', 22:04:02 INFO - 'create-virtualenv', 22:04:02 INFO - 'install', 22:04:02 INFO - 'run-tests'), 22:04:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:04:02 INFO - 'download_minidump_stackwalk': True, 22:04:02 INFO - 'download_symbols': 'ondemand', 22:04:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:04:02 INFO - 'tooltool.py': '/tools/tooltool.py', 22:04:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:04:02 INFO - '/tools/misc-python/virtualenv.py')}, 22:04:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:04:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:04:02 INFO - 'installer_path': 'installer.exe', 22:04:02 INFO - 'log_level': 'info', 22:04:02 INFO - 'log_name': 'talos', 22:04:02 INFO - 'log_to_console': True, 22:04:02 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:04:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:04:02 INFO - 'opt_config_files': (), 22:04:02 INFO - 'pip_index': False, 22:04:02 INFO - 'sps_profile': False, 22:04:02 INFO - 'sps_profile_interval': 0, 22:04:02 INFO - 'suite': 'dromaeojs', 22:04:02 INFO - 'system_bits': '32', 22:04:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:04:02 INFO - 'title': 'talos-linux64-ix-055', 22:04:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:04:02 INFO - 'use_talos_json': True, 22:04:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:04:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:04:02 INFO - 'work_dir': 'build'} 22:04:02 INFO - [mozharness: 2016-12-22 06:04:02.319470Z] Running clobber step. 22:04:02 INFO - Running pre-action listener: _resource_record_pre_action 22:04:02 INFO - Running main action method: clobber 22:04:02 INFO - rmtree: /builds/slave/test/build 22:04:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:04:02 INFO - Running post-action listener: _resource_record_post_action 22:04:02 INFO - [mozharness: 2016-12-22 06:04:02.763742Z] Finished clobber step (success) 22:04:02 INFO - [mozharness: 2016-12-22 06:04:02.763843Z] Running read-buildbot-config step. 22:04:02 INFO - Running pre-action listener: _resource_record_pre_action 22:04:02 INFO - Running main action method: read_buildbot_config 22:04:02 INFO - Using buildbot properties: 22:04:02 INFO - { 22:04:02 INFO - "project": "", 22:04:02 INFO - "product": "firefox", 22:04:02 INFO - "script_repo_revision": "default", 22:04:02 INFO - "builddir": "graphics_ubuntu64_hw_test-dromaeojs", 22:04:02 INFO - "repository": "", 22:04:02 INFO - "buildername": "Ubuntu HW 12.04 x64 graphics talos dromaeojs", 22:04:02 INFO - "buildid": "20161221210953", 22:04:02 INFO - "pgo_build": "False", 22:04:02 INFO - "basedir": "/builds/slave/test", 22:04:02 INFO - "buildnumber": 32, 22:04:02 INFO - "slavename": "talos-linux64-ix-055", 22:04:02 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 22:04:02 INFO - "platform": "ubuntu64_hw", 22:04:02 INFO - "scheduler": "tests-graphics-linux64-talos", 22:04:02 INFO - "branch": "graphics", 22:04:02 INFO - "repo_path": "projects/graphics", 22:04:02 INFO - "revision": "27c8866e112f61feb69b5c7bd96627b03fbeb62e", 22:04:02 INFO - "stage_platform": "linux64", 22:04:02 INFO - "builduid": "698872a1fa494048ba5e1f52229232ee", 22:04:02 INFO - "slavebuilddir": "test" 22:04:02 INFO - } 22:04:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/aXLCOP0bRsmUty2LkLueQA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 22:04:02 INFO - Running post-action listener: _resource_record_post_action 22:04:02 INFO - [mozharness: 2016-12-22 06:04:02.766421Z] Finished read-buildbot-config step (success) 22:04:02 INFO - [mozharness: 2016-12-22 06:04:02.766511Z] Running download-and-extract step. 22:04:02 INFO - Running pre-action listener: _resource_record_pre_action 22:04:02 INFO - Running main action method: download_and_extract 22:04:02 INFO - mkdir: /builds/slave/test/build/tests 22:04:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:04:02 INFO - trying https://queue.taskcluster.net/v1/task/aXLCOP0bRsmUty2LkLueQA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 22:04:02 INFO - Downloading https://queue.taskcluster.net/v1/task/aXLCOP0bRsmUty2LkLueQA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 22:04:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aXLCOP0bRsmUty2LkLueQA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:04:03 INFO - Downloaded 1361 bytes. 22:04:03 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 22:04:03 INFO - Using the following test package requirements: 22:04:03 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 22:04:03 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 22:04:03 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:04:03 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 22:04:03 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:04:03 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 22:04:03 INFO - u'jsshell-linux-x86_64.zip'], 22:04:03 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 22:04:03 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:04:03 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 22:04:03 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 22:04:03 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:04:03 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 22:04:03 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:04:03 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 22:04:03 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:04:03 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 22:04:03 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:04:03 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:04:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aXLCOP0bRsmUty2LkLueQA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 22:04:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aXLCOP0bRsmUty2LkLueQA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:04:03 INFO - Fetch https://queue.taskcluster.net/v1/task/aXLCOP0bRsmUty2LkLueQA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 22:04:04 INFO - Expected file size: 20258275 22:04:04 INFO - Obtained file size: 20258275 22:04:08 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:04:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aXLCOP0bRsmUty2LkLueQA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 22:04:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aXLCOP0bRsmUty2LkLueQA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:04:08 INFO - Fetch https://queue.taskcluster.net/v1/task/aXLCOP0bRsmUty2LkLueQA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 22:04:08 INFO - Expected file size: 20258275 22:04:08 INFO - Obtained file size: 20258275 22:04:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aXLCOP0bRsmUty2LkLueQA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 22:04:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aXLCOP0bRsmUty2LkLueQA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:04:13 INFO - Fetch https://queue.taskcluster.net/v1/task/aXLCOP0bRsmUty2LkLueQA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 22:04:15 INFO - Expected file size: 14409734 22:04:15 INFO - Obtained file size: 14409734 22:04:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:04:15 INFO - trying https://queue.taskcluster.net/v1/task/aXLCOP0bRsmUty2LkLueQA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 22:04:15 INFO - Downloading https://queue.taskcluster.net/v1/task/aXLCOP0bRsmUty2LkLueQA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 22:04:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aXLCOP0bRsmUty2LkLueQA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:04:16 INFO - Downloaded 55648215 bytes. 22:04:16 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aXLCOP0bRsmUty2LkLueQA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 22:04:16 INFO - mkdir: /builds/slave/test/properties 22:04:16 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:04:16 INFO - Writing to file /builds/slave/test/properties/build_url 22:04:16 INFO - Contents: 22:04:16 INFO - build_url:https://queue.taskcluster.net/v1/task/aXLCOP0bRsmUty2LkLueQA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 22:04:17 INFO - Running post-action listener: _resource_record_post_action 22:04:17 INFO - Running post-action listener: set_extra_try_arguments 22:04:17 INFO - [mozharness: 2016-12-22 06:04:17.171729Z] Finished download-and-extract step (success) 22:04:17 INFO - [mozharness: 2016-12-22 06:04:17.171957Z] Running populate-webroot step. 22:04:17 INFO - Running pre-action listener: _resource_record_pre_action 22:04:17 INFO - Running main action method: populate_webroot 22:04:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:04:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:04:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:04:17 INFO - '--tppagecycles', 22:04:17 INFO - '7'], 22:04:17 INFO - 'tests': ['tsvgm']}}, 22:04:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:04:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:04:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:04:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:04:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:04:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:04:17 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 22:04:17 INFO - 'talos_options': ['--disable-e10s'], 22:04:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:04:17 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 22:04:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:04:17 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 22:04:17 INFO - 'talos_options': ['--disable-e10s'], 22:04:17 INFO - 'tests': ['damp', 'tps']}, 22:04:17 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 22:04:17 INFO - 'tests': ['damp', 'tps']}, 22:04:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:04:17 INFO - 'tests': ['dromaeo_dom']}, 22:04:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:04:17 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:04:17 INFO - 'tests': ['basic_compositor_video']}, 22:04:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:04:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:04:17 INFO - 'tests': ['a11yr', 22:04:17 INFO - 'ts_paint', 22:04:17 INFO - 'tpaint', 22:04:17 INFO - 'sessionrestore', 22:04:17 INFO - 'sessionrestore_no_auto_restore', 22:04:17 INFO - 'tabpaint']}, 22:04:17 INFO - 'other-e10s': {'tests': ['a11yr', 22:04:17 INFO - 'ts_paint', 22:04:17 INFO - 'tpaint', 22:04:17 INFO - 'sessionrestore', 22:04:17 INFO - 'sessionrestore_no_auto_restore', 22:04:17 INFO - 'tabpaint']}, 22:04:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:04:17 INFO - 'ts_paint', 22:04:17 INFO - 'tpaint', 22:04:17 INFO - 'sessionrestore', 22:04:17 INFO - 'sessionrestore_no_auto_restore', 22:04:17 INFO - 'tabpaint']}, 22:04:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:04:17 INFO - 'ts_paint', 22:04:17 INFO - 'tpaint', 22:04:17 INFO - 'sessionrestore', 22:04:17 INFO - 'sessionrestore_no_auto_restore', 22:04:17 INFO - 'tabpaint']}, 22:04:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:04:17 INFO - 'tests': ['a11yr', 22:04:17 INFO - 'ts_paint', 22:04:17 INFO - 'tpaint', 22:04:17 INFO - 'sessionrestore', 22:04:17 INFO - 'sessionrestore_no_auto_restore', 22:04:17 INFO - 'tabpaint']}, 22:04:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:04:17 INFO - 'tests': ['a11yr', 22:04:17 INFO - 'ts_paint', 22:04:17 INFO - 'tpaint', 22:04:17 INFO - 'sessionrestore', 22:04:17 INFO - 'sessionrestore_no_auto_restore', 22:04:17 INFO - 'tabpaint']}, 22:04:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:04:17 INFO - 'tests': ['tsvgx', 22:04:17 INFO - 'tsvgr_opacity', 22:04:17 INFO - 'tart', 22:04:17 INFO - 'tscrollx', 22:04:17 INFO - 'cart', 22:04:17 INFO - 'tsvg_static']}, 22:04:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:04:17 INFO - 'tsvgr_opacity', 22:04:17 INFO - 'tart', 22:04:17 INFO - 'tscrollx', 22:04:17 INFO - 'cart', 22:04:17 INFO - 'tsvg_static']}, 22:04:17 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 22:04:17 INFO - 'talos_options': ['--disable-e10s'], 22:04:17 INFO - 'tests': ['tp5o']}, 22:04:17 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 22:04:17 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 22:04:17 INFO - 'talos_options': ['--disable-e10s', 22:04:17 INFO - '--xperf_path', 22:04:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:04:17 INFO - 'tests': ['tp5n']}, 22:04:17 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 22:04:17 INFO - 'talos_options': ['--xperf_path', 22:04:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:04:17 INFO - 'tests': ['tp5n']}}, 22:04:17 INFO - 'talos.zip': {'path': '', 22:04:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:04:17 INFO - Running post-action listener: _resource_record_post_action 22:04:17 INFO - [mozharness: 2016-12-22 06:04:17.192787Z] Finished populate-webroot step (success) 22:04:17 INFO - [mozharness: 2016-12-22 06:04:17.192876Z] Running create-virtualenv step. 22:04:17 INFO - Running pre-action listener: _resource_record_pre_action 22:04:17 INFO - Running main action method: create_virtualenv 22:04:17 INFO - Creating virtualenv /builds/slave/test/build/venv 22:04:17 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 22:04:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:04:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:04:17 INFO - Using real prefix '/usr' 22:04:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:04:17 INFO - Installing distribute.............................................................................................................................................................................................done. 22:04:19 INFO - Installing pip.................done. 22:04:19 INFO - Return code: 0 22:04:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:04:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:04:19 INFO - Reading from file tmpfile_stdout 22:04:19 INFO - Output received: 22:04:19 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 22:04:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:04:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:04:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:04:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:04:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:04:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:04:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:04:19 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 0x239f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x256ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2710c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x270e3f0>, '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 0x270ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x270f1a0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1482386417.927915-1480998484', '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 22:04:19 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 22:04:19 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 22:04:19 INFO - Using env: {'DISPLAY': ':0', 22:04:19 INFO - 'HOME': '/home/cltbld', 22:04:19 INFO - 'LANG': 'en_US.UTF-8', 22:04:19 INFO - 'LANGUAGE': 'en_US:en', 22:04:19 INFO - 'LOGNAME': 'cltbld', 22:04:19 INFO - 'MAIL': '/var/mail/cltbld', 22:04:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:04:19 INFO - 'MOZ_NO_REMOTE': '1', 22:04:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:04:19 INFO - 'NO_EM_RESTART': '1', 22:04:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:04:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:04:19 INFO - 'PWD': '/builds/slave/test', 22:04:19 INFO - 'SHELL': '/bin/bash', 22:04:19 INFO - 'SHLVL': '1', 22:04:19 INFO - 'TERM': 'linux', 22:04:19 INFO - 'TMOUT': '86400', 22:04:19 INFO - 'USER': 'cltbld', 22:04:19 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1482386417.927915-1480998484', 22:04:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:04:19 INFO - '_': '/tools/buildbot/bin/python'} 22:04:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:04:20 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 22:04:20 INFO - Cleaning up... 22:04:20 INFO - Return code: 0 22:04:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:04:20 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')]} 22:04:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:04:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:04:20 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')]} 22:04:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:04:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:04:20 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 0x239f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x256ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2710c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x270e3f0>, '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 0x270ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x270f1a0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1482386417.927915-1480998484', '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 22:04:20 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 22:04:20 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 22:04:20 INFO - Using env: {'DISPLAY': ':0', 22:04:20 INFO - 'HOME': '/home/cltbld', 22:04:20 INFO - 'LANG': 'en_US.UTF-8', 22:04:20 INFO - 'LANGUAGE': 'en_US:en', 22:04:20 INFO - 'LOGNAME': 'cltbld', 22:04:20 INFO - 'MAIL': '/var/mail/cltbld', 22:04:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:04:20 INFO - 'MOZ_NO_REMOTE': '1', 22:04:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:04:20 INFO - 'NO_EM_RESTART': '1', 22:04:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:04:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:04:20 INFO - 'PWD': '/builds/slave/test', 22:04:20 INFO - 'SHELL': '/bin/bash', 22:04:20 INFO - 'SHLVL': '1', 22:04:20 INFO - 'TERM': 'linux', 22:04:20 INFO - 'TMOUT': '86400', 22:04:20 INFO - 'USER': 'cltbld', 22:04:20 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1482386417.927915-1480998484', 22:04:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:04:20 INFO - '_': '/tools/buildbot/bin/python'} 22:04:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:04:20 INFO - Downloading/unpacking psutil>=3.1.1 22:04:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:04:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:04:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:04:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:04:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:04:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:04:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:04:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:04:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:04:22 INFO - Installing collected packages: psutil 22:04:22 INFO - Running setup.py install for psutil 22:04:22 INFO - building 'psutil._psutil_linux' extension 22:04:22 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 22:04:22 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 22:04:22 INFO - building 'psutil._psutil_posix' extension 22:04:22 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 22:04:22 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 22:04:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:04:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:04:23 INFO - Successfully installed psutil 22:04:23 INFO - Cleaning up... 22:04:23 INFO - Return code: 0 22:04:23 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:04:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:04:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:04:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:04:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:04:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:04:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:04:23 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 0x239f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x256ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2710c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x270e3f0>, '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 0x270ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x270f1a0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1482386417.927915-1480998484', '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 22:04:23 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 22:04:23 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 22:04:23 INFO - Using env: {'DISPLAY': ':0', 22:04:23 INFO - 'HOME': '/home/cltbld', 22:04:23 INFO - 'LANG': 'en_US.UTF-8', 22:04:23 INFO - 'LANGUAGE': 'en_US:en', 22:04:23 INFO - 'LOGNAME': 'cltbld', 22:04:23 INFO - 'MAIL': '/var/mail/cltbld', 22:04:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:04:23 INFO - 'MOZ_NO_REMOTE': '1', 22:04:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:04:23 INFO - 'NO_EM_RESTART': '1', 22:04:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:04:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:04:23 INFO - 'PWD': '/builds/slave/test', 22:04:23 INFO - 'SHELL': '/bin/bash', 22:04:23 INFO - 'SHLVL': '1', 22:04:23 INFO - 'TERM': 'linux', 22:04:23 INFO - 'TMOUT': '86400', 22:04:23 INFO - 'USER': 'cltbld', 22:04:23 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1482386417.927915-1480998484', 22:04:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:04:23 INFO - '_': '/tools/buildbot/bin/python'} 22:04:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:04:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:04:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:04:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:04:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:04:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:04:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:04:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:04:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:04:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:04:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:04:25 INFO - Installing collected packages: mozsystemmonitor 22:04:25 INFO - Running setup.py install for mozsystemmonitor 22:04:25 INFO - Successfully installed mozsystemmonitor 22:04:25 INFO - Cleaning up... 22:04:25 INFO - Return code: 0 22:04:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:04: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')]} 22:04:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:04:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:04: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')]} 22:04:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:04:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:04: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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x239f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x256ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2710c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x270e3f0>, '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 0x270ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x270f1a0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1482386417.927915-1480998484', '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 22:04: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==2.5.1'] in /builds/slave/test/build 22:04: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==2.5.1 22:04:25 INFO - Using env: {'DISPLAY': ':0', 22:04:25 INFO - 'HOME': '/home/cltbld', 22:04:25 INFO - 'LANG': 'en_US.UTF-8', 22:04:25 INFO - 'LANGUAGE': 'en_US:en', 22:04:25 INFO - 'LOGNAME': 'cltbld', 22:04:25 INFO - 'MAIL': '/var/mail/cltbld', 22:04:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:04:25 INFO - 'MOZ_NO_REMOTE': '1', 22:04:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:04:25 INFO - 'NO_EM_RESTART': '1', 22:04:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:04:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:04:25 INFO - 'PWD': '/builds/slave/test', 22:04:25 INFO - 'SHELL': '/bin/bash', 22:04:25 INFO - 'SHLVL': '1', 22:04:25 INFO - 'TERM': 'linux', 22:04:25 INFO - 'TMOUT': '86400', 22:04:25 INFO - 'USER': 'cltbld', 22:04:25 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1482386417.927915-1480998484', 22:04:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:04:25 INFO - '_': '/tools/buildbot/bin/python'} 22:04:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:04:25 INFO - Downloading/unpacking jsonschema==2.5.1 22:04:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:04:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:04:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:04:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:04:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:04:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:04:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:04:27 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:04:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:04:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:04:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:04:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:04:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:04:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:04:27 INFO - Downloading functools32-3.2.3-2.tar.gz 22:04:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:04:27 INFO - warning: no files found matching '*.txt' 22:04:27 INFO - no previously-included directories found matching 'build' 22:04:27 INFO - no previously-included directories found matching 'dist' 22:04:27 INFO - no previously-included directories found matching '.git*' 22:04:27 INFO - Installing collected packages: jsonschema, functools32 22:04:28 INFO - Running setup.py install for functools32 22:04:28 INFO - warning: no files found matching '*.txt' 22:04:28 INFO - no previously-included directories found matching 'build' 22:04:28 INFO - no previously-included directories found matching 'dist' 22:04:28 INFO - no previously-included directories found matching '.git*' 22:04:28 INFO - Successfully installed jsonschema functools32 22:04:28 INFO - Cleaning up... 22:04:28 INFO - Return code: 0 22:04:28 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:04:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:04:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:04:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:04:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:04:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:04:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:04:28 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 0x239f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x256ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2710c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x270e3f0>, '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 0x270ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x270f1a0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1482386417.927915-1480998484', '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 22:04:28 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 22:04:28 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 22:04:28 INFO - Using env: {'DISPLAY': ':0', 22:04:28 INFO - 'HOME': '/home/cltbld', 22:04:28 INFO - 'LANG': 'en_US.UTF-8', 22:04:28 INFO - 'LANGUAGE': 'en_US:en', 22:04:28 INFO - 'LOGNAME': 'cltbld', 22:04:28 INFO - 'MAIL': '/var/mail/cltbld', 22:04:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:04:28 INFO - 'MOZ_NO_REMOTE': '1', 22:04:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:04:28 INFO - 'NO_EM_RESTART': '1', 22:04:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:04:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:04:28 INFO - 'PWD': '/builds/slave/test', 22:04:28 INFO - 'SHELL': '/bin/bash', 22:04:28 INFO - 'SHLVL': '1', 22:04:28 INFO - 'TERM': 'linux', 22:04:28 INFO - 'TMOUT': '86400', 22:04:28 INFO - 'USER': 'cltbld', 22:04:28 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1482386417.927915-1480998484', 22:04:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:04:28 INFO - '_': '/tools/buildbot/bin/python'} 22:04:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:04:28 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:04:28 INFO - Cleaning up... 22:04:28 INFO - Return code: 0 22:04:28 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:04:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:04:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:04:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:04:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:04:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:04:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:04:28 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 0x239f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x256ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2710c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x270e3f0>, '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 0x270ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x270f1a0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1482386417.927915-1480998484', '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 22:04:28 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 22:04:28 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 22:04:28 INFO - Using env: {'DISPLAY': ':0', 22:04:28 INFO - 'HOME': '/home/cltbld', 22:04:28 INFO - 'LANG': 'en_US.UTF-8', 22:04:28 INFO - 'LANGUAGE': 'en_US:en', 22:04:28 INFO - 'LOGNAME': 'cltbld', 22:04:28 INFO - 'MAIL': '/var/mail/cltbld', 22:04:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:04:28 INFO - 'MOZ_NO_REMOTE': '1', 22:04:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:04:28 INFO - 'NO_EM_RESTART': '1', 22:04:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:04:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:04:28 INFO - 'PWD': '/builds/slave/test', 22:04:28 INFO - 'SHELL': '/bin/bash', 22:04:28 INFO - 'SHLVL': '1', 22:04:28 INFO - 'TERM': 'linux', 22:04:28 INFO - 'TMOUT': '86400', 22:04:28 INFO - 'USER': 'cltbld', 22:04:28 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1482386417.927915-1480998484', 22:04:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:04:28 INFO - '_': '/tools/buildbot/bin/python'} 22:04:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:04:28 INFO - Downloading/unpacking blobuploader==1.2.4 22:04:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:04:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:04:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:04:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:04:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:04:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:04:30 INFO - Downloading blobuploader-1.2.4.tar.gz 22:04:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:04:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:04:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:04:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:04:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:04:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:04:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:04:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:04:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:04:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:04:31 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 22:04:31 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 22:04:31 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 22:04:31 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 22:04:31 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 22:04:31 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 22:04:31 INFO - Downloading docopt-0.6.1.tar.gz 22:04:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:04:31 INFO - Installing collected packages: blobuploader, requests, docopt 22:04:31 INFO - Running setup.py install for blobuploader 22:04:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:04:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:04:31 INFO - Running setup.py install for requests 22:04:31 INFO - Running setup.py install for docopt 22:04:31 INFO - Successfully installed blobuploader requests docopt 22:04:31 INFO - Cleaning up... 22:04:32 INFO - Return code: 0 22:04:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:04:32 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')]} 22:04:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:04:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:04:32 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')]} 22:04:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:04:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:04:32 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 0x239f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x256ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2710c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x270e3f0>, '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 0x270ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x270f1a0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1482386417.927915-1480998484', '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 22:04:32 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 22:04:32 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 22:04:32 INFO - Using env: {'DISPLAY': ':0', 22:04:32 INFO - 'HOME': '/home/cltbld', 22:04:32 INFO - 'LANG': 'en_US.UTF-8', 22:04:32 INFO - 'LANGUAGE': 'en_US:en', 22:04:32 INFO - 'LOGNAME': 'cltbld', 22:04:32 INFO - 'MAIL': '/var/mail/cltbld', 22:04:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:04:32 INFO - 'MOZ_NO_REMOTE': '1', 22:04:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:04:32 INFO - 'NO_EM_RESTART': '1', 22:04:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:04:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:04:32 INFO - 'PWD': '/builds/slave/test', 22:04:32 INFO - 'SHELL': '/bin/bash', 22:04:32 INFO - 'SHLVL': '1', 22:04:32 INFO - 'TERM': 'linux', 22:04:32 INFO - 'TMOUT': '86400', 22:04:32 INFO - 'USER': 'cltbld', 22:04:32 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1482386417.927915-1480998484', 22:04:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:04:32 INFO - '_': '/tools/buildbot/bin/python'} 22:04:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:04:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:04:32 INFO - Running setup.py (path:/tmp/pip-ccKUOi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:04:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:04:32 INFO - Running setup.py (path:/tmp/pip-iyqb1l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:04:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:04:32 INFO - Running setup.py (path:/tmp/pip-G0Z2kE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:04:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:04:32 INFO - Running setup.py (path:/tmp/pip-u9rWGW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:04:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:04:32 INFO - Running setup.py (path:/tmp/pip-47uBHK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:04:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:04:32 INFO - Running setup.py (path:/tmp/pip-i8LXHT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:04:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:04:32 INFO - Running setup.py (path:/tmp/pip-5qT1iO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:04:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:04:32 INFO - Running setup.py (path:/tmp/pip-Hbm5Ac-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:04:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:04:32 INFO - Running setup.py (path:/tmp/pip-pQpor0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:04:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:04:32 INFO - Running setup.py (path:/tmp/pip-wCtWYs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:04:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:04:32 INFO - Running setup.py (path:/tmp/pip-ct3bmz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:04:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:04:33 INFO - Running setup.py (path:/tmp/pip-NJgHGA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:04:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:04:33 INFO - Running setup.py (path:/tmp/pip-mjVRwI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:04:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:04:33 INFO - Running setup.py (path:/tmp/pip-d3DZ9Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:04:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:04:33 INFO - Running setup.py (path:/tmp/pip-FFjyk4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:04:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:04:33 INFO - Running setup.py (path:/tmp/pip-e1hgpl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:04:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:04:33 INFO - Running setup.py (path:/tmp/pip-lycX7x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:04:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:04:33 INFO - Running setup.py install for manifestparser 22:04:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:04:33 INFO - Running setup.py install for mozcrash 22:04:33 INFO - Running setup.py install for mozdebug 22:04:33 INFO - Running setup.py install for mozdevice 22:04:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:04:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:04:33 INFO - Running setup.py install for mozfile 22:04:33 INFO - Running setup.py install for mozhttpd 22:04:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:04:34 INFO - Running setup.py install for mozinfo 22:04:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:04:34 INFO - Running setup.py install for mozInstall 22:04:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:04:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:04:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:04:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:04:34 INFO - Running setup.py install for mozleak 22:04:34 INFO - Running setup.py install for mozlog 22:04:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:04:34 INFO - Running setup.py install for moznetwork 22:04:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:04:34 INFO - Running setup.py install for mozprocess 22:04:34 INFO - Running setup.py install for mozprofile 22:04:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:04:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:04:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:04:34 INFO - Running setup.py install for mozrunner 22:04:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:04:34 INFO - Running setup.py install for mozscreenshot 22:04:35 INFO - Running setup.py install for moztest 22:04:35 INFO - Running setup.py install for mozversion 22:04:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:04:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:04:35 INFO - Cleaning up... 22:04:35 INFO - Return code: 0 22:04:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:04:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:04:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:04:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:04:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:04:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:04:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:04:35 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 0x239f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x256ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2710c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x270e3f0>, '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 0x270ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x270f1a0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1482386417.927915-1480998484', '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 22:04:35 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 22:04:35 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 22:04:35 INFO - Using env: {'DISPLAY': ':0', 22:04:35 INFO - 'HOME': '/home/cltbld', 22:04:35 INFO - 'LANG': 'en_US.UTF-8', 22:04:35 INFO - 'LANGUAGE': 'en_US:en', 22:04:35 INFO - 'LOGNAME': 'cltbld', 22:04:35 INFO - 'MAIL': '/var/mail/cltbld', 22:04:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:04:35 INFO - 'MOZ_NO_REMOTE': '1', 22:04:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:04:35 INFO - 'NO_EM_RESTART': '1', 22:04:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:04:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:04:35 INFO - 'PWD': '/builds/slave/test', 22:04:35 INFO - 'SHELL': '/bin/bash', 22:04:35 INFO - 'SHLVL': '1', 22:04:35 INFO - 'TERM': 'linux', 22:04:35 INFO - 'TMOUT': '86400', 22:04:35 INFO - 'USER': 'cltbld', 22:04:35 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1482386417.927915-1480998484', 22:04:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:04:35 INFO - '_': '/tools/buildbot/bin/python'} 22:04:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:04:35 INFO - Running setup.py (path:/tmp/pip-VqxkN1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:04:35 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)) 22:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:04:35 INFO - Running setup.py (path:/tmp/pip-KY1wGp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:04:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 22:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:04:35 INFO - Running setup.py (path:/tmp/pip-pkLie7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:04:35 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)) 22:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:04:35 INFO - Running setup.py (path:/tmp/pip-9LEQlE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:04:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 22:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:04:35 INFO - Running setup.py (path:/tmp/pip-ZzwOzB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:04:35 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)) 22:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:04:35 INFO - Running setup.py (path:/tmp/pip-48imh6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:04:35 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)) 22:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:04:35 INFO - Running setup.py (path:/tmp/pip-EjDB__-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:04:35 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)) 22:04:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:04:35 INFO - Running setup.py (path:/tmp/pip-Q9m2f6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:04:36 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)) 22:04:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:04:36 INFO - Running setup.py (path:/tmp/pip-JTx_AQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:04:36 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)) 22:04:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:04:36 INFO - Running setup.py (path:/tmp/pip-tj_yiV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:04:36 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)) 22:04:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:04:36 INFO - Running setup.py (path:/tmp/pip-WRiGNJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:04:36 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)) 22:04:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:04:36 INFO - Running setup.py (path:/tmp/pip-Dn9YP2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:04:36 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)) 22:04:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:04:36 INFO - Running setup.py (path:/tmp/pip-dTcEDo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:04:36 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)) 22:04:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:04:36 INFO - Running setup.py (path:/tmp/pip-lKStLY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:04:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 22:04:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:04:36 INFO - Running setup.py (path:/tmp/pip-Ih8nVz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:04:36 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)) 22:04:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:04:36 INFO - Running setup.py (path:/tmp/pip-fPDlOy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:04:36 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)) 22:04:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:04:36 INFO - Running setup.py (path:/tmp/pip-nPA7o9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:04:36 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)) 22:04:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:04:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:04:36 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)) 22:04:36 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:04:36 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:04:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:04:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:04:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:04:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:04:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:04:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:04:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:04:38 INFO - Downloading blessings-1.6.tar.gz 22:04:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:04:38 INFO - Installing collected packages: blessings 22:04:38 INFO - Running setup.py install for blessings 22:04:38 INFO - Successfully installed blessings 22:04:38 INFO - Cleaning up... 22:04:38 INFO - Return code: 0 22:04:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:04:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:04:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:04:39 INFO - Reading from file tmpfile_stdout 22:04:39 INFO - Current package versions: 22:04:39 INFO - argparse == 1.2.1 22:04:39 INFO - blessings == 1.6 22:04:39 INFO - blobuploader == 1.2.4 22:04:39 INFO - docopt == 0.6.1 22:04:39 INFO - functools32 == 3.2.3-2 22:04:39 INFO - jsonschema == 2.5.1 22:04:39 INFO - manifestparser == 1.1 22:04:39 INFO - mozInstall == 1.12 22:04:39 INFO - mozcrash == 1.0 22:04:39 INFO - mozdebug == 0.1 22:04:39 INFO - mozdevice == 0.49 22:04:39 INFO - mozfile == 1.2 22:04:39 INFO - mozhttpd == 0.7 22:04:39 INFO - mozinfo == 0.9 22:04:39 INFO - mozleak == 0.1 22:04:39 INFO - mozlog == 3.4 22:04:39 INFO - moznetwork == 0.27 22:04:39 INFO - mozprocess == 0.23 22:04:39 INFO - mozprofile == 0.28 22:04:39 INFO - mozrunner == 6.13 22:04:39 INFO - mozscreenshot == 0.1 22:04:39 INFO - mozsystemmonitor == 0.3 22:04:39 INFO - moztest == 0.7 22:04:39 INFO - mozversion == 1.4 22:04:39 INFO - psutil == 3.1.1 22:04:39 INFO - requests == 1.2.3 22:04:39 INFO - wsgiref == 0.1.2 22:04:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:04:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:04:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:04:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:04:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:04:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:04:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:04:39 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 0x239f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x256ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2710c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x270e3f0>, '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 0x270ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x270f1a0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1482386417.927915-1480998484', '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 22:04:39 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 22:04:39 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 22:04:39 INFO - Using env: {'DISPLAY': ':0', 22:04:39 INFO - 'HOME': '/home/cltbld', 22:04:39 INFO - 'LANG': 'en_US.UTF-8', 22:04:39 INFO - 'LANGUAGE': 'en_US:en', 22:04:39 INFO - 'LOGNAME': 'cltbld', 22:04:39 INFO - 'MAIL': '/var/mail/cltbld', 22:04:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:04:39 INFO - 'MOZ_NO_REMOTE': '1', 22:04:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:04:39 INFO - 'NO_EM_RESTART': '1', 22:04:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:04:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:04:39 INFO - 'PWD': '/builds/slave/test', 22:04:39 INFO - 'SHELL': '/bin/bash', 22:04:39 INFO - 'SHLVL': '1', 22:04:39 INFO - 'TERM': 'linux', 22:04:39 INFO - 'TMOUT': '86400', 22:04:39 INFO - 'USER': 'cltbld', 22:04:39 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1482386417.927915-1480998484', 22:04:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:04:39 INFO - '_': '/tools/buildbot/bin/python'} 22:04:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:04:39 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)) 22:04:39 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)) 22:04:39 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)) 22:04:39 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)) 22:04:39 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)) 22:04:39 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)) 22:04:39 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)) 22:04:39 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)) 22:04:39 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)) 22:04:39 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)) 22:04:39 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)) 22:04:39 INFO - Cleaning up... 22:04:39 INFO - Return code: 0 22:04:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:04:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:04:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:04:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:04:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:04:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:04:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:04:39 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 0x239f1f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x256ba80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2710c70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x270e3f0>, '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 0x270ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x270f1a0>, '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': 'bb9b54edd4a927dc735d112f000001d9-1482386417.927915-1480998484', '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 22:04:39 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 22:04:39 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 22:04:39 INFO - Using env: {'DISPLAY': ':0', 22:04:39 INFO - 'HOME': '/home/cltbld', 22:04:39 INFO - 'LANG': 'en_US.UTF-8', 22:04:39 INFO - 'LANGUAGE': 'en_US:en', 22:04:39 INFO - 'LOGNAME': 'cltbld', 22:04:39 INFO - 'MAIL': '/var/mail/cltbld', 22:04:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:04:39 INFO - 'MOZ_NO_REMOTE': '1', 22:04:39 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:04:39 INFO - 'NO_EM_RESTART': '1', 22:04:39 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:04:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:04:39 INFO - 'PWD': '/builds/slave/test', 22:04:39 INFO - 'SHELL': '/bin/bash', 22:04:39 INFO - 'SHLVL': '1', 22:04:39 INFO - 'TERM': 'linux', 22:04:39 INFO - 'TMOUT': '86400', 22:04:39 INFO - 'USER': 'cltbld', 22:04:39 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1482386417.927915-1480998484', 22:04:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:04:39 INFO - '_': '/tools/buildbot/bin/python'} 22:04:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:04:39 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:04:39 INFO - Cleaning up... 22:04:39 INFO - Return code: 0 22:04:39 INFO - Running post-action listener: _resource_record_post_action 22:04:39 INFO - Running post-action listener: _start_resource_monitoring 22:04:39 INFO - Starting resource monitoring. 22:04:39 INFO - [mozharness: 2016-12-22 06:04:39.665859Z] Finished create-virtualenv step (success) 22:04:39 INFO - [mozharness: 2016-12-22 06:04:39.666372Z] Running install step. 22:04:39 INFO - Running pre-action listener: _resource_record_pre_action 22:04:39 INFO - Running main action method: install 22:04:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:04:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:04:39 INFO - Reading from file tmpfile_stdout 22:04:39 INFO - Detecting whether we're running mozinstall >=1.0... 22:04:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:04:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:04:39 INFO - Reading from file tmpfile_stdout 22:04:39 INFO - Output received: 22:04:39 INFO - Usage: mozinstall [options] installer 22:04:39 INFO - Options: 22:04:39 INFO - -h, --help show this help message and exit 22:04:39 INFO - -d DEST, --destination=DEST 22:04:39 INFO - Directory to install application into. [default: 22:04:39 INFO - "/builds/slave/test"] 22:04:39 INFO - --app=APP Application being installed. [default: firefox] 22:04:39 INFO - mkdir: /builds/slave/test/build/application 22:04:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:04:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:04:55 INFO - Reading from file tmpfile_stdout 22:04:55 INFO - Output received: 22:04:55 INFO - /builds/slave/test/build/application/firefox/firefox 22:04:55 INFO - Running post-action listener: _resource_record_post_action 22:04:55 INFO - [mozharness: 2016-12-22 06:04:55.984248Z] Finished install step (success) 22:04:55 INFO - [mozharness: 2016-12-22 06:04:55.984556Z] Running run-tests step. 22:04:55 INFO - Running pre-action listener: _resource_record_pre_action 22:04:55 INFO - Running main action method: run_tests 22:04:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:04:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:04:55 INFO - Python 2.7.3 22:04:55 INFO - Return code: 0 22:04:55 INFO - grabbing minidump binary from tooltool 22:04:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:04:55 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 0x270e3f0>, '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 0x270ecf0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x270f1a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:04:55 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 22:04:55 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 22:04:55 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 22:04:56 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:04:56 INFO - Return code: 0 22:04:56 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:04:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:04:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:04:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:04:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:04:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aXLCOP0bRsmUty2LkLueQA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--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 22:04:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/aXLCOP0bRsmUty2LkLueQA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-055 --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 22:04:56 INFO - Using env: {'DISPLAY': ':0', 22:04:56 INFO - 'HOME': '/home/cltbld', 22:04:56 INFO - 'LANG': 'en_US.UTF-8', 22:04:56 INFO - 'LANGUAGE': 'en_US:en', 22:04:56 INFO - 'LOGNAME': 'cltbld', 22:04:56 INFO - 'MAIL': '/var/mail/cltbld', 22:04:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:04:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:04:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:04:56 INFO - 'MOZ_NO_REMOTE': '1', 22:04:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:04:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:04:56 INFO - 'NO_EM_RESTART': '1', 22:04:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:04:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:04:56 INFO - 'PWD': '/builds/slave/test', 22:04:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:04:56 INFO - 'SHELL': '/bin/bash', 22:04:56 INFO - 'SHLVL': '1', 22:04:56 INFO - 'TERM': 'linux', 22:04:56 INFO - 'TMOUT': '86400', 22:04:56 INFO - 'USER': 'cltbld', 22:04:56 INFO - 'XDG_SESSION_COOKIE': 'bb9b54edd4a927dc735d112f000001d9-1482386417.927915-1480998484', 22:04:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:04:56 INFO - '_': '/tools/buildbot/bin/python'} 22:04:56 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aXLCOP0bRsmUty2LkLueQA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-055', '--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 22:04:56 INFO - mozversion application_buildid: 20161221210953 22:04:56 INFO - mozversion application_changeset: 27c8866e112f61feb69b5c7bd96627b03fbeb62e 22:04:56 INFO - mozversion application_display_name: Nightly 22:04:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:04:56 INFO - mozversion application_name: Firefox 22:04:56 INFO - mozversion application_remotingname: firefox 22:04:56 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 22:04:56 INFO - mozversion application_vendor: Mozilla 22:04:56 INFO - mozversion application_version: 53.0a1 22:04:56 INFO - mozversion platform_buildid: 20161221210953 22:04:56 INFO - mozversion platform_changeset: 27c8866e112f61feb69b5c7bd96627b03fbeb62e 22:04:56 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 22:04:56 INFO - mozversion platform_version: 53.0a1 22:04:56 INFO - using testdate: 1482386696 22:04:56 INFO - actual date: 1482386696 22:04:56 INFO - starting webserver on 'localhost:46370' 22:04:56 INFO - SUITE-START | Running 2 tests 22:04:56 INFO - TEST-START | dromaeo_css 22:04:56 INFO - operating with platform_type : linux_ 22:04:56 INFO - Initialising browser for dromaeo_css test... 22:04:56 INFO - TEST-INFO | started process 9288 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpd9SFf2/profile http://localhost:46370/getInfo.html) 22:05:01 INFO - PROCESS | 9288 | __metrics Screen width/height:1600/1200 22:05:01 INFO - PROCESS | 9288 | colorDepth:24 22:05:01 INFO - PROCESS | 9288 | Browser inner width/height: 1024/697 22:05:01 INFO - PROCESS | 9288 | __metrics 22:05:03 INFO - PROCESS | 9288 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:05:03 INFO - PROCESS | 9288 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:05:03 INFO - PROCESS | 9288 | console.error: PushService: 22:05:03 INFO - PROCESS | 9288 | stateChangeProcessEnqueue: Error transitioning state 22:05:03 INFO - PROCESS | 9288 | UnknownError 22:05:04 INFO - TEST-INFO | 9288: exit 0 22:05:04 INFO - Browser initialized. 22:05:04 INFO - Running cycle 1/1 for dromaeo_css test... 22:05:04 INFO - TEST-INFO | started process 9370 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpd9SFf2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:05:16 INFO - PROCESS | 9370 | RSS: Main: 155357184 22:05:16 INFO - PROCESS | 9370 | 22:06:07 INFO - PROCESS | 9370 | 1482386767564 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] 22:08:22 INFO - PROCESS | 9370 | Cycle 1(1): loaded http://localhost:46370/tests/dromaeo/cssquery-dojo.html (next: http://localhost:46370/tests/dromaeo/cssquery-ext.html) 22:08:23 INFO - PROCESS | 9370 | RSS: Main: 301916160 22:08:23 INFO - PROCESS | 9370 | 22:11:26 INFO - PROCESS | 9370 | Cycle 1(1): loaded http://localhost:46370/tests/dromaeo/cssquery-ext.html (next: http://localhost:46370/tests/dromaeo/cssquery-jquery.html) 22:11:27 INFO - PROCESS | 9370 | RSS: Main: 245420032 22:11:27 INFO - PROCESS | 9370 | 22:14:29 INFO - PROCESS | 9370 | Cycle 1(1): loaded http://localhost:46370/tests/dromaeo/cssquery-jquery.html (next: http://localhost:46370/tests/dromaeo/cssquery-mootools.html) 22:14:30 INFO - PROCESS | 9370 | RSS: Main: 230109184 22:14:30 INFO - PROCESS | 9370 | 22:17:32 INFO - PROCESS | 9370 | Cycle 1(1): loaded http://localhost:46370/tests/dromaeo/cssquery-mootools.html (next: http://localhost:46370/tests/dromaeo/cssquery-prototype.html) 22:17:33 INFO - PROCESS | 9370 | RSS: Main: 190242816 22:17:33 INFO - PROCESS | 9370 | 22:20:36 INFO - PROCESS | 9370 | Cycle 1(1): loaded http://localhost:46370/tests/dromaeo/cssquery-prototype.html (next: http://localhost:46370/tests/dromaeo/cssquery-yui.html) 22:20:37 INFO - PROCESS | 9370 | RSS: Main: 189480960 22:20:37 INFO - PROCESS | 9370 | 22:23:40 INFO - PROCESS | 9370 | Cycle 1(1): loaded http://localhost:46370/tests/dromaeo/cssquery-yui.html (next: http://localhost:46370/tests/dromaeo/cssquery-dojo.html) 22:23:40 INFO - PROCESS | 9370 | RSS: Main: 257474560 22:23:40 INFO - PROCESS | 9370 | 22:23:40 INFO - PROCESS | 9370 | __start_tp_report 22:23:40 INFO - PROCESS | 9370 | _x_x_mozilla_page_load 22:23:40 INFO - PROCESS | 9370 | _x_x_mozilla_page_load_details 22:23:40 INFO - PROCESS | 9370 | |i|pagename|runs| 22:23:40 INFO - PROCESS | 9370 | |0;dojo.html;2611.869406529673;2623.7376262373778;2658.229113557068;2665.680118385794;2673.1713168917636;4693.929591056137;4731.71609703418;4758.072175925696;4795.088933102708;4804.303376010481;4801.4718380978065;4810.567048965592;4817.964137710392;4872.829450969208;4905.018996200765;4377.649788016951;4385.8464953726625;4422.469303683562;4451.035661320569;4457.576530229623;4094.795260236991;4497.977510112445;4508.346289787979;4558.156741002925;4592.586667199951;4329.588689074564;4455.688101832873;4475.955240447586;4481.193385190664;4568.223402021665;4356.978215108904;4399.714018588781;4427.977860110711;4439.667024973108;4444.622207112399;4405.383246345515;4417.58032986869;4432.357308190327;4468.977655111737;4490.685652004361;4802.111609352246;4847.272909063633;4859.927101093486;4872.65891387603;4908.288298196741;4988.700677959334;5017.523335283142;5022.899542009176;5084.3644544431945;5159.17453207485;5108.821191258288;5168.483151684839;5174.172132458826;5198.142306519419;5213.869653258661;4801.687890287155;4815.22956326986;4822.710637361699;4826.203676393356;4877.414710234794;5159.303494028333;5203.531682148549;5294.867628309246;5296.867578310496;5300.761465734051;4999.675021123615;5047.873803154877;5048.066107770074;5053.242013697901;5059.089363914531;5147.3308469898675;5173.508516690908;5176.792928282826;5234.528892399702;5241.292425522582;5069.188929771292;5122.308488354024;5129.204973229155;5192.039472697563;5192.247124166975;4967.428745694291;4971.477994810565;5107.387113546324;5111.028904508132;5127.359080114986;4404.405405270248;4428.225060614444;4437.134758722018;4452.666050046195;4500.392447019611;3826.885193444201;3845.442410850467;3859.0352411897024;3883.475730776309;3885.708571857122;4429.867103986886;4438.533953934855;4446.377507149002;4457.3091170868565;4488.371627972087;4999.675021123615;5033.421156566968;5055.69665820052;5150.304708864331;5155.587552995751;4467.419235499365;4474.932876006863;4489.371487991683;4507.932381014288;4519.7740112994215;4680.625549955996;4686.81252749893;4694.108119457293;4703.600193983481;4732.692374995614;4643.930341044955;4654.37165982588;4686.92969605463;4811.903761924812;4813.15769740287;5144.536991670618;5178.275041494118;5180.248863914787;5253.212018197301;5262.763175657181;4796.112719146969;4799.424069111729;4810.11012962603;4814.253790662453;4846.781894814671;5125.231215317732;5127.256547800623;5135.666181698178;5136.743162841768;5172.870678233075;5000.4749501303295;5049.9495005049475;5127.846164615067;5136.768845401891;5141.408737995029;5004.2493625956395;5023.34696489454;5024;5045.9243111352625;5067.417247016493;5003.824866129668;5023;5033.119204139334;5036.143855544493;5060.443351231288;4841.733704646137;4842.903141937071;4926.679765815211;4963.230699241623;4974.701517908937;5100.387953445462;5103.591712663052;5116.539511443988;5144.614153938544;5158.97420512895;4736.171170045297;4773.021530586152;4776.355192049029;4852.80588776445;4875.366202393666 22:23:40 INFO - PROCESS | 9370 | |1;ext.html;529.2114749023953;531.8617159538519;548.2352118794282;562.0585519255246;570.4523657289002;27470.31324216893;36049.9185024449;36795.44806827893;36872.81563592185;42036.15927681452;40127;40620;40695.18609627813;40733.388999165036;40764.79617601908;7293.088363954505;7515.173330933593;7631.732889348874;7646.617669116522;7675.9232407676045;7142.25006374331;9581.568829402693;9879.901200988006;9893.060159284856;10231.721034870641;9851.605935762596;9911.157551608121;9921.702348929544;9924.15644670204;9924.801503969917;9878.703638890845;9920.40477571348;9938.453385063818;9947.55236014381;9952.950235248778;9699.272554558365;9741.025897410273;9758.170555502791;9759.121679048849;9773.902260977371;36787.63212367869;37082.8145859269;37111;37169;37203.06992325214;2797.8041537092413;2947.941041179186;2949.188973032412;3021.3806169735153;3326.0021993401883;26907;28142.718572814214;28190.154295371172;28308.29229269264;28416.573751393746;3412.7269818414716;3453.689167974894;3510.5436293281964;3555.6799888009946;3570.321638888603;236670;237388;237789;238036;238307;49443;49796.25305620419;49861;49903.75048124809;49910.50089499131;51647.741761290956;51733.48266517362;51928.22107668388;51950.74024629928;51960.48039519632;13521.256330901699;13679.452821887213;13688.178709277476;13704.65738356549;13710.65723356924;9383.577739001761;9694.660686876066;10366.378017318833;11166.776664466664;11245.94377028126;14060.437582496586;14546.199959002357;14660.344397304716;14703.926480367743;14839.40642374293;5470.7264636768;5555.416681248492;5641.492265696107;5688.431156884361;5694.202811606378;736.9520981136209;831.1480732249372;877.8770972063916;882.8719835623799;1008.606643409071;25742.35644108875;27075;27398;27452.450950981267;27734;873.6199753107419;873.8645509945968;927.2581934452411;946.2997381937316;980.3970558106855;14051.78922316166;14416.495422660157;14510.709785804225;14613.042463256159;14674.706505869823;109167.45416272868;109193.45403272932;109594;109850.45074774575;110568;47129.764351178026;47284.29073563899;47333.763331183814;47346.76326618345;47353.28970065452;1010.2221289607108;1044.4516628769957;1045.9110975567162;1099.6865893220395;1113.6158025481195;25542.616860747476;27735.583966240927;27934;30555;31162;43275.35086973761;43838.56161438472;43954.34068488911;44001.55998439975;44046;317650;321099;321642;321679;322048;23077.53844923126;24083.879580601984;24164.87917560401;24225.51548969046;33756.49365259474;34542.82728586341;34793.304133917685;35040;35308.64691353054;35759.64240357563;29965.850170749007;30092.69907300899;30117.849410752806;30441.39117217599;31454.213644659067;29709;29725.405491890473;29848.552271715507;29923.70076299209;30780.692193077783;29631.555526667547;30450.84774576113;30653.540196896618;30673.846630766704;30701.385972280877;24231.51536969286;25991.48017039611;26807.329816754736;27472.313192169557;28536.57195142033;21563.460913477287;22051.95179239169;22130.66803997909;22215.66676499886;22304 22:23:40 INFO - PROCESS | 9370 | |2;jquery.html;345.3697002969581;363.28070420567275;365.30227265922093;365.524817736942;379.20745641609034;12390.752184956296;15365.69268614627;15934.92032539837;16379;17096.145192740376;16625.83374166258;16757.748633770505;16944.491665250032;16977;17041.74437383437;4870.269459581055;5089.923651145235;5101.948980510203;5308.628396012281;5354.544863686591;3396.575428071491;3585.7131429485707;5392.299001129848;5437.374701909292;5439.755211015514;5170.431252562215;5397.487238712336;5426.511613954744;5484.698341591231;5489.066858634042;5107.080725469414;5369.382521010056;5469.535089517396;5476.58925580579;5479.6164268501025;5271.156614941629;5273.839755253838;5275.947240527584;5277.498637629419;5291.206319052173;12163.51345946161;14914.403423863032;19521.023948802645;19679.11443985024;19717;1752.3954235788633;1804.3504338438152;2256.142665787007;2268.534950335177;2343.250159948817;8858.73423797287;9026.323025773094;9035.096490350978;9199.494027828467;9365.157135857806;1624.7562865570142;1727.723564229717;1768.478298901827;1774.8047714751367;1850.7038873780266;63476;64768;66232.6688366555;67852.66073669649;68213;9517.524123793783;9723.76353438713;9785.217182625373;9812.376986756537;9948.602055917827;13454;13466.596002119952;13475.393607287695;13481.460741570423;13503.932480337731;6608.3391660834495;6796.184457865088;6847.931520684829;6868.416184624263;6929.41100006495;6139.140520327158;6143.38566143391;6269.780557680459;6319.747210111544;6517.576357536745;4304.655627549789;4326.307790753464;4331.4152589400655;4347.152305300436;4447.466304043471;1751.7810273715786;1770.097040853002;1789.1412122181166;1791.8745687801988;1819.145001849129;730.5908691132983;821.1419067074921;832.2094010689868;872.1583671756658;900.7567956651667;16076.43732469358;16278.837211627731;16503.257353419125;16589.50231493057;17190.656186876444;770.0836005153961;794.8807678848219;796.6375299238832;847.1020718038899;920.8020275640636;9044.81910361789;9424.151826335663;9529.952350238205;9603.951980240054;9627.9037209627;249881;250699;339595;340460;341620;54935.45064549383;60817.39182608117;77462.6126869362;77586.6120669393;81167.5941620288;906.8322360363354;945.7730144765344;955.2167222877174;961.5240455974338;961.8124465729255;15043.623909401915;15261.658419553067;15301.540953771404;15389;16023.519294421187;33810.83094584511;34538.309233815686;34959.475607865395;35627.28745425129;35932;43073.569264306956;45717.771411142734;46249.76875115603;48073.27890081722;50655.493445065076;7421.4062874968795;7432.70269189248;7587.924120758722;7677.539347639159;7924.801879953047;10730.946345268223;11136.443177840914;11468.082553395876;11538.59614913474;11892.524299027942;5182.24857395683;6029.849253768691;8557.801907732797;10000.799984000425;10163.085322321027;10119;10342.5345859435;10368.326058806153;10548.260780869845;10756.784864302826;7261.201267860637;7476.140243871807;7623.580703061385;7967.681292748457;10460.424676642857;9950.303478756707;10804.67585972422;11186.993170614684;11370.658880233606;11822.822657660314;10847.40339281347;10880.292780969212;10996.12031037531;11065.557377704801;11472.827907581226 22:23:40 INFO - PROCESS | 9370 | |3;mootools.html;1291.502771432998;1334.0128305054257;1398.6083896508974;1588.1027219620905;1661.4101993792196;6900;7337.26627337266;7338.596377199252;7345.66944487498;7347.7795666130105;6215.471684906788;6443.097966284724;7013.614251216181;7205.8558828823525;7208.207097219301;2102.23268506995;2151.4621344663833;2152.817010554105;2156.590247852903;2157.946051348721;2100.401385605103;2115.249086574267;2170.6201414752436;2171.4896999205175;2174.554216385637;2085.8539902206853;2087.926922557711;2106.8314534837177;2110.4301838503593;2110.556783075556;2086.061272427406;2102.2536999365234;2103.7685854555984;2106.8314534837177;2107.95784084319;2056.5578400643935;2105.147415296807;2126;2133.98933005334;2144.4960434297927;7253.455990800711;7296.489245752799;7312.3053309936;7332;7334.816629584303;4342.174986752507;4484.035932274576;4484.686071974963;4484.910301793946;4493.595576398107;2870.7416332529965;3020.501617233154;3071.800332978349;3072.6773688762582;3074.3236487972836;872.7294538693026;901.4906577783531;924.1959495239184;926.5413620257948;952.7903861150536;9852.605895764162;9887.209023278121;9899.45552994585;9908.10827025564;9921.05749953746;3910.8240129194255;4110.05468742191;4207.936880946788;4228.133232687292;4245.978770106191;8480.321574274043;8483.660653573788;8487.872681909776;8495.40532162755;8506.617202225914;4431.379606855043;4432.667549933703;4433.82264709408;4436.489803672619;4438.378626992224;3574.088607405095;3688.61269566697;3958.0698535844053;4115.485564304462;4137.13120244752;3407.1141503208846;3435.639257877887;3533.3463309287863;3543.7342199335153;3571.196480791801;1091.7216109891926;1100.9119270458343;1111.261011427408;1112.0880877680386;1134.3987686526154;761.0980987529795;773.5126870071819;812.9065157506963;819.5983967855708;845.9915400846036;4753.073150735573;4788.066327066189;4843.927341089887;4991.151504244287;5241.449647787003;387.53689341237515;400.159664704119;411.1755929361611;413.5843477305289;416.2320518643099;3383.881564145243;3386.339663765542;3400.59192896854;3407.693307602328;3512.6838584527004;8654.697085601974;8918.55407229636;8983.505907175168;9034.503102329303;9041.954790226138;8971.685990990283;8972.057933916974;8985.640574376952;8990.370674052885;8993.640254389751;6817.216020157646;6882.139732533434;7012.368886800111;7014.719411223596;7015.473839462061;4829.13075646387;4901.60304313269;4910.705357678551;4941;5134.922976155435;8036.4776289541;8163;8165.183481651788;8233.958830205811;8380.58097095155;8258.926339576057;8318.292945099492;8354.373421993254;8412.957935210285;8417.957910210409;5530.585206109477;5790.623609465371;6339.778107766206;6460.547761656639;6464.709088091141;6979.546329488567;7046.964765176142;7099.893501597377;7106.182788979334;7108.502404831612;6240.438360547572;6372.426481616677;6428.550001500037;6502.414782669583;6574.046763219402;6260.624362538262;6265.216847894013;6350.301466838551;6419;6426.839329016812;5958.7616495341435;6111.96944015277;6114.969425152845;6115.266168059861;6115.4099934016585;4190.706650534434;4284.143171365677;4409.096135292193;4436.711613745097;4452.087322098897;4421.425214722066;4437.534058923767;4473.530279320625;4523.615492683159;4560.908781824279 22:23:40 INFO - PROCESS | 9370 | |4;prototype.html;347.3885960709152;349.9100302557534;352.49593081892897;362.67902905928247;368.1073397012245;4848.7575621218975;4858.757062146896;4859.829905953292;4875.171220892456;4928.0534188995325;4749.453812811527;4760.642951778622;4818.421789385278;4839.540243676854;4841.443234028079;3631.182983828637;3732.6080761520057;3769.6984241260634;3825.221272108469;3839.212961342918;3839.404892241707;3849.8845034649007;3866.9226615467674;3877.875416129333;3884.611538846121;3802.90492737681;3808.333541630204;3812.8856134316015;3818.694504439652;3849.2878817418728;3623.655752703489;3663.7618554793985;3723.7951912644794;3767.585565587783;3771.5097037385103;3479.2867462170193;3480.9129771755656;3482.355764183634;3505.7896526208256;3506.4564992426212;4680.204365257914;4689.62483001359;4689.648276379251;4693.835715749932;4704.811807527695;4319.76241306728;4355.063661312833;4398.912021759579;4431.313146462304;4457.420535330419;5023;5023.296738456619;5025.195968644999;5027.497250274943;5030.547250747451;3813.809309534512;3817.503724515823;3823.5985221551614;3825.9617403825882;3838.347480928249;5123.462036486189;5220.582353411718;5268.525832675077;5273.235380869753;5278.340207474066;3886.6502014818802;3907.7264591478324;3945.072907866649;3953.150072734376;3955.892350141907;3976.324024915771;3991.423387761876;3995.2808494471274;4001.979990100031;4004.3993400989502;4413.91858994542;4421;4425.911481770347;4430.224710675619;4440.977795111068;4855.562999330077;4950.975245123751;4957.7769000395065;4972.3591214899;5088.5420312171345;1353.8578449262882;1357.6809449779294;1362.1418506340942;1362.5163067111232;1371.7530844448095;950.2160717408166;977.8630991661173;988.7231575158967;997.755549890267;998.5955687946393;4238.406623072772;4341;4344.435223420998;4378.146261479044;4477.552244775497;5146.433892271847;5190.97404512975;5193.272941788153;5194.27280180775;5195.68825870449;4465.687401881837;4482.305242687388;4486.932696009562;4487.237169681162;4494.191045611821;2554.616807478893;2641.1284276188812;2653.217300896202;2656.296081538394;2672.7327267273504;4654.743989080633;4669.906601868011;4696.33280185536;4709.81160753566;4735.016939328201;5147.382314122255;5157.535821776059;5165.354330708661;5174.534291913746;5186;4544.909461566167;4713.481517033124;4844.273358996108;4849.514199365643;4881.145799485076;5161.7677204526635;5163.612729045261;5171.224316352577;5188.714620695898;5229.6402935237875;5078.644494885322;5090.618203634668;5100.948990510047;5118.641695081308;5128.000039992315;4661.021185551072;4843.975780121077;4848.151573474698;4849.636277279289;4856.465788763304;3673.265346930571;3677.558692956862;3693.759905606127;3702.241435318202;3706.925861482809;5218.139007063792;5226;5235.554977826928;5237.502437268454;5247.685138891526;4839.612830973443;5015.749212539431;5094.872628184325;5105.463926287686;5111.923321150261;5079.466656001066;5104.259882316969;5105.28526006352;5105.28526006352;5114.3862736471865;5030.320906677552;5031.270465782514;5037.471065538066;5046.545810877039;5060.822871199491;4861.076395484846;4933.484726030414;5000.174971129868;5092.439831618593;5127.717975511383;4821.9035619288115;4950.975245123751;4951.72765497901;4955.529224723646;4959.801607935787 22:23:40 INFO - PROCESS | 9370 | |5;yui.html;598.3478008970221;602.8010756450371;627.5356236385073;699.4055053204777;740.7925780781376;12325.753484930296;12925;13199.472021119143;13306;13609.183448993055;12386.132970692033;12884.484620615163;13232.536861209835;13462.5288114916;13465.730685386286;773.49722680258;787.3425689549233;789.7591234673422;790.2295262119445;790.3913986230599;624.1012941364444;645.8675971425846;770.3759954436896;797.6091715059637;800.1078797141175;827.9917200827974;830.4643504939311;831.8336332733447;832.1096426823301;832.2759199496446;649.6491894377078;822.5640410582341;823.016495288131;823.2138307915926;823.3084209264247;492.85707144927926;594.9494292985102;611.4863514647717;612.2194202391986;615.8214117905802;13228.669283267995;13232.933835330858;13240.86759132406;13262.270575118364;13271.071025028252;12519.812202816966;12586.307753073577;12719.364031798466;13016.414261358166;13024.218546887218;11227.494762735696;11516.193866429354;11539.192256542045;11731;11775.528978840835;4077.8980525486804;4101.138760860223;4189.539150693421;4302.161078589676;4310.2672545667;60038.699806500685;62563.68718156426;62962;63444.36555634431;63525.68237158831;9581.233501319877;9748.9025109748;9817.852732209023;9833.704988850346;9962.402255864672;18643.881367118018;18664.906675466536;18774.249030038645;18812.623747524973;18833.623327533645;6497.382748638872;6509.934900651027;6661.200655921223;6737.326267373322;6892.138482689664;3587.210813620999;3655.214128962286;3685.7972811495624;3703;3707.5180226570915;2534.087728417768;2619.148776647597;2637.512060268856;2652.933676658099;2687.059529164777;485.2866286558753;492.35255638834815;523.5209783048468;535.0983592646403;555.5805366947928;492.778249787597;624.1511544299749;632.550888868899;633.9809805705836;649.6232185332495;8396.748097556962;8406.243438090602;8425.536595487307;8567.74296771098;8643.17889800468;457.5378867344007;466.96964697294567;478.77258302306126;490.19852541095565;539.541389818651;3854.6530812227033;3972.4041393790585;3979.144483935968;3980.402939559089;4090.1206240658403;55419.72290138604;66582;67015.32984670189;69633;69727;60918;61039.69480152571;61735.691321544;62005.37994619996;62898;51526.22710659343;51638.48361516412;52256.21615675768;52273;53336;7743.419243556643;7807.453478256468;7877.251661092187;7959.801004974922;8189.754307370788;37098.44352334663;37122;37126.25747485089;37799.62200377961;37858.8107059474;43913;48136;49806.75096624494;50595.24107138322;52855.73572132115;8861;9234.95382523083;9240.445573265626;9248.352615316864;9598.856017159887;11221.71945701364;11408.486618102039;11491;12248.693782655504;12251.754964900474;9161.267098632226;9683.854742179014;9931.553080111267;10223.131033862204;10281.709852847316;11347.205695601559;11527.365994870363;11724.355160465915;11930.463129159036;11947.522099116286;12028.458719357825;12031.338276394883;12093.57672481459;12157.878421215675;12424.813627795771;9285.276119514705;9681.611435997345;10116.494175291058;10131.392116473035;10289.74275643115;9971.351862128939;10227.130693890806;10264.178865690876;10324.896751032695;10415.635452759117 22:23:40 INFO - PROCESS | 9370 | __end_tp_report 22:23:40 INFO - PROCESS | 9370 | __start_cc_report 22:23:40 INFO - PROCESS | 9370 | _x_x_mozilla_cycle_collect,1664 22:23:40 INFO - PROCESS | 9370 | __end_cc_report 22:23:40 INFO - PROCESS | 9370 | __startTimestamp1482387820340__endTimestamp 22:23:40 INFO - PROCESS | 9370 | 22:23:40 INFO - PROCESS | 9370 | ------- Summary: start ------- 22:23:40 INFO - PROCESS | 9370 | Number of tests: 6 22:23:40 INFO - PROCESS | 9370 | 22:23:40 INFO - PROCESS | 9370 | [#0] dojo.html Cycles:165 Average:4755.49 Median:4846.78 stddev:495.12 (10.2%) stddev-sans-first:467.21 22:23:40 INFO - PROCESS | 9370 | Values: 2611.9 2623.7 2658.2 2665.7 2673.2 4693.9 4731.7 4758.1 4795.1 4804.3 4801.5 4810.6 4818.0 4872.8 4905.0 4377.6 4385.8 4422.5 4451.0 4457.6 4094.8 4498.0 4508.3 4558.2 4592.6 4329.6 4455.7 4476.0 4481.2 4568.2 4357.0 4399.7 4428.0 4439.7 4444.6 4405.4 4417.6 4432.4 4469.0 4490.7 4802.1 4847.3 4859.9 4872.7 4908.3 4988.7 5017.5 5022.9 5084.4 5159.2 5108.8 5168.5 5174.2 5198.1 5213.9 4801.7 4815.2 4822.7 4826.2 4877.4 5159.3 5203.5 5294.9 5296.9 5300.8 4999.7 5047.9 5048.1 5053.2 5059.1 5147.3 5173.5 5176.8 5234.5 5241.3 5069.2 5122.3 5129.2 5192.0 5192.2 4967.4 4971.5 5107.4 5111.0 5127.4 4404.4 4428.2 4437.1 4452.7 4500.4 3826.9 3845.4 3859.0 3883.5 3885.7 4429.9 4438.5 4446.4 4457.3 4488.4 4999.7 5033.4 5055.7 5150.3 5155.6 4467.4 4474.9 4489.4 4507.9 4519.8 4680.6 4686.8 4694.1 4703.6 4732.7 4643.9 4654.4 4686.9 4811.9 4813.2 5144.5 5178.3 5180.2 5253.2 5262.8 4796.1 4799.4 4810.1 4814.3 4846.8 5125.2 5127.3 5135.7 5136.7 5172.9 5000.5 5049.9 5127.8 5136.8 5141.4 5004.2 5023.3 5024.0 5045.9 5067.4 5003.8 5023.0 5033.1 5036.1 5060.4 4841.7 4842.9 4926.7 4963.2 4974.7 5100.4 5103.6 5116.5 5144.6 5159.0 4736.2 4773.0 4776.4 4852.8 4875.4 22:23:40 INFO - PROCESS | 9370 | 22:23:40 INFO - PROCESS | 9370 | [#1] ext.html Cycles:180 Average:38153.59 Median:18584.61 stddev:62999.87 (339.0%) stddev-sans-first:63113.26 22:23:40 INFO - PROCESS | 9370 | Values: 529.2 531.9 548.2 562.1 570.5 27470.3 36049.9 36795.4 36872.8 42036.2 40127.0 40620.0 40695.2 40733.4 40764.8 7293.1 7515.2 7631.7 7646.6 7675.9 7142.3 9581.6 9879.9 9893.1 10231.7 9851.6 9911.2 9921.7 9924.2 9924.8 9878.7 9920.4 9938.5 9947.6 9953.0 9699.3 9741.0 9758.2 9759.1 9773.9 36787.6 37082.8 37111.0 37169.0 37203.1 2797.8 2947.9 2949.2 3021.4 3326.0 26907.0 28142.7 28190.2 28308.3 28416.6 3412.7 3453.7 3510.5 3555.7 3570.3 236670.0 237388.0 237789.0 238036.0 238307.0 49443.0 49796.3 49861.0 49903.8 49910.5 51647.7 51733.5 51928.2 51950.7 51960.5 13521.3 13679.5 13688.2 13704.7 13710.7 9383.6 9694.7 10366.4 11166.8 11245.9 14060.4 14546.2 14660.3 14703.9 14839.4 5470.7 5555.4 5641.5 5688.4 5694.2 737.0 831.1 877.9 882.9 1008.6 25742.4 27075.0 27398.0 27452.5 27734.0 873.6 873.9 927.3 946.3 980.4 14051.8 14416.5 14510.7 14613.0 14674.7 109167.5 109193.5 109594.0 109850.5 110568.0 47129.8 47284.3 47333.8 47346.8 47353.3 1010.2 1044.5 1045.9 1099.7 1113.6 25542.6 27735.6 27934.0 30555.0 31162.0 43275.4 43838.6 43954.3 44001.6 44046.0 317650.0 321099.0 321642.0 321679.0 322048.0 23077.5 24083.9 24164.9 24225.5 33756.5 34542.8 34793.3 35040.0 35308.6 35759.6 29965.9 30092.7 30117.8 30441.4 31454.2 29709.0 29725.4 29848.6 29923.7 30780.7 29631.6 30450.8 30653.5 30673.8 30701.4 24231.5 25991.5 26807.3 27472.3 28536.6 21563.5 22052.0 22130.7 22215.7 22304.0 22:23:40 INFO - PROCESS | 9370 | 22:23:40 INFO - PROCESS | 9370 | [#2] jquery.html Cycles:180 Average:21260.47 Median:45983.77 stddev:51227.38 (111.4%) stddev-sans-first:51347.02 22:23:40 INFO - PROCESS | 9370 | Values: 345.4 363.3 365.3 365.5 379.2 12390.8 15365.7 15934.9 16379.0 17096.1 16625.8 16757.7 16944.5 16977.0 17041.7 4870.3 5089.9 5101.9 5308.6 5354.5 3396.6 3585.7 5392.3 5437.4 5439.8 5170.4 5397.5 5426.5 5484.7 5489.1 5107.1 5369.4 5469.5 5476.6 5479.6 5271.2 5273.8 5275.9 5277.5 5291.2 12163.5 14914.4 19521.0 19679.1 19717.0 1752.4 1804.4 2256.1 2268.5 2343.3 8858.7 9026.3 9035.1 9199.5 9365.2 1624.8 1727.7 1768.5 1774.8 1850.7 63476.0 64768.0 66232.7 67852.7 68213.0 9517.5 9723.8 9785.2 9812.4 9948.6 13454.0 13466.6 13475.4 13481.5 13503.9 6608.3 6796.2 6847.9 6868.4 6929.4 6139.1 6143.4 6269.8 6319.7 6517.6 4304.7 4326.3 4331.4 4347.2 4447.5 1751.8 1770.1 1789.1 1791.9 1819.1 730.6 821.1 832.2 872.2 900.8 16076.4 16278.8 16503.3 16589.5 17190.7 770.1 794.9 796.6 847.1 920.8 9044.8 9424.2 9530.0 9604.0 9627.9 249881.0 250699.0 339595.0 340460.0 341620.0 54935.5 60817.4 77462.6 77586.6 81167.6 906.8 945.8 955.2 961.5 961.8 15043.6 15261.7 15301.5 15389.0 16023.5 33810.8 34538.3 34959.5 35627.3 35932.0 43073.6 45717.8 46249.8 48073.3 50655.5 7421.4 7432.7 7587.9 7677.5 7924.8 10730.9 11136.4 11468.1 11538.6 11892.5 5182.2 6029.8 8557.8 10000.8 10163.1 10119.0 10342.5 10368.3 10548.3 10756.8 7261.2 7476.1 7623.6 7967.7 10460.4 9950.3 10804.7 11187.0 11370.7 11822.8 10847.4 10880.3 10996.1 11065.6 11472.8 22:23:40 INFO - PROCESS | 9370 | 22:23:40 INFO - PROCESS | 9370 | [#3] mootools.html Cycles:180 Average:4835.27 Median:4925.85 stddev:2658.32 (54.0%) stddev-sans-first:2652.43 22:23:40 INFO - PROCESS | 9370 | Values: 1291.5 1334.0 1398.6 1588.1 1661.4 6900.0 7337.3 7338.6 7345.7 7347.8 6215.5 6443.1 7013.6 7205.9 7208.2 2102.2 2151.5 2152.8 2156.6 2157.9 2100.4 2115.2 2170.6 2171.5 2174.6 2085.9 2087.9 2106.8 2110.4 2110.6 2086.1 2102.3 2103.8 2106.8 2108.0 2056.6 2105.1 2126.0 2134.0 2144.5 7253.5 7296.5 7312.3 7332.0 7334.8 4342.2 4484.0 4484.7 4484.9 4493.6 2870.7 3020.5 3071.8 3072.7 3074.3 872.7 901.5 924.2 926.5 952.8 9852.6 9887.2 9899.5 9908.1 9921.1 3910.8 4110.1 4207.9 4228.1 4246.0 8480.3 8483.7 8487.9 8495.4 8506.6 4431.4 4432.7 4433.8 4436.5 4438.4 3574.1 3688.6 3958.1 4115.5 4137.1 3407.1 3435.6 3533.3 3543.7 3571.2 1091.7 1100.9 1111.3 1112.1 1134.4 761.1 773.5 812.9 819.6 846.0 4753.1 4788.1 4843.9 4991.2 5241.4 387.5 400.2 411.2 413.6 416.2 3383.9 3386.3 3400.6 3407.7 3512.7 8654.7 8918.6 8983.5 9034.5 9042.0 8971.7 8972.1 8985.6 8990.4 8993.6 6817.2 6882.1 7012.4 7014.7 7015.5 4829.1 4901.6 4910.7 4941.0 5134.9 8036.5 8163.0 8165.2 8234.0 8380.6 8258.9 8318.3 8354.4 8413.0 8418.0 5530.6 5790.6 6339.8 6460.5 6464.7 6979.5 7047.0 7099.9 7106.2 7108.5 6240.4 6372.4 6428.6 6502.4 6574.0 6260.6 6265.2 6350.3 6419.0 6426.8 5958.8 6112.0 6115.0 6115.3 6115.4 4190.7 4284.1 4409.1 4436.7 4452.1 4421.4 4437.5 4473.5 4523.6 4560.9 22:23:40 INFO - PROCESS | 9370 | 22:23:40 INFO - PROCESS | 9370 | [#4] prototype.html Cycles:180 Average:4219.87 Median:4711.65 stddev:1182.68 (25.1%) stddev-sans-first:1149.72 22:23:40 INFO - PROCESS | 9370 | Values: 347.4 349.9 352.5 362.7 368.1 4848.8 4858.8 4859.8 4875.2 4928.1 4749.5 4760.6 4818.4 4839.5 4841.4 3631.2 3732.6 3769.7 3825.2 3839.2 3839.4 3849.9 3866.9 3877.9 3884.6 3802.9 3808.3 3812.9 3818.7 3849.3 3623.7 3663.8 3723.8 3767.6 3771.5 3479.3 3480.9 3482.4 3505.8 3506.5 4680.2 4689.6 4689.6 4693.8 4704.8 4319.8 4355.1 4398.9 4431.3 4457.4 5023.0 5023.3 5025.2 5027.5 5030.5 3813.8 3817.5 3823.6 3826.0 3838.3 5123.5 5220.6 5268.5 5273.2 5278.3 3886.7 3907.7 3945.1 3953.2 3955.9 3976.3 3991.4 3995.3 4002.0 4004.4 4413.9 4421.0 4425.9 4430.2 4441.0 4855.6 4951.0 4957.8 4972.4 5088.5 1353.9 1357.7 1362.1 1362.5 1371.8 950.2 977.9 988.7 997.8 998.6 4238.4 4341.0 4344.4 4378.1 4477.6 5146.4 5191.0 5193.3 5194.3 5195.7 4465.7 4482.3 4486.9 4487.2 4494.2 2554.6 2641.1 2653.2 2656.3 2672.7 4654.7 4669.9 4696.3 4709.8 4735.0 5147.4 5157.5 5165.4 5174.5 5186.0 4544.9 4713.5 4844.3 4849.5 4881.1 5161.8 5163.6 5171.2 5188.7 5229.6 5078.6 5090.6 5100.9 5118.6 5128.0 4661.0 4844.0 4848.2 4849.6 4856.5 3673.3 3677.6 3693.8 3702.2 3706.9 5218.1 5226.0 5235.6 5237.5 5247.7 4839.6 5015.7 5094.9 5105.5 5111.9 5079.5 5104.3 5105.3 5105.3 5114.4 5030.3 5031.3 5037.5 5046.5 5060.8 4861.1 4933.5 5000.2 5092.4 5127.7 4821.9 4951.0 4951.7 4955.5 4959.8 22:23:40 INFO - PROCESS | 9370 | 22:23:40 INFO - PROCESS | 9370 | [#5] yui.html Cycles:180 Average:14976.56 Median:51060.73 stddev:18932.95 (37.1%) stddev-sans-first:18955.28 22:23:40 INFO - PROCESS | 9370 | Values: 598.3 602.8 627.5 699.4 740.8 12325.8 12925.0 13199.5 13306.0 13609.2 12386.1 12884.5 13232.5 13462.5 13465.7 773.5 787.3 789.8 790.2 790.4 624.1 645.9 770.4 797.6 800.1 828.0 830.5 831.8 832.1 832.3 649.6 822.6 823.0 823.2 823.3 492.9 594.9 611.5 612.2 615.8 13228.7 13232.9 13240.9 13262.3 13271.1 12519.8 12586.3 12719.4 13016.4 13024.2 11227.5 11516.2 11539.2 11731.0 11775.5 4077.9 4101.1 4189.5 4302.2 4310.3 60038.7 62563.7 62962.0 63444.4 63525.7 9581.2 9748.9 9817.9 9833.7 9962.4 18643.9 18664.9 18774.2 18812.6 18833.6 6497.4 6509.9 6661.2 6737.3 6892.1 3587.2 3655.2 3685.8 3703.0 3707.5 2534.1 2619.1 2637.5 2652.9 2687.1 485.3 492.4 523.5 535.1 555.6 492.8 624.2 632.6 634.0 649.6 8396.7 8406.2 8425.5 8567.7 8643.2 457.5 467.0 478.8 490.2 539.5 3854.7 3972.4 3979.1 3980.4 4090.1 55419.7 66582.0 67015.3 69633.0 69727.0 60918.0 61039.7 61735.7 62005.4 62898.0 51526.2 51638.5 52256.2 52273.0 53336.0 7743.4 7807.5 7877.3 7959.8 8189.8 37098.4 37122.0 37126.3 37799.6 37858.8 43913.0 48136.0 49806.8 50595.2 52855.7 8861.0 9235.0 9240.4 9248.4 9598.9 11221.7 11408.5 11491.0 12248.7 12251.8 9161.3 9683.9 9931.6 10223.1 10281.7 11347.2 11527.4 11724.4 11930.5 11947.5 12028.5 12031.3 12093.6 12157.9 12424.8 9285.3 9681.6 10116.5 10131.4 10289.7 9971.4 10227.1 10264.2 10324.9 10415.6 22:23:40 INFO - PROCESS | 9370 | -------- Summary: end -------- 22:23:40 INFO - PROCESS | 9370 | 22:23:41 INFO - TEST-INFO | 9370: exit 0 22:23:41 INFO - TEST-OK | dromaeo_css | took 1124872ms 22:23:41 INFO - TEST-START | kraken 22:23:41 INFO - operating with platform_type : linux_ 22:23:41 INFO - Initialising browser for kraken test... 22:23:41 INFO - TEST-INFO | started process 9672 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprKXj5P/profile http://localhost:46370/getInfo.html) 22:23:45 INFO - PROCESS | 9672 | __metrics Screen width/height:1600/1200 22:23:45 INFO - PROCESS | 9672 | colorDepth:24 22:23:45 INFO - PROCESS | 9672 | Browser inner width/height: 1024/697 22:23:45 INFO - PROCESS | 9672 | __metrics 22:23:45 INFO - PROCESS | 9672 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 22:23:45 INFO - PROCESS | 9672 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:23:46 INFO - PROCESS | 9672 | console.error: PushService: 22:23:46 INFO - PROCESS | 9672 | stateChangeProcessEnqueue: Error transitioning state 22:23:46 INFO - PROCESS | 9672 | UnknownError 22:23:46 INFO - TEST-INFO | 9672: exit 0 22:23:46 INFO - Browser initialized. 22:23:46 INFO - Running cycle 1/1 for kraken test... 22:23:46 INFO - TEST-INFO | started process 9754 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmprKXj5P/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:23:58 INFO - PROCESS | 9754 | RSS: Main: 155242496 22:23:58 INFO - PROCESS | 9754 | 22:24:01 INFO - PROCESS | 9754 | Cycle 1(1): loaded http://localhost:46370/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:46370/tests/kraken/driver.html?testName=audio-beat-detection) 22:24:02 INFO - PROCESS | 9754 | RSS: Main: 208809984 22:24:02 INFO - PROCESS | 9754 | 22:24:05 INFO - PROCESS | 9754 | Cycle 1(1): loaded http://localhost:46370/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:46370/tests/kraken/driver.html?testName=audio-dft) 22:24:05 INFO - PROCESS | 9754 | RSS: Main: 187650048 22:24:05 INFO - PROCESS | 9754 | 22:24:08 INFO - PROCESS | 9754 | Cycle 1(1): loaded http://localhost:46370/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:46370/tests/kraken/driver.html?testName=audio-fft) 22:24:09 INFO - PROCESS | 9754 | RSS: Main: 270401536 22:24:09 INFO - PROCESS | 9754 | 22:24:11 INFO - PROCESS | 9754 | Cycle 1(1): loaded http://localhost:46370/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:46370/tests/kraken/driver.html?testName=audio-oscillator) 22:24:12 INFO - PROCESS | 9754 | RSS: Main: 181325824 22:24:12 INFO - PROCESS | 9754 | 22:24:14 INFO - PROCESS | 9754 | Cycle 1(1): loaded http://localhost:46370/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:46370/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:24:15 INFO - PROCESS | 9754 | RSS: Main: 184836096 22:24:15 INFO - PROCESS | 9754 | 22:24:18 INFO - PROCESS | 9754 | Cycle 1(1): loaded http://localhost:46370/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:46370/tests/kraken/driver.html?testName=imaging-darkroom) 22:24:19 INFO - PROCESS | 9754 | RSS: Main: 224051200 22:24:19 INFO - PROCESS | 9754 | 22:24:22 INFO - PROCESS | 9754 | Cycle 1(1): loaded http://localhost:46370/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:46370/tests/kraken/driver.html?testName=imaging-desaturate) 22:24:23 INFO - PROCESS | 9754 | RSS: Main: 217899008 22:24:23 INFO - PROCESS | 9754 | 22:24:26 INFO - PROCESS | 9754 | Cycle 1(1): loaded http://localhost:46370/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:46370/tests/kraken/driver.html?testName=json-parse-financial) 22:24:26 INFO - PROCESS | 9754 | RSS: Main: 219725824 22:24:26 INFO - PROCESS | 9754 | 22:24:28 INFO - PROCESS | 9754 | Cycle 1(1): loaded http://localhost:46370/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:46370/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:24:29 INFO - PROCESS | 9754 | RSS: Main: 183123968 22:24:29 INFO - PROCESS | 9754 | 22:24:30 INFO - PROCESS | 9754 | Cycle 1(1): loaded http://localhost:46370/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:46370/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:24:31 INFO - PROCESS | 9754 | RSS: Main: 190472192 22:24:31 INFO - PROCESS | 9754 | 22:24:33 INFO - PROCESS | 9754 | Cycle 1(1): loaded http://localhost:46370/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:46370/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:24:34 INFO - PROCESS | 9754 | RSS: Main: 199258112 22:24:34 INFO - PROCESS | 9754 | 22:24:37 INFO - PROCESS | 9754 | Cycle 1(1): loaded http://localhost:46370/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:46370/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:24:37 INFO - PROCESS | 9754 | RSS: Main: 208728064 22:24:37 INFO - PROCESS | 9754 | 22:24:40 INFO - PROCESS | 9754 | Cycle 1(1): loaded http://localhost:46370/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:46370/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:24:41 INFO - PROCESS | 9754 | RSS: Main: 205434880 22:24:41 INFO - PROCESS | 9754 | 22:24:43 INFO - PROCESS | 9754 | Cycle 1(1): loaded http://localhost:46370/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:46370/tests/kraken/driver.html?testName=ai-astar) 22:24:43 INFO - PROCESS | 9754 | RSS: Main: 255791104 22:24:43 INFO - PROCESS | 9754 | 22:24:43 INFO - PROCESS | 9754 | __start_tp_report 22:24:43 INFO - PROCESS | 9754 | _x_x_mozilla_page_load 22:24:43 INFO - PROCESS | 9754 | _x_x_mozilla_page_load_details 22:24:43 INFO - PROCESS | 9754 | |i|pagename|runs| 22:24:43 INFO - PROCESS | 9754 | |0;ai-astar;108;115;115;115;113;113;113;113;115;112 22:24:43 INFO - PROCESS | 9754 | |1;audio-beat-detection;156;124;124;167;124;124;126;129;142;130 22:24:43 INFO - PROCESS | 9754 | |2;audio-dft;160;161;161;162;159;161;161;161;162;159 22:24:43 INFO - PROCESS | 9754 | |3;audio-fft;75;75;75;106;78;77;76;77;102;75 22:24:43 INFO - PROCESS | 9754 | |4;audio-oscillator;83;84;109;85;85;84;101;86;85;110 22:24:43 INFO - PROCESS | 9754 | |5;imaging-gaussian-blur;127;127;127;127;126;126;127;127;127;127 22:24:43 INFO - PROCESS | 9754 | |6;imaging-darkroom;148;147;147;146;149;146;147;148;149;147 22:24:43 INFO - PROCESS | 9754 | |7;imaging-desaturate;95;95;96;95;95;95;95;95;95;95 22:24:43 INFO - PROCESS | 9754 | |8;json-parse-financial;63;63;62;63;63;64;65;64;65;64 22:24:43 INFO - PROCESS | 9754 | |9;json-stringify-tinderbox;66;66;66;66;69;66;66;66;66;68 22:24:43 INFO - PROCESS | 9754 | |10;stanford-crypto-aes;85;85;86;85;86;94;85;93;84;85 22:24:43 INFO - PROCESS | 9754 | |11;stanford-crypto-ccm;152;143;142;137;133;137;138;134;180;135 22:24:43 INFO - PROCESS | 9754 | |12;stanford-crypto-pbkdf2;175;176;177;180;174;176;179;176;178;179 22:24:43 INFO - PROCESS | 9754 | |13;stanford-crypto-sha256-iterative;67;68;68;72;68;68;67;68;67;68 22:24:43 INFO - PROCESS | 9754 | __end_tp_report 22:24:43 INFO - PROCESS | 9754 | __start_cc_report 22:24:43 INFO - PROCESS | 9754 | _x_x_mozilla_cycle_collect,1960 22:24:43 INFO - PROCESS | 9754 | __end_cc_report 22:24:43 INFO - PROCESS | 9754 | __startTimestamp1482387883597__endTimestamp 22:24:43 INFO - PROCESS | 9754 | 22:24:43 INFO - PROCESS | 9754 | ------- Summary: start ------- 22:24:43 INFO - PROCESS | 9754 | Number of tests: 14 22:24:43 INFO - PROCESS | 9754 | 22:24:43 INFO - PROCESS | 9754 | [#0] ai-astar Cycles:10 Average:113.20 Median:114.00 stddev:2.15 (1.9%) stddev-sans-first:1.20 22:24:43 INFO - PROCESS | 9754 | Values: 108.0 115.0 115.0 115.0 113.0 113.0 113.0 113.0 115.0 112.0 22:24:43 INFO - PROCESS | 9754 | 22:24:43 INFO - PROCESS | 9754 | [#1] audio-beat-detection Cycles:10 Average:134.60 Median:129.50 stddev:15.41 (11.9%) stddev-sans-first:14.27 22:24:43 INFO - PROCESS | 9754 | Values: 156.0 124.0 124.0 167.0 124.0 124.0 126.0 129.0 142.0 130.0 22:24:43 INFO - PROCESS | 9754 | 22:24:43 INFO - PROCESS | 9754 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:1.06 (0.7%) stddev-sans-first:1.09 22:24:43 INFO - PROCESS | 9754 | Values: 160.0 161.0 161.0 162.0 159.0 161.0 161.0 161.0 162.0 159.0 22:24:43 INFO - PROCESS | 9754 | 22:24:43 INFO - PROCESS | 9754 | [#3] audio-fft Cycles:10 Average:81.60 Median:75.50 stddev:11.89 (15.7%) stddev-sans-first:12.37 22:24:43 INFO - PROCESS | 9754 | Values: 75.0 75.0 75.0 106.0 78.0 77.0 76.0 77.0 102.0 75.0 22:24:43 INFO - PROCESS | 9754 | 22:24:43 INFO - PROCESS | 9754 | [#4] audio-oscillator Cycles:10 Average:91.20 Median:84.50 stddev:10.95 (13.0%) stddev-sans-first:11.21 22:24:43 INFO - PROCESS | 9754 | Values: 83.0 84.0 109.0 85.0 85.0 84.0 101.0 86.0 85.0 110.0 22:24:43 INFO - PROCESS | 9754 | 22:24:43 INFO - PROCESS | 9754 | [#5] imaging-gaussian-blur Cycles:10 Average:126.80 Median:127.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 22:24:43 INFO - PROCESS | 9754 | Values: 127.0 127.0 127.0 127.0 126.0 126.0 127.0 127.0 127.0 127.0 22:24:43 INFO - PROCESS | 9754 | 22:24:43 INFO - PROCESS | 9754 | [#6] imaging-darkroom Cycles:10 Average:147.40 Median:147.50 stddev:1.07 (0.7%) stddev-sans-first:1.12 22:24:43 INFO - PROCESS | 9754 | Values: 148.0 147.0 147.0 146.0 149.0 146.0 147.0 148.0 149.0 147.0 22:24:43 INFO - PROCESS | 9754 | 22:24:43 INFO - PROCESS | 9754 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 22:24:43 INFO - PROCESS | 9754 | Values: 95.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 22:24:43 INFO - PROCESS | 9754 | 22:24:43 INFO - PROCESS | 9754 | [#8] json-parse-financial Cycles:10 Average:63.60 Median:64.00 stddev:0.97 (1.5%) stddev-sans-first:1.00 22:24:43 INFO - PROCESS | 9754 | Values: 63.0 63.0 62.0 63.0 63.0 64.0 65.0 64.0 65.0 64.0 22:24:43 INFO - PROCESS | 9754 | 22:24:43 INFO - PROCESS | 9754 | [#9] json-stringify-tinderbox Cycles:10 Average:66.50 Median:66.00 stddev:1.08 (1.6%) stddev-sans-first:1.13 22:24:43 INFO - PROCESS | 9754 | Values: 66.0 66.0 66.0 66.0 69.0 66.0 66.0 66.0 66.0 68.0 22:24:43 INFO - PROCESS | 9754 | 22:24:43 INFO - PROCESS | 9754 | [#10] stanford-crypto-aes Cycles:10 Average:86.80 Median:85.50 stddev:3.58 (4.2%) stddev-sans-first:3.74 22:24:43 INFO - PROCESS | 9754 | Values: 85.0 85.0 86.0 85.0 86.0 94.0 85.0 93.0 84.0 85.0 22:24:43 INFO - PROCESS | 9754 | 22:24:43 INFO - PROCESS | 9754 | [#11] stanford-crypto-ccm Cycles:10 Average:143.10 Median:140.00 stddev:14.11 (10.1%) stddev-sans-first:14.60 22:24:43 INFO - PROCESS | 9754 | Values: 152.0 143.0 142.0 137.0 133.0 137.0 138.0 134.0 180.0 135.0 22:24:43 INFO - PROCESS | 9754 | 22:24:43 INFO - PROCESS | 9754 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.00 Median:177.50 stddev:1.94 (1.1%) stddev-sans-first:1.92 22:24:43 INFO - PROCESS | 9754 | Values: 175.0 176.0 177.0 180.0 174.0 176.0 179.0 176.0 178.0 179.0 22:24:43 INFO - PROCESS | 9754 | 22:24:43 INFO - PROCESS | 9754 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:68.00 stddev:1.45 (2.1%) stddev-sans-first:1.48 22:24:43 INFO - PROCESS | 9754 | Values: 67.0 68.0 68.0 72.0 68.0 68.0 67.0 68.0 67.0 68.0 22:24:43 INFO - PROCESS | 9754 | -------- Summary: end -------- 22:24:43 INFO - PROCESS | 9754 | 22:24:44 INFO - TEST-INFO | 9754: exit 0 22:24:44 INFO - TEST-OK | kraken | took 63607ms 22:24:44 INFO - SUITE-END | took 1188s 22:24:45 INFO - Completed test suite (00:19:48) 22:24:45 INFO - javascript benchmark 22:24:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2611.869406529673, 2623.7376262373778, 2658.229113557068, 2665.680118385794, 2673.1713168917636, 4693.929591056137, 4731.71609703418, 4758.072175925696, 4795.088933102708, 4804.303376010481, 4801.4718380978065, 4810.567048965592, 4817.964137710392, 4872.829450969208, 4905.018996200765, 4377.649788016951, 4385.8464953726625, 4422.469303683562, 4451.035661320569, 4457.576530229623, 4094.795260236991, 4497.977510112445, 4508.346289787979, 4558.156741002925, 4592.586667199951, 4329.588689074564, 4455.688101832873, 4475.955240447586, 4481.193385190664, 4568.223402021665, 4356.978215108904, 4399.714018588781, 4427.977860110711, 4439.667024973108, 4444.622207112399, 4405.383246345515, 4417.58032986869, 4432.357308190327, 4468.977655111737, 4490.685652004361, 4802.111609352246, 4847.272909063633, 4859.927101093486, 4872.65891387603, 4908.288298196741, 4988.700677959334, 5017.523335283142, 5022.899542009176, 5084.3644544431945, 5159.17453207485, 5108.821191258288, 5168.483151684839, 5174.172132458826, 5198.142306519419, 5213.869653258661, 4801.687890287155, 4815.22956326986, 4822.710637361699, 4826.203676393356, 4877.414710234794, 5159.303494028333, 5203.531682148549, 5294.867628309246, 5296.867578310496, 5300.761465734051, 4999.675021123615, 5047.873803154877, 5048.066107770074, 5053.242013697901, 5059.089363914531, 5147.3308469898675, 5173.508516690908, 5176.792928282826, 5234.528892399702, 5241.292425522582, 5069.188929771292, 5122.308488354024, 5129.204973229155, 5192.039472697563, 5192.247124166975, 4967.428745694291, 4971.477994810565, 5107.387113546324, 5111.028904508132, 5127.359080114986, 4404.405405270248, 4428.225060614444, 4437.134758722018, 4452.666050046195, 4500.392447019611, 3826.885193444201, 3845.442410850467, 3859.0352411897024, 3883.475730776309, 3885.708571857122, 4429.867103986886, 4438.533953934855, 4446.377507149002, 4457.3091170868565, 4488.371627972087, 4999.675021123615, 5033.421156566968, 5055.69665820052, 5150.304708864331, 5155.587552995751, 4467.419235499365, 4474.932876006863, 4489.371487991683, 4507.932381014288, 4519.7740112994215, 4680.625549955996, 4686.81252749893, 4694.108119457293, 4703.600193983481, 4732.692374995614, 4643.930341044955, 4654.37165982588, 4686.92969605463, 4811.903761924812, 4813.15769740287, 5144.536991670618, 5178.275041494118, 5180.248863914787, 5253.212018197301, 5262.763175657181, 4796.112719146969, 4799.424069111729, 4810.11012962603, 4814.253790662453, 4846.781894814671, 5125.231215317732, 5127.256547800623, 5135.666181698178, 5136.743162841768, 5172.870678233075, 5000.4749501303295, 5049.9495005049475, 5127.846164615067, 5136.768845401891, 5141.408737995029, 5004.2493625956395, 5023.34696489454, 5024.0, 5045.9243111352625, 5067.417247016493, 5003.824866129668, 5023.0, 5033.119204139334, 5036.143855544493, 5060.443351231288, 4841.733704646137, 4842.903141937071, 4926.679765815211, 4963.230699241623, 4974.701517908937, 5100.387953445462, 5103.591712663052, 5116.539511443988, 5144.614153938544, 5158.97420512895, 4736.171170045297, 4773.021530586152, 4776.355192049029, 4852.80588776445, 4875.366202393666], "value": 4723.719223527122, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [529.2114749023953, 531.8617159538519, 548.2352118794282, 562.0585519255246, 570.4523657289002, 27470.31324216893, 36049.9185024449, 36795.44806827893, 36872.81563592185, 42036.15927681452, 40127.0, 40620.0, 40695.18609627813, 40733.388999165036, 40764.79617601908, 7293.088363954505, 7515.173330933593, 7631.732889348874, 7646.617669116522, 7675.9232407676045, 7142.25006374331, 9581.568829402693, 9879.901200988006, 9893.060159284856, 10231.721034870641, 9851.605935762596, 9911.157551608121, 9921.702348929544, 9924.15644670204, 9924.801503969917, 9878.703638890845, 9920.40477571348, 9938.453385063818, 9947.55236014381, 9952.950235248778, 9699.272554558365, 9741.025897410273, 9758.170555502791, 9759.121679048849, 9773.902260977371, 36787.63212367869, 37082.8145859269, 37111.0, 37169.0, 37203.06992325214, 2797.8041537092413, 2947.941041179186, 2949.188973032412, 3021.3806169735153, 3326.0021993401883, 26907.0, 28142.718572814214, 28190.154295371172, 28308.29229269264, 28416.573751393746, 3412.7269818414716, 3453.689167974894, 3510.5436293281964, 3555.6799888009946, 3570.321638888603, 236670.0, 237388.0, 237789.0, 238036.0, 238307.0, 49443.0, 49796.25305620419, 49861.0, 49903.75048124809, 49910.50089499131, 51647.741761290956, 51733.48266517362, 51928.22107668388, 51950.74024629928, 51960.48039519632, 13521.256330901699, 13679.452821887213, 13688.178709277476, 13704.65738356549, 13710.65723356924, 9383.577739001761, 9694.660686876066, 10366.378017318833, 11166.776664466664, 11245.94377028126, 14060.437582496586, 14546.199959002357, 14660.344397304716, 14703.926480367743, 14839.40642374293, 5470.7264636768, 5555.416681248492, 5641.492265696107, 5688.431156884361, 5694.202811606378, 736.9520981136209, 831.1480732249372, 877.8770972063916, 882.8719835623799, 1008.606643409071, 25742.35644108875, 27075.0, 27398.0, 27452.450950981267, 27734.0, 873.6199753107419, 873.8645509945968, 927.2581934452411, 946.2997381937316, 980.3970558106855, 14051.78922316166, 14416.495422660157, 14510.709785804225, 14613.042463256159, 14674.706505869823, 109167.45416272868, 109193.45403272932, 109594.0, 109850.45074774575, 110568.0, 47129.764351178026, 47284.29073563899, 47333.763331183814, 47346.76326618345, 47353.28970065452, 1010.2221289607108, 1044.4516628769957, 1045.9110975567162, 1099.6865893220395, 1113.6158025481195, 25542.616860747476, 27735.583966240927, 27934.0, 30555.0, 31162.0, 43275.35086973761, 43838.56161438472, 43954.34068488911, 44001.55998439975, 44046.0, 317650.0, 321099.0, 321642.0, 321679.0, 322048.0, 23077.53844923126, 24083.879580601984, 24164.87917560401, 24225.51548969046, 33756.49365259474, 34542.82728586341, 34793.304133917685, 35040.0, 35308.64691353054, 35759.64240357563, 29965.850170749007, 30092.69907300899, 30117.849410752806, 30441.39117217599, 31454.213644659067, 29709.0, 29725.405491890473, 29848.552271715507, 29923.70076299209, 30780.692193077783, 29631.555526667547, 30450.84774576113, 30653.540196896618, 30673.846630766704, 30701.385972280877, 24231.51536969286, 25991.48017039611, 26807.329816754736, 27472.313192169557, 28536.57195142033, 21563.460913477287, 22051.95179239169, 22130.66803997909, 22215.66676499886, 22304.0], "value": 16295.095188968588, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [345.3697002969581, 363.28070420567275, 365.30227265922093, 365.524817736942, 379.20745641609034, 12390.752184956296, 15365.69268614627, 15934.92032539837, 16379.0, 17096.145192740376, 16625.83374166258, 16757.748633770505, 16944.491665250032, 16977.0, 17041.74437383437, 4870.269459581055, 5089.923651145235, 5101.948980510203, 5308.628396012281, 5354.544863686591, 3396.575428071491, 3585.7131429485707, 5392.299001129848, 5437.374701909292, 5439.755211015514, 5170.431252562215, 5397.487238712336, 5426.511613954744, 5484.698341591231, 5489.066858634042, 5107.080725469414, 5369.382521010056, 5469.535089517396, 5476.58925580579, 5479.6164268501025, 5271.156614941629, 5273.839755253838, 5275.947240527584, 5277.498637629419, 5291.206319052173, 12163.51345946161, 14914.403423863032, 19521.023948802645, 19679.11443985024, 19717.0, 1752.3954235788633, 1804.3504338438152, 2256.142665787007, 2268.534950335177, 2343.250159948817, 8858.73423797287, 9026.323025773094, 9035.096490350978, 9199.494027828467, 9365.157135857806, 1624.7562865570142, 1727.723564229717, 1768.478298901827, 1774.8047714751367, 1850.7038873780266, 63476.0, 64768.0, 66232.6688366555, 67852.66073669649, 68213.0, 9517.524123793783, 9723.76353438713, 9785.217182625373, 9812.376986756537, 9948.602055917827, 13454.0, 13466.596002119952, 13475.393607287695, 13481.460741570423, 13503.932480337731, 6608.3391660834495, 6796.184457865088, 6847.931520684829, 6868.416184624263, 6929.41100006495, 6139.140520327158, 6143.38566143391, 6269.780557680459, 6319.747210111544, 6517.576357536745, 4304.655627549789, 4326.307790753464, 4331.4152589400655, 4347.152305300436, 4447.466304043471, 1751.7810273715786, 1770.097040853002, 1789.1412122181166, 1791.8745687801988, 1819.145001849129, 730.5908691132983, 821.1419067074921, 832.2094010689868, 872.1583671756658, 900.7567956651667, 16076.43732469358, 16278.837211627731, 16503.257353419125, 16589.50231493057, 17190.656186876444, 770.0836005153961, 794.8807678848219, 796.6375299238832, 847.1020718038899, 920.8020275640636, 9044.81910361789, 9424.151826335663, 9529.952350238205, 9603.951980240054, 9627.9037209627, 249881.0, 250699.0, 339595.0, 340460.0, 341620.0, 54935.45064549383, 60817.39182608117, 77462.6126869362, 77586.6120669393, 81167.5941620288, 906.8322360363354, 945.7730144765344, 955.2167222877174, 961.5240455974338, 961.8124465729255, 15043.623909401915, 15261.658419553067, 15301.540953771404, 15389.0, 16023.519294421187, 33810.83094584511, 34538.309233815686, 34959.475607865395, 35627.28745425129, 35932.0, 43073.569264306956, 45717.771411142734, 46249.76875115603, 48073.27890081722, 50655.493445065076, 7421.4062874968795, 7432.70269189248, 7587.924120758722, 7677.539347639159, 7924.801879953047, 10730.946345268223, 11136.443177840914, 11468.082553395876, 11538.59614913474, 11892.524299027942, 5182.24857395683, 6029.849253768691, 8557.801907732797, 10000.799984000425, 10163.085322321027, 10119.0, 10342.5345859435, 10368.326058806153, 10548.260780869845, 10756.784864302826, 7261.201267860637, 7476.140243871807, 7623.580703061385, 7967.681292748457, 10460.424676642857, 9950.303478756707, 10804.67585972422, 11186.993170614684, 11370.658880233606, 11822.822657660314, 10847.40339281347, 10880.292780969212, 10996.12031037531, 11065.557377704801, 11472.827907581226], "value": 7919.2525831081275, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1291.502771432998, 1334.0128305054257, 1398.6083896508974, 1588.1027219620905, 1661.4101993792196, 6900.0, 7337.26627337266, 7338.596377199252, 7345.66944487498, 7347.7795666130105, 6215.471684906788, 6443.097966284724, 7013.614251216181, 7205.8558828823525, 7208.207097219301, 2102.23268506995, 2151.4621344663833, 2152.817010554105, 2156.590247852903, 2157.946051348721, 2100.401385605103, 2115.249086574267, 2170.6201414752436, 2171.4896999205175, 2174.554216385637, 2085.8539902206853, 2087.926922557711, 2106.8314534837177, 2110.4301838503593, 2110.556783075556, 2086.061272427406, 2102.2536999365234, 2103.7685854555984, 2106.8314534837177, 2107.95784084319, 2056.5578400643935, 2105.147415296807, 2126.0, 2133.98933005334, 2144.4960434297927, 7253.455990800711, 7296.489245752799, 7312.3053309936, 7332.0, 7334.816629584303, 4342.174986752507, 4484.035932274576, 4484.686071974963, 4484.910301793946, 4493.595576398107, 2870.7416332529965, 3020.501617233154, 3071.800332978349, 3072.6773688762582, 3074.3236487972836, 872.7294538693026, 901.4906577783531, 924.1959495239184, 926.5413620257948, 952.7903861150536, 9852.605895764162, 9887.209023278121, 9899.45552994585, 9908.10827025564, 9921.05749953746, 3910.8240129194255, 4110.05468742191, 4207.936880946788, 4228.133232687292, 4245.978770106191, 8480.321574274043, 8483.660653573788, 8487.872681909776, 8495.40532162755, 8506.617202225914, 4431.379606855043, 4432.667549933703, 4433.82264709408, 4436.489803672619, 4438.378626992224, 3574.088607405095, 3688.61269566697, 3958.0698535844053, 4115.485564304462, 4137.13120244752, 3407.1141503208846, 3435.639257877887, 3533.3463309287863, 3543.7342199335153, 3571.196480791801, 1091.7216109891926, 1100.9119270458343, 1111.261011427408, 1112.0880877680386, 1134.3987686526154, 761.0980987529795, 773.5126870071819, 812.9065157506963, 819.5983967855708, 845.9915400846036, 4753.073150735573, 4788.066327066189, 4843.927341089887, 4991.151504244287, 5241.449647787003, 387.53689341237515, 400.159664704119, 411.1755929361611, 413.5843477305289, 416.2320518643099, 3383.881564145243, 3386.339663765542, 3400.59192896854, 3407.693307602328, 3512.6838584527004, 8654.697085601974, 8918.55407229636, 8983.505907175168, 9034.503102329303, 9041.954790226138, 8971.685990990283, 8972.057933916974, 8985.640574376952, 8990.370674052885, 8993.640254389751, 6817.216020157646, 6882.139732533434, 7012.368886800111, 7014.719411223596, 7015.473839462061, 4829.13075646387, 4901.60304313269, 4910.705357678551, 4941.0, 5134.922976155435, 8036.4776289541, 8163.0, 8165.183481651788, 8233.958830205811, 8380.58097095155, 8258.926339576057, 8318.292945099492, 8354.373421993254, 8412.957935210285, 8417.957910210409, 5530.585206109477, 5790.623609465371, 6339.778107766206, 6460.547761656639, 6464.709088091141, 6979.546329488567, 7046.964765176142, 7099.893501597377, 7106.182788979334, 7108.502404831612, 6240.438360547572, 6372.426481616677, 6428.550001500037, 6502.414782669583, 6574.046763219402, 6260.624362538262, 6265.216847894013, 6350.301466838551, 6419.0, 6426.839329016812, 5958.7616495341435, 6111.96944015277, 6114.969425152845, 6115.266168059861, 6115.4099934016585, 4190.706650534434, 4284.143171365677, 4409.096135292193, 4436.711613745097, 4452.087322098897, 4421.425214722066, 4437.534058923767, 4473.530279320625, 4523.615492683159, 4560.908781824279], "value": 3867.908511137826, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.3885960709152, 349.9100302557534, 352.49593081892897, 362.67902905928247, 368.1073397012245, 4848.7575621218975, 4858.757062146896, 4859.829905953292, 4875.171220892456, 4928.0534188995325, 4749.453812811527, 4760.642951778622, 4818.421789385278, 4839.540243676854, 4841.443234028079, 3631.182983828637, 3732.6080761520057, 3769.6984241260634, 3825.221272108469, 3839.212961342918, 3839.404892241707, 3849.8845034649007, 3866.9226615467674, 3877.875416129333, 3884.611538846121, 3802.90492737681, 3808.333541630204, 3812.8856134316015, 3818.694504439652, 3849.2878817418728, 3623.655752703489, 3663.7618554793985, 3723.7951912644794, 3767.585565587783, 3771.5097037385103, 3479.2867462170193, 3480.9129771755656, 3482.355764183634, 3505.7896526208256, 3506.4564992426212, 4680.204365257914, 4689.62483001359, 4689.648276379251, 4693.835715749932, 4704.811807527695, 4319.76241306728, 4355.063661312833, 4398.912021759579, 4431.313146462304, 4457.420535330419, 5023.0, 5023.296738456619, 5025.195968644999, 5027.497250274943, 5030.547250747451, 3813.809309534512, 3817.503724515823, 3823.5985221551614, 3825.9617403825882, 3838.347480928249, 5123.462036486189, 5220.582353411718, 5268.525832675077, 5273.235380869753, 5278.340207474066, 3886.6502014818802, 3907.7264591478324, 3945.072907866649, 3953.150072734376, 3955.892350141907, 3976.324024915771, 3991.423387761876, 3995.2808494471274, 4001.979990100031, 4004.3993400989502, 4413.91858994542, 4421.0, 4425.911481770347, 4430.224710675619, 4440.977795111068, 4855.562999330077, 4950.975245123751, 4957.7769000395065, 4972.3591214899, 5088.5420312171345, 1353.8578449262882, 1357.6809449779294, 1362.1418506340942, 1362.5163067111232, 1371.7530844448095, 950.2160717408166, 977.8630991661173, 988.7231575158967, 997.755549890267, 998.5955687946393, 4238.406623072772, 4341.0, 4344.435223420998, 4378.146261479044, 4477.552244775497, 5146.433892271847, 5190.97404512975, 5193.272941788153, 5194.27280180775, 5195.68825870449, 4465.687401881837, 4482.305242687388, 4486.932696009562, 4487.237169681162, 4494.191045611821, 2554.616807478893, 2641.1284276188812, 2653.217300896202, 2656.296081538394, 2672.7327267273504, 4654.743989080633, 4669.906601868011, 4696.33280185536, 4709.81160753566, 4735.016939328201, 5147.382314122255, 5157.535821776059, 5165.354330708661, 5174.534291913746, 5186.0, 4544.909461566167, 4713.481517033124, 4844.273358996108, 4849.514199365643, 4881.145799485076, 5161.7677204526635, 5163.612729045261, 5171.224316352577, 5188.714620695898, 5229.6402935237875, 5078.644494885322, 5090.618203634668, 5100.948990510047, 5118.641695081308, 5128.000039992315, 4661.021185551072, 4843.975780121077, 4848.151573474698, 4849.636277279289, 4856.465788763304, 3673.265346930571, 3677.558692956862, 3693.759905606127, 3702.241435318202, 3706.925861482809, 5218.139007063792, 5226.0, 5235.554977826928, 5237.502437268454, 5247.685138891526, 4839.612830973443, 5015.749212539431, 5094.872628184325, 5105.463926287686, 5111.923321150261, 5079.466656001066, 5104.259882316969, 5105.28526006352, 5105.28526006352, 5114.3862736471865, 5030.320906677552, 5031.270465782514, 5037.471065538066, 5046.545810877039, 5060.822871199491, 4861.076395484846, 4933.484726030414, 5000.174971129868, 5092.439831618593, 5127.717975511383, 4821.9035619288115, 4950.975245123751, 4951.72765497901, 4955.529224723646, 4959.801607935787], "value": 3866.342325840471, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [598.3478008970221, 602.8010756450371, 627.5356236385073, 699.4055053204777, 740.7925780781376, 12325.753484930296, 12925.0, 13199.472021119143, 13306.0, 13609.183448993055, 12386.132970692033, 12884.484620615163, 13232.536861209835, 13462.5288114916, 13465.730685386286, 773.49722680258, 787.3425689549233, 789.7591234673422, 790.2295262119445, 790.3913986230599, 624.1012941364444, 645.8675971425846, 770.3759954436896, 797.6091715059637, 800.1078797141175, 827.9917200827974, 830.4643504939311, 831.8336332733447, 832.1096426823301, 832.2759199496446, 649.6491894377078, 822.5640410582341, 823.016495288131, 823.2138307915926, 823.3084209264247, 492.85707144927926, 594.9494292985102, 611.4863514647717, 612.2194202391986, 615.8214117905802, 13228.669283267995, 13232.933835330858, 13240.86759132406, 13262.270575118364, 13271.071025028252, 12519.812202816966, 12586.307753073577, 12719.364031798466, 13016.414261358166, 13024.218546887218, 11227.494762735696, 11516.193866429354, 11539.192256542045, 11731.0, 11775.528978840835, 4077.8980525486804, 4101.138760860223, 4189.539150693421, 4302.161078589676, 4310.2672545667, 60038.699806500685, 62563.68718156426, 62962.0, 63444.36555634431, 63525.68237158831, 9581.233501319877, 9748.9025109748, 9817.852732209023, 9833.704988850346, 9962.402255864672, 18643.881367118018, 18664.906675466536, 18774.249030038645, 18812.623747524973, 18833.623327533645, 6497.382748638872, 6509.934900651027, 6661.200655921223, 6737.326267373322, 6892.138482689664, 3587.210813620999, 3655.214128962286, 3685.7972811495624, 3703.0, 3707.5180226570915, 2534.087728417768, 2619.148776647597, 2637.512060268856, 2652.933676658099, 2687.059529164777, 485.2866286558753, 492.35255638834815, 523.5209783048468, 535.0983592646403, 555.5805366947928, 492.778249787597, 624.1511544299749, 632.550888868899, 633.9809805705836, 649.6232185332495, 8396.748097556962, 8406.243438090602, 8425.536595487307, 8567.74296771098, 8643.17889800468, 457.5378867344007, 466.96964697294567, 478.77258302306126, 490.19852541095565, 539.541389818651, 3854.6530812227033, 3972.4041393790585, 3979.144483935968, 3980.402939559089, 4090.1206240658403, 55419.72290138604, 66582.0, 67015.32984670189, 69633.0, 69727.0, 60918.0, 61039.69480152571, 61735.691321544, 62005.37994619996, 62898.0, 51526.22710659343, 51638.48361516412, 52256.21615675768, 52273.0, 53336.0, 7743.419243556643, 7807.453478256468, 7877.251661092187, 7959.801004974922, 8189.754307370788, 37098.44352334663, 37122.0, 37126.25747485089, 37799.62200377961, 37858.8107059474, 43913.0, 48136.0, 49806.75096624494, 50595.24107138322, 52855.73572132115, 8861.0, 9234.95382523083, 9240.445573265626, 9248.352615316864, 9598.856017159887, 11221.71945701364, 11408.486618102039, 11491.0, 12248.693782655504, 12251.754964900474, 9161.267098632226, 9683.854742179014, 9931.553080111267, 10223.131033862204, 10281.709852847316, 11347.205695601559, 11527.365994870363, 11724.355160465915, 11930.463129159036, 11947.522099116286, 12028.458719357825, 12031.338276394883, 12093.57672481459, 12157.878421215675, 12424.813627795771, 9285.276119514705, 9681.611435997345, 10116.494175291058, 10131.392116473035, 10289.74275643115, 9971.351862128939, 10227.130693890806, 10264.178865690876, 10324.896751032695, 10415.635452759117], "value": 6214.617446361423, "unit": "score"}], "name": "dromaeo_css", "value": 6197.4151010703235, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 115.0, 115.0, 115.0, 113.0, 113.0, 113.0, 113.0, 115.0, 112.0], "value": 113.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 124.0, 124.0, 167.0, 124.0, 124.0, 126.0, 129.0, 142.0, 130.0], "value": 134.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 161.0, 161.0, 162.0, 159.0, 161.0, 161.0, 161.0, 162.0, 159.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 106.0, 78.0, 77.0, 76.0, 77.0, 102.0, 75.0], "value": 81.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 109.0, 85.0, 85.0, 84.0, 101.0, 86.0, 85.0, 110.0], "value": 91.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 127.0, 127.0, 127.0, 126.0, 126.0, 127.0, 127.0, 127.0, 127.0], "value": 126.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 147.0, 147.0, 146.0, 149.0, 146.0, 147.0, 148.0, 149.0, 147.0], "value": 147.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 62.0, 63.0, 63.0, 64.0, 65.0, 64.0, 65.0, 64.0], "value": 63.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 66.0, 69.0, 66.0, 66.0, 66.0, 66.0, 68.0], "value": 66.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 86.0, 85.0, 86.0, 94.0, 85.0, 93.0, 84.0, 85.0], "value": 86.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 143.0, 142.0, 137.0, 133.0, 137.0, 138.0, 134.0, 180.0, 135.0], "value": 143.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 176.0, 177.0, 180.0, 174.0, 176.0, 179.0, 176.0, 178.0, 179.0], "value": 177.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 68.0, 72.0, 68.0, 68.0, 67.0, 68.0, 67.0, 68.0], "value": 68.1, "unit": "score"}], "name": "kraken", "value": 1555.6999999999998, "alertThreshold": 2.0}]} 22:24:45 INFO - Return code: 0 22:24:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:24:45 INFO - # TBPL SUCCESS # 22:24:45 INFO - Running post-action listener: _resource_record_post_action 22:24:45 INFO - [mozharness: 2016-12-22 06:24:45.387875Z] Finished run-tests step (success) 22:24:45 INFO - Running post-run listener: _resource_record_post_run 22:24:45 INFO - Total resource usage - Wall time: 1205s; CPU: 13.0%; Read bytes: 7872512; Write bytes: 393293824; Read time: 1476; Write time: 289948 22:24:45 INFO - TinderboxPrint: CPU usage
13.1% 22:24:45 INFO - TinderboxPrint: I/O read bytes / time
7,872,512 / 1,476 22:24:45 INFO - TinderboxPrint: I/O write bytes / time
393,293,824 / 289,948 22:24:45 INFO - TinderboxPrint: CPU idle
8,356.5 (86.9%) 22:24:45 INFO - TinderboxPrint: CPU user
1,213.3 (12.6%) 22:24:45 INFO - TinderboxPrint: Swap in / out
0 / 0 22:24:45 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 110497792; Read time: 0; Write time: 57092 22:24:45 INFO - run-tests - Wall time: 1189s; CPU: 13.0%; Read bytes: 6119424; Write bytes: 233693184; Read time: 1244; Write time: 216464 22:24:46 INFO - Running post-run listener: _upload_blobber_files 22:24:46 INFO - Blob upload gear active. 22:24:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:24:46 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 22:24:46 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:24:46 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:24:46 INFO - (blobuploader) - INFO - Open directory for files ... 22:24:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:24:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:24:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:24:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:24:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:24:47 INFO - (blobuploader) - INFO - Done attempting. 22:24:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:24:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:24:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:24:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:24:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:24:48 INFO - (blobuploader) - INFO - Done attempting. 22:24:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:24:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:24:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:24:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:24:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:24:51 INFO - (blobuploader) - INFO - Done attempting. 22:24:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 22:24:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:24:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:24:52 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 22:24:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:24:52 INFO - (blobuploader) - INFO - Done attempting. 22:24:52 INFO - (blobuploader) - INFO - Iteration through files over. 22:24:52 INFO - Return code: 0 22:24:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:24:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:24:52 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/6fb8603813fe2d541cd8cf58e4245c1014b9e51f59bbe06e49e9702359566046806719e2dcdce23e781f191d180d3751037980ca1c9aee70d61ebf0b1f89803f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/fdd2ea9b1b0deef9f5ad4f9f3cee1bdb1400c0e55e8e0a0fb1d69b695696e3bc573cf07e80d252497c486c1ca0b3cd92dddd3dd45ea9ece1bf5ae929efa4958a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9db0efd4daec906a1a2c931c91218e79ea46f3b9faa15acb5bf2201209253d329220e06f75446b0b763f6abd20294658e053455dee61ec29913885277fb4d03b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ae89e8e16109694a9512cb5005cb9d6ff770b7c0493b684d59f77158bf7441501fb27b510ec1731d1c3ae6b3a512a98cbc434977647afef6b4ea7c483a07fd76"} 22:24:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:24:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:24:52 INFO - Contents: 22:24:52 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/6fb8603813fe2d541cd8cf58e4245c1014b9e51f59bbe06e49e9702359566046806719e2dcdce23e781f191d180d3751037980ca1c9aee70d61ebf0b1f89803f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/fdd2ea9b1b0deef9f5ad4f9f3cee1bdb1400c0e55e8e0a0fb1d69b695696e3bc573cf07e80d252497c486c1ca0b3cd92dddd3dd45ea9ece1bf5ae929efa4958a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9db0efd4daec906a1a2c931c91218e79ea46f3b9faa15acb5bf2201209253d329220e06f75446b0b763f6abd20294658e053455dee61ec29913885277fb4d03b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ae89e8e16109694a9512cb5005cb9d6ff770b7c0493b684d59f77158bf7441501fb27b510ec1731d1c3ae6b3a512a98cbc434977647afef6b4ea7c483a07fd76"} 22:24:52 INFO - Running post-run listener: copy_logs_to_upload_dir 22:24:52 INFO - Copying logs to upload dir... 22:24:52 INFO - mkdir: /builds/slave/test/build/upload/logs 22:24:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.195569 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-12-21 22:24:52.351022) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-21 22:24:52.354417) ========= 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=bb9b54edd4a927dc735d112f000001d9-1482386417.927915-1480998484 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/6fb8603813fe2d541cd8cf58e4245c1014b9e51f59bbe06e49e9702359566046806719e2dcdce23e781f191d180d3751037980ca1c9aee70d61ebf0b1f89803f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/fdd2ea9b1b0deef9f5ad4f9f3cee1bdb1400c0e55e8e0a0fb1d69b695696e3bc573cf07e80d252497c486c1ca0b3cd92dddd3dd45ea9ece1bf5ae929efa4958a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9db0efd4daec906a1a2c931c91218e79ea46f3b9faa15acb5bf2201209253d329220e06f75446b0b763f6abd20294658e053455dee61ec29913885277fb4d03b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ae89e8e16109694a9512cb5005cb9d6ff770b7c0493b684d59f77158bf7441501fb27b510ec1731d1c3ae6b3a512a98cbc434977647afef6b4ea7c483a07fd76"} build_url:https://queue.taskcluster.net/v1/task/aXLCOP0bRsmUty2LkLueQA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011736 build_url: 'https://queue.taskcluster.net/v1/task/aXLCOP0bRsmUty2LkLueQA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/6fb8603813fe2d541cd8cf58e4245c1014b9e51f59bbe06e49e9702359566046806719e2dcdce23e781f191d180d3751037980ca1c9aee70d61ebf0b1f89803f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/fdd2ea9b1b0deef9f5ad4f9f3cee1bdb1400c0e55e8e0a0fb1d69b695696e3bc573cf07e80d252497c486c1ca0b3cd92dddd3dd45ea9ece1bf5ae929efa4958a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9db0efd4daec906a1a2c931c91218e79ea46f3b9faa15acb5bf2201209253d329220e06f75446b0b763f6abd20294658e053455dee61ec29913885277fb4d03b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/ae89e8e16109694a9512cb5005cb9d6ff770b7c0493b684d59f77158bf7441501fb27b510ec1731d1c3ae6b3a512a98cbc434977647afef6b4ea7c483a07fd76"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-21 22:24:52.379562) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 22:24:52.379883) ========= 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=bb9b54edd4a927dc735d112f000001d9-1482386417.927915-1480998484 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004451 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 22:24:52.465245) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-21 22:24:52.465557) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-21 22:24:52.465860) ========= ========= Total master_lag: 0.16 =========