builder: graphics_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-024 starttime: 1481400832.67 results: success (0) buildid: 20161210112351 builduid: 24ea621872654573a7b1492f3ceb0bdc revision: 85efd4833716400d0ce85c2a30e408050afb1406 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-10 12:13:52.665868) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-10 12:13:52.666317) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-10 12:13:52.666600) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-10 12:13:52.712849) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-10 12:13:52.713108) ========= 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=41a7e32711b8e08598a63237000001d6-1481400544.280785-2108051025 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-10 12:13:52-- 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% 46.0M=0s 2016-12-10 12:13:53 (46.0 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.103502 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-10 12:13:52.831272) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-10 12:13:52.831559) ========= 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=41a7e32711b8e08598a63237000001d6-1481400544.280785-2108051025 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014995 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-10 12:13:52.887313) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-10 12:13:52.887627) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 85efd4833716400d0ce85c2a30e408050afb1406 --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 85efd4833716400d0ce85c2a30e408050afb1406 --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=41a7e32711b8e08598a63237000001d6-1481400544.280785-2108051025 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-10 12:13:53,206 truncating revision to first 12 chars 2016-12-10 12:13:53,207 Setting DEBUG logging. 2016-12-10 12:13:53,207 attempt 1/10 2016-12-10 12:13:53,207 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/85efd4833716?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-10 12:13:54,251 unpacking tar archive at: graphics-85efd4833716/testing/mozharness/ program finished with exit code 0 elapsedTime=1.359621 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-10 12:13:54.262178) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-10 12:13:54.262571) ========= script_repo_revision: 85efd4833716400d0ce85c2a30e408050afb1406 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-10 12:13:54.263047) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-10 12:13:54.263361) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-10 12:13:54.272121) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-12-10 12:13:54.272399) ========= /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=41a7e32711b8e08598a63237000001d6-1481400544.280785-2108051025 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 12:13:54 INFO - MultiFileLogger online at 20161210 12:13:54 in /builds/slave/test 12:13:54 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 12:13:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:13:54 INFO - {'append_to_log': False, 12:13:54 INFO - 'base_work_dir': '/builds/slave/test', 12:13:54 INFO - 'blob_upload_branch': 'Graphics', 12:13:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:13:54 INFO - 'branch': 'Graphics', 12:13:54 INFO - 'buildbot_json_path': 'buildprops.json', 12:13:54 INFO - 'config_files': ('talos/linux_config.py',), 12:13:54 INFO - 'default_actions': ('clobber', 12:13:54 INFO - 'read-buildbot-config', 12:13:54 INFO - 'download-and-extract', 12:13:54 INFO - 'populate-webroot', 12:13:54 INFO - 'create-virtualenv', 12:13:54 INFO - 'install', 12:13:54 INFO - 'run-tests'), 12:13:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:13:54 INFO - 'download_minidump_stackwalk': True, 12:13:54 INFO - 'download_symbols': 'ondemand', 12:13:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:13:54 INFO - 'tooltool.py': '/tools/tooltool.py', 12:13:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:13:54 INFO - '/tools/misc-python/virtualenv.py')}, 12:13:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:13:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:13:54 INFO - 'installer_path': 'installer.exe', 12:13:54 INFO - 'log_level': 'info', 12:13:54 INFO - 'log_name': 'talos', 12:13:54 INFO - 'log_to_console': True, 12:13:54 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 12:13:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 12:13:54 INFO - 'opt_config_files': (), 12:13:54 INFO - 'pip_index': False, 12:13:54 INFO - 'sps_profile': False, 12:13:54 INFO - 'sps_profile_interval': 0, 12:13:54 INFO - 'suite': 'dromaeojs', 12:13:54 INFO - 'system_bits': '32', 12:13:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:13:54 INFO - 'title': 'talos-linux64-ix-024', 12:13:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:13:54 INFO - 'use_talos_json': True, 12:13:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:13:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:13:54 INFO - 'work_dir': 'build'} 12:13:54 INFO - [mozharness: 2016-12-10 20:13:54.686442Z] Running clobber step. 12:13:54 INFO - Running pre-action listener: _resource_record_pre_action 12:13:54 INFO - Running main action method: clobber 12:13:54 INFO - rmtree: /builds/slave/test/build 12:13:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:13:55 INFO - Running post-action listener: _resource_record_post_action 12:13:55 INFO - [mozharness: 2016-12-10 20:13:55.154658Z] Finished clobber step (success) 12:13:55 INFO - [mozharness: 2016-12-10 20:13:55.154758Z] Running read-buildbot-config step. 12:13:55 INFO - Running pre-action listener: _resource_record_pre_action 12:13:55 INFO - Running main action method: read_buildbot_config 12:13:55 INFO - Using buildbot properties: 12:13:55 INFO - { 12:13:55 INFO - "project": "", 12:13:55 INFO - "product": "firefox", 12:13:55 INFO - "script_repo_revision": "default", 12:13:55 INFO - "builddir": "graphics_ubuntu64_hw_test-dromaeojs", 12:13:55 INFO - "repository": "", 12:13:55 INFO - "buildername": "Ubuntu HW 12.04 x64 graphics talos dromaeojs", 12:13:55 INFO - "buildid": "20161210112351", 12:13:55 INFO - "pgo_build": "False", 12:13:55 INFO - "basedir": "/builds/slave/test", 12:13:55 INFO - "buildnumber": 27, 12:13:55 INFO - "slavename": "talos-linux64-ix-024", 12:13:55 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 12:13:55 INFO - "platform": "ubuntu64_hw", 12:13:55 INFO - "scheduler": "tests-graphics-linux64-talos", 12:13:55 INFO - "branch": "graphics", 12:13:55 INFO - "repo_path": "projects/graphics", 12:13:55 INFO - "revision": "85efd4833716400d0ce85c2a30e408050afb1406", 12:13:55 INFO - "stage_platform": "linux64", 12:13:55 INFO - "builduid": "24ea621872654573a7b1492f3ceb0bdc", 12:13:55 INFO - "slavebuilddir": "test" 12:13:55 INFO - } 12:13:55 INFO - Found installer url https://queue.taskcluster.net/v1/task/XJ7sCnsBQNuinfnHn-fvtA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 12:13:55 INFO - Running post-action listener: _resource_record_post_action 12:13:55 INFO - [mozharness: 2016-12-10 20:13:55.157180Z] Finished read-buildbot-config step (success) 12:13:55 INFO - [mozharness: 2016-12-10 20:13:55.157268Z] Running download-and-extract step. 12:13:55 INFO - Running pre-action listener: _resource_record_pre_action 12:13:55 INFO - Running main action method: download_and_extract 12:13:55 INFO - mkdir: /builds/slave/test/build/tests 12:13: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')]} 12:13:55 INFO - trying https://queue.taskcluster.net/v1/task/XJ7sCnsBQNuinfnHn-fvtA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 12:13:55 INFO - Downloading https://queue.taskcluster.net/v1/task/XJ7sCnsBQNuinfnHn-fvtA/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 12:13:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XJ7sCnsBQNuinfnHn-fvtA/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 12:13:56 INFO - Downloaded 1361 bytes. 12:13:56 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 12:13:56 INFO - Using the following test package requirements: 12:13:56 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 12:13:56 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 12:13:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 12:13:56 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 12:13:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 12:13:56 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 12:13:56 INFO - u'jsshell-linux-x86_64.zip'], 12:13:56 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 12:13:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 12:13:56 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 12:13:56 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 12:13:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 12:13:56 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 12:13:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 12:13:56 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 12:13:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 12:13:56 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 12:13:56 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 12:13:56 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 12:13:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XJ7sCnsBQNuinfnHn-fvtA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 12:13:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XJ7sCnsBQNuinfnHn-fvtA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:13:56 INFO - Fetch https://queue.taskcluster.net/v1/task/XJ7sCnsBQNuinfnHn-fvtA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 12:13:58 INFO - Expected file size: 20222327 12:13:58 INFO - Obtained file size: 20222327 12:14:02 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 12:14:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XJ7sCnsBQNuinfnHn-fvtA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 12:14:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XJ7sCnsBQNuinfnHn-fvtA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 12:14:02 INFO - Fetch https://queue.taskcluster.net/v1/task/XJ7sCnsBQNuinfnHn-fvtA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 12:14:02 INFO - Expected file size: 20222327 12:14:02 INFO - Obtained file size: 20222327 12:14:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/XJ7sCnsBQNuinfnHn-fvtA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 12:14:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/XJ7sCnsBQNuinfnHn-fvtA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 12:14:07 INFO - Fetch https://queue.taskcluster.net/v1/task/XJ7sCnsBQNuinfnHn-fvtA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 12:14:08 INFO - Expected file size: 14409734 12:14:08 INFO - Obtained file size: 14409734 12:14:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:09 INFO - trying https://queue.taskcluster.net/v1/task/XJ7sCnsBQNuinfnHn-fvtA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 12:14:09 INFO - Downloading https://queue.taskcluster.net/v1/task/XJ7sCnsBQNuinfnHn-fvtA/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 12:14:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/XJ7sCnsBQNuinfnHn-fvtA/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 12:14:11 INFO - Downloaded 55783659 bytes. 12:14:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XJ7sCnsBQNuinfnHn-fvtA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 12:14:11 INFO - mkdir: /builds/slave/test/properties 12:14:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:14:11 INFO - Writing to file /builds/slave/test/properties/build_url 12:14:11 INFO - Contents: 12:14:11 INFO - build_url:https://queue.taskcluster.net/v1/task/XJ7sCnsBQNuinfnHn-fvtA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 12:14:12 INFO - Running post-action listener: _resource_record_post_action 12:14:12 INFO - Running post-action listener: set_extra_try_arguments 12:14:12 INFO - [mozharness: 2016-12-10 20:14:12.165060Z] Finished download-and-extract step (success) 12:14:12 INFO - [mozharness: 2016-12-10 20:14:12.165284Z] Running populate-webroot step. 12:14:12 INFO - Running pre-action listener: _resource_record_pre_action 12:14:12 INFO - Running main action method: populate_webroot 12:14:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:14:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:14:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:14:12 INFO - '--tppagecycles', 12:14:12 INFO - '7'], 12:14:12 INFO - 'tests': ['tsvgm']}}, 12:14:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:14:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:14:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:14:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:14:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:14:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:14:12 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:14:12 INFO - 'talos_options': ['--disable-e10s'], 12:14:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:14:12 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:14:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:14:12 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:14:12 INFO - 'talos_options': ['--disable-e10s'], 12:14:12 INFO - 'tests': ['damp', 'tps']}, 12:14:12 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:14:12 INFO - 'tests': ['damp', 'tps']}, 12:14:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:14:12 INFO - 'tests': ['dromaeo_dom']}, 12:14:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:14:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:14:12 INFO - 'tests': ['basic_compositor_video']}, 12:14:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:14:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:14:12 INFO - 'tests': ['a11yr', 12:14:12 INFO - 'ts_paint', 12:14:12 INFO - 'tpaint', 12:14:12 INFO - 'sessionrestore', 12:14:12 INFO - 'sessionrestore_no_auto_restore', 12:14:12 INFO - 'tabpaint']}, 12:14:12 INFO - 'other-e10s': {'tests': ['a11yr', 12:14:12 INFO - 'ts_paint', 12:14:12 INFO - 'tpaint', 12:14:12 INFO - 'sessionrestore', 12:14:12 INFO - 'sessionrestore_no_auto_restore', 12:14:12 INFO - 'tabpaint']}, 12:14:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:14:12 INFO - 'ts_paint', 12:14:12 INFO - 'tpaint', 12:14:12 INFO - 'sessionrestore', 12:14:12 INFO - 'sessionrestore_no_auto_restore', 12:14:12 INFO - 'tabpaint']}, 12:14:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:14:12 INFO - 'ts_paint', 12:14:12 INFO - 'tpaint', 12:14:12 INFO - 'sessionrestore', 12:14:12 INFO - 'sessionrestore_no_auto_restore', 12:14:12 INFO - 'tabpaint']}, 12:14:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:14:12 INFO - 'tests': ['a11yr', 12:14:12 INFO - 'ts_paint', 12:14:12 INFO - 'tpaint', 12:14:12 INFO - 'sessionrestore', 12:14:12 INFO - 'sessionrestore_no_auto_restore', 12:14:12 INFO - 'tabpaint']}, 12:14:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:14:12 INFO - 'tests': ['a11yr', 12:14:12 INFO - 'ts_paint', 12:14:12 INFO - 'tpaint', 12:14:12 INFO - 'sessionrestore', 12:14:12 INFO - 'sessionrestore_no_auto_restore', 12:14:12 INFO - 'tabpaint']}, 12:14:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:14:12 INFO - 'tests': ['tsvgx', 12:14:12 INFO - 'tsvgr_opacity', 12:14:12 INFO - 'tart', 12:14:12 INFO - 'tscrollx', 12:14:12 INFO - 'cart', 12:14:12 INFO - 'tsvg_static']}, 12:14:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:14:12 INFO - 'tsvgr_opacity', 12:14:12 INFO - 'tart', 12:14:12 INFO - 'tscrollx', 12:14:12 INFO - 'cart', 12:14:12 INFO - 'tsvg_static']}, 12:14:12 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:14:12 INFO - 'talos_options': ['--disable-e10s'], 12:14:12 INFO - 'tests': ['tp5o']}, 12:14:12 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:14:12 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:14:12 INFO - 'talos_options': ['--disable-e10s', 12:14:12 INFO - '--xperf_path', 12:14:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:14:12 INFO - 'tests': ['tp5n']}, 12:14:12 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:14:12 INFO - 'talos_options': ['--xperf_path', 12:14:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:14:12 INFO - 'tests': ['tp5n']}}, 12:14:12 INFO - 'talos.zip': {'path': '', 12:14:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:14:12 INFO - Running post-action listener: _resource_record_post_action 12:14:12 INFO - [mozharness: 2016-12-10 20:14:12.186351Z] Finished populate-webroot step (success) 12:14:12 INFO - [mozharness: 2016-12-10 20:14:12.186438Z] Running create-virtualenv step. 12:14:12 INFO - Running pre-action listener: _resource_record_pre_action 12:14:12 INFO - Running main action method: create_virtualenv 12:14:12 INFO - Creating virtualenv /builds/slave/test/build/venv 12:14:12 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 12:14:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:14:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:14:12 INFO - Using real prefix '/usr' 12:14:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:14:12 INFO - Installing distribute.............................................................................................................................................................................................done. 12:14:14 INFO - Installing pip.................done. 12:14:14 INFO - Return code: 0 12:14:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:14:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:14:14 INFO - Reading from file tmpfile_stdout 12:14:14 INFO - Output received: 12:14:14 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) 12:14:14 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:14:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:14 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f404757be30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1da5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f43120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fd3f20>, '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 0x1f40c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f40f90>, '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': '41a7e32711b8e08598a63237000001d6-1481400544.280785-2108051025', '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 12:14:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:14:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:14:14 INFO - Using env: {'DISPLAY': ':0', 12:14:14 INFO - 'HOME': '/home/cltbld', 12:14:14 INFO - 'LANG': 'en_US.UTF-8', 12:14:14 INFO - 'LANGUAGE': 'en_US:en', 12:14:14 INFO - 'LOGNAME': 'cltbld', 12:14:14 INFO - 'MAIL': '/var/mail/cltbld', 12:14:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:14 INFO - 'MOZ_NO_REMOTE': '1', 12:14:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:14:14 INFO - 'NO_EM_RESTART': '1', 12:14:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:14:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:14 INFO - 'PWD': '/builds/slave/test', 12:14:14 INFO - 'SHELL': '/bin/bash', 12:14:14 INFO - 'SHLVL': '1', 12:14:14 INFO - 'TERM': 'linux', 12:14:14 INFO - 'TMOUT': '86400', 12:14:14 INFO - 'USER': 'cltbld', 12:14:14 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1481400544.280785-2108051025', 12:14:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:14 INFO - '_': '/tools/buildbot/bin/python'} 12:14:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:15 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 12:14:15 INFO - Cleaning up... 12:14:15 INFO - Return code: 0 12:14:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:14: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')]} 12:14:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14: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')]} 12:14:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:15 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 0x7f404757be30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1da5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f43120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fd3f20>, '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 0x1f40c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f40f90>, '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': '41a7e32711b8e08598a63237000001d6-1481400544.280785-2108051025', '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 12:14:15 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 12:14:15 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 12:14:15 INFO - Using env: {'DISPLAY': ':0', 12:14:15 INFO - 'HOME': '/home/cltbld', 12:14:15 INFO - 'LANG': 'en_US.UTF-8', 12:14:15 INFO - 'LANGUAGE': 'en_US:en', 12:14:15 INFO - 'LOGNAME': 'cltbld', 12:14:15 INFO - 'MAIL': '/var/mail/cltbld', 12:14:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:15 INFO - 'MOZ_NO_REMOTE': '1', 12:14:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:14:15 INFO - 'NO_EM_RESTART': '1', 12:14:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:14:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:15 INFO - 'PWD': '/builds/slave/test', 12:14:15 INFO - 'SHELL': '/bin/bash', 12:14:15 INFO - 'SHLVL': '1', 12:14:15 INFO - 'TERM': 'linux', 12:14:15 INFO - 'TMOUT': '86400', 12:14:15 INFO - 'USER': 'cltbld', 12:14:15 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1481400544.280785-2108051025', 12:14:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:15 INFO - '_': '/tools/buildbot/bin/python'} 12:14:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:15 INFO - Downloading/unpacking psutil>=3.1.1 12:14:15 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 12:14:15 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 12:14:15 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 12:14:15 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 12:14:15 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 12:14:15 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 12:14:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:14:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:14:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:14:17 INFO - Installing collected packages: psutil 12:14:17 INFO - Running setup.py install for psutil 12:14:17 INFO - building 'psutil._psutil_linux' extension 12:14:17 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 12:14:17 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 12:14:17 INFO - building 'psutil._psutil_posix' extension 12:14:17 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 12:14:17 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 12:14:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:14:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:14:17 INFO - Successfully installed psutil 12:14:17 INFO - Cleaning up... 12:14:18 INFO - Return code: 0 12:14:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:14:18 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')]} 12:14:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:18 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')]} 12:14:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:18 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 0x7f404757be30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1da5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f43120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fd3f20>, '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 0x1f40c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f40f90>, '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': '41a7e32711b8e08598a63237000001d6-1481400544.280785-2108051025', '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 12:14:18 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 12:14:18 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 12:14:18 INFO - Using env: {'DISPLAY': ':0', 12:14:18 INFO - 'HOME': '/home/cltbld', 12:14:18 INFO - 'LANG': 'en_US.UTF-8', 12:14:18 INFO - 'LANGUAGE': 'en_US:en', 12:14:18 INFO - 'LOGNAME': 'cltbld', 12:14:18 INFO - 'MAIL': '/var/mail/cltbld', 12:14:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:18 INFO - 'MOZ_NO_REMOTE': '1', 12:14:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:14:18 INFO - 'NO_EM_RESTART': '1', 12:14:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:14:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:18 INFO - 'PWD': '/builds/slave/test', 12:14:18 INFO - 'SHELL': '/bin/bash', 12:14:18 INFO - 'SHLVL': '1', 12:14:18 INFO - 'TERM': 'linux', 12:14:18 INFO - 'TMOUT': '86400', 12:14:18 INFO - 'USER': 'cltbld', 12:14:18 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1481400544.280785-2108051025', 12:14:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:18 INFO - '_': '/tools/buildbot/bin/python'} 12:14:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:14:18 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 12:14:18 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 12:14:18 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 12:14:18 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 12:14:18 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 12:14:18 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 12:14:20 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:14:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:14:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:14:20 INFO - Installing collected packages: mozsystemmonitor 12:14:20 INFO - Running setup.py install for mozsystemmonitor 12:14:20 INFO - Successfully installed mozsystemmonitor 12:14:20 INFO - Cleaning up... 12:14:20 INFO - Return code: 0 12:14:20 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:14: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')]} 12:14:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14: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')]} 12:14:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14: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', '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 0x7f404757be30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1da5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f43120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fd3f20>, '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 0x1f40c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f40f90>, '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': '41a7e32711b8e08598a63237000001d6-1481400544.280785-2108051025', '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 12:14: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:14: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 jsonschema==2.5.1 12:14:20 INFO - Using env: {'DISPLAY': ':0', 12:14:20 INFO - 'HOME': '/home/cltbld', 12:14:20 INFO - 'LANG': 'en_US.UTF-8', 12:14:20 INFO - 'LANGUAGE': 'en_US:en', 12:14:20 INFO - 'LOGNAME': 'cltbld', 12:14:20 INFO - 'MAIL': '/var/mail/cltbld', 12:14:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:20 INFO - 'MOZ_NO_REMOTE': '1', 12:14:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:14:20 INFO - 'NO_EM_RESTART': '1', 12:14:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:14:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:20 INFO - 'PWD': '/builds/slave/test', 12:14:20 INFO - 'SHELL': '/bin/bash', 12:14:20 INFO - 'SHLVL': '1', 12:14:20 INFO - 'TERM': 'linux', 12:14:20 INFO - 'TMOUT': '86400', 12:14:20 INFO - 'USER': 'cltbld', 12:14:20 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1481400544.280785-2108051025', 12:14:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:20 INFO - '_': '/tools/buildbot/bin/python'} 12:14:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:20 INFO - Downloading/unpacking jsonschema==2.5.1 12:14: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 12:14: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 12:14: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 12:14: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 12:14: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 12:14: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 12:14:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:14:22 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:14:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:14:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:14:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:14:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:14:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:14:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:14:22 INFO - Downloading functools32-3.2.3-2.tar.gz 12:14:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:14:22 INFO - warning: no files found matching '*.txt' 12:14:22 INFO - no previously-included directories found matching 'build' 12:14:22 INFO - no previously-included directories found matching 'dist' 12:14:22 INFO - no previously-included directories found matching '.git*' 12:14:22 INFO - Installing collected packages: jsonschema, functools32 12:14:22 INFO - Running setup.py install for functools32 12:14:23 INFO - warning: no files found matching '*.txt' 12:14:23 INFO - no previously-included directories found matching 'build' 12:14:23 INFO - no previously-included directories found matching 'dist' 12:14:23 INFO - no previously-included directories found matching '.git*' 12:14:23 INFO - Successfully installed jsonschema functools32 12:14:23 INFO - Cleaning up... 12:14:23 INFO - Return code: 0 12:14:23 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:14: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')]} 12:14:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14: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')]} 12:14:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14: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', '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 0x7f404757be30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1da5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f43120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fd3f20>, '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 0x1f40c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f40f90>, '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': '41a7e32711b8e08598a63237000001d6-1481400544.280785-2108051025', '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 12:14: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:14: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 functools32==3.2.3-2 12:14:23 INFO - Using env: {'DISPLAY': ':0', 12:14:23 INFO - 'HOME': '/home/cltbld', 12:14:23 INFO - 'LANG': 'en_US.UTF-8', 12:14:23 INFO - 'LANGUAGE': 'en_US:en', 12:14:23 INFO - 'LOGNAME': 'cltbld', 12:14:23 INFO - 'MAIL': '/var/mail/cltbld', 12:14:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:23 INFO - 'MOZ_NO_REMOTE': '1', 12:14:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:14:23 INFO - 'NO_EM_RESTART': '1', 12:14:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:14:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:23 INFO - 'PWD': '/builds/slave/test', 12:14:23 INFO - 'SHELL': '/bin/bash', 12:14:23 INFO - 'SHLVL': '1', 12:14:23 INFO - 'TERM': 'linux', 12:14:23 INFO - 'TMOUT': '86400', 12:14:23 INFO - 'USER': 'cltbld', 12:14:23 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1481400544.280785-2108051025', 12:14:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:23 INFO - '_': '/tools/buildbot/bin/python'} 12:14:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:23 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:14:23 INFO - Cleaning up... 12:14:23 INFO - Return code: 0 12:14:23 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:14: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')]} 12:14:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14: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')]} 12:14:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14: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', '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 0x7f404757be30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1da5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f43120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fd3f20>, '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 0x1f40c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f40f90>, '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': '41a7e32711b8e08598a63237000001d6-1481400544.280785-2108051025', '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 12:14: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:14: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 blobuploader==1.2.4 12:14:23 INFO - Using env: {'DISPLAY': ':0', 12:14:23 INFO - 'HOME': '/home/cltbld', 12:14:23 INFO - 'LANG': 'en_US.UTF-8', 12:14:23 INFO - 'LANGUAGE': 'en_US:en', 12:14:23 INFO - 'LOGNAME': 'cltbld', 12:14:23 INFO - 'MAIL': '/var/mail/cltbld', 12:14:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:23 INFO - 'MOZ_NO_REMOTE': '1', 12:14:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:14:23 INFO - 'NO_EM_RESTART': '1', 12:14:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:14:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:23 INFO - 'PWD': '/builds/slave/test', 12:14:23 INFO - 'SHELL': '/bin/bash', 12:14:23 INFO - 'SHLVL': '1', 12:14:23 INFO - 'TERM': 'linux', 12:14:23 INFO - 'TMOUT': '86400', 12:14:23 INFO - 'USER': 'cltbld', 12:14:23 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1481400544.280785-2108051025', 12:14:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:23 INFO - '_': '/tools/buildbot/bin/python'} 12:14:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:23 INFO - Downloading/unpacking blobuploader==1.2.4 12:14: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 12:14: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 12:14: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 12:14: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 12:14: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 12:14: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 12:14:25 INFO - Downloading blobuploader-1.2.4.tar.gz 12:14:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:14:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:14: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 12:14: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 12:14: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 12:14: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 12:14: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 12:14: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 12:14:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:14:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:14:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:14:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:14:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:14:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:14:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:14:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:14:26 INFO - Downloading docopt-0.6.1.tar.gz 12:14:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:14:26 INFO - Installing collected packages: blobuploader, requests, docopt 12:14:26 INFO - Running setup.py install for blobuploader 12:14:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:14:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:14:26 INFO - Running setup.py install for requests 12:14:26 INFO - Running setup.py install for docopt 12:14:26 INFO - Successfully installed blobuploader requests docopt 12:14:26 INFO - Cleaning up... 12:14:26 INFO - Return code: 0 12:14:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:14:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:26 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 0x7f404757be30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1da5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f43120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fd3f20>, '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 0x1f40c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f40f90>, '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': '41a7e32711b8e08598a63237000001d6-1481400544.280785-2108051025', '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 12:14:26 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 12:14:26 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 12:14:26 INFO - Using env: {'DISPLAY': ':0', 12:14:26 INFO - 'HOME': '/home/cltbld', 12:14:26 INFO - 'LANG': 'en_US.UTF-8', 12:14:26 INFO - 'LANGUAGE': 'en_US:en', 12:14:26 INFO - 'LOGNAME': 'cltbld', 12:14:26 INFO - 'MAIL': '/var/mail/cltbld', 12:14:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:26 INFO - 'MOZ_NO_REMOTE': '1', 12:14:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:14:26 INFO - 'NO_EM_RESTART': '1', 12:14:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:14:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:26 INFO - 'PWD': '/builds/slave/test', 12:14:26 INFO - 'SHELL': '/bin/bash', 12:14:26 INFO - 'SHLVL': '1', 12:14:26 INFO - 'TERM': 'linux', 12:14:26 INFO - 'TMOUT': '86400', 12:14:26 INFO - 'USER': 'cltbld', 12:14:26 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1481400544.280785-2108051025', 12:14:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:26 INFO - '_': '/tools/buildbot/bin/python'} 12:14:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:14:27 INFO - Running setup.py (path:/tmp/pip-PM07za-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:14:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:14:27 INFO - Running setup.py (path:/tmp/pip-m7mVaE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:14:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:14:27 INFO - Running setup.py (path:/tmp/pip-4FtBRs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:14:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:14:27 INFO - Running setup.py (path:/tmp/pip-WndpeF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:14:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:14:27 INFO - Running setup.py (path:/tmp/pip-ApxAGM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:14:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:14:27 INFO - Running setup.py (path:/tmp/pip-UKP7vf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:14:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:14:27 INFO - Running setup.py (path:/tmp/pip-seb1JV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:14:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:14:27 INFO - Running setup.py (path:/tmp/pip-PozYFs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:14:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:14:27 INFO - Running setup.py (path:/tmp/pip-2n4HtH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:14:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:14:27 INFO - Running setup.py (path:/tmp/pip-Thxw0t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:14:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:14:27 INFO - Running setup.py (path:/tmp/pip-TfxLUB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:14:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:14:27 INFO - Running setup.py (path:/tmp/pip-hchXJC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:14:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:14:28 INFO - Running setup.py (path:/tmp/pip-QIdTy_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:14:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:14:28 INFO - Running setup.py (path:/tmp/pip-rFUfkz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:14:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:14:28 INFO - Running setup.py (path:/tmp/pip-66u6op-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:14:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:14:28 INFO - Running setup.py (path:/tmp/pip-P9Xkgt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:14:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:14:28 INFO - Running setup.py (path:/tmp/pip-m_Adsu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:14:28 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:14:28 INFO - Running setup.py install for manifestparser 12:14:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:14:28 INFO - Running setup.py install for mozcrash 12:14:28 INFO - Running setup.py install for mozdebug 12:14:28 INFO - Running setup.py install for mozdevice 12:14:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:14:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:14:28 INFO - Running setup.py install for mozfile 12:14:28 INFO - Running setup.py install for mozhttpd 12:14:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:14:28 INFO - Running setup.py install for mozinfo 12:14:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:14:29 INFO - Running setup.py install for mozInstall 12:14:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:14:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:14:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:14:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:14:29 INFO - Running setup.py install for mozleak 12:14:29 INFO - Running setup.py install for mozlog 12:14:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:14:29 INFO - Running setup.py install for moznetwork 12:14:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:14:29 INFO - Running setup.py install for mozprocess 12:14:29 INFO - Running setup.py install for mozprofile 12:14:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:14:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:14:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:14:29 INFO - Running setup.py install for mozrunner 12:14:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:14:29 INFO - Running setup.py install for mozscreenshot 12:14:29 INFO - Running setup.py install for moztest 12:14:30 INFO - Running setup.py install for mozversion 12:14:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:14:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:14:30 INFO - Cleaning up... 12:14:30 INFO - Return code: 0 12:14:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:14:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:30 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 0x7f404757be30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1da5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f43120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fd3f20>, '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 0x1f40c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f40f90>, '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': '41a7e32711b8e08598a63237000001d6-1481400544.280785-2108051025', '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 12:14:30 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 12:14:30 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 12:14:30 INFO - Using env: {'DISPLAY': ':0', 12:14:30 INFO - 'HOME': '/home/cltbld', 12:14:30 INFO - 'LANG': 'en_US.UTF-8', 12:14:30 INFO - 'LANGUAGE': 'en_US:en', 12:14:30 INFO - 'LOGNAME': 'cltbld', 12:14:30 INFO - 'MAIL': '/var/mail/cltbld', 12:14:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:30 INFO - 'MOZ_NO_REMOTE': '1', 12:14:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:14:30 INFO - 'NO_EM_RESTART': '1', 12:14:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:14:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:30 INFO - 'PWD': '/builds/slave/test', 12:14:30 INFO - 'SHELL': '/bin/bash', 12:14:30 INFO - 'SHLVL': '1', 12:14:30 INFO - 'TERM': 'linux', 12:14:30 INFO - 'TMOUT': '86400', 12:14:30 INFO - 'USER': 'cltbld', 12:14:30 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1481400544.280785-2108051025', 12:14:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:30 INFO - '_': '/tools/buildbot/bin/python'} 12:14:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:14:30 INFO - Running setup.py (path:/tmp/pip-teEmDm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:14:30 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)) 12:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:14:30 INFO - Running setup.py (path:/tmp/pip-Cj8aeg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:14:30 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)) 12:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:14:30 INFO - Running setup.py (path:/tmp/pip-utJI6L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:14:30 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)) 12:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:14:30 INFO - Running setup.py (path:/tmp/pip-OSz7w0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:14:30 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)) 12:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:14:30 INFO - Running setup.py (path:/tmp/pip-EMh8uP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:14:30 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)) 12:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:14:30 INFO - Running setup.py (path:/tmp/pip-H97wSq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:14:30 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)) 12:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:14:30 INFO - Running setup.py (path:/tmp/pip-l5OHRR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:14:30 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)) 12:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:14:30 INFO - Running setup.py (path:/tmp/pip-V3XMPr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:14:30 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)) 12:14:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:14:30 INFO - Running setup.py (path:/tmp/pip-zRa_6s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:14:31 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)) 12:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:14:31 INFO - Running setup.py (path:/tmp/pip-ARgpCy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:14:31 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)) 12:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:14:31 INFO - Running setup.py (path:/tmp/pip-8ZKxiy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:14:31 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)) 12:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:14:31 INFO - Running setup.py (path:/tmp/pip-nQbRHG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:14:31 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)) 12:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:14:31 INFO - Running setup.py (path:/tmp/pip-IxVxJQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:14:31 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)) 12:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:14:31 INFO - Running setup.py (path:/tmp/pip-EFHz_2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:14:31 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)) 12:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:14:31 INFO - Running setup.py (path:/tmp/pip-CK8s8O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:14:31 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)) 12:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:14:31 INFO - Running setup.py (path:/tmp/pip-r8E0B6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:14:31 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)) 12:14:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:14:31 INFO - Running setup.py (path:/tmp/pip-lLVCvR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:14:31 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)) 12:14:31 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)) 12:14:31 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)) 12:14:31 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)) 12:14:31 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)) 12:14:31 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)) 12:14:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:14: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 12:14: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 12:14: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 12:14: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 12:14: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 12:14: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 12:14:33 INFO - Downloading blessings-1.6.tar.gz 12:14:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:14:33 INFO - Installing collected packages: blessings 12:14:33 INFO - Running setup.py install for blessings 12:14:33 INFO - Successfully installed blessings 12:14:33 INFO - Cleaning up... 12:14:33 INFO - Return code: 0 12:14:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:14:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:14:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:14:34 INFO - Reading from file tmpfile_stdout 12:14:34 INFO - Current package versions: 12:14:34 INFO - argparse == 1.2.1 12:14:34 INFO - blessings == 1.6 12:14:34 INFO - blobuploader == 1.2.4 12:14:34 INFO - docopt == 0.6.1 12:14:34 INFO - functools32 == 3.2.3-2 12:14:34 INFO - jsonschema == 2.5.1 12:14:34 INFO - manifestparser == 1.1 12:14:34 INFO - mozInstall == 1.12 12:14:34 INFO - mozcrash == 1.0 12:14:34 INFO - mozdebug == 0.1 12:14:34 INFO - mozdevice == 0.49 12:14:34 INFO - mozfile == 1.2 12:14:34 INFO - mozhttpd == 0.7 12:14:34 INFO - mozinfo == 0.9 12:14:34 INFO - mozleak == 0.1 12:14:34 INFO - mozlog == 3.4 12:14:34 INFO - moznetwork == 0.27 12:14:34 INFO - mozprocess == 0.23 12:14:34 INFO - mozprofile == 0.28 12:14:34 INFO - mozrunner == 6.13 12:14:34 INFO - mozscreenshot == 0.1 12:14:34 INFO - mozsystemmonitor == 0.3 12:14:34 INFO - moztest == 0.7 12:14:34 INFO - mozversion == 1.4 12:14:34 INFO - psutil == 3.1.1 12:14:34 INFO - requests == 1.2.3 12:14:34 INFO - wsgiref == 0.1.2 12:14:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:14:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:34 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 0x7f404757be30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1da5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f43120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fd3f20>, '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 0x1f40c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f40f90>, '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': '41a7e32711b8e08598a63237000001d6-1481400544.280785-2108051025', '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 12:14:34 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 12:14:34 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 12:14:34 INFO - Using env: {'DISPLAY': ':0', 12:14:34 INFO - 'HOME': '/home/cltbld', 12:14:34 INFO - 'LANG': 'en_US.UTF-8', 12:14:34 INFO - 'LANGUAGE': 'en_US:en', 12:14:34 INFO - 'LOGNAME': 'cltbld', 12:14:34 INFO - 'MAIL': '/var/mail/cltbld', 12:14:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:34 INFO - 'MOZ_NO_REMOTE': '1', 12:14:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:14:34 INFO - 'NO_EM_RESTART': '1', 12:14:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:14:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:34 INFO - 'PWD': '/builds/slave/test', 12:14:34 INFO - 'SHELL': '/bin/bash', 12:14:34 INFO - 'SHLVL': '1', 12:14:34 INFO - 'TERM': 'linux', 12:14:34 INFO - 'TMOUT': '86400', 12:14:34 INFO - 'USER': 'cltbld', 12:14:34 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1481400544.280785-2108051025', 12:14:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:34 INFO - '_': '/tools/buildbot/bin/python'} 12:14:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:34 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)) 12:14:34 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)) 12:14:34 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)) 12:14:34 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)) 12:14:34 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)) 12:14:34 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)) 12:14:34 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)) 12:14:34 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)) 12:14:34 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)) 12:14:34 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)) 12:14:34 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)) 12:14:34 INFO - Cleaning up... 12:14:34 INFO - Return code: 0 12:14:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:14:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:14:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:14:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:14:34 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 0x7f404757be30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1da5a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1f43120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fd3f20>, '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 0x1f40c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f40f90>, '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': '41a7e32711b8e08598a63237000001d6-1481400544.280785-2108051025', '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 12:14:34 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 12:14:34 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 12:14:34 INFO - Using env: {'DISPLAY': ':0', 12:14:34 INFO - 'HOME': '/home/cltbld', 12:14:34 INFO - 'LANG': 'en_US.UTF-8', 12:14:34 INFO - 'LANGUAGE': 'en_US:en', 12:14:34 INFO - 'LOGNAME': 'cltbld', 12:14:34 INFO - 'MAIL': '/var/mail/cltbld', 12:14:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:34 INFO - 'MOZ_NO_REMOTE': '1', 12:14:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:14:34 INFO - 'NO_EM_RESTART': '1', 12:14:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:14:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:34 INFO - 'PWD': '/builds/slave/test', 12:14:34 INFO - 'SHELL': '/bin/bash', 12:14:34 INFO - 'SHLVL': '1', 12:14:34 INFO - 'TERM': 'linux', 12:14:34 INFO - 'TMOUT': '86400', 12:14:34 INFO - 'USER': 'cltbld', 12:14:34 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1481400544.280785-2108051025', 12:14:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:34 INFO - '_': '/tools/buildbot/bin/python'} 12:14:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:14:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:14:34 INFO - Cleaning up... 12:14:34 INFO - Return code: 0 12:14:34 INFO - Running post-action listener: _resource_record_post_action 12:14:34 INFO - Running post-action listener: _start_resource_monitoring 12:14:34 INFO - Starting resource monitoring. 12:14:34 INFO - [mozharness: 2016-12-10 20:14:34.585436Z] Finished create-virtualenv step (success) 12:14:34 INFO - [mozharness: 2016-12-10 20:14:34.585953Z] Running install step. 12:14:34 INFO - Running pre-action listener: _resource_record_pre_action 12:14:34 INFO - Running main action method: install 12:14:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:14:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:14:34 INFO - Reading from file tmpfile_stdout 12:14:34 INFO - Detecting whether we're running mozinstall >=1.0... 12:14:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:14:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:14:34 INFO - Reading from file tmpfile_stdout 12:14:34 INFO - Output received: 12:14:34 INFO - Usage: mozinstall [options] installer 12:14:34 INFO - Options: 12:14:34 INFO - -h, --help show this help message and exit 12:14:34 INFO - -d DEST, --destination=DEST 12:14:34 INFO - Directory to install application into. [default: 12:14:34 INFO - "/builds/slave/test"] 12:14:34 INFO - --app=APP Application being installed. [default: firefox] 12:14:34 INFO - mkdir: /builds/slave/test/build/application 12:14:34 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'] 12:14:34 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 12:14:50 INFO - Reading from file tmpfile_stdout 12:14:50 INFO - Output received: 12:14:50 INFO - /builds/slave/test/build/application/firefox/firefox 12:14:50 INFO - Running post-action listener: _resource_record_post_action 12:14:50 INFO - [mozharness: 2016-12-10 20:14:50.931618Z] Finished install step (success) 12:14:50 INFO - [mozharness: 2016-12-10 20:14:50.931858Z] Running run-tests step. 12:14:50 INFO - Running pre-action listener: _resource_record_pre_action 12:14:50 INFO - Running main action method: run_tests 12:14:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:14:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:14:50 INFO - Python 2.7.3 12:14:50 INFO - Return code: 0 12:14:50 INFO - grabbing minidump binary from tooltool 12:14:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:14:50 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 0x1fd3f20>, '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 0x1f40c20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1f40f90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:14:50 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 12:14:50 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 12:14:50 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 12:14:50 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:14:51 INFO - Return code: 0 12:14:51 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 12:14:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:14:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:14:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 12:14:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:14:51 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/XJ7sCnsBQNuinfnHn-fvtA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-024', '--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 12:14:51 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/XJ7sCnsBQNuinfnHn-fvtA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-024 --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 12:14:51 INFO - Using env: {'DISPLAY': ':0', 12:14:51 INFO - 'HOME': '/home/cltbld', 12:14:51 INFO - 'LANG': 'en_US.UTF-8', 12:14:51 INFO - 'LANGUAGE': 'en_US:en', 12:14:51 INFO - 'LOGNAME': 'cltbld', 12:14:51 INFO - 'MAIL': '/var/mail/cltbld', 12:14:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:14:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 12:14:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:14:51 INFO - 'MOZ_NO_REMOTE': '1', 12:14:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:14:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 12:14:51 INFO - 'NO_EM_RESTART': '1', 12:14:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 12:14:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:14:51 INFO - 'PWD': '/builds/slave/test', 12:14:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:14:51 INFO - 'SHELL': '/bin/bash', 12:14:51 INFO - 'SHLVL': '1', 12:14:51 INFO - 'TERM': 'linux', 12:14:51 INFO - 'TMOUT': '86400', 12:14:51 INFO - 'USER': 'cltbld', 12:14:51 INFO - 'XDG_SESSION_COOKIE': '41a7e32711b8e08598a63237000001d6-1481400544.280785-2108051025', 12:14:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:14:51 INFO - '_': '/tools/buildbot/bin/python'} 12:14:51 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/XJ7sCnsBQNuinfnHn-fvtA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-024', '--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 12:14:51 INFO - mozversion application_buildid: 20161210112351 12:14:51 INFO - mozversion application_changeset: 85efd4833716400d0ce85c2a30e408050afb1406 12:14:51 INFO - mozversion application_display_name: Nightly 12:14:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:14:51 INFO - mozversion application_name: Firefox 12:14:51 INFO - mozversion application_remotingname: firefox 12:14:51 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 12:14:51 INFO - mozversion application_vendor: Mozilla 12:14:51 INFO - mozversion application_version: 53.0a1 12:14:51 INFO - mozversion platform_buildid: 20161210112351 12:14:51 INFO - mozversion platform_changeset: 85efd4833716400d0ce85c2a30e408050afb1406 12:14:51 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 12:14:51 INFO - mozversion platform_version: 53.0a1 12:14:51 INFO - using testdate: 1481400891 12:14:51 INFO - actual date: 1481400891 12:14:51 INFO - starting webserver on 'localhost:37179' 12:14:51 INFO - SUITE-START | Running 2 tests 12:14:51 INFO - TEST-START | dromaeo_css 12:14:51 INFO - operating with platform_type : linux_ 12:14:51 INFO - Initialising browser for dromaeo_css test... 12:14:51 INFO - TEST-INFO | started process 9778 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpky8pRu/profile http://localhost:37179/getInfo.html) 12:14:55 INFO - PROCESS | 9778 | __metrics Screen width/height:1600/1200 12:14:55 INFO - PROCESS | 9778 | colorDepth:24 12:14:55 INFO - PROCESS | 9778 | Browser inner width/height: 1024/697 12:14:55 INFO - PROCESS | 9778 | __metrics 12:14:56 INFO - PROCESS | 9778 | 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" 12:14:56 INFO - PROCESS | 9778 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:14:57 INFO - PROCESS | 9778 | console.error: PushService: 12:14:57 INFO - PROCESS | 9778 | stateChangeProcessEnqueue: Error transitioning state 12:14:57 INFO - PROCESS | 9778 | UnknownError 12:14:57 INFO - TEST-INFO | 9778: exit 0 12:14:57 INFO - Browser initialized. 12:14:57 INFO - Running cycle 1/1 for dromaeo_css test... 12:14:57 INFO - TEST-INFO | started process 9861 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpky8pRu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:15:10 INFO - PROCESS | 9861 | RSS: Main: 173510656 12:15:10 INFO - PROCESS | 9861 | 12:16:01 INFO - PROCESS | 9861 | 1481400961115 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] 12:18:15 INFO - PROCESS | 9861 | Cycle 1(1): loaded http://localhost:37179/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37179/tests/dromaeo/cssquery-ext.html) 12:18:16 INFO - PROCESS | 9861 | RSS: Main: 300154880 12:18:16 INFO - PROCESS | 9861 | 12:21:19 INFO - PROCESS | 9861 | Cycle 1(1): loaded http://localhost:37179/tests/dromaeo/cssquery-ext.html (next: http://localhost:37179/tests/dromaeo/cssquery-jquery.html) 12:21:20 INFO - PROCESS | 9861 | RSS: Main: 279076864 12:21:20 INFO - PROCESS | 9861 | 12:24:22 INFO - PROCESS | 9861 | Cycle 1(1): loaded http://localhost:37179/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37179/tests/dromaeo/cssquery-mootools.html) 12:24:23 INFO - PROCESS | 9861 | RSS: Main: 219193344 12:24:23 INFO - PROCESS | 9861 | 12:27:25 INFO - PROCESS | 9861 | Cycle 1(1): loaded http://localhost:37179/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37179/tests/dromaeo/cssquery-prototype.html) 12:27:26 INFO - PROCESS | 9861 | RSS: Main: 217870336 12:27:26 INFO - PROCESS | 9861 | 12:30:29 INFO - PROCESS | 9861 | Cycle 1(1): loaded http://localhost:37179/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37179/tests/dromaeo/cssquery-yui.html) 12:30:30 INFO - PROCESS | 9861 | RSS: Main: 217485312 12:30:30 INFO - PROCESS | 9861 | 12:33:33 INFO - PROCESS | 9861 | Cycle 1(1): loaded http://localhost:37179/tests/dromaeo/cssquery-yui.html (next: http://localhost:37179/tests/dromaeo/cssquery-dojo.html) 12:33:33 INFO - PROCESS | 9861 | RSS: Main: 303042560 12:33:33 INFO - PROCESS | 9861 | 12:33:33 INFO - PROCESS | 9861 | __start_tp_report 12:33:33 INFO - PROCESS | 9861 | _x_x_mozilla_page_load 12:33:33 INFO - PROCESS | 9861 | _x_x_mozilla_page_load_details 12:33:33 INFO - PROCESS | 9861 | |i|pagename|runs| 12:33:33 INFO - PROCESS | 9861 | |0;dojo.html;2550.311415917702;2580.0324878170686;2582.7546383093613;2591.170825335892;2595.05280572591;4500.324951257313;4568.291914753218;4609.976950115249;4645.581897629213;4658.97670511647;4560.6123479504195;4570.268756998881;4579.656525760573;4588;4591.7244965302025;4197;4210.7473551586845;4225.302825033866;4316.417283666712;4328;4277.593628605293;4310.849120280791;4357.542458041908;4387.846425375113;4413.558644135577;4162.83348666053;4179.895502612429;4189.434426352434;4247.851325203634;4314.374415709704;4193.056562273495;4203.138356636882;4218.87343379696;4221.134667393177;4259.74441533509;4197.643200327976;4202.768847713374;4217.578242175789;4248.044190057243;4258.190943720683;4559.954400456019;4567.109413664337;4579.519150489217;4598.057398233387;4625.4911959684405;4776.044791041805;4802.375691160162;4806.759662016885;4912.115819152551;4953.752312384367;4963.627727920384;4987.675801072919;5033.723145227011;5068.113080210948;5120.3855537335385;4732.124556956939;4739.857804265877;4739.928901066487;4740.691855029412;4781.139394908881;4904.901901961941;5156.871078223075;5168.767405466763;5169.638125331191;5223.425423203445;4742.66801323904;4822.493638168012;4823.2282834746275;4849.75751212438;4862.78117484714;4950.43070046942;4954.405471343462;4955.083309587738;4955.231939049453;4997.600191984632;4889.731064791399;4892.510748925079;4932.802687892516;4939.357883475125;4979.377577802775;4760.166970780099;4804.8318308859025;4809.711417314972;4867.464578896319;4984.551390374883;4305.31115021595;4317.956820431818;4376.015396535818;4376.693631445832;4379.080393117417;3744.4383342498845;3756.887293381203;3761.1725420407465;3783.0164157318745;3796.5823759386444;4169.645580125723;4311.374850646639;4318.935215971763;4322.891927701769;4364.869053928387;4841.540053694894;4918.852434426973;4920.384951881015;4923.729194894244;4924.704517728947;4199.286121359376;4225.112726327444;4240.745555266632;4242.087951090469;4248.086661367759;4319.719218250866;4470.575295346936;4472.262076757299;4501.099780044005;4505.81976720934;4624.237000894815;4639.062437820829;4643.837465688753;4644.814207431665;4685.2035154023215;4963.975180124076;4971.900561988812;4973.875653108701;4977.178765503651;5019.949800501949;4491.326301054868;4532.818687252473;4536.6143877770755;4600.907981840412;4639.280911458729;4932.802687892444;4962.925556116734;4976.8755781105765;4983.601311894967;4984.825531106394;5008.9248661269385;5015.072211640858;5015.322931404214;5017.924731129109;5063.746812659426;4668.136394766979;4806.519348065165;4869.391326084239;4870.464248932685;4915.0415668944215;4693.413323334583;4763.7856296467435;4794.688345257547;4815.036992601424;4827.324174615486;4773.284007398918;4786.1624215762795;4787.186178349759;4829.179039563212;4836.782344794563;4880.975595122001;4882.609391248762;4886.65793394459;4889.486603906538;4935.901281974412;4632.907341853212;4635;4639.327297541905;4701.059788042337;4760.6191504678845 12:33:33 INFO - PROCESS | 9861 | |1;ext.html;526.3157894736842;530.2338121414557;546.5354448718588;591.4233622218337;595.0419824083225;27382.178534643943;28015.299617509572;38612.99999999996;39613.405798912965;39892.40161397582;39484.1031793641;39870.8006459968;39873.800630996804;39889;40085.19829603406;7364.926350736478;7584.165741768401;7584.507007044552;7586.3172314491685;7592.240775922392;7420.591867447289;7634.656440460192;9520.8571871422;9818.410895346266;9821.066998635153;9786.412815231073;9853;9856.112949834513;9865.358751681153;9871.259655525828;9681.225501959862;9718.076782705632;9737.07497787709;9746.46394448305;9748.658796942109;9688.273379496495;9723.270754693356;9748.853767193497;9759.219262459057;9761.951190244003;37399.62600373989;37546.06134846623;37740.81129594362;37912.620873791464;37948.05129871747;3101.596792416994;3106.4718997770433;3106.7980581262373;3190.9361812763846;3350.547676063749;27896.30259243515;27911.302217444525;28169;28940.131796045942;29771.40457190844;3462.290230502741;3500.7549471537004;3502.330741409595;3529.982350088233;3622.0582648511577;226752;227336;227508.86245568833;227675;228123;51759.48240517547;52122.73938630358;52283;52303.47696523062;52397;54764.45235547673;54863.72568137134;54879.725601371734;54906.72546637321;55006.44993550013;14655.926720366544;14886;14952.551423457096;14952.775708364383;15046;10268.691939241835;10587.15302775776;11042.116630669527;11077.058450031676;11087.72280692989;16130.919345403197;16502.75245871313;16568.917155414387;16669;16910.32358705638;5693.629914055573;5847.883042339129;5852.385499522489;5888.499477544457;6022.608530445507;740.9629518524052;812.9837403251902;882.0297672560132;975.9560819763128;993.9105480506789;26385.604215936775;27481.17556473309;27819;27838.16485505438;27886.3028424291;920.3189639666598;925.8657494663371;959.1271942532262;978.114806100466;1021.5300961557618;13605.31973401326;14524.636884077983;14577.562673119823;14720.116792992454;14790.482333118505;340062;576901;583686;587583;587635;42679.57320426756;43251.35122973095;43263.13473730571;43288.567114328456;43307;1042.3537406807827;1061.9256652034283;1139.67519257013;1182.6570294614637;1192.5408717643595;23580.88209558941;25712.228633141036;26105.477890441703;27887.44225115527;28406.147815565568;39546.406803897386;39557;41128.58871411367;41159.7942010288;44179.558204417546;318474;320921;321682;321693;322279;20095.397138085882;20418.693719594514;22865;23368.298951031495;23371.88314058419;36426.635733642324;36555;36692.44961325529;39916.201675965734;40139.380486732465;30591.847040765544;30676.539851902686;30777.230569235053;31030.844845775628;31075;31172;31200.53199202059;31266.37467250597;31799.364012720078;32011.679883200868;30383.240418989702;31289.530657040617;31472.842635786674;31573.8421307892;31760.68239317577;27792.722072779823;28269.858650706614;28532.85733571319;28740.712592873802;28837;21870.781292186875;22702.318930432113;22703.886480567493;22715.432114197276;22738.886305568365 12:33:33 INFO - PROCESS | 9861 | |2;jquery.html;333.6496678487587;343.7250199840129;356.6059504247806;361.2431955054162;377.12883239716257;12275.202111862724;15314.38742450302;15595.688086238268;16262.7560586591;17196.312147514116;16574;16612.086335251555;16914.492565223063;17012.574685632866;17041.233144508496;3858.5369755629363;4815.446223684277;4985.326980857592;5021.648484606079;5035.370578677665;4910.091633047879;4913.852584422473;4946.109700253953;4952.133376659088;4952.925706114411;4424.40270563473;4562.110388474249;4915.5084491550915;4918;4920.532549407801;4584.931226031612;4801.591864691488;4856.077345304416;4866.148424025781;4886.951130488721;4242.957570424318;4346.978265108654;4362.520122786491;4368.453943257093;4371.672124590668;19280.132394042303;19819.405417837486;19879.20483180671;19929.601407971906;19956.002199889946;1957.8825270483674;2055.588882223561;2174.5324755177553;2486.0428734937;2501.9874900625564;12646.810297845539;12781.936090319488;13230.66923326915;13349.13230640015;13577;1711.6234428425726;1720.4322573550699;1771.5216891439368;1779.8487128593956;1800.7568978187908;63223.68388158076;63466.365336346506;63755.68122159406;65227.673861630385;65907.3409265906;8965.327600429993;9005.909940900638;9331.393459425251;9401.811963760687;9485.620575177054;12777.680557986125;12780.93609531965;12872.742545149233;12880.935595322151;12896.613101606967;6862.245153033163;7010.018597396369;7063.964680176669;7094.68073936674;7163.10461192351;6552.180977377828;6721.126253587003;6762.323767623297;6770.864582708318;6786.4570834333135;5828.825135245949;5957.582969192202;6022.3676513966275;6024.759009639653;6253.624782513064;1698.388580111159;1720.7676963609752;1746.1094841630859;1763.3651885321274;1773.3527262549335;708.5040471669852;776.378896882503;825.405707890318;858.3862538285156;887.2192470625808;15066.322015509328;15294.311755971008;15773.842261577467;15833.52499425019;16242.837571624368;808.5714571277232;819.979500512492;825.4675734151439;849.8739170598858;868.9254752246783;8306.916930830614;9066.592003359865;9129.726108216764;9191.21874640663;9203.447793132433;226299;227493;229317;230605;333672;74055.25944740592;75073.24926750663;77402.22597773951;78067.60966195133;78159.60920195477;906.6056265524386;923.612082925173;958.9808203835884;962.153305091515;964.0263334032677;15423.228838558252;15425.228738563252;16024.679506410039;16326.918365408097;16349.264283107052;34845.651543484244;35475;35541.82229088838;36124;36439.27121457503;46586.76706616445;48781.268280975106;49068.75465622649;50688;51184.74407627938;7134.785956421316;7138.143422789299;7275.090613673291;7465.813354666177;7528.322450979438;9848.310618256653;11150.832737509107;11189.496472658588;11623.88376116228;11737.35444550558;9410.153086222273;9877.50612469388;9936.254780891317;10297.433641149808;10356.792864142826;9897.307188496736;10066.647667331608;10076.445795481026;10126.898731012596;10165.949170254102;9867.753306167404;9928.801423971625;10219.846702299323;10314.226433017404;10380.948095259475;6267.749290028531;6655.401013908771;6675.132232809704;6854.965725171542;7008.9649551753955;8277.296429803293;9431.104045115702;9623.759406014906;9895.802083958424;10225.642102526674 12:33:33 INFO - PROCESS | 9861 | |3;mootools.html;1211.5941159711497;1299.6945717756328;1312.9343532823357;1377.449020391843;1618.2232528386367;6102;6907.37833594977;7186.640667966607;7186.820329491766;7195.640217989093;6687.230968438631;6795.626240556768;6799.592024478535;6994.3705066544;7000.404965577919;1761.656476986988;1817.7273408988692;2037.1443993522719;2084.1871670048695;2086.4679506725765;2074;2135.7864213578596;2136.1562182937732;2153.8384621153477;2158.1583182558816;2061.4124974382303;2081.0635214153617;2087.926922557711;2088.457001179697;2104.631689454347;2078.314156328408;2102.127617038927;2109.367189843041;2112.926047588327;2114.95770084599;2058.711780350752;2082.3961051295105;2083.3020937985793;2086.2176683743596;2087.759907610614;7109.857802843914;7234.1319041714805;7249.275072492761;7249.492535522516;7259.745908893216;4208.116295577933;4341.934870976938;4346.15250026245;4352.825886964518;4359.346098085312;2646.1937285009108;2723.4553089382202;2727.318170457386;2766.3222510484857;2776.7917406194415;834.7662654456761;847.966081356745;904.263025634106;905.4522014181391;907.1155623267261;9346.766330841716;9690.806183876353;9694.079062489052;9697.127258546694;9708.75728106796;3762.9623703762613;3845.5962123977138;3916.6279203475624;3949.755826914513;4030.435738996543;8391.202835730595;8395.202455766692;8401.873971890427;8408.159184081544;8411.663533458715;4304;4396.098799746044;4486.102779444124;4488.102379524108;4490.910181796411;3472.305538892181;3626.7098632109896;3941.192055628589;3965.6827453803626;3991.980040099781;3420.555327807369;3430.4168291390524;3529.8411571479346;3548.6628770266784;3553.9644603553634;1104.216006635282;1146.4095990564865;1146.7190538318166;1147.1167201254987;1153.2388623508443;803.0965164190286;810.4610434061401;820.2289847543291;825.1212458731432;828.7265202483167;4696.966667333181;4824.95175048245;4827.493113223061;5030.37120359955;5033.672811267256;799.6121880887765;826.4008593769469;827.557256867573;874.3398733955822;875.2429148786381;3386.1873150444208;3531.29374125176;3673.3571624965525;3689.6679298863232;3841.2893614681375;8574.742757717278;8722.476651400937;8902.688405905763;8911;8923.241524470362;8648.913510864937;8660.69687560945;8794.252488538417;8804.647814087493;8831.823363532694;6563.343665633399;6646.53474256807;6744.494162937701;6754.493412994045;6754.797356079325;4831.154547954165;5032.169692000779;5058.873528161678;5118.948810511996;5302.80774956807;7638.847223055619;7783.494072885244;7841.607919604111;8030.397720171128;8049.476784009021;7664.770056898302;7848.960755196187;7898.447108702336;8085.676572937016;8092.71675491355;6305.96847015762;6384.968075159594;6410;6457.257415397289;6467.482601391972;6888.793336199922;6907.6891539879825;6919.411849992807;6936.653167341714;6985.895211571932;6044.637321760709;6127.050307202391;6154.969225153845;6184.81445556634;6343.397377249154;6192.473639740492;6241.063840423973;6251.937480625135;6281.089242059785;6323.747050118128;5909.320428150818;6000;6001.699915004319;6008.54935879802;6008.819735407945;4175.331946888483;4349.586789255016;4387.802548885244;4419.580139886705;4453.064856380196;4172.833086676499;4179.519355274182;4255.87232383029;4386.605205531518;4408.779561022 12:33:33 INFO - PROCESS | 9861 | |4;prototype.html;323.42430473756724;327.59705562158547;328.8963976347452;332.6374252065248;337.3522836154583;4700;4760.857174284768;4767.49823805501;4777.689450185735;4781.665283430161;4634.933965188;4662.274958265438;4679.391679081715;4700.247960326348;4742.075295317414;3601.6398360163903;3702.426123950792;3719.3863012602887;3737.1965027519077;3766.736328457009;3772.320982223199;3772.6604605585494;3781.3193625147464;3781.773093614378;3794.829232684536;3745.62543745626;3759.2105657811894;3766.472693822867;3776.1126135358168;3782.470454136423;3583.4624806279007;3689.335919534483;3706.277275931181;3746.265226384727;3770.2271034437877;3367.410703126943;3368.730501559894;3373.9662603374145;3374.240795820945;3376.6454522275053;4585.816567337302;4602.102589994953;4603.539646035403;4604.6776725629215;4636.86089417318;3944.6449819516383;4135.855245066408;4281.464816897887;4283.357496375537;4294.935575966363;4903.730294833783;4920.6309526785635;4929.531694489017;4959.057779021974;4960.553550180501;3522.9295414091575;3527.7177825773874;3532.2935412917263;3534.964650353489;3539.610642829287;5017.322661440732;5191.195364718475;5228.516454684032;5230.241614965808;5231.424543300234;3545.6631619996574;3558.288342331544;3564.6791788739138;3569.910752231215;3572.5534308211472;3613.9277214455565;3615.1865830188;3625.184333524936;3635.8727444539313;3636.8727094551564;4114.177164567099;4158.14962341761;4172.645325147336;4179.519355274182;4193.97903010483;4638.698484598491;4919.580891280597;4934.67924584908;4964.1809101498575;4974.527419895104;1216.5073145376136;1216.6228469174584;1216.9817452738216;1222.6148763139583;1228.6129869091208;779.5907148746953;807.8505476486869;836.7155167243167;844.8183640517318;848.1476116502876;4150.91698166041;4278.358246263023;4392.516823149451;4415.161119387306;4430.62339701129;5030.032280954255;5035.521625445577;5054.519820617035;5082.898342033212;5139.946311006235;4324.567543245713;4335.523092459827;4365.890852728708;4381.605655491021;4386.912261754747;2500.2624225853415;2600.5839065749387;2610.6163733221424;2625.671791026122;2645.933851653724;4496.6177874881005;4615.177005082974;4693.47462074827;4699.271612900006;4725.046845721424;5137.9743101285;5151.716655583904;5188.688678679216;5190.325257716473;5200.7919683213;4523;4785.59322457595;4792.065547218329;4805.231163013901;4849.951500485021;5120.564751996122;5144.459831717765;5145.768440420265;5153.433122356463;5163.793448262028;4933.76655836041;5032.798688052583;5057.457475470017;5083.72039537829;5094.617903657315;4767.380240568704;4790.939895844681;4796.880077998078;4797.232442809273;4811.566958973709;3585.8027808470783;3602.243528858969;3608.9097772554846;3621.5292012038267;3628.872989445357;5072.365954255718;5115.061402719959;5126.6923984561045;5127.79488820443;5140.044474427144;4779.593734532604;4889.877753056202;4919.581835543876;4930.5069493050405;4932.679375840559;4928.2853986171085;4934.383202099738;4937.308776771183;4958.404991401055;4965.18074517715;4846.200376937766;4868.221084626584;4876.171050921423;4879.951200488092;4889.266610008527;4792.161371759998;4816.8554943351755;4884;4892.8532144035735;5000.324956131021;4844.515548445127;4875.878547933924;4893.486183950634;4922.630802689884;4924.679895806761 12:33:33 INFO - PROCESS | 9861 | |5;yui.html;658.2693210536304;673.7035704290112;684.1789852177383;721.3868212019784;737.6938570493244;12232.510699572018;13402.396892139865;13417.597472075853;13653.317334133304;13656.863431365697;12773.616791496246;12780.233186008823;12798.36008199591;12922.547710830122;12931.482740690384;612.1246617337206;633.8193614819777;751.4439314906971;770.4799260499169;775.2170307988915;749.61394881636;779.4504874063799;788.1724189600897;789.8064974081358;790.411143697943;835.7659855240543;835.8161204534993;836.7113345895656;837.7109897085496;838.0195171649185;641.7016087519279;666.5334266013809;827.2761333832926;829.5686243153557;830.0744669693308;581.3140494216823;603.9909401358983;607.1318015238207;607.6688204928324;608.3703367015119;13498.662533436644;13526.18842869431;13531.39108740109;13542.255175965318;13545.729085418236;11863.822042669366;11964.70088247792;12198.512059517609;12228.694282642917;12397.938010309981;11230.775384492264;11324.830127548094;11363.465932099143;11450.942745286304;11494.42527873603;3783.735138540303;3818.2172654606015;3850.6726928211137;3868.400397938324;3887.436321733361;58589.41410585882;60525;60708.089378659795;63144;63281.36718632847;9396.248300135972;9435.386699864486;10222.48887555634;10261.9486902565;10308.433036183082;18125.456236312933;18220.180091895898;18328.083595820157;18334;18462.81537184611;6482.060101285381;6551.115599394021;6683.465322774166;6699.095622091053;6916.6541672916155;3558.768680035789;3622.8913132606062;3650.7079433645245;3653.3789255826573;3661.487391765155;2698.096137793822;2738.6439762830705;2758.5241895585764;2763.7512623863545;2766.1424958263;459.85054857171554;474.9525047495223;475.8453502611665;480.60830423204976;485.4708367879029;421.7659199144443;499.4880247746017;523.037610796137;523.5235935298936;557.0613516225173;7329.963350183321;7358.484906056525;7383.15093764224;7452.447926294945;7531.435142364345;521.5228066319276;532.2442132172324;546.2952790899774;556.6910364747523;575.4561938967719;3435.57055368079;3450.292689998544;3454.3091381723757;3555.413516563604;3631.3463576555946;62625.06062409068;62857.37142628607;63083;67750.3224967744;68040.6597967007;55790;56327.71836140793;57097.71451142718;57667;57795.422045779844;50020.49979500231;50789.74605126951;50924.236136457985;51217.7439112802;51285.743571281906;6676.0987266718375;6730.730770769114;6761.391474767294;6911.688973996283;7003.3346832050875;37033.444498332006;38009.80995095007;38145.80927095347;38293.42559861549;38432;42454.36318455287;42791.78604107084;43115.56884431116;43530.34704479372;45554.77222613866;11146.832797508207;11380.317180969168;11416.657500275005;11537.942310288396;11641.301521908712;12305.26168429897;12869.163504372185;13444.39500222512;13873.236971966639;13920.721585568435;12166.817497737718;12401.13192076546;12573.308468034345;12905.161164524276;13296.135751176143;12189.939050304693;12232.755344892874;12576.182548134342;12632.11575189728;13119.475220991053;10947.233693641369;11609.071274298205;11624;11677.532898683958;12430.751384972431;9916.355436896578;10267;10427.731764635631;10555.94722026385;10689.625863094754;9369.390989585656;9675.70972870815;10160.660868876295;10511.369317840954;10549.419781912069 12:33:33 INFO - PROCESS | 9861 | __end_tp_report 12:33:33 INFO - PROCESS | 9861 | __start_cc_report 12:33:33 INFO - PROCESS | 9861 | _x_x_mozilla_cycle_collect,1267 12:33:33 INFO - PROCESS | 9861 | __end_cc_report 12:33:33 INFO - PROCESS | 9861 | __startTimestamp1481402013942__endTimestamp 12:33:33 INFO - PROCESS | 9861 | 12:33:33 INFO - PROCESS | 9861 | ------- Summary: start ------- 12:33:33 INFO - PROCESS | 9861 | Number of tests: 6 12:33:33 INFO - PROCESS | 9861 | 12:33:33 INFO - PROCESS | 9861 | [#0] dojo.html Cycles:165 Average:4578.33 Median:4668.14 stddev:475.78 (10.2%) stddev-sans-first:449.86 12:33:33 INFO - PROCESS | 9861 | Values: 2550.3 2580.0 2582.8 2591.2 2595.1 4500.3 4568.3 4610.0 4645.6 4659.0 4560.6 4570.3 4579.7 4588.0 4591.7 4197.0 4210.7 4225.3 4316.4 4328.0 4277.6 4310.8 4357.5 4387.8 4413.6 4162.8 4179.9 4189.4 4247.9 4314.4 4193.1 4203.1 4218.9 4221.1 4259.7 4197.6 4202.8 4217.6 4248.0 4258.2 4560.0 4567.1 4579.5 4598.1 4625.5 4776.0 4802.4 4806.8 4912.1 4953.8 4963.6 4987.7 5033.7 5068.1 5120.4 4732.1 4739.9 4739.9 4740.7 4781.1 4904.9 5156.9 5168.8 5169.6 5223.4 4742.7 4822.5 4823.2 4849.8 4862.8 4950.4 4954.4 4955.1 4955.2 4997.6 4889.7 4892.5 4932.8 4939.4 4979.4 4760.2 4804.8 4809.7 4867.5 4984.6 4305.3 4318.0 4376.0 4376.7 4379.1 3744.4 3756.9 3761.2 3783.0 3796.6 4169.6 4311.4 4318.9 4322.9 4364.9 4841.5 4918.9 4920.4 4923.7 4924.7 4199.3 4225.1 4240.7 4242.1 4248.1 4319.7 4470.6 4472.3 4501.1 4505.8 4624.2 4639.1 4643.8 4644.8 4685.2 4964.0 4971.9 4973.9 4977.2 5019.9 4491.3 4532.8 4536.6 4600.9 4639.3 4932.8 4962.9 4976.9 4983.6 4984.8 5008.9 5015.1 5015.3 5017.9 5063.7 4668.1 4806.5 4869.4 4870.5 4915.0 4693.4 4763.8 4794.7 4815.0 4827.3 4773.3 4786.2 4787.2 4829.2 4836.8 4881.0 4882.6 4886.7 4889.5 4935.9 4632.9 4635.0 4639.3 4701.1 4760.6 12:33:33 INFO - PROCESS | 9861 | 12:33:33 INFO - PROCESS | 9861 | [#1] ext.html Cycles:180 Average:49795.99 Median:3501.54 stddev:103668.70 (2960.7%) stddev-sans-first:103893.52 12:33:33 INFO - PROCESS | 9861 | Values: 526.3 530.2 546.5 591.4 595.0 27382.2 28015.3 38613.0 39613.4 39892.4 39484.1 39870.8 39873.8 39889.0 40085.2 7364.9 7584.2 7584.5 7586.3 7592.2 7420.6 7634.7 9520.9 9818.4 9821.1 9786.4 9853.0 9856.1 9865.4 9871.3 9681.2 9718.1 9737.1 9746.5 9748.7 9688.3 9723.3 9748.9 9759.2 9762.0 37399.6 37546.1 37740.8 37912.6 37948.1 3101.6 3106.5 3106.8 3190.9 3350.5 27896.3 27911.3 28169.0 28940.1 29771.4 3462.3 3500.8 3502.3 3530.0 3622.1 226752.0 227336.0 227508.9 227675.0 228123.0 51759.5 52122.7 52283.0 52303.5 52397.0 54764.5 54863.7 54879.7 54906.7 55006.4 14655.9 14886.0 14952.6 14952.8 15046.0 10268.7 10587.2 11042.1 11077.1 11087.7 16130.9 16502.8 16568.9 16669.0 16910.3 5693.6 5847.9 5852.4 5888.5 6022.6 741.0 813.0 882.0 976.0 993.9 26385.6 27481.2 27819.0 27838.2 27886.3 920.3 925.9 959.1 978.1 1021.5 13605.3 14524.6 14577.6 14720.1 14790.5 340062.0 576901.0 583686.0 587583.0 587635.0 42679.6 43251.4 43263.1 43288.6 43307.0 1042.4 1061.9 1139.7 1182.7 1192.5 23580.9 25712.2 26105.5 27887.4 28406.1 39546.4 39557.0 41128.6 41159.8 44179.6 318474.0 320921.0 321682.0 321693.0 322279.0 20095.4 20418.7 22865.0 23368.3 23371.9 36426.6 36555.0 36692.4 39916.2 40139.4 30591.8 30676.5 30777.2 31030.8 31075.0 31172.0 31200.5 31266.4 31799.4 32011.7 30383.2 31289.5 31472.8 31573.8 31760.7 27792.7 28269.9 28532.9 28740.7 28837.0 21870.8 22702.3 22703.9 22715.4 22738.9 12:33:33 INFO - PROCESS | 9861 | 12:33:33 INFO - PROCESS | 9861 | [#2] jquery.html Cycles:180 Average:19993.16 Median:4835.76 stddev:42973.56 (888.7%) stddev-sans-first:43068.77 12:33:33 INFO - PROCESS | 9861 | Values: 333.6 343.7 356.6 361.2 377.1 12275.2 15314.4 15595.7 16262.8 17196.3 16574.0 16612.1 16914.5 17012.6 17041.2 3858.5 4815.4 4985.3 5021.6 5035.4 4910.1 4913.9 4946.1 4952.1 4952.9 4424.4 4562.1 4915.5 4918.0 4920.5 4584.9 4801.6 4856.1 4866.1 4887.0 4243.0 4347.0 4362.5 4368.5 4371.7 19280.1 19819.4 19879.2 19929.6 19956.0 1957.9 2055.6 2174.5 2486.0 2502.0 12646.8 12781.9 13230.7 13349.1 13577.0 1711.6 1720.4 1771.5 1779.8 1800.8 63223.7 63466.4 63755.7 65227.7 65907.3 8965.3 9005.9 9331.4 9401.8 9485.6 12777.7 12780.9 12872.7 12880.9 12896.6 6862.2 7010.0 7064.0 7094.7 7163.1 6552.2 6721.1 6762.3 6770.9 6786.5 5828.8 5957.6 6022.4 6024.8 6253.6 1698.4 1720.8 1746.1 1763.4 1773.4 708.5 776.4 825.4 858.4 887.2 15066.3 15294.3 15773.8 15833.5 16242.8 808.6 820.0 825.5 849.9 868.9 8306.9 9066.6 9129.7 9191.2 9203.4 226299.0 227493.0 229317.0 230605.0 333672.0 74055.3 75073.2 77402.2 78067.6 78159.6 906.6 923.6 959.0 962.2 964.0 15423.2 15425.2 16024.7 16326.9 16349.3 34845.7 35475.0 35541.8 36124.0 36439.3 46586.8 48781.3 49068.8 50688.0 51184.7 7134.8 7138.1 7275.1 7465.8 7528.3 9848.3 11150.8 11189.5 11623.9 11737.4 9410.2 9877.5 9936.3 10297.4 10356.8 9897.3 10066.6 10076.4 10126.9 10165.9 9867.8 9928.8 10219.8 10314.2 10380.9 6267.7 6655.4 6675.1 6855.0 7009.0 8277.3 9431.1 9623.8 9895.8 10225.6 12:33:33 INFO - PROCESS | 9861 | 12:33:33 INFO - PROCESS | 9861 | [#3] mootools.html Cycles:180 Average:4752.64 Median:5954.66 stddev:2576.13 (43.3%) stddev-sans-first:2569.61 12:33:33 INFO - PROCESS | 9861 | Values: 1211.6 1299.7 1312.9 1377.4 1618.2 6102.0 6907.4 7186.6 7186.8 7195.6 6687.2 6795.6 6799.6 6994.4 7000.4 1761.7 1817.7 2037.1 2084.2 2086.5 2074.0 2135.8 2136.2 2153.8 2158.2 2061.4 2081.1 2087.9 2088.5 2104.6 2078.3 2102.1 2109.4 2112.9 2115.0 2058.7 2082.4 2083.3 2086.2 2087.8 7109.9 7234.1 7249.3 7249.5 7259.7 4208.1 4341.9 4346.2 4352.8 4359.3 2646.2 2723.5 2727.3 2766.3 2776.8 834.8 848.0 904.3 905.5 907.1 9346.8 9690.8 9694.1 9697.1 9708.8 3763.0 3845.6 3916.6 3949.8 4030.4 8391.2 8395.2 8401.9 8408.2 8411.7 4304.0 4396.1 4486.1 4488.1 4490.9 3472.3 3626.7 3941.2 3965.7 3992.0 3420.6 3430.4 3529.8 3548.7 3554.0 1104.2 1146.4 1146.7 1147.1 1153.2 803.1 810.5 820.2 825.1 828.7 4697.0 4825.0 4827.5 5030.4 5033.7 799.6 826.4 827.6 874.3 875.2 3386.2 3531.3 3673.4 3689.7 3841.3 8574.7 8722.5 8902.7 8911.0 8923.2 8648.9 8660.7 8794.3 8804.6 8831.8 6563.3 6646.5 6744.5 6754.5 6754.8 4831.2 5032.2 5058.9 5118.9 5302.8 7638.8 7783.5 7841.6 8030.4 8049.5 7664.8 7849.0 7898.4 8085.7 8092.7 6306.0 6385.0 6410.0 6457.3 6467.5 6888.8 6907.7 6919.4 6936.7 6985.9 6044.6 6127.1 6155.0 6184.8 6343.4 6192.5 6241.1 6251.9 6281.1 6323.7 5909.3 6000.0 6001.7 6008.5 6008.8 4175.3 4349.6 4387.8 4419.6 4453.1 4172.8 4179.5 4255.9 4386.6 4408.8 12:33:33 INFO - PROCESS | 9861 | 12:33:33 INFO - PROCESS | 9861 | [#4] prototype.html Cycles:180 Average:4109.07 Median:4670.83 stddev:1191.87 (25.5%) stddev-sans-first:1160.85 12:33:33 INFO - PROCESS | 9861 | Values: 323.4 327.6 328.9 332.6 337.4 4700.0 4760.9 4767.5 4777.7 4781.7 4634.9 4662.3 4679.4 4700.2 4742.1 3601.6 3702.4 3719.4 3737.2 3766.7 3772.3 3772.7 3781.3 3781.8 3794.8 3745.6 3759.2 3766.5 3776.1 3782.5 3583.5 3689.3 3706.3 3746.3 3770.2 3367.4 3368.7 3374.0 3374.2 3376.6 4585.8 4602.1 4603.5 4604.7 4636.9 3944.6 4135.9 4281.5 4283.4 4294.9 4903.7 4920.6 4929.5 4959.1 4960.6 3522.9 3527.7 3532.3 3535.0 3539.6 5017.3 5191.2 5228.5 5230.2 5231.4 3545.7 3558.3 3564.7 3569.9 3572.6 3613.9 3615.2 3625.2 3635.9 3636.9 4114.2 4158.1 4172.6 4179.5 4194.0 4638.7 4919.6 4934.7 4964.2 4974.5 1216.5 1216.6 1217.0 1222.6 1228.6 779.6 807.9 836.7 844.8 848.1 4150.9 4278.4 4392.5 4415.2 4430.6 5030.0 5035.5 5054.5 5082.9 5139.9 4324.6 4335.5 4365.9 4381.6 4386.9 2500.3 2600.6 2610.6 2625.7 2645.9 4496.6 4615.2 4693.5 4699.3 4725.0 5138.0 5151.7 5188.7 5190.3 5200.8 4523.0 4785.6 4792.1 4805.2 4850.0 5120.6 5144.5 5145.8 5153.4 5163.8 4933.8 5032.8 5057.5 5083.7 5094.6 4767.4 4790.9 4796.9 4797.2 4811.6 3585.8 3602.2 3608.9 3621.5 3628.9 5072.4 5115.1 5126.7 5127.8 5140.0 4779.6 4889.9 4919.6 4930.5 4932.7 4928.3 4934.4 4937.3 4958.4 4965.2 4846.2 4868.2 4876.2 4880.0 4889.3 4792.2 4816.9 4884.0 4892.9 5000.3 4844.5 4875.9 4893.5 4922.6 4924.7 12:33:33 INFO - PROCESS | 9861 | 12:33:33 INFO - PROCESS | 9861 | [#5] yui.html Cycles:180 Average:14702.03 Median:21438.06 stddev:18119.46 (84.5%) stddev-sans-first:18139.60 12:33:33 INFO - PROCESS | 9861 | Values: 658.3 673.7 684.2 721.4 737.7 12232.5 13402.4 13417.6 13653.3 13656.9 12773.6 12780.2 12798.4 12922.5 12931.5 612.1 633.8 751.4 770.5 775.2 749.6 779.5 788.2 789.8 790.4 835.8 835.8 836.7 837.7 838.0 641.7 666.5 827.3 829.6 830.1 581.3 604.0 607.1 607.7 608.4 13498.7 13526.2 13531.4 13542.3 13545.7 11863.8 11964.7 12198.5 12228.7 12397.9 11230.8 11324.8 11363.5 11450.9 11494.4 3783.7 3818.2 3850.7 3868.4 3887.4 58589.4 60525.0 60708.1 63144.0 63281.4 9396.2 9435.4 10222.5 10261.9 10308.4 18125.5 18220.2 18328.1 18334.0 18462.8 6482.1 6551.1 6683.5 6699.1 6916.7 3558.8 3622.9 3650.7 3653.4 3661.5 2698.1 2738.6 2758.5 2763.8 2766.1 459.9 475.0 475.8 480.6 485.5 421.8 499.5 523.0 523.5 557.1 7330.0 7358.5 7383.2 7452.4 7531.4 521.5 532.2 546.3 556.7 575.5 3435.6 3450.3 3454.3 3555.4 3631.3 62625.1 62857.4 63083.0 67750.3 68040.7 55790.0 56327.7 57097.7 57667.0 57795.4 50020.5 50789.7 50924.2 51217.7 51285.7 6676.1 6730.7 6761.4 6911.7 7003.3 37033.4 38009.8 38145.8 38293.4 38432.0 42454.4 42791.8 43115.6 43530.3 45554.8 11146.8 11380.3 11416.7 11537.9 11641.3 12305.3 12869.2 13444.4 13873.2 13920.7 12166.8 12401.1 12573.3 12905.2 13296.1 12189.9 12232.8 12576.2 12632.1 13119.5 10947.2 11609.1 11624.0 11677.5 12430.8 9916.4 10267.0 10427.7 10555.9 10689.6 9369.4 9675.7 10160.7 10511.4 10549.4 12:33:33 INFO - PROCESS | 9861 | -------- Summary: end -------- 12:33:33 INFO - PROCESS | 9861 | 12:33:34 INFO - TEST-INFO | 9861: exit 0 12:33:34 INFO - TEST-OK | dromaeo_css | took 1123735ms 12:33:34 INFO - TEST-START | kraken 12:33:34 INFO - operating with platform_type : linux_ 12:33:34 INFO - Initialising browser for kraken test... 12:33:34 INFO - TEST-INFO | started process 10168 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVezVGD/profile http://localhost:37179/getInfo.html) 12:33:39 INFO - PROCESS | 10168 | __metrics Screen width/height:1600/1200 12:33:39 INFO - PROCESS | 10168 | colorDepth:24 12:33:39 INFO - PROCESS | 10168 | Browser inner width/height: 1024/697 12:33:39 INFO - PROCESS | 10168 | __metrics 12:33:39 INFO - PROCESS | 10168 | 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" 12:33:39 INFO - PROCESS | 10168 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:33:41 INFO - TEST-INFO | 10168: exit 0 12:33:41 INFO - Browser initialized. 12:33:41 INFO - Running cycle 1/1 for kraken test... 12:33:41 INFO - TEST-INFO | started process 10252 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVezVGD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:33:53 INFO - PROCESS | 10252 | RSS: Main: 173445120 12:33:53 INFO - PROCESS | 10252 | 12:33:56 INFO - PROCESS | 10252 | Cycle 1(1): loaded http://localhost:37179/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37179/tests/kraken/driver.html?testName=audio-beat-detection) 12:33:57 INFO - PROCESS | 10252 | RSS: Main: 235204608 12:33:57 INFO - PROCESS | 10252 | 12:34:00 INFO - PROCESS | 10252 | Cycle 1(1): loaded http://localhost:37179/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37179/tests/kraken/driver.html?testName=audio-dft) 12:34:00 INFO - PROCESS | 10252 | RSS: Main: 218484736 12:34:00 INFO - PROCESS | 10252 | 12:34:03 INFO - PROCESS | 10252 | Cycle 1(1): loaded http://localhost:37179/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37179/tests/kraken/driver.html?testName=audio-fft) 12:34:04 INFO - PROCESS | 10252 | RSS: Main: 300937216 12:34:04 INFO - PROCESS | 10252 | 12:34:06 INFO - PROCESS | 10252 | Cycle 1(1): loaded http://localhost:37179/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37179/tests/kraken/driver.html?testName=audio-oscillator) 12:34:07 INFO - PROCESS | 10252 | RSS: Main: 210096128 12:34:07 INFO - PROCESS | 10252 | 12:34:09 INFO - PROCESS | 10252 | Cycle 1(1): loaded http://localhost:37179/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37179/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:34:10 INFO - PROCESS | 10252 | RSS: Main: 214138880 12:34:10 INFO - PROCESS | 10252 | 12:34:13 INFO - PROCESS | 10252 | Cycle 1(1): loaded http://localhost:37179/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37179/tests/kraken/driver.html?testName=imaging-darkroom) 12:34:14 INFO - PROCESS | 10252 | RSS: Main: 253673472 12:34:14 INFO - PROCESS | 10252 | 12:34:17 INFO - PROCESS | 10252 | Cycle 1(1): loaded http://localhost:37179/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37179/tests/kraken/driver.html?testName=imaging-desaturate) 12:34:18 INFO - PROCESS | 10252 | RSS: Main: 253095936 12:34:18 INFO - PROCESS | 10252 | 12:34:21 INFO - PROCESS | 10252 | Cycle 1(1): loaded http://localhost:37179/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37179/tests/kraken/driver.html?testName=json-parse-financial) 12:34:21 INFO - PROCESS | 10252 | RSS: Main: 252665856 12:34:21 INFO - PROCESS | 10252 | 12:34:23 INFO - PROCESS | 10252 | Cycle 1(1): loaded http://localhost:37179/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37179/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:34:24 INFO - PROCESS | 10252 | RSS: Main: 214921216 12:34:24 INFO - PROCESS | 10252 | 12:34:25 INFO - PROCESS | 10252 | Cycle 1(1): loaded http://localhost:37179/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37179/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:34:26 INFO - PROCESS | 10252 | RSS: Main: 222265344 12:34:26 INFO - PROCESS | 10252 | 12:34:28 INFO - PROCESS | 10252 | Cycle 1(1): loaded http://localhost:37179/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37179/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:34:29 INFO - PROCESS | 10252 | RSS: Main: 226496512 12:34:29 INFO - PROCESS | 10252 | 12:34:32 INFO - PROCESS | 10252 | Cycle 1(1): loaded http://localhost:37179/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37179/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:34:33 INFO - PROCESS | 10252 | RSS: Main: 224403456 12:34:33 INFO - PROCESS | 10252 | 12:34:36 INFO - PROCESS | 10252 | Cycle 1(1): loaded http://localhost:37179/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37179/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:34:36 INFO - PROCESS | 10252 | RSS: Main: 228564992 12:34:36 INFO - PROCESS | 10252 | 12:34:38 INFO - PROCESS | 10252 | Cycle 1(1): loaded http://localhost:37179/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37179/tests/kraken/driver.html?testName=ai-astar) 12:34:38 INFO - PROCESS | 10252 | RSS: Main: 280834048 12:34:38 INFO - PROCESS | 10252 | 12:34:38 INFO - PROCESS | 10252 | __start_tp_report 12:34:38 INFO - PROCESS | 10252 | _x_x_mozilla_page_load 12:34:38 INFO - PROCESS | 10252 | _x_x_mozilla_page_load_details 12:34:38 INFO - PROCESS | 10252 | |i|pagename|runs| 12:34:38 INFO - PROCESS | 10252 | |0;ai-astar;107;114;114;114;114;116;115;117;116;114 12:34:38 INFO - PROCESS | 10252 | |1;audio-beat-detection;157;128;132;170;141;137;129;131;151;134 12:34:38 INFO - PROCESS | 10252 | |2;audio-dft;163;163;163;162;163;162;161;162;163;161 12:34:38 INFO - PROCESS | 10252 | |3;audio-fft;75;76;77;120;76;77;77;76;90;76 12:34:38 INFO - PROCESS | 10252 | |4;audio-oscillator;85;85;112;86;85;85;106;86;87;112 12:34:38 INFO - PROCESS | 10252 | |5;imaging-gaussian-blur;127;128;128;128;128;127;128;127;127;127 12:34:38 INFO - PROCESS | 10252 | |6;imaging-darkroom;146;147;147;147;147;147;147;146;145;145 12:34:38 INFO - PROCESS | 10252 | |7;imaging-desaturate;95;96;95;96;96;95;95;95;95;95 12:34:38 INFO - PROCESS | 10252 | |8;json-parse-financial;66;65;73;65;68;68;67;67;67;67 12:34:38 INFO - PROCESS | 10252 | |9;json-stringify-tinderbox;66;67;65;66;68;67;66;66;66;66 12:34:38 INFO - PROCESS | 10252 | |10;stanford-crypto-aes;92;95;88;89;89;89;91;90;134;88 12:34:38 INFO - PROCESS | 10252 | |11;stanford-crypto-ccm;149;152;151;151;149;138;152;147;166;138 12:34:38 INFO - PROCESS | 10252 | |12;stanford-crypto-pbkdf2;181;177;178;183;181;179;181;189;178;178 12:34:38 INFO - PROCESS | 10252 | |13;stanford-crypto-sha256-iterative;69;68;69;69;69;68;70;68;68;68 12:34:38 INFO - PROCESS | 10252 | __end_tp_report 12:34:38 INFO - PROCESS | 10252 | __start_cc_report 12:34:38 INFO - PROCESS | 10252 | _x_x_mozilla_cycle_collect,2002 12:34:38 INFO - PROCESS | 10252 | __end_cc_report 12:34:38 INFO - PROCESS | 10252 | __startTimestamp1481402078870__endTimestamp 12:34:38 INFO - PROCESS | 10252 | 12:34:38 INFO - PROCESS | 10252 | ------- Summary: start ------- 12:34:38 INFO - PROCESS | 10252 | Number of tests: 14 12:34:38 INFO - PROCESS | 10252 | 12:34:38 INFO - PROCESS | 10252 | [#0] ai-astar Cycles:10 Average:114.10 Median:114.50 stddev:2.73 (2.4%) stddev-sans-first:1.17 12:34:38 INFO - PROCESS | 10252 | Values: 107.0 114.0 114.0 114.0 114.0 116.0 115.0 117.0 116.0 114.0 12:34:38 INFO - PROCESS | 10252 | 12:34:38 INFO - PROCESS | 10252 | [#1] audio-beat-detection Cycles:10 Average:141.00 Median:139.00 stddev:13.97 (10.0%) stddev-sans-first:13.56 12:34:38 INFO - PROCESS | 10252 | Values: 157.0 128.0 132.0 170.0 141.0 137.0 129.0 131.0 151.0 134.0 12:34:38 INFO - PROCESS | 10252 | 12:34:38 INFO - PROCESS | 10252 | [#2] audio-dft Cycles:10 Average:162.30 Median:163.00 stddev:0.82 (0.5%) stddev-sans-first:0.83 12:34:38 INFO - PROCESS | 10252 | Values: 163.0 163.0 163.0 162.0 163.0 162.0 161.0 162.0 163.0 161.0 12:34:38 INFO - PROCESS | 10252 | 12:34:38 INFO - PROCESS | 10252 | [#3] audio-fft Cycles:10 Average:82.00 Median:76.50 stddev:14.05 (18.4%) stddev-sans-first:14.67 12:34:38 INFO - PROCESS | 10252 | Values: 75.0 76.0 77.0 120.0 76.0 77.0 77.0 76.0 90.0 76.0 12:34:38 INFO - PROCESS | 10252 | 12:34:38 INFO - PROCESS | 10252 | [#4] audio-oscillator Cycles:10 Average:92.90 Median:85.00 stddev:11.93 (14.0%) stddev-sans-first:12.31 12:34:38 INFO - PROCESS | 10252 | Values: 85.0 85.0 112.0 86.0 85.0 85.0 106.0 86.0 87.0 112.0 12:34:38 INFO - PROCESS | 10252 | 12:34:38 INFO - PROCESS | 10252 | [#5] imaging-gaussian-blur Cycles:10 Average:127.50 Median:128.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 12:34:38 INFO - PROCESS | 10252 | Values: 127.0 128.0 128.0 128.0 128.0 127.0 128.0 127.0 127.0 127.0 12:34:38 INFO - PROCESS | 10252 | 12:34:38 INFO - PROCESS | 10252 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:147.00 stddev:0.84 (0.6%) stddev-sans-first:0.88 12:34:38 INFO - PROCESS | 10252 | Values: 146.0 147.0 147.0 147.0 147.0 147.0 147.0 146.0 145.0 145.0 12:34:38 INFO - PROCESS | 10252 | 12:34:38 INFO - PROCESS | 10252 | [#7] imaging-desaturate Cycles:10 Average:95.30 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 12:34:38 INFO - PROCESS | 10252 | Values: 95.0 96.0 95.0 96.0 96.0 95.0 95.0 95.0 95.0 95.0 12:34:38 INFO - PROCESS | 10252 | 12:34:38 INFO - PROCESS | 10252 | [#8] json-parse-financial Cycles:10 Average:67.30 Median:67.00 stddev:2.26 (3.4%) stddev-sans-first:2.35 12:34:38 INFO - PROCESS | 10252 | Values: 66.0 65.0 73.0 65.0 68.0 68.0 67.0 67.0 67.0 67.0 12:34:38 INFO - PROCESS | 10252 | 12:34:38 INFO - PROCESS | 10252 | [#9] json-stringify-tinderbox Cycles:10 Average:66.30 Median:66.00 stddev:0.82 (1.2%) stddev-sans-first:0.87 12:34:38 INFO - PROCESS | 10252 | Values: 66.0 67.0 65.0 66.0 68.0 67.0 66.0 66.0 66.0 66.0 12:34:38 INFO - PROCESS | 10252 | 12:34:38 INFO - PROCESS | 10252 | [#10] stanford-crypto-aes Cycles:10 Average:94.50 Median:89.50 stddev:14.04 (15.7%) stddev-sans-first:14.86 12:34:38 INFO - PROCESS | 10252 | Values: 92.0 95.0 88.0 89.0 89.0 89.0 91.0 90.0 134.0 88.0 12:34:38 INFO - PROCESS | 10252 | 12:34:38 INFO - PROCESS | 10252 | [#11] stanford-crypto-ccm Cycles:10 Average:149.30 Median:151.00 stddev:7.89 (5.2%) stddev-sans-first:8.37 12:34:38 INFO - PROCESS | 10252 | Values: 149.0 152.0 151.0 151.0 149.0 138.0 152.0 147.0 166.0 138.0 12:34:38 INFO - PROCESS | 10252 | 12:34:38 INFO - PROCESS | 10252 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.50 Median:181.00 stddev:3.54 (2.0%) stddev-sans-first:3.75 12:34:38 INFO - PROCESS | 10252 | Values: 181.0 177.0 178.0 183.0 181.0 179.0 181.0 189.0 178.0 178.0 12:34:38 INFO - PROCESS | 10252 | 12:34:38 INFO - PROCESS | 10252 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.60 Median:69.00 stddev:0.70 (1.0%) stddev-sans-first:0.73 12:34:38 INFO - PROCESS | 10252 | Values: 69.0 68.0 69.0 69.0 69.0 68.0 70.0 68.0 68.0 68.0 12:34:38 INFO - PROCESS | 10252 | -------- Summary: end -------- 12:34:38 INFO - PROCESS | 10252 | 12:34:40 INFO - TEST-INFO | 10252: exit 0 12:34:40 INFO - TEST-OK | kraken | took 65284ms 12:34:40 INFO - SUITE-END | took 1189s 12:34:40 INFO - Completed test suite (00:19:49) 12:34:40 INFO - javascript benchmark 12:34:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2550.311415917702, 2580.0324878170686, 2582.7546383093613, 2591.170825335892, 2595.05280572591, 4500.324951257313, 4568.291914753218, 4609.976950115249, 4645.581897629213, 4658.97670511647, 4560.6123479504195, 4570.268756998881, 4579.656525760573, 4588.0, 4591.7244965302025, 4197.0, 4210.7473551586845, 4225.302825033866, 4316.417283666712, 4328.0, 4277.593628605293, 4310.849120280791, 4357.542458041908, 4387.846425375113, 4413.558644135577, 4162.83348666053, 4179.895502612429, 4189.434426352434, 4247.851325203634, 4314.374415709704, 4193.056562273495, 4203.138356636882, 4218.87343379696, 4221.134667393177, 4259.74441533509, 4197.643200327976, 4202.768847713374, 4217.578242175789, 4248.044190057243, 4258.190943720683, 4559.954400456019, 4567.109413664337, 4579.519150489217, 4598.057398233387, 4625.4911959684405, 4776.044791041805, 4802.375691160162, 4806.759662016885, 4912.115819152551, 4953.752312384367, 4963.627727920384, 4987.675801072919, 5033.723145227011, 5068.113080210948, 5120.3855537335385, 4732.124556956939, 4739.857804265877, 4739.928901066487, 4740.691855029412, 4781.139394908881, 4904.901901961941, 5156.871078223075, 5168.767405466763, 5169.638125331191, 5223.425423203445, 4742.66801323904, 4822.493638168012, 4823.2282834746275, 4849.75751212438, 4862.78117484714, 4950.43070046942, 4954.405471343462, 4955.083309587738, 4955.231939049453, 4997.600191984632, 4889.731064791399, 4892.510748925079, 4932.802687892516, 4939.357883475125, 4979.377577802775, 4760.166970780099, 4804.8318308859025, 4809.711417314972, 4867.464578896319, 4984.551390374883, 4305.31115021595, 4317.956820431818, 4376.015396535818, 4376.693631445832, 4379.080393117417, 3744.4383342498845, 3756.887293381203, 3761.1725420407465, 3783.0164157318745, 3796.5823759386444, 4169.645580125723, 4311.374850646639, 4318.935215971763, 4322.891927701769, 4364.869053928387, 4841.540053694894, 4918.852434426973, 4920.384951881015, 4923.729194894244, 4924.704517728947, 4199.286121359376, 4225.112726327444, 4240.745555266632, 4242.087951090469, 4248.086661367759, 4319.719218250866, 4470.575295346936, 4472.262076757299, 4501.099780044005, 4505.81976720934, 4624.237000894815, 4639.062437820829, 4643.837465688753, 4644.814207431665, 4685.2035154023215, 4963.975180124076, 4971.900561988812, 4973.875653108701, 4977.178765503651, 5019.949800501949, 4491.326301054868, 4532.818687252473, 4536.6143877770755, 4600.907981840412, 4639.280911458729, 4932.802687892444, 4962.925556116734, 4976.8755781105765, 4983.601311894967, 4984.825531106394, 5008.9248661269385, 5015.072211640858, 5015.322931404214, 5017.924731129109, 5063.746812659426, 4668.136394766979, 4806.519348065165, 4869.391326084239, 4870.464248932685, 4915.0415668944215, 4693.413323334583, 4763.7856296467435, 4794.688345257547, 4815.036992601424, 4827.324174615486, 4773.284007398918, 4786.1624215762795, 4787.186178349759, 4829.179039563212, 4836.782344794563, 4880.975595122001, 4882.609391248762, 4886.65793394459, 4889.486603906538, 4935.901281974412, 4632.907341853212, 4635.0, 4639.327297541905, 4701.059788042337, 4760.6191504678845], "value": 4548.213684971418, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [526.3157894736842, 530.2338121414557, 546.5354448718588, 591.4233622218337, 595.0419824083225, 27382.178534643943, 28015.299617509572, 38612.99999999996, 39613.405798912965, 39892.40161397582, 39484.1031793641, 39870.8006459968, 39873.800630996804, 39889.0, 40085.19829603406, 7364.926350736478, 7584.165741768401, 7584.507007044552, 7586.3172314491685, 7592.240775922392, 7420.591867447289, 7634.656440460192, 9520.8571871422, 9818.410895346266, 9821.066998635153, 9786.412815231073, 9853.0, 9856.112949834513, 9865.358751681153, 9871.259655525828, 9681.225501959862, 9718.076782705632, 9737.07497787709, 9746.46394448305, 9748.658796942109, 9688.273379496495, 9723.270754693356, 9748.853767193497, 9759.219262459057, 9761.951190244003, 37399.62600373989, 37546.06134846623, 37740.81129594362, 37912.620873791464, 37948.05129871747, 3101.596792416994, 3106.4718997770433, 3106.7980581262373, 3190.9361812763846, 3350.547676063749, 27896.30259243515, 27911.302217444525, 28169.0, 28940.131796045942, 29771.40457190844, 3462.290230502741, 3500.7549471537004, 3502.330741409595, 3529.982350088233, 3622.0582648511577, 226752.0, 227336.0, 227508.86245568833, 227675.0, 228123.0, 51759.48240517547, 52122.73938630358, 52283.0, 52303.47696523062, 52397.0, 54764.45235547673, 54863.72568137134, 54879.725601371734, 54906.72546637321, 55006.44993550013, 14655.926720366544, 14886.0, 14952.551423457096, 14952.775708364383, 15046.0, 10268.691939241835, 10587.15302775776, 11042.116630669527, 11077.058450031676, 11087.72280692989, 16130.919345403197, 16502.75245871313, 16568.917155414387, 16669.0, 16910.32358705638, 5693.629914055573, 5847.883042339129, 5852.385499522489, 5888.499477544457, 6022.608530445507, 740.9629518524052, 812.9837403251902, 882.0297672560132, 975.9560819763128, 993.9105480506789, 26385.604215936775, 27481.17556473309, 27819.0, 27838.16485505438, 27886.3028424291, 920.3189639666598, 925.8657494663371, 959.1271942532262, 978.114806100466, 1021.5300961557618, 13605.31973401326, 14524.636884077983, 14577.562673119823, 14720.116792992454, 14790.482333118505, 340062.0, 576901.0, 583686.0, 587583.0, 587635.0, 42679.57320426756, 43251.35122973095, 43263.13473730571, 43288.567114328456, 43307.0, 1042.3537406807827, 1061.9256652034283, 1139.67519257013, 1182.6570294614637, 1192.5408717643595, 23580.88209558941, 25712.228633141036, 26105.477890441703, 27887.44225115527, 28406.147815565568, 39546.406803897386, 39557.0, 41128.58871411367, 41159.7942010288, 44179.558204417546, 318474.0, 320921.0, 321682.0, 321693.0, 322279.0, 20095.397138085882, 20418.693719594514, 22865.0, 23368.298951031495, 23371.88314058419, 36426.635733642324, 36555.0, 36692.44961325529, 39916.201675965734, 40139.380486732465, 30591.847040765544, 30676.539851902686, 30777.230569235053, 31030.844845775628, 31075.0, 31172.0, 31200.53199202059, 31266.37467250597, 31799.364012720078, 32011.679883200868, 30383.240418989702, 31289.530657040617, 31472.842635786674, 31573.8421307892, 31760.68239317577, 27792.722072779823, 28269.858650706614, 28532.85733571319, 28740.712592873802, 28837.0, 21870.781292186875, 22702.318930432113, 22703.886480567493, 22715.432114197276, 22738.886305568365], "value": 17184.06091057323, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.6496678487587, 343.7250199840129, 356.6059504247806, 361.2431955054162, 377.12883239716257, 12275.202111862724, 15314.38742450302, 15595.688086238268, 16262.7560586591, 17196.312147514116, 16574.0, 16612.086335251555, 16914.492565223063, 17012.574685632866, 17041.233144508496, 3858.5369755629363, 4815.446223684277, 4985.326980857592, 5021.648484606079, 5035.370578677665, 4910.091633047879, 4913.852584422473, 4946.109700253953, 4952.133376659088, 4952.925706114411, 4424.40270563473, 4562.110388474249, 4915.5084491550915, 4918.0, 4920.532549407801, 4584.931226031612, 4801.591864691488, 4856.077345304416, 4866.148424025781, 4886.951130488721, 4242.957570424318, 4346.978265108654, 4362.520122786491, 4368.453943257093, 4371.672124590668, 19280.132394042303, 19819.405417837486, 19879.20483180671, 19929.601407971906, 19956.002199889946, 1957.8825270483674, 2055.588882223561, 2174.5324755177553, 2486.0428734937, 2501.9874900625564, 12646.810297845539, 12781.936090319488, 13230.66923326915, 13349.13230640015, 13577.0, 1711.6234428425726, 1720.4322573550699, 1771.5216891439368, 1779.8487128593956, 1800.7568978187908, 63223.68388158076, 63466.365336346506, 63755.68122159406, 65227.673861630385, 65907.3409265906, 8965.327600429993, 9005.909940900638, 9331.393459425251, 9401.811963760687, 9485.620575177054, 12777.680557986125, 12780.93609531965, 12872.742545149233, 12880.935595322151, 12896.613101606967, 6862.245153033163, 7010.018597396369, 7063.964680176669, 7094.68073936674, 7163.10461192351, 6552.180977377828, 6721.126253587003, 6762.323767623297, 6770.864582708318, 6786.4570834333135, 5828.825135245949, 5957.582969192202, 6022.3676513966275, 6024.759009639653, 6253.624782513064, 1698.388580111159, 1720.7676963609752, 1746.1094841630859, 1763.3651885321274, 1773.3527262549335, 708.5040471669852, 776.378896882503, 825.405707890318, 858.3862538285156, 887.2192470625808, 15066.322015509328, 15294.311755971008, 15773.842261577467, 15833.52499425019, 16242.837571624368, 808.5714571277232, 819.979500512492, 825.4675734151439, 849.8739170598858, 868.9254752246783, 8306.916930830614, 9066.592003359865, 9129.726108216764, 9191.21874640663, 9203.447793132433, 226299.0, 227493.0, 229317.0, 230605.0, 333672.0, 74055.25944740592, 75073.24926750663, 77402.22597773951, 78067.60966195133, 78159.60920195477, 906.6056265524386, 923.612082925173, 958.9808203835884, 962.153305091515, 964.0263334032677, 15423.228838558252, 15425.228738563252, 16024.679506410039, 16326.918365408097, 16349.264283107052, 34845.651543484244, 35475.0, 35541.82229088838, 36124.0, 36439.27121457503, 46586.76706616445, 48781.268280975106, 49068.75465622649, 50688.0, 51184.74407627938, 7134.785956421316, 7138.143422789299, 7275.090613673291, 7465.813354666177, 7528.322450979438, 9848.310618256653, 11150.832737509107, 11189.496472658588, 11623.88376116228, 11737.35444550558, 9410.153086222273, 9877.50612469388, 9936.254780891317, 10297.433641149808, 10356.792864142826, 9897.307188496736, 10066.647667331608, 10076.445795481026, 10126.898731012596, 10165.949170254102, 9867.753306167404, 9928.801423971625, 10219.846702299323, 10314.226433017404, 10380.948095259475, 6267.749290028531, 6655.401013908771, 6675.132232809704, 6854.965725171542, 7008.9649551753955, 8277.296429803293, 9431.104045115702, 9623.759406014906, 9895.802083958424, 10225.642102526674], "value": 7899.90823497361, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1211.5941159711497, 1299.6945717756328, 1312.9343532823357, 1377.449020391843, 1618.2232528386367, 6102.0, 6907.37833594977, 7186.640667966607, 7186.820329491766, 7195.640217989093, 6687.230968438631, 6795.626240556768, 6799.592024478535, 6994.3705066544, 7000.404965577919, 1761.656476986988, 1817.7273408988692, 2037.1443993522719, 2084.1871670048695, 2086.4679506725765, 2074.0, 2135.7864213578596, 2136.1562182937732, 2153.8384621153477, 2158.1583182558816, 2061.4124974382303, 2081.0635214153617, 2087.926922557711, 2088.457001179697, 2104.631689454347, 2078.314156328408, 2102.127617038927, 2109.367189843041, 2112.926047588327, 2114.95770084599, 2058.711780350752, 2082.3961051295105, 2083.3020937985793, 2086.2176683743596, 2087.759907610614, 7109.857802843914, 7234.1319041714805, 7249.275072492761, 7249.492535522516, 7259.745908893216, 4208.116295577933, 4341.934870976938, 4346.15250026245, 4352.825886964518, 4359.346098085312, 2646.1937285009108, 2723.4553089382202, 2727.318170457386, 2766.3222510484857, 2776.7917406194415, 834.7662654456761, 847.966081356745, 904.263025634106, 905.4522014181391, 907.1155623267261, 9346.766330841716, 9690.806183876353, 9694.079062489052, 9697.127258546694, 9708.75728106796, 3762.9623703762613, 3845.5962123977138, 3916.6279203475624, 3949.755826914513, 4030.435738996543, 8391.202835730595, 8395.202455766692, 8401.873971890427, 8408.159184081544, 8411.663533458715, 4304.0, 4396.098799746044, 4486.102779444124, 4488.102379524108, 4490.910181796411, 3472.305538892181, 3626.7098632109896, 3941.192055628589, 3965.6827453803626, 3991.980040099781, 3420.555327807369, 3430.4168291390524, 3529.8411571479346, 3548.6628770266784, 3553.9644603553634, 1104.216006635282, 1146.4095990564865, 1146.7190538318166, 1147.1167201254987, 1153.2388623508443, 803.0965164190286, 810.4610434061401, 820.2289847543291, 825.1212458731432, 828.7265202483167, 4696.966667333181, 4824.95175048245, 4827.493113223061, 5030.37120359955, 5033.672811267256, 799.6121880887765, 826.4008593769469, 827.557256867573, 874.3398733955822, 875.2429148786381, 3386.1873150444208, 3531.29374125176, 3673.3571624965525, 3689.6679298863232, 3841.2893614681375, 8574.742757717278, 8722.476651400937, 8902.688405905763, 8911.0, 8923.241524470362, 8648.913510864937, 8660.69687560945, 8794.252488538417, 8804.647814087493, 8831.823363532694, 6563.343665633399, 6646.53474256807, 6744.494162937701, 6754.493412994045, 6754.797356079325, 4831.154547954165, 5032.169692000779, 5058.873528161678, 5118.948810511996, 5302.80774956807, 7638.847223055619, 7783.494072885244, 7841.607919604111, 8030.397720171128, 8049.476784009021, 7664.770056898302, 7848.960755196187, 7898.447108702336, 8085.676572937016, 8092.71675491355, 6305.96847015762, 6384.968075159594, 6410.0, 6457.257415397289, 6467.482601391972, 6888.793336199922, 6907.6891539879825, 6919.411849992807, 6936.653167341714, 6985.895211571932, 6044.637321760709, 6127.050307202391, 6154.969225153845, 6184.81445556634, 6343.397377249154, 6192.473639740492, 6241.063840423973, 6251.937480625135, 6281.089242059785, 6323.747050118128, 5909.320428150818, 6000.0, 6001.699915004319, 6008.54935879802, 6008.819735407945, 4175.331946888483, 4349.586789255016, 4387.802548885244, 4419.580139886705, 4453.064856380196, 4172.833086676499, 4179.519355274182, 4255.87232383029, 4386.605205531518, 4408.779561022], "value": 3873.3956447058576, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [323.42430473756724, 327.59705562158547, 328.8963976347452, 332.6374252065248, 337.3522836154583, 4700.0, 4760.857174284768, 4767.49823805501, 4777.689450185735, 4781.665283430161, 4634.933965188, 4662.274958265438, 4679.391679081715, 4700.247960326348, 4742.075295317414, 3601.6398360163903, 3702.426123950792, 3719.3863012602887, 3737.1965027519077, 3766.736328457009, 3772.320982223199, 3772.6604605585494, 3781.3193625147464, 3781.773093614378, 3794.829232684536, 3745.62543745626, 3759.2105657811894, 3766.472693822867, 3776.1126135358168, 3782.470454136423, 3583.4624806279007, 3689.335919534483, 3706.277275931181, 3746.265226384727, 3770.2271034437877, 3367.410703126943, 3368.730501559894, 3373.9662603374145, 3374.240795820945, 3376.6454522275053, 4585.816567337302, 4602.102589994953, 4603.539646035403, 4604.6776725629215, 4636.86089417318, 3944.6449819516383, 4135.855245066408, 4281.464816897887, 4283.357496375537, 4294.935575966363, 4903.730294833783, 4920.6309526785635, 4929.531694489017, 4959.057779021974, 4960.553550180501, 3522.9295414091575, 3527.7177825773874, 3532.2935412917263, 3534.964650353489, 3539.610642829287, 5017.322661440732, 5191.195364718475, 5228.516454684032, 5230.241614965808, 5231.424543300234, 3545.6631619996574, 3558.288342331544, 3564.6791788739138, 3569.910752231215, 3572.5534308211472, 3613.9277214455565, 3615.1865830188, 3625.184333524936, 3635.8727444539313, 3636.8727094551564, 4114.177164567099, 4158.14962341761, 4172.645325147336, 4179.519355274182, 4193.97903010483, 4638.698484598491, 4919.580891280597, 4934.67924584908, 4964.1809101498575, 4974.527419895104, 1216.5073145376136, 1216.6228469174584, 1216.9817452738216, 1222.6148763139583, 1228.6129869091208, 779.5907148746953, 807.8505476486869, 836.7155167243167, 844.8183640517318, 848.1476116502876, 4150.91698166041, 4278.358246263023, 4392.516823149451, 4415.161119387306, 4430.62339701129, 5030.032280954255, 5035.521625445577, 5054.519820617035, 5082.898342033212, 5139.946311006235, 4324.567543245713, 4335.523092459827, 4365.890852728708, 4381.605655491021, 4386.912261754747, 2500.2624225853415, 2600.5839065749387, 2610.6163733221424, 2625.671791026122, 2645.933851653724, 4496.6177874881005, 4615.177005082974, 4693.47462074827, 4699.271612900006, 4725.046845721424, 5137.9743101285, 5151.716655583904, 5188.688678679216, 5190.325257716473, 5200.7919683213, 4523.0, 4785.59322457595, 4792.065547218329, 4805.231163013901, 4849.951500485021, 5120.564751996122, 5144.459831717765, 5145.768440420265, 5153.433122356463, 5163.793448262028, 4933.76655836041, 5032.798688052583, 5057.457475470017, 5083.72039537829, 5094.617903657315, 4767.380240568704, 4790.939895844681, 4796.880077998078, 4797.232442809273, 4811.566958973709, 3585.8027808470783, 3602.243528858969, 3608.9097772554846, 3621.5292012038267, 3628.872989445357, 5072.365954255718, 5115.061402719959, 5126.6923984561045, 5127.79488820443, 5140.044474427144, 4779.593734532604, 4889.877753056202, 4919.581835543876, 4930.5069493050405, 4932.679375840559, 4928.2853986171085, 4934.383202099738, 4937.308776771183, 4958.404991401055, 4965.18074517715, 4846.200376937766, 4868.221084626584, 4876.171050921423, 4879.951200488092, 4889.266610008527, 4792.161371759998, 4816.8554943351755, 4884.0, 4892.8532144035735, 5000.324956131021, 4844.515548445127, 4875.878547933924, 4893.486183950634, 4922.630802689884, 4924.679895806761], "value": 3737.1910912207463, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [658.2693210536304, 673.7035704290112, 684.1789852177383, 721.3868212019784, 737.6938570493244, 12232.510699572018, 13402.396892139865, 13417.597472075853, 13653.317334133304, 13656.863431365697, 12773.616791496246, 12780.233186008823, 12798.36008199591, 12922.547710830122, 12931.482740690384, 612.1246617337206, 633.8193614819777, 751.4439314906971, 770.4799260499169, 775.2170307988915, 749.61394881636, 779.4504874063799, 788.1724189600897, 789.8064974081358, 790.411143697943, 835.7659855240543, 835.8161204534993, 836.7113345895656, 837.7109897085496, 838.0195171649185, 641.7016087519279, 666.5334266013809, 827.2761333832926, 829.5686243153557, 830.0744669693308, 581.3140494216823, 603.9909401358983, 607.1318015238207, 607.6688204928324, 608.3703367015119, 13498.662533436644, 13526.18842869431, 13531.39108740109, 13542.255175965318, 13545.729085418236, 11863.822042669366, 11964.70088247792, 12198.512059517609, 12228.694282642917, 12397.938010309981, 11230.775384492264, 11324.830127548094, 11363.465932099143, 11450.942745286304, 11494.42527873603, 3783.735138540303, 3818.2172654606015, 3850.6726928211137, 3868.400397938324, 3887.436321733361, 58589.41410585882, 60525.0, 60708.089378659795, 63144.0, 63281.36718632847, 9396.248300135972, 9435.386699864486, 10222.48887555634, 10261.9486902565, 10308.433036183082, 18125.456236312933, 18220.180091895898, 18328.083595820157, 18334.0, 18462.81537184611, 6482.060101285381, 6551.115599394021, 6683.465322774166, 6699.095622091053, 6916.6541672916155, 3558.768680035789, 3622.8913132606062, 3650.7079433645245, 3653.3789255826573, 3661.487391765155, 2698.096137793822, 2738.6439762830705, 2758.5241895585764, 2763.7512623863545, 2766.1424958263, 459.85054857171554, 474.9525047495223, 475.8453502611665, 480.60830423204976, 485.4708367879029, 421.7659199144443, 499.4880247746017, 523.037610796137, 523.5235935298936, 557.0613516225173, 7329.963350183321, 7358.484906056525, 7383.15093764224, 7452.447926294945, 7531.435142364345, 521.5228066319276, 532.2442132172324, 546.2952790899774, 556.6910364747523, 575.4561938967719, 3435.57055368079, 3450.292689998544, 3454.3091381723757, 3555.413516563604, 3631.3463576555946, 62625.06062409068, 62857.37142628607, 63083.0, 67750.3224967744, 68040.6597967007, 55790.0, 56327.71836140793, 57097.71451142718, 57667.0, 57795.422045779844, 50020.49979500231, 50789.74605126951, 50924.236136457985, 51217.7439112802, 51285.743571281906, 6676.0987266718375, 6730.730770769114, 6761.391474767294, 6911.688973996283, 7003.3346832050875, 37033.444498332006, 38009.80995095007, 38145.80927095347, 38293.42559861549, 38432.0, 42454.36318455287, 42791.78604107084, 43115.56884431116, 43530.34704479372, 45554.77222613866, 11146.832797508207, 11380.317180969168, 11416.657500275005, 11537.942310288396, 11641.301521908712, 12305.26168429897, 12869.163504372185, 13444.39500222512, 13873.236971966639, 13920.721585568435, 12166.817497737718, 12401.13192076546, 12573.308468034345, 12905.161164524276, 13296.135751176143, 12189.939050304693, 12232.755344892874, 12576.182548134342, 12632.11575189728, 13119.475220991053, 10947.233693641369, 11609.071274298205, 11624.0, 11677.532898683958, 12430.751384972431, 9916.355436896578, 10267.0, 10427.731764635631, 10555.94722026385, 10689.625863094754, 9369.390989585656, 9675.70972870815, 10160.660868876295, 10511.369317840954, 10549.419781912069], "value": 6170.629216270239, "unit": "score"}], "name": "dromaeo_css", "value": 6169.754763205136, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 114.0, 114.0, 114.0, 114.0, 116.0, 115.0, 117.0, 116.0, 114.0], "value": 114.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 128.0, 132.0, 170.0, 141.0, 137.0, 129.0, 131.0, 151.0, 134.0], "value": 141.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 163.0, 163.0, 162.0, 163.0, 162.0, 161.0, 162.0, 163.0, 161.0], "value": 162.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 77.0, 120.0, 76.0, 77.0, 77.0, 76.0, 90.0, 76.0], "value": 82.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 112.0, 86.0, 85.0, 85.0, 106.0, 86.0, 87.0, 112.0], "value": 92.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 128.0, 128.0, 128.0, 128.0, 127.0, 128.0, 127.0, 127.0, 127.0], "value": 127.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 147.0, 147.0, 147.0, 147.0, 147.0, 146.0, 145.0, 145.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 96.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 73.0, 65.0, 68.0, 68.0, 67.0, 67.0, 67.0, 67.0], "value": 67.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 65.0, 66.0, 68.0, 67.0, 66.0, 66.0, 66.0, 66.0], "value": 66.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 95.0, 88.0, 89.0, 89.0, 89.0, 91.0, 90.0, 134.0, 88.0], "value": 94.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 152.0, 151.0, 151.0, 149.0, 138.0, 152.0, 147.0, 166.0, 138.0], "value": 149.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 177.0, 178.0, 183.0, 181.0, 179.0, 181.0, 189.0, 178.0, 178.0], "value": 180.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 69.0, 69.0, 69.0, 68.0, 70.0, 68.0, 68.0, 68.0], "value": 68.6, "unit": "score"}], "name": "kraken", "value": 1587.9999999999998, "alertThreshold": 2.0}]} 12:34:40 INFO - Return code: 0 12:34:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:34:40 INFO - # TBPL SUCCESS # 12:34:40 INFO - Running post-action listener: _resource_record_post_action 12:34:40 INFO - [mozharness: 2016-12-10 20:34:40.680738Z] Finished run-tests step (success) 12:34:40 INFO - Running post-run listener: _resource_record_post_run 12:34:40 INFO - Total resource usage - Wall time: 1206s; CPU: 13.0%; Read bytes: 4096; Write bytes: 389779456; Read time: 12; Write time: 237436 12:34:40 INFO - TinderboxPrint: CPU usage
13.1% 12:34:40 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 12:34:40 INFO - TinderboxPrint: I/O write bytes / time
389,779,456 / 237,436 12:34:40 INFO - TinderboxPrint: CPU idle
8,358.1 (86.9%) 12:34:40 INFO - TinderboxPrint: CPU user
1,212.9 (12.6%) 12:34:40 INFO - TinderboxPrint: Swap in / out
0 / 0 12:34:40 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 99819520; Read time: 0; Write time: 76916 12:34:40 INFO - run-tests - Wall time: 1190s; CPU: 13.0%; Read bytes: 0; Write bytes: 289959936; Read time: 0; Write time: 160520 12:34:41 INFO - Running post-run listener: _upload_blobber_files 12:34:41 INFO - Blob upload gear active. 12:34:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:34:41 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 12:34:41 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'] 12:34:41 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 12:34:41 INFO - (blobuploader) - INFO - Open directory for files ... 12:34:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:34:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:34:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:34:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:34:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:34:45 INFO - (blobuploader) - INFO - Done attempting. 12:34:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:34:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:34:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:34:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:34:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:34:46 INFO - (blobuploader) - INFO - Done attempting. 12:34:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:34:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:34:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:34:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:34:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:34:46 INFO - (blobuploader) - INFO - Done attempting. 12:34:46 INFO - (blobuploader) - INFO - Iteration through files over. 12:34:46 INFO - Return code: 0 12:34:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:34:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:34:46 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/63f90aee01faa2c627123e0c8fc2897891bee236701195430ef4a392f0f03a71e0c2492260564e9d431f4ea355198aea1a682f8f6798ecbd45cb44565e87f47b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/3eeeb1f5e1922cb04cb7d798efee0ac74f8b1e4c4fe367199ef9d57f60df0e9dc7fa9e220f5c138b1d73c63c7dc620d6b227044d44fa5c6cbab95b862cb6774d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/16aea6ab2e8c24bdd34512f05993834c2800589936b3b4a9475ff65b5d8c0645ea67653a4cc0d601955abb5a6675a599466dfb5b9b6b0b774771dd5ec5bffa62"} 12:34:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:34:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:34:46 INFO - Contents: 12:34:46 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/63f90aee01faa2c627123e0c8fc2897891bee236701195430ef4a392f0f03a71e0c2492260564e9d431f4ea355198aea1a682f8f6798ecbd45cb44565e87f47b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/3eeeb1f5e1922cb04cb7d798efee0ac74f8b1e4c4fe367199ef9d57f60df0e9dc7fa9e220f5c138b1d73c63c7dc620d6b227044d44fa5c6cbab95b862cb6774d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/16aea6ab2e8c24bdd34512f05993834c2800589936b3b4a9475ff65b5d8c0645ea67653a4cc0d601955abb5a6675a599466dfb5b9b6b0b774771dd5ec5bffa62"} 12:34:46 INFO - Running post-run listener: copy_logs_to_upload_dir 12:34:46 INFO - Copying logs to upload dir... 12:34:46 INFO - mkdir: /builds/slave/test/build/upload/logs 12:34:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.443139 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2016-12-10 12:34:46.732218) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-10 12:34:46.735996) ========= 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=41a7e32711b8e08598a63237000001d6-1481400544.280785-2108051025 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/63f90aee01faa2c627123e0c8fc2897891bee236701195430ef4a392f0f03a71e0c2492260564e9d431f4ea355198aea1a682f8f6798ecbd45cb44565e87f47b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/3eeeb1f5e1922cb04cb7d798efee0ac74f8b1e4c4fe367199ef9d57f60df0e9dc7fa9e220f5c138b1d73c63c7dc620d6b227044d44fa5c6cbab95b862cb6774d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/16aea6ab2e8c24bdd34512f05993834c2800589936b3b4a9475ff65b5d8c0645ea67653a4cc0d601955abb5a6675a599466dfb5b9b6b0b774771dd5ec5bffa62"} build_url:https://queue.taskcluster.net/v1/task/XJ7sCnsBQNuinfnHn-fvtA/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011318 build_url: 'https://queue.taskcluster.net/v1/task/XJ7sCnsBQNuinfnHn-fvtA/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/63f90aee01faa2c627123e0c8fc2897891bee236701195430ef4a392f0f03a71e0c2492260564e9d431f4ea355198aea1a682f8f6798ecbd45cb44565e87f47b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/3eeeb1f5e1922cb04cb7d798efee0ac74f8b1e4c4fe367199ef9d57f60df0e9dc7fa9e220f5c138b1d73c63c7dc620d6b227044d44fa5c6cbab95b862cb6774d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/16aea6ab2e8c24bdd34512f05993834c2800589936b3b4a9475ff65b5d8c0645ea67653a4cc0d601955abb5a6675a599466dfb5b9b6b0b774771dd5ec5bffa62"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-10 12:34:46.761993) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-10 12:34:46.762366) ========= 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=41a7e32711b8e08598a63237000001d6-1481400544.280785-2108051025 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004501 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-10 12:34:46.848398) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-10 12:34:46.848839) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-10 12:34:46.849154) ========= ========= Total master_lag: 0.18 =========