builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-051 starttime: 1478826183.48 results: success (0) buildid: 20161110142519 builduid: 26b43e8e6e164048bbbbb38657aa0623 revision: efcc8dbc5c3904c92b50c1f475dd110bc9ac197a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-10 17:03:03.485134) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-10 17:03:03.485873) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-10 17:03:03.486181) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-10 17:03:03.590316) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-10 17:03:03.590661) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1478826071.42805-465473151 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-10 17:03:04-- 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% 226M=0s 2016-11-10 17:03:04 (226 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.097338 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-10 17:03:03.708609) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-10 17:03:03.708902) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1478826071.42805-465473151 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020310 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-10 17:03:03.792537) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-10 17:03:03.792854) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev efcc8dbc5c3904c92b50c1f475dd110bc9ac197a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev efcc8dbc5c3904c92b50c1f475dd110bc9ac197a --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=5ac5dba3e01a12f1ff77ab15000001b1-1478826071.42805-465473151 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-10 17:03:05,131 truncating revision to first 12 chars 2016-11-10 17:03:05,131 Setting DEBUG logging. 2016-11-10 17:03:05,131 attempt 1/10 2016-11-10 17:03:05,131 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/efcc8dbc5c39?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-10 17:03:05,433 unpacking tar archive at: mozilla-aurora-efcc8dbc5c39/testing/mozharness/ program finished with exit code 0 elapsedTime=0.628790 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-10 17:03:04.438420) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-10 17:03:04.438786) ========= script_repo_revision: efcc8dbc5c3904c92b50c1f475dd110bc9ac197a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-10 17:03:04.439247) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-10 17:03:04.439521) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-10 17:03:04.453310) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-11-10 17:03:04.453643) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5ac5dba3e01a12f1ff77ab15000001b1-1478826071.42805-465473151 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 17:03:05 INFO - MultiFileLogger online at 20161110 17:03:05 in /builds/slave/test 17:03:05 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 17:03:05 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:03:05 INFO - {'append_to_log': False, 17:03:05 INFO - 'base_work_dir': '/builds/slave/test', 17:03:05 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 17:03:05 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:03:05 INFO - 'branch': 'Mozilla-Aurora', 17:03:05 INFO - 'buildbot_json_path': 'buildprops.json', 17:03:05 INFO - 'config_files': ('talos/linux_config.py',), 17:03:05 INFO - 'default_actions': ('clobber', 17:03:05 INFO - 'read-buildbot-config', 17:03:05 INFO - 'download-and-extract', 17:03:05 INFO - 'populate-webroot', 17:03:05 INFO - 'create-virtualenv', 17:03:05 INFO - 'install', 17:03:05 INFO - 'run-tests'), 17:03:05 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:03:05 INFO - 'download_minidump_stackwalk': True, 17:03:05 INFO - 'download_symbols': 'ondemand', 17:03:05 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:03:05 INFO - 'tooltool.py': '/tools/tooltool.py', 17:03:05 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:03:05 INFO - '/tools/misc-python/virtualenv.py')}, 17:03:05 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:03:05 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:03:05 INFO - 'installer_path': 'installer.exe', 17:03:05 INFO - 'log_level': 'info', 17:03:05 INFO - 'log_name': 'talos', 17:03:05 INFO - 'log_to_console': True, 17:03:05 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 17:03:05 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 17:03:05 INFO - 'opt_config_files': (), 17:03:05 INFO - 'pip_index': False, 17:03:05 INFO - 'sps_profile': False, 17:03:05 INFO - 'sps_profile_interval': 0, 17:03:05 INFO - 'suite': 'dromaeojs', 17:03:05 INFO - 'system_bits': '32', 17:03:05 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:03:05 INFO - 'title': 'talos-linux64-ix-051', 17:03:05 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:03:05 INFO - 'use_talos_json': True, 17:03:05 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:03:05 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:03:05 INFO - 'work_dir': 'build'} 17:03:05 INFO - [mozharness: 2016-11-11 01:03:05.889901Z] Running clobber step. 17:03:05 INFO - Running pre-action listener: _resource_record_pre_action 17:03:05 INFO - Running main action method: clobber 17:03:05 INFO - rmtree: /builds/slave/test/build 17:03:05 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:03:06 INFO - Running post-action listener: _resource_record_post_action 17:03:06 INFO - [mozharness: 2016-11-11 01:03:06.867618Z] Finished clobber step (success) 17:03:06 INFO - [mozharness: 2016-11-11 01:03:06.867728Z] Running read-buildbot-config step. 17:03:06 INFO - Running pre-action listener: _resource_record_pre_action 17:03:06 INFO - Running main action method: read_buildbot_config 17:03:06 INFO - Using buildbot properties: 17:03:06 INFO - { 17:03:06 INFO - "project": "", 17:03:06 INFO - "product": "firefox", 17:03:06 INFO - "script_repo_revision": "production", 17:03:06 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 17:03:06 INFO - "repository": "", 17:03:06 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 17:03:06 INFO - "buildid": "20161110142519", 17:03:06 INFO - "pgo_build": "True", 17:03:06 INFO - "basedir": "/builds/slave/test", 17:03:06 INFO - "buildnumber": 577, 17:03:06 INFO - "slavename": "talos-linux64-ix-051", 17:03:06 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 17:03:06 INFO - "platform": "ubuntu64_hw", 17:03:06 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 17:03:06 INFO - "branch": "mozilla-aurora", 17:03:06 INFO - "repo_path": "releases/mozilla-aurora", 17:03:06 INFO - "revision": "efcc8dbc5c3904c92b50c1f475dd110bc9ac197a", 17:03:06 INFO - "stage_platform": "linux64-pgo", 17:03:06 INFO - "builduid": "26b43e8e6e164048bbbbb38657aa0623", 17:03:06 INFO - "slavebuilddir": "test" 17:03:06 INFO - } 17:03:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/VzphWR2ESeavuGTJ7NawUQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 17:03:06 INFO - Running post-action listener: _resource_record_post_action 17:03:06 INFO - [mozharness: 2016-11-11 01:03:06.870676Z] Finished read-buildbot-config step (success) 17:03:06 INFO - [mozharness: 2016-11-11 01:03:06.870764Z] Running download-and-extract step. 17:03:06 INFO - Running pre-action listener: _resource_record_pre_action 17:03:06 INFO - Running main action method: download_and_extract 17:03:06 INFO - mkdir: /builds/slave/test/build/tests 17:03:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:03:06 INFO - trying https://queue.taskcluster.net/v1/task/VzphWR2ESeavuGTJ7NawUQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 17:03:06 INFO - Downloading https://queue.taskcluster.net/v1/task/VzphWR2ESeavuGTJ7NawUQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 17:03:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VzphWR2ESeavuGTJ7NawUQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 17:03:07 INFO - Downloaded 1361 bytes. 17:03:07 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 17:03:07 INFO - Using the following test package requirements: 17:03:07 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 17:03:07 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 17:03:07 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 17:03:07 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 17:03:07 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 17:03:07 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 17:03:07 INFO - u'jsshell-linux-x86_64.zip'], 17:03:07 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 17:03:07 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 17:03:07 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 17:03:07 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 17:03:07 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 17:03:07 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 17:03:07 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 17:03:07 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 17:03:07 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 17:03:07 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 17:03:07 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 17:03:07 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 17:03:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VzphWR2ESeavuGTJ7NawUQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 17:03:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VzphWR2ESeavuGTJ7NawUQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:03:07 INFO - Fetch https://queue.taskcluster.net/v1/task/VzphWR2ESeavuGTJ7NawUQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 17:03:08 INFO - Expected file size: 21896066 17:03:12 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 17:03:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VzphWR2ESeavuGTJ7NawUQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 17:03:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VzphWR2ESeavuGTJ7NawUQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 17:03:12 INFO - Fetch https://queue.taskcluster.net/v1/task/VzphWR2ESeavuGTJ7NawUQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 17:03:13 INFO - Expected file size: 21896066 17:03:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VzphWR2ESeavuGTJ7NawUQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 17:03:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VzphWR2ESeavuGTJ7NawUQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 17:03:17 INFO - Fetch https://queue.taskcluster.net/v1/task/VzphWR2ESeavuGTJ7NawUQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 17:03:18 INFO - Expected file size: 14414089 17:03: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')]} 17:03:18 INFO - trying https://queue.taskcluster.net/v1/task/VzphWR2ESeavuGTJ7NawUQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 17:03:18 INFO - Downloading https://queue.taskcluster.net/v1/task/VzphWR2ESeavuGTJ7NawUQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 17:03:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VzphWR2ESeavuGTJ7NawUQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 17:03:20 INFO - Downloaded 64032980 bytes. 17:03:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VzphWR2ESeavuGTJ7NawUQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 17:03:20 INFO - mkdir: /builds/slave/test/properties 17:03:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:03:20 INFO - Writing to file /builds/slave/test/properties/build_url 17:03:20 INFO - Contents: 17:03:20 INFO - build_url:https://queue.taskcluster.net/v1/task/VzphWR2ESeavuGTJ7NawUQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 17:03:21 INFO - Running post-action listener: _resource_record_post_action 17:03:21 INFO - Running post-action listener: set_extra_try_arguments 17:03:21 INFO - [mozharness: 2016-11-11 01:03:21.153760Z] Finished download-and-extract step (success) 17:03:21 INFO - [mozharness: 2016-11-11 01:03:21.153990Z] Running populate-webroot step. 17:03:21 INFO - Running pre-action listener: _resource_record_pre_action 17:03:21 INFO - Running main action method: populate_webroot 17:03:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:03:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:03:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:03:21 INFO - '--tppagecycles', 17:03:21 INFO - '7'], 17:03:21 INFO - 'tests': ['tsvgm']}}, 17:03:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:03:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:03:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:03:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:03:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:03:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:03:21 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:03:21 INFO - 'talos_options': ['--disable-e10s'], 17:03:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:03:21 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:03:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:03:21 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:03:21 INFO - 'talos_options': ['--disable-e10s'], 17:03:21 INFO - 'tests': ['damp', 'tps']}, 17:03:21 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:03:21 INFO - 'tests': ['damp', 'tps']}, 17:03:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:03:21 INFO - 'tests': ['dromaeo_dom']}, 17:03:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:03:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:03:21 INFO - 'tests': ['basic_compositor_video']}, 17:03:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:03:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:03:21 INFO - 'tests': ['a11yr', 17:03:21 INFO - 'ts_paint', 17:03:21 INFO - 'tpaint', 17:03:21 INFO - 'sessionrestore', 17:03:21 INFO - 'sessionrestore_no_auto_restore', 17:03:21 INFO - 'tabpaint']}, 17:03:21 INFO - 'other-e10s': {'tests': ['a11yr', 17:03:21 INFO - 'ts_paint', 17:03:21 INFO - 'tpaint', 17:03:21 INFO - 'sessionrestore', 17:03:21 INFO - 'sessionrestore_no_auto_restore', 17:03:21 INFO - 'tabpaint']}, 17:03:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:03:21 INFO - 'ts_paint', 17:03:21 INFO - 'tpaint', 17:03:21 INFO - 'sessionrestore', 17:03:21 INFO - 'sessionrestore_no_auto_restore', 17:03:21 INFO - 'tabpaint']}, 17:03:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:03:21 INFO - 'ts_paint', 17:03:21 INFO - 'tpaint', 17:03:21 INFO - 'sessionrestore', 17:03:21 INFO - 'sessionrestore_no_auto_restore', 17:03:21 INFO - 'tabpaint']}, 17:03:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:03:21 INFO - 'tests': ['a11yr', 17:03:21 INFO - 'ts_paint', 17:03:21 INFO - 'tpaint', 17:03:21 INFO - 'sessionrestore', 17:03:21 INFO - 'sessionrestore_no_auto_restore', 17:03:21 INFO - 'tabpaint']}, 17:03:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:03:21 INFO - 'tests': ['a11yr', 17:03:21 INFO - 'ts_paint', 17:03:21 INFO - 'tpaint', 17:03:21 INFO - 'sessionrestore', 17:03:21 INFO - 'sessionrestore_no_auto_restore', 17:03:21 INFO - 'tabpaint']}, 17:03:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:03:21 INFO - 'tests': ['tsvgx', 17:03:21 INFO - 'tsvgr_opacity', 17:03:21 INFO - 'tart', 17:03:21 INFO - 'tscrollx', 17:03:21 INFO - 'cart']}, 17:03:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:03:21 INFO - 'tsvgr_opacity', 17:03:21 INFO - 'tart', 17:03:21 INFO - 'tscrollx', 17:03:21 INFO - 'cart']}, 17:03:21 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:03:21 INFO - 'talos_options': ['--disable-e10s'], 17:03:21 INFO - 'tests': ['tp5o']}, 17:03:21 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:03:21 INFO - 'tests': ['tp5o']}, 17:03:21 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:03:21 INFO - 'talos_options': ['--disable-e10s', 17:03:21 INFO - '--xperf_path', 17:03:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:03:21 INFO - 'tests': ['tp5n']}, 17:03:21 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:03:21 INFO - 'talos_options': ['--xperf_path', 17:03:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:03:21 INFO - 'tests': ['tp5n']}}, 17:03:21 INFO - 'talos.zip': {'path': '', 17:03:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:03:21 INFO - Running post-action listener: _resource_record_post_action 17:03:21 INFO - [mozharness: 2016-11-11 01:03:21.173961Z] Finished populate-webroot step (success) 17:03:21 INFO - [mozharness: 2016-11-11 01:03:21.174050Z] Running create-virtualenv step. 17:03:21 INFO - Running pre-action listener: _resource_record_pre_action 17:03:21 INFO - Running main action method: create_virtualenv 17:03:21 INFO - Creating virtualenv /builds/slave/test/build/venv 17:03:21 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 17:03:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:03:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:03:21 INFO - Using real prefix '/usr' 17:03:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:03:21 INFO - Installing distribute.............................................................................................................................................................................................done. 17:03:23 INFO - Installing pip.................done. 17:03:23 INFO - Return code: 0 17:03:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:03: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')]} 17:03:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:03:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:03: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')]} 17:03:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:03:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:03: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd18ead3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1410ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x140df60>, '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 0x140ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x140f1d0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1478826071.42805-465473151', '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 17:03: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', 'pip>=1.5'] in /builds/slave/test/build 17:03: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 pip>=1.5 17:03:23 INFO - Using env: {'DISPLAY': ':0', 17:03:23 INFO - 'HOME': '/home/cltbld', 17:03:23 INFO - 'LANG': 'en_US.UTF-8', 17:03:23 INFO - 'LANGUAGE': 'en_US:en', 17:03:23 INFO - 'LOGNAME': 'cltbld', 17:03:23 INFO - 'MAIL': '/var/mail/cltbld', 17:03:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:03:23 INFO - 'MOZ_NO_REMOTE': '1', 17:03:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:03:23 INFO - 'NO_EM_RESTART': '1', 17:03:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:03:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:03:23 INFO - 'PWD': '/builds/slave/test', 17:03:23 INFO - 'SHELL': '/bin/bash', 17:03:23 INFO - 'SHLVL': '1', 17:03:23 INFO - 'TERM': 'linux', 17:03:23 INFO - 'TMOUT': '86400', 17:03:23 INFO - 'USER': 'cltbld', 17:03:23 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1478826071.42805-465473151', 17:03:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:03:23 INFO - '_': '/tools/buildbot/bin/python'} 17:03:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:03:23 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 17:03:23 INFO - Cleaning up... 17:03:23 INFO - Return code: 0 17:03:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:03: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')]} 17:03:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:03:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:03: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')]} 17:03:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:03:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:03: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', '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 0x7fd18ead3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1410ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x140df60>, '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 0x140ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x140f1d0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1478826071.42805-465473151', '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 17:03: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', 'psutil>=3.1.1'] in /builds/slave/test/build 17:03: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 psutil>=3.1.1 17:03:23 INFO - Using env: {'DISPLAY': ':0', 17:03:23 INFO - 'HOME': '/home/cltbld', 17:03:23 INFO - 'LANG': 'en_US.UTF-8', 17:03:23 INFO - 'LANGUAGE': 'en_US:en', 17:03:23 INFO - 'LOGNAME': 'cltbld', 17:03:23 INFO - 'MAIL': '/var/mail/cltbld', 17:03:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:03:23 INFO - 'MOZ_NO_REMOTE': '1', 17:03:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:03:23 INFO - 'NO_EM_RESTART': '1', 17:03:23 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:03:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:03:23 INFO - 'PWD': '/builds/slave/test', 17:03:23 INFO - 'SHELL': '/bin/bash', 17:03:23 INFO - 'SHLVL': '1', 17:03:23 INFO - 'TERM': 'linux', 17:03:23 INFO - 'TMOUT': '86400', 17:03:23 INFO - 'USER': 'cltbld', 17:03:23 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1478826071.42805-465473151', 17:03:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:03:23 INFO - '_': '/tools/buildbot/bin/python'} 17:03:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:03:24 INFO - Downloading/unpacking psutil>=3.1.1 17:03:24 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 17:03:24 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 17:03:24 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 17:03:24 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 17:03:24 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 17:03:24 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 17:03:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:03:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:03:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:03:26 INFO - Installing collected packages: psutil 17:03:26 INFO - Running setup.py install for psutil 17:03:26 INFO - building 'psutil._psutil_linux' extension 17:03:26 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 17:03:26 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 17:03:26 INFO - building 'psutil._psutil_posix' extension 17:03:26 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 17:03:26 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 17:03:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:03:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:03:26 INFO - Successfully installed psutil 17:03:26 INFO - Cleaning up... 17:03:26 INFO - Return code: 0 17:03:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:03: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')]} 17:03:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:03:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:03: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')]} 17:03:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:03:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:03:26 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 0x7fd18ead3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1410ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x140df60>, '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 0x140ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x140f1d0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1478826071.42805-465473151', '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 17:03:26 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 17:03:26 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 17:03:26 INFO - Using env: {'DISPLAY': ':0', 17:03:26 INFO - 'HOME': '/home/cltbld', 17:03:26 INFO - 'LANG': 'en_US.UTF-8', 17:03:26 INFO - 'LANGUAGE': 'en_US:en', 17:03:26 INFO - 'LOGNAME': 'cltbld', 17:03:26 INFO - 'MAIL': '/var/mail/cltbld', 17:03:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:03:26 INFO - 'MOZ_NO_REMOTE': '1', 17:03:26 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:03:26 INFO - 'NO_EM_RESTART': '1', 17:03:26 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:03:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:03:26 INFO - 'PWD': '/builds/slave/test', 17:03:26 INFO - 'SHELL': '/bin/bash', 17:03:26 INFO - 'SHLVL': '1', 17:03:26 INFO - 'TERM': 'linux', 17:03:26 INFO - 'TMOUT': '86400', 17:03:26 INFO - 'USER': 'cltbld', 17:03:26 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1478826071.42805-465473151', 17:03:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:03:26 INFO - '_': '/tools/buildbot/bin/python'} 17:03:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:03:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:03: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 17:03: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 17:03: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 17:03: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 17:03: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 17:03: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 17:03:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:03:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:03:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:03:28 INFO - Installing collected packages: mozsystemmonitor 17:03:28 INFO - Running setup.py install for mozsystemmonitor 17:03:29 INFO - Successfully installed mozsystemmonitor 17:03:29 INFO - Cleaning up... 17:03:29 INFO - Return code: 0 17:03:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:03:29 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')]} 17:03:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:03:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:03:29 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')]} 17:03:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:03:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:03:29 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 0x7fd18ead3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1410ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x140df60>, '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 0x140ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x140f1d0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1478826071.42805-465473151', '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 17:03:29 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 17:03:29 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 17:03:29 INFO - Using env: {'DISPLAY': ':0', 17:03:29 INFO - 'HOME': '/home/cltbld', 17:03:29 INFO - 'LANG': 'en_US.UTF-8', 17:03:29 INFO - 'LANGUAGE': 'en_US:en', 17:03:29 INFO - 'LOGNAME': 'cltbld', 17:03:29 INFO - 'MAIL': '/var/mail/cltbld', 17:03:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:03:29 INFO - 'MOZ_NO_REMOTE': '1', 17:03:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:03:29 INFO - 'NO_EM_RESTART': '1', 17:03:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:03:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:03:29 INFO - 'PWD': '/builds/slave/test', 17:03:29 INFO - 'SHELL': '/bin/bash', 17:03:29 INFO - 'SHLVL': '1', 17:03:29 INFO - 'TERM': 'linux', 17:03:29 INFO - 'TMOUT': '86400', 17:03:29 INFO - 'USER': 'cltbld', 17:03:29 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1478826071.42805-465473151', 17:03:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:03:29 INFO - '_': '/tools/buildbot/bin/python'} 17:03:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:03:29 INFO - Downloading/unpacking jsonschema==2.5.1 17:03:29 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 17:03:29 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 17:03:29 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 17:03:29 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 17:03:29 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 17:03:29 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 17:03:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:03:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:03: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 17:03: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 17:03: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 17:03: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 17:03: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 17:03: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 17:03:31 INFO - Downloading functools32-3.2.3-2.tar.gz 17:03:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:03:31 INFO - warning: no files found matching '*.txt' 17:03:31 INFO - no previously-included directories found matching 'build' 17:03:31 INFO - no previously-included directories found matching 'dist' 17:03:31 INFO - no previously-included directories found matching '.git*' 17:03:31 INFO - Installing collected packages: jsonschema, functools32 17:03:31 INFO - Running setup.py install for functools32 17:03:31 INFO - warning: no files found matching '*.txt' 17:03:31 INFO - no previously-included directories found matching 'build' 17:03:31 INFO - no previously-included directories found matching 'dist' 17:03:31 INFO - no previously-included directories found matching '.git*' 17:03:31 INFO - Successfully installed jsonschema functools32 17:03:31 INFO - Cleaning up... 17:03:31 INFO - Return code: 0 17:03:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:03:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:03:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:03:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:03:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:03:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:03:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:03:31 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 0x7fd18ead3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1410ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x140df60>, '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 0x140ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x140f1d0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1478826071.42805-465473151', '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 17:03:31 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 17:03:31 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 17:03:31 INFO - Using env: {'DISPLAY': ':0', 17:03:31 INFO - 'HOME': '/home/cltbld', 17:03:31 INFO - 'LANG': 'en_US.UTF-8', 17:03:31 INFO - 'LANGUAGE': 'en_US:en', 17:03:31 INFO - 'LOGNAME': 'cltbld', 17:03:31 INFO - 'MAIL': '/var/mail/cltbld', 17:03:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:03:31 INFO - 'MOZ_NO_REMOTE': '1', 17:03:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:03:31 INFO - 'NO_EM_RESTART': '1', 17:03:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:03:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:03:31 INFO - 'PWD': '/builds/slave/test', 17:03:31 INFO - 'SHELL': '/bin/bash', 17:03:31 INFO - 'SHLVL': '1', 17:03:31 INFO - 'TERM': 'linux', 17:03:31 INFO - 'TMOUT': '86400', 17:03:31 INFO - 'USER': 'cltbld', 17:03:31 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1478826071.42805-465473151', 17:03:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:03:31 INFO - '_': '/tools/buildbot/bin/python'} 17:03:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:03:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:03:31 INFO - Cleaning up... 17:03:31 INFO - Return code: 0 17:03:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:03:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:03:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:03:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:03:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:03:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:03:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:03:31 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 0x7fd18ead3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1410ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x140df60>, '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 0x140ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x140f1d0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1478826071.42805-465473151', '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 17:03:31 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 17:03:31 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 17:03:31 INFO - Using env: {'DISPLAY': ':0', 17:03:31 INFO - 'HOME': '/home/cltbld', 17:03:31 INFO - 'LANG': 'en_US.UTF-8', 17:03:31 INFO - 'LANGUAGE': 'en_US:en', 17:03:31 INFO - 'LOGNAME': 'cltbld', 17:03:31 INFO - 'MAIL': '/var/mail/cltbld', 17:03:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:03:31 INFO - 'MOZ_NO_REMOTE': '1', 17:03:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:03:31 INFO - 'NO_EM_RESTART': '1', 17:03:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:03:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:03:31 INFO - 'PWD': '/builds/slave/test', 17:03:31 INFO - 'SHELL': '/bin/bash', 17:03:31 INFO - 'SHLVL': '1', 17:03:31 INFO - 'TERM': 'linux', 17:03:31 INFO - 'TMOUT': '86400', 17:03:31 INFO - 'USER': 'cltbld', 17:03:31 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1478826071.42805-465473151', 17:03:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:03:31 INFO - '_': '/tools/buildbot/bin/python'} 17:03:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:03:32 INFO - Downloading/unpacking blobuploader==1.2.4 17:03:32 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 17:03:32 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 17:03:32 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 17:03:32 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 17:03:32 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 17:03:32 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 17:03:34 INFO - Downloading blobuploader-1.2.4.tar.gz 17:03:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:03:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:03:34 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 17:03:34 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 17:03:34 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 17:03:34 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 17:03:34 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 17:03:34 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 17:03:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:03:34 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:03:34 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 17:03:34 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 17:03:34 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 17:03:34 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 17:03:34 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 17:03:34 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 17:03:34 INFO - Downloading docopt-0.6.1.tar.gz 17:03:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:03:34 INFO - Installing collected packages: blobuploader, requests, docopt 17:03:34 INFO - Running setup.py install for blobuploader 17:03:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:03:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:03:34 INFO - Running setup.py install for requests 17:03:35 INFO - Running setup.py install for docopt 17:03:35 INFO - Successfully installed blobuploader requests docopt 17:03:35 INFO - Cleaning up... 17:03:35 INFO - Return code: 0 17:03:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:03:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:03:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:03:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:03:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:03:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:03:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:03:35 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 0x7fd18ead3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1410ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x140df60>, '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 0x140ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x140f1d0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1478826071.42805-465473151', '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 17:03:35 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 17:03:35 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 17:03:35 INFO - Using env: {'DISPLAY': ':0', 17:03:35 INFO - 'HOME': '/home/cltbld', 17:03:35 INFO - 'LANG': 'en_US.UTF-8', 17:03:35 INFO - 'LANGUAGE': 'en_US:en', 17:03:35 INFO - 'LOGNAME': 'cltbld', 17:03:35 INFO - 'MAIL': '/var/mail/cltbld', 17:03:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:03:35 INFO - 'MOZ_NO_REMOTE': '1', 17:03:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:03:35 INFO - 'NO_EM_RESTART': '1', 17:03:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:03:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:03:35 INFO - 'PWD': '/builds/slave/test', 17:03:35 INFO - 'SHELL': '/bin/bash', 17:03:35 INFO - 'SHLVL': '1', 17:03:35 INFO - 'TERM': 'linux', 17:03:35 INFO - 'TMOUT': '86400', 17:03:35 INFO - 'USER': 'cltbld', 17:03:35 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1478826071.42805-465473151', 17:03:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:03:35 INFO - '_': '/tools/buildbot/bin/python'} 17:03:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:03:35 INFO - Running setup.py (path:/tmp/pip-a7hdbM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:03:35 INFO - Running setup.py (path:/tmp/pip-OagAlL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:03:35 INFO - Running setup.py (path:/tmp/pip-WTj8vN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:03:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:03:35 INFO - Running setup.py (path:/tmp/pip-XW66Id-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:03:36 INFO - Running setup.py (path:/tmp/pip-W3QfyS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:03:36 INFO - Running setup.py (path:/tmp/pip-BPjogn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:03:36 INFO - Running setup.py (path:/tmp/pip-xdiCJI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:03:36 INFO - Running setup.py (path:/tmp/pip-bFWM_w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:03:36 INFO - Running setup.py (path:/tmp/pip-nzjPVB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:03:36 INFO - Running setup.py (path:/tmp/pip-A5paWX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:03:36 INFO - Running setup.py (path:/tmp/pip-sNKDYn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:03:36 INFO - Running setup.py (path:/tmp/pip-i46Z7h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:03:36 INFO - Running setup.py (path:/tmp/pip-3vRH8A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:03:36 INFO - Running setup.py (path:/tmp/pip-GVqGak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:03:36 INFO - Running setup.py (path:/tmp/pip-bH6avF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:03:36 INFO - Running setup.py (path:/tmp/pip-jaXbM4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:03:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:03:36 INFO - Running setup.py (path:/tmp/pip-OCfcUx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:03:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:03:36 INFO - Running setup.py install for manifestparser 17:03:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:03:37 INFO - Running setup.py install for mozcrash 17:03:37 INFO - Running setup.py install for mozdebug 17:03:37 INFO - Running setup.py install for mozdevice 17:03:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:03:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:03:37 INFO - Running setup.py install for mozfile 17:03:37 INFO - Running setup.py install for mozhttpd 17:03:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:03:37 INFO - Running setup.py install for mozinfo 17:03:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:03:37 INFO - Running setup.py install for mozInstall 17:03:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:03:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:03:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:03:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:03:37 INFO - Running setup.py install for mozleak 17:03:37 INFO - Running setup.py install for mozlog 17:03:37 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:03:37 INFO - Running setup.py install for moznetwork 17:03:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:03:38 INFO - Running setup.py install for mozprocess 17:03:38 INFO - Running setup.py install for mozprofile 17:03:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:03:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:03:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:03:38 INFO - Running setup.py install for mozrunner 17:03:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:03:38 INFO - Running setup.py install for mozscreenshot 17:03:38 INFO - Running setup.py install for moztest 17:03:38 INFO - Running setup.py install for mozversion 17:03:38 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:03:38 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:03:38 INFO - Cleaning up... 17:03:38 INFO - Return code: 0 17:03:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:03:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:03:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:03:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:03:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:03:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:03:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:03:38 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 0x7fd18ead3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1410ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x140df60>, '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 0x140ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x140f1d0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1478826071.42805-465473151', '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 17:03:38 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 17:03:38 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 17:03:38 INFO - Using env: {'DISPLAY': ':0', 17:03:38 INFO - 'HOME': '/home/cltbld', 17:03:38 INFO - 'LANG': 'en_US.UTF-8', 17:03:38 INFO - 'LANGUAGE': 'en_US:en', 17:03:38 INFO - 'LOGNAME': 'cltbld', 17:03:38 INFO - 'MAIL': '/var/mail/cltbld', 17:03:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:03:38 INFO - 'MOZ_NO_REMOTE': '1', 17:03:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:03:38 INFO - 'NO_EM_RESTART': '1', 17:03:38 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:03:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:03:38 INFO - 'PWD': '/builds/slave/test', 17:03:38 INFO - 'SHELL': '/bin/bash', 17:03:38 INFO - 'SHLVL': '1', 17:03:38 INFO - 'TERM': 'linux', 17:03:38 INFO - 'TMOUT': '86400', 17:03:38 INFO - 'USER': 'cltbld', 17:03:38 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1478826071.42805-465473151', 17:03:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:03:38 INFO - '_': '/tools/buildbot/bin/python'} 17:03:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:03:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:03:38 INFO - Running setup.py (path:/tmp/pip-7Kk6xM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:03:39 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)) 17:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:03:39 INFO - Running setup.py (path:/tmp/pip-t2hURj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:03:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:03:39 INFO - Running setup.py (path:/tmp/pip-RXb4V4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:03:39 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)) 17:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:03:39 INFO - Running setup.py (path:/tmp/pip-BKR9UN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:03:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:03:39 INFO - Running setup.py (path:/tmp/pip-MUEShO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:03:39 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)) 17:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:03:39 INFO - Running setup.py (path:/tmp/pip-d7iRv5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:03:39 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)) 17:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:03:39 INFO - Running setup.py (path:/tmp/pip-1Q57C4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:03:39 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)) 17:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:03:39 INFO - Running setup.py (path:/tmp/pip-s6fEZq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:03:39 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)) 17:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:03:39 INFO - Running setup.py (path:/tmp/pip-e1UgYW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:03:39 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)) 17:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:03:39 INFO - Running setup.py (path:/tmp/pip-eQ2Mnk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:03:39 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)) 17:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:03:39 INFO - Running setup.py (path:/tmp/pip-dIJohJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:03:39 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)) 17:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:03:39 INFO - Running setup.py (path:/tmp/pip-o7rE6P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:03:39 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)) 17:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:03:39 INFO - Running setup.py (path:/tmp/pip-7Dcjkz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:03:39 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)) 17:03:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:03:39 INFO - Running setup.py (path:/tmp/pip-pgQFDc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:03:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 17:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:03:40 INFO - Running setup.py (path:/tmp/pip-2nIyvW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:03:40 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)) 17:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:03:40 INFO - Running setup.py (path:/tmp/pip-Wptj_Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:03:40 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)) 17:03:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:03:40 INFO - Running setup.py (path:/tmp/pip-MlYLOS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:03:40 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)) 17:03:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:03:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:03:40 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)) 17:03:40 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:03:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:03:40 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:03:40 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 17:03:40 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 17:03:40 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 17:03:40 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 17:03:40 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 17:03:40 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 17:03:42 INFO - Downloading blessings-1.6.tar.gz 17:03:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:03:42 INFO - Installing collected packages: blessings 17:03:42 INFO - Running setup.py install for blessings 17:03:42 INFO - Successfully installed blessings 17:03:42 INFO - Cleaning up... 17:03:42 INFO - Return code: 0 17:03:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:03:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:03:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:03:42 INFO - Reading from file tmpfile_stdout 17:03:42 INFO - Current package versions: 17:03:42 INFO - argparse == 1.2.1 17:03:42 INFO - blessings == 1.6 17:03:42 INFO - blobuploader == 1.2.4 17:03:42 INFO - docopt == 0.6.1 17:03:42 INFO - functools32 == 3.2.3-2 17:03:42 INFO - jsonschema == 2.5.1 17:03:42 INFO - manifestparser == 1.1 17:03:42 INFO - mozInstall == 1.12 17:03:42 INFO - mozcrash == 0.17 17:03:42 INFO - mozdebug == 0.1 17:03:42 INFO - mozdevice == 0.48 17:03:42 INFO - mozfile == 1.2 17:03:42 INFO - mozhttpd == 0.7 17:03:42 INFO - mozinfo == 0.9 17:03:42 INFO - mozleak == 0.1 17:03:42 INFO - mozlog == 3.4 17:03:42 INFO - moznetwork == 0.27 17:03:42 INFO - mozprocess == 0.23 17:03:42 INFO - mozprofile == 0.28 17:03:42 INFO - mozrunner == 6.12 17:03:42 INFO - mozscreenshot == 0.1 17:03:42 INFO - mozsystemmonitor == 0.3 17:03:42 INFO - moztest == 0.7 17:03:42 INFO - mozversion == 1.4 17:03:42 INFO - psutil == 3.1.1 17:03:42 INFO - requests == 1.2.3 17:03:42 INFO - wsgiref == 0.1.2 17:03:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:03:42 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')]} 17:03:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:03:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:03:42 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')]} 17:03:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:03:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:03:42 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 0x7fd18ead3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1410ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x140df60>, '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 0x140ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x140f1d0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1478826071.42805-465473151', '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 17:03:42 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 17:03:42 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 17:03:42 INFO - Using env: {'DISPLAY': ':0', 17:03:42 INFO - 'HOME': '/home/cltbld', 17:03:42 INFO - 'LANG': 'en_US.UTF-8', 17:03:42 INFO - 'LANGUAGE': 'en_US:en', 17:03:42 INFO - 'LOGNAME': 'cltbld', 17:03:42 INFO - 'MAIL': '/var/mail/cltbld', 17:03:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:03:42 INFO - 'MOZ_NO_REMOTE': '1', 17:03:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:03:42 INFO - 'NO_EM_RESTART': '1', 17:03:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:03:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:03:42 INFO - 'PWD': '/builds/slave/test', 17:03:42 INFO - 'SHELL': '/bin/bash', 17:03:42 INFO - 'SHLVL': '1', 17:03:42 INFO - 'TERM': 'linux', 17:03:42 INFO - 'TMOUT': '86400', 17:03:42 INFO - 'USER': 'cltbld', 17:03:42 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1478826071.42805-465473151', 17:03:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:03:42 INFO - '_': '/tools/buildbot/bin/python'} 17:03:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:03:42 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)) 17:03:42 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)) 17:03:42 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)) 17:03:42 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)) 17:03:42 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)) 17:03:42 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)) 17:03:42 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)) 17:03:42 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)) 17:03:42 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)) 17:03:42 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)) 17:03:42 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)) 17:03:42 INFO - Cleaning up... 17:03:42 INFO - Return code: 0 17:03:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:03:42 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')]} 17:03:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:03:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:03:42 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')]} 17:03:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:03:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:03:42 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 0x7fd18ead3f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x13438a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1410ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x140df60>, '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 0x140ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x140f1d0>, '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': '5ac5dba3e01a12f1ff77ab15000001b1-1478826071.42805-465473151', '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 17:03:42 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 17:03:42 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 17:03:42 INFO - Using env: {'DISPLAY': ':0', 17:03:42 INFO - 'HOME': '/home/cltbld', 17:03:42 INFO - 'LANG': 'en_US.UTF-8', 17:03:42 INFO - 'LANGUAGE': 'en_US:en', 17:03:42 INFO - 'LOGNAME': 'cltbld', 17:03:42 INFO - 'MAIL': '/var/mail/cltbld', 17:03:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:03:42 INFO - 'MOZ_NO_REMOTE': '1', 17:03:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:03:42 INFO - 'NO_EM_RESTART': '1', 17:03:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:03:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:03:42 INFO - 'PWD': '/builds/slave/test', 17:03:42 INFO - 'SHELL': '/bin/bash', 17:03:42 INFO - 'SHLVL': '1', 17:03:42 INFO - 'TERM': 'linux', 17:03:42 INFO - 'TMOUT': '86400', 17:03:42 INFO - 'USER': 'cltbld', 17:03:42 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1478826071.42805-465473151', 17:03:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:03:42 INFO - '_': '/tools/buildbot/bin/python'} 17:03:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:03:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:03:43 INFO - Cleaning up... 17:03:43 INFO - Return code: 0 17:03:43 INFO - Running post-action listener: _resource_record_post_action 17:03:43 INFO - Running post-action listener: _start_resource_monitoring 17:03:43 INFO - Starting resource monitoring. 17:03:43 INFO - [mozharness: 2016-11-11 01:03:43.116949Z] Finished create-virtualenv step (success) 17:03:43 INFO - [mozharness: 2016-11-11 01:03:43.117406Z] Running install step. 17:03:43 INFO - Running pre-action listener: _resource_record_pre_action 17:03:43 INFO - Running main action method: install 17:03:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:03:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:03:43 INFO - Reading from file tmpfile_stdout 17:03:43 INFO - Detecting whether we're running mozinstall >=1.0... 17:03:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:03:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:03:43 INFO - Reading from file tmpfile_stdout 17:03:43 INFO - Output received: 17:03:43 INFO - Usage: mozinstall [options] installer 17:03:43 INFO - Options: 17:03:43 INFO - -h, --help show this help message and exit 17:03:43 INFO - -d DEST, --destination=DEST 17:03:43 INFO - Directory to install application into. [default: 17:03:43 INFO - "/builds/slave/test"] 17:03:43 INFO - --app=APP Application being installed. [default: firefox] 17:03:43 INFO - mkdir: /builds/slave/test/build/application 17:03:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 17:03:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 17:03:59 INFO - Reading from file tmpfile_stdout 17:03:59 INFO - Output received: 17:03:59 INFO - /builds/slave/test/build/application/firefox/firefox 17:03:59 INFO - Running post-action listener: _resource_record_post_action 17:03:59 INFO - [mozharness: 2016-11-11 01:03:59.394880Z] Finished install step (success) 17:03:59 INFO - [mozharness: 2016-11-11 01:03:59.395125Z] Running run-tests step. 17:03:59 INFO - Running pre-action listener: _resource_record_pre_action 17:03:59 INFO - Running main action method: run_tests 17:03:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:03:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:03:59 INFO - Python 2.7.3 17:03:59 INFO - Return code: 0 17:03:59 INFO - grabbing minidump binary from tooltool 17:03:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:03:59 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/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 0x140df60>, '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 0x140ed40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x140f1d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:03:59 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 17:03:59 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 17:03:59 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 17:03:59 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:03:59 INFO - Return code: 0 17:03:59 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 17:03:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:03:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:03:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 17:03:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:03:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VzphWR2ESeavuGTJ7NawUQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--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 17:03:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VzphWR2ESeavuGTJ7NawUQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-051 --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 17:03:59 INFO - Using env: {'DISPLAY': ':0', 17:03:59 INFO - 'HOME': '/home/cltbld', 17:03:59 INFO - 'LANG': 'en_US.UTF-8', 17:03:59 INFO - 'LANGUAGE': 'en_US:en', 17:03:59 INFO - 'LOGNAME': 'cltbld', 17:03:59 INFO - 'MAIL': '/var/mail/cltbld', 17:03:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:03:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 17:03:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:03:59 INFO - 'MOZ_NO_REMOTE': '1', 17:03:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:03:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 17:03:59 INFO - 'NO_EM_RESTART': '1', 17:03:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 17:03:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:03:59 INFO - 'PWD': '/builds/slave/test', 17:03:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:03:59 INFO - 'SHELL': '/bin/bash', 17:03:59 INFO - 'SHLVL': '1', 17:03:59 INFO - 'TERM': 'linux', 17:03:59 INFO - 'TMOUT': '86400', 17:03:59 INFO - 'USER': 'cltbld', 17:03:59 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1478826071.42805-465473151', 17:03:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:03:59 INFO - '_': '/tools/buildbot/bin/python'} 17:03:59 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VzphWR2ESeavuGTJ7NawUQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-051', '--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 17:03:59 INFO - mozversion application_buildid: 20161110142519 17:03:59 INFO - mozversion application_changeset: efcc8dbc5c3904c92b50c1f475dd110bc9ac197a 17:03:59 INFO - mozversion application_display_name: FirefoxDeveloperEdition 17:03:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:03:59 INFO - mozversion application_name: Firefox 17:03:59 INFO - mozversion application_remotingname: firefox-dev 17:03:59 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:03:59 INFO - mozversion application_vendor: Mozilla 17:03:59 INFO - mozversion application_version: 51.0a2 17:03:59 INFO - mozversion platform_buildid: 20161110142519 17:03:59 INFO - mozversion platform_changeset: efcc8dbc5c3904c92b50c1f475dd110bc9ac197a 17:03:59 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:03:59 INFO - mozversion platform_version: 51.0a2 17:03:59 INFO - using testdate: 1478826239 17:03:59 INFO - actual date: 1478826239 17:03:59 INFO - starting webserver on 'localhost:45109' 17:03:59 INFO - SUITE-START | Running 2 tests 17:03:59 INFO - TEST-START | dromaeo_css 17:03:59 INFO - operating with platform_type : linux_ 17:03:59 INFO - Initialising browser for dromaeo_css test... 17:03:59 INFO - TEST-INFO | started process 26979 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzGyiLd/profile http://localhost:45109/getInfo.html) 17:04:03 INFO - PROCESS | 26979 | __metrics Screen width/height:1600/1200 17:04:03 INFO - PROCESS | 26979 | colorDepth:24 17:04:03 INFO - PROCESS | 26979 | Browser inner width/height: 1024/697 17:04:03 INFO - PROCESS | 26979 | __metrics 17:04:04 INFO - PROCESS | 26979 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:04:04 INFO - PROCESS | 26979 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:04:04 INFO - PROCESS | 26979 | console.error: PushService: 17:04:04 INFO - PROCESS | 26979 | stateChangeProcessEnqueue: Error transitioning state 17:04:04 INFO - PROCESS | 26979 | UnknownError 17:04:05 INFO - TEST-INFO | 26979: exit 0 17:04:05 INFO - Browser initialized. 17:04:05 INFO - Running cycle 1/1 for dromaeo_css test... 17:04:05 INFO - TEST-INFO | started process 27067 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzGyiLd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:04:16 INFO - PROCESS | 27067 | RSS: Main: 144785408 17:04:16 INFO - PROCESS | 27067 | 17:05:08 INFO - PROCESS | 27067 | 1478826308142 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] 17:07:17 INFO - PROCESS | 27067 | Cycle 1(1): loaded http://localhost:45109/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45109/tests/dromaeo/cssquery-ext.html) 17:07:18 INFO - PROCESS | 27067 | RSS: Main: 246435840 17:07:18 INFO - PROCESS | 27067 | 17:10:20 INFO - PROCESS | 27067 | Cycle 1(1): loaded http://localhost:45109/tests/dromaeo/cssquery-ext.html (next: http://localhost:45109/tests/dromaeo/cssquery-jquery.html) 17:10:21 INFO - PROCESS | 27067 | RSS: Main: 228106240 17:10:21 INFO - PROCESS | 27067 | 17:13:23 INFO - PROCESS | 27067 | Cycle 1(1): loaded http://localhost:45109/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45109/tests/dromaeo/cssquery-mootools.html) 17:13:23 INFO - PROCESS | 27067 | RSS: Main: 176340992 17:13:23 INFO - PROCESS | 27067 | 17:16:25 INFO - PROCESS | 27067 | Cycle 1(1): loaded http://localhost:45109/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45109/tests/dromaeo/cssquery-prototype.html) 17:16:26 INFO - PROCESS | 27067 | RSS: Main: 173645824 17:16:26 INFO - PROCESS | 27067 | 17:19:28 INFO - PROCESS | 27067 | Cycle 1(1): loaded http://localhost:45109/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45109/tests/dromaeo/cssquery-yui.html) 17:19:29 INFO - PROCESS | 27067 | RSS: Main: 175157248 17:19:29 INFO - PROCESS | 27067 | 17:22:31 INFO - PROCESS | 27067 | Cycle 1(1): loaded http://localhost:45109/tests/dromaeo/cssquery-yui.html (next: http://localhost:45109/tests/dromaeo/cssquery-dojo.html) 17:22:32 INFO - PROCESS | 27067 | RSS: Main: 266719232 17:22:32 INFO - PROCESS | 27067 | 17:22:32 INFO - PROCESS | 27067 | __start_tp_report 17:22:32 INFO - PROCESS | 27067 | _x_x_mozilla_page_load 17:22:32 INFO - PROCESS | 27067 | _x_x_mozilla_page_load_details 17:22:32 INFO - PROCESS | 27067 | |i|pagename|runs| 17:22:32 INFO - PROCESS | 27067 | |0;dojo.html;3095.613048368954;3105.844707764612;3113.4551453495633;3122.609673790776;3124.89062882799;5491.066518691822;5561.805336813212;5591.720413979305;5598.916016259749;5620.241267428901;5559.305086864142;5608.859778505539;5613.494785469307;5615.2138700581845;5631.57763167763;5052.065367906931;5075.9492405076035;5110.744462776865;5155.5360017598405;5158.845234642967;4918.434380046305;4940.728259945701;5123.257127716479;5172.870678233036;5177.585793136559;4967.05625931072;4968.975155124219;4995.525425084629;4998.325226094467;5050.419201791786;5054.166062599685;5059.367579052619;5121.897562048775;5126;5157.9941911327305;4806.0628177505405;5008.12357837377;5036.571891389236;5074.314967479417;5098.796048158069;5329.706866122362;5403.054465468527;5418.756155972991;5428.0500912340185;5498.560115190775;5858.238429004245;5874.647521148702;5960.940390596125;5962.821115366546;5965.701714914237;5801.9129713054335;5950.9107363389585;5981;5994.550408719363;5994.940050599525;5369.516743493065;5404.83785486436;5420;5437.918431223535;5478.01395748765;5911.704414779244;5932.614380065368;5947.82156535296;5951.315598706117;5951.702414879325;5675.1487276907965;5676.68778217202;5680.573956953245;5684.63049901755;5684.715764211773;5806.65160090396;5823.242978412779;5845.357010728816;5846.766129354864;5917.408259174048;5835.066389377679;5859.619124756877;5865.912011319833;5868.178455016301;5872.295324560994;5844.649321040752;5892.558058145656;5900.350961394243;5906.527477801766;5925.555583331267;4391.912161756747;4435.090806384684;4453.131639330364;4482.103579284156;4488.910221795546;4009.4787677602308;4044.5550989391145;4056.533498647634;4074.1851629673592;4080.959190408117;5697.202391665171;5711.8000869969355;5714.228579141846;5714.628549144292;5752.108423194411;5803.883922321615;5838.241028666246;5856.443637854397;5861.443162899518;6010.398960103955;5634.464725851038;5636;5638.661680299195;5660.915086273709;5674.744636491368;4982.451930287665;4982.551570358685;4988.925166122511;4989.700617962933;5020;5851.912221316684;6004.099385092271;6127.325994140641;6133.417325354084;6153.1693221415435;5974.163617093697;6015.518758499397;6017.488513476404;6051.939480605138;6055.091736239599;6219.844503887439;6238.781642642486;6243.7814676486105;6244.250689917239;6268.310485846644;5622.184783206494;5643.463870932255;5872.119182122716;5912.733926973383;5917.733701983335;5868.3251426086545;5888;5904.9704751475965;5919.200907877382;5982.820515384546;5790.276215473066;5797.275340582427;5798.913016304843;5804.2744656917885;5804.709764511673;5622.8313150605545;5782.479576838105;5783.392743761844;5793.797217097382;5794.015017447128;5562.332520097614;5694.231278777478;5739.368669446274;5754.971225143848;5841;5828.766849326149;5873.5594830388745;5891.1752354669525;5907.025340818888;5911.379305172895;5580.804671836466;5645.350784659652;5739.368669446274;5760.481556659921;5762.4525670061275 17:22:32 INFO - PROCESS | 27067 | |1;ext.html;593.691280534122;622.4273668225234;645.4674893213095;651.6220592056608;658.6410406328549;34722;47295;48288.51711482884;48344.27483587749;48357.75821120898;48378;48822.755886220606;48824.75587622066;48873;48914.26628600565;8567.271781898515;9472.289578281621;9590.568424420882;9604.711858644218;9607.27945404094;8479.74560763175;11106.167037472182;11359.261647992855;11376.829347559793;11674;11545;11595.478203480821;11598.768024639503;11610.825837612443;11623.12826538013;11423.428828558539;11485.540578376855;11498.71253218672;11503.424828758529;11504.02215811657;11143.665690029313;11209.495572699248;11210.887891121147;11217.551297948072;11226.15803814709;45129.54870451286;45408.54591454109;45430;45490.545094548965;45495;3704.1480459494196;3712.9133087670066;3796.3356412627954;3828.3109040372724;3980.960190398088;33930.90950728594;35008;35297.1175720609;35813.82093089518;36193.81903090468;4023.2355852387955;4085.795710214507;4170.666346692257;4204.62158405745;4226.598473145046;375380;379043;379568;379900;380371;66215.33784662189;66564;66621.66689166523;66673.66663166654;66804.33195668078;40513.18973620511;41530.79234603868;41598;43764;43860;18964.241430342907;19193;19194.904025479784;19203.423897283104;19203.615927681647;13198.472061117447;13438.798418023738;13925.442982280596;14728.631784205267;14899;20535.075921583564;20733.792662073487;20743.377698669065;20965.68551471729;20994.265200717902;7475.278059650952;7502.437317201123;7534.886976695354;7578.924210757822;7673.700308867129;1036.3729943384294;1051.642263553633;1125.971850703739;1172.6716519374588;1180.1326025371345;34442.82778586141;34564;34661.65338346635;34728.47907281393;34946.650533494845;1151.2804497189256;1158.3108050709814;1174.8590169179581;1195.946182421793;1234.2841152131741;17459.30162793474;18290.451286461426;18381.448556543324;18412.907935460236;18516.351927682466;612633;618176;619880;620267;620786;63934;64060;64126.67936660287;64188.679056604415;64526.677366612865;1324.9536266230637;1356.5658989123385;1408.6055904347004;1423.786432035203;1477.106350657858;32083.35833283368;36623.81688091542;36859.63140368562;36866.63133368739;37028.44457333088;55786.442135579746;56255;56316;56351.43648563462;56469;444736;445975;446758;446894;448041;33342.16644583905;34241.82879085688;34252.31495370029;34370.484442733876;34640.30719385648;52497;52622;52874.47125528695;56808.715956419954;57426.7128664354;45066;46691.766541167075;47301.52698473109;47422.76288618535;47857.52142478531;44534.55465445434;45332.546674534155;45613;46345.30482042839;47402;45534.54465455303;46401.53598463972;46752.532474676176;46781;46824.53175468338;38689.6131038686;40007.19985600213;40278.39582406325;40736.42019621952;41172.588274116875;30745.538816917317;30810.537841931942;31100;31714;32564.34871302608 17:22:32 INFO - PROCESS | 27067 | |2;jquery.html;392.248843464765;426.7183658785202;437.3287004448173;443.3372108697497;445.22753023504225;16679.49961501154;21542.784572154273;21784.45538861527;21788.45528861779;22069;19902.900485497554;20344.69482957757;20353.185872565078;20427.591448171028;20636.38090857273;5619.7471113799775;6106.572539922207;6221.937780622204;6266.216722909637;6287.308396076451;6018.638881667092;6057.51539876811;6150.446459818615;6227.159333489966;6229.09678096678;6088.482478989291;6091.208142941411;6093.695315234243;6095.603785753934;6099.48154406876;5911.6748578828365;6068;6081.148639190517;6168.8766224675255;6185.103160041814;5564.4991950724625;5569.415211402826;5573.832785016456;5576.470235327678;5577.9163312550345;24598.63102053453;24787.25638230856;24963.375915602075;24995;25145.874270628712;2600.54490464168;2776.8195067320557;2788.484130435876;2870.397216584499;2972.65814431341;14963.476278330316;15434.382624695;15534.844651553452;15992.920035399864;16128.75806862898;1987.0958713785194;2004.8195662390308;2079.511314841011;2122.1087143399654;2185;86958.60683991252;88750;89243.55378223132;90797;91625.08374916232;10795.892041079489;10797.190210734068;10876.891231087746;10921;11037.889621103846;14960.925195373953;14994.850051499563;15055.096694198382;15077.698446031018;15105.622359441102;8233.876491852627;8310.958445207856;8369.916300836914;8455.74632761018;8509.404341696021;8256.793580160424;8393.202645748644;8436.24073833358;8497.192766687154;8532.402731808837;4645.6980296280635;4739.289106634033;4933.01339732055;5036.471170527115;5087.364079490064;1882.6799444094263;1888.8583356248337;1900.7338972543855;1924.70167124096;1956.9021548922497;921.764949937775;1019.143919107938;1019.8419245017034;1041.6770801051698;1061.400308825511;19804.90097549503;20508;20576.794232057786;20799.44213383813;20906.26828061041;1011.6509804117568;1044.09685621938;1053.7839742852698;1065.5790962569743;1070.3577853287966;10731.014762269006;10857.782844343068;11305.516824340188;11344.205905586694;11567.248128871597;528585;539538;542048;543332;543791;94024.52987735017;97698.511507442;101523;103139.48430257801;103275;1030.6289735695143;1036.8807587127426;1072.0726571515738;1072.6674730833467;1072.8390741388698;18614.34849780251;18751.249950001846;19001.43534946735;19061;19191;42063.7896810514;43085;44083.55916440795;44649.33026004677;45086.54913450955;58937.70531147317;59291.407085928586;62125.68937155285;62577.68711156415;64050;9238.815223695354;9412.22339409862;9476.241900648069;9635.903640963501;9639.421634701941;14500.85499144995;14530.346134423766;14578.125312481285;15071.4724984625;15114.168720720467;12261.816072759095;12383.257004579755;12648.367581620698;12796.552120675731;12927.935360323138;12356.691082723004;12362.196457230611;12462.127651064338;12686.365681716063;12701.745965080461;12638.304893230961;12765.93617031909;12938.822954238132;12977.091603588036;13131.343432828511;9142.26861851037;9304.534773261175;9431.198348140484;9479.359780930336;9490.85763713531;10836.783264334827;11031.951964563354;11062.612808551663;11331;11423.543058277575 17:22:32 INFO - PROCESS | 27067 | |3;mootools.html;1381.9930900345496;1525.9465918692845;1556.0663601838894;1693.9576510587242;1789.4631610516842;8415;9401.247900167988;9648.85526717099;9652.517374131283;9972.551235194416;9747.561359738811;9813.509324533763;9816.361936474124;9838;9845.556949937252;2535.4295283561237;2561.9103331383403;2562.090457887451;2562.7052888917733;2564.384547708546;2477.8513289202615;2585.1985884375904;2587.6636037315125;2588.4046669265986;2589.2361753282735;2516.9119080832174;2526.7978561715017;2585.237354980276;2590.5466543354837;2592.364870606704;2507.4107584717576;2508.76166764158;2515.421453065796;2540.0982651158756;2548.7833534149436;2503.7371076037116;2509.04656230633;2518.2067648690786;2521.760432758885;2539.707933587624;9599;9647.372920760128;9705.223582113415;9718.757031074208;9722.222222222204;5523.116301391758;5686;5686.402927692616;5772.451617096369;5811;3583.4266517357096;3624.0758606555423;3634.854605815764;3635;3685.4840322354894;1187.0147777344782;1187.994060029703;1196.5094311332398;1198.8980936620399;2199.4941163532476;13433.529826456028;13886.583402497941;13916.930415347959;13926.234057126956;13948.2328471934;4717.575418212377;4945.67853089548;5010.248462730619;5155.355580552431;5156.8968620627375;10882.129429645767;10885.510152043176;10890.401027943539;10891.183161262938;10901.672949811518;5790;5802.390748971382;5803.042497987869;5804.361520232771;5805.274340707411;4429.889252768642;4651.86044418668;4696.81212751493;4704.929426058612;5434.456554344534;4203.76879271643;4250.192463431938;4360.236958532244;4372.191144638188;4391.429114215131;1269.4224128021729;1283.140296001681;1293.2822283632674;1296.0538806671184;1300.9154404963647;957.2676902169845;960.6589660670501;962.3696478806393;1013.7110923386799;1039.18424037132;6363.936360636427;6565.967170164118;6585.670716464253;6589.967050164719;6673.366030227121;510.1149505607768;511.08260672093684;517.1905967161381;527.7203082366352;529.4652401074942;4265.658747300208;4277.550857159953;4348.782560871944;4390.912181756347;4454.888627784331;12012.519499220109;12342.259464432163;12472.87527124717;12485.375731213402;12498.50005999768;12378.81431778524;12402.627921162379;12410.193337433038;12413.93793031029;12415.813762793565;8597.441166324168;8957.686480973134;8968.10318968098;9166.633334666672;9190.632374704937;6656.600603963778;6727.529072964846;6773.153355830521;6896.344847239504;6956.234814170539;11002.669919902415;11358.148138889452;11518.251313664585;11539.711507212387;11599.420028998686;10303.278770486293;10671.57313707443;11757.882421175678;11831.46758395857;11832.230904991147;8588.914110858812;8797.912020879709;8880.644774208959;8909.064548222343;8963.327750418614;9040.728778136667;9175.220106290773;9395.530223488662;9398.2951278653;9398.624055037722;8169.142240064708;8234.094249632655;8283.29591984664;8345.54099524532;8547.95726021366;8059;8087.8382432349845;8258.752237432887;8319.833603328021;8666.566671666265;7253.8186545336785;7524.774256772305;7566.546007239584;7637.923620763943;7642.541447513167;5429.158480435539;5433.320834895638;5436.510714035756;5470.124780035176;5549.445055494418;5554.333479982428;5573.439436957497;5581.804636837691;5640.266765320482;5699.031164701928 17:22:32 INFO - PROCESS | 27067 | |4;prototype.html;355.14055984517483;356.4243746349645;362.2935276211388;372.2796388987308;372.3725522494595;5488.134034428299;5558.861028474285;5566.805161819337;5575.386707462176;5579.609427340083;5182.948170518293;5209.082913568404;5281.18141688038;5351.197320401932;5513.862153446166;4283.250431174548;4284.464441944757;4287.464066991633;4287.957120428787;4302.956970430287;4273.529911709709;4307.052448461349;4352.6300264477395;4355.716878402894;4357.912841743163;4167.791340511248;4238.025254191522;4254.680898932577;4269.188854117723;4286.507051689049;4230.471191101113;4244.575542445762;4247.7893800266775;4249.766262855542;4260.914781704364;3781.7541859779026;3800.37293846515;3801.581825999138;3803.0872590578338;3813.427985802152;5259.658122222043;5366.4633536646415;5377.139657654796;5383.542398896098;5393.78424863005;4898.926516102261;4910.90178196434;4923.75381230937;4925;4933.703977761345;5690.40250773667;5703.54371650267;5716.599028408079;5739.483446489794;5744.310682718059;4129.15352352767;4159.708820382574;4189.706720529564;4195.454590903163;4201.390798334224;5912.368995796872;5923.763049477972;5926.140709597084;5935.970320148414;5950.5834591578605;4211.768352740629;4226;4254.510731265944;4265.296226122717;4268.189044081614;4137.958620413818;4268.530461649216;4293.141371725605;4299.462567179103;4303.569643035734;4818.831340903052;4825.418401155365;4832.299316599144;4836.46798852126;4837.37114175155;5740.770369185269;5752.539796816328;5762.683052432073;5769.134629805562;5771.85570360736;1419.2123371528694;1431.935562899672;1492.5074725340612;1494.9103053816805;1521.764126560385;968.2544440780559;989.3322007644811;1003.0320740485466;1004.217513912796;1008.9809292614513;5549.94551035302;5581.888362232733;5603.551715862721;5636.619028338084;5651.773929042793;5686.175504551899;5896.528277737771;5897.616654917502;5902.6458412495385;5910.438508341701;5514.28314319144;5517.393086760453;5523.226748255248;5524.806631767869;5534.391216966131;3084.228942764309;3095.5296234288535;3099.705527974839;3100.3644252928098;3126.1871913302252;5856.941430585724;5906.704664766745;5969.432903874125;5992.333411631782;6070.180525629072;5904.940950590439;5912.438318359749;5918.638713096012;5931.733072011683;5966;5772.740226689725;5998.580099393088;6028.758849645965;6064.848378790565;6071.817845464643;5693.202951586698;5766.740496677576;5769.94230057694;5772.509336706259;5811.622244554091;5728.713564321851;5731.426857314235;5756.194132821324;5790.160426738015;5806.738696758741;5841.4367235986865;5903.7048147593305;5949.787114957874;5997.220361352996;6054.364291749479;4210.621044106045;4218.915621687611;4226.260404429151;4235.30117530604;4256.553061928576;5787.421257874179;5799.101139323411;5803.970980145073;5829;5844.6785426801935;5455.536279416294;5633.6619802811965;5639.464250896158;5672.744726487235;5672.893785711746;5661.830145095653;5664.631798933056;5670.206171136127;5686.545076393796;5702.6293290935955;5457.563394928476;5524.254225679482;5554.2779438672715;5582.916256256078;5652.85191627179;5702;5719.48524632785;5741.3971532988435;5751.051076572318;5767.8558036049435;5500.80747173847;5566.051881841323;5596.132599447093;5621.8594535136945;5649.745761440663 17:22:32 INFO - PROCESS | 27067 | |5;yui.html;786.2098590916132;791.9485233459828;799.308598062676;800.2717527050384;856.6616186606288;15323.310451029702;16490.175491225422;16496.010239385647;16531.338746450143;16577.33690652374;12969.675758106052;12982.221066736003;13022.218666879993;13041.67395815105;13051.151675141109;710.4706993289981;724.0117239967433;838.1618381618382;844.6579135450153;862.2239984014375;859.1794835931702;860.3418384935499;861.014138811061;861.7845538615346;862.1464749897619;902.9819403611924;903.9005709371996;905.7735566108473;906.5150144672601;907.9818403631923;746.2500187311745;757.9166291707935;871.4771137317622;883.8762573239752;898.7753061734567;660.115445303291;663.6615326183633;665.2416245480157;666.3902529185789;666.8999650052531;13689.931550342184;13776.862231377658;13780;13796.931015344959;13797.310134493235;13512.932435337858;13581.185128892299;13801.309934503235;14036.578902632937;14036.859631403657;13010.349482525931;13106.541271055563;13205.537806176833;13318.334083295797;13343.266120363376;4462.308342206963;4484.1928452878465;4494.775261236925;4513.50351461339;4578.587927086545;87654.99999999936;88988;89028.55485722594;89181.10818891793;89558.5522072392;11132.276402033842;11211.943940280247;11303.773924521463;11757.529698811952;11888.465019074163;22586.435339116655;22666.66000010001;22716.65925011126;22743.545129097656;22972.770272297064;8527.403081784216;8592.570371481526;8754.299656027502;8806.911930880737;8934.50860202682;4717.693349932312;4823.517648235148;4845.224764037737;5044.949550504448;5117.130087885069;3392.6776956189124;3539.0975301298004;3546.184377593168;3547.024568243702;3551.449525323579;531.3039917707815;595.4135176850798;611.62996387186;616.6146158650844;624.3506752976854;635.9236891572948;695.9025736396909;698.6332175607846;721.8448033672786;743.7471259771595;9413.90586094144;9602.95198524003;9632.27757918159;9697.660581879742;9758.658446954465;667.9563997644847;681.7852376501386;683.1290105115918;696.4010950582496;731.7950973727364;4542.000759832831;4749.976250118727;4763.595094416944;4791.281307803857;4799.66402351832;99155.50422247843;101215.49392252992;103711;135683;139490;97353;100565;101637;102412;104819;87259;87501.12498875057;87875.56062219777;90259;93839.53080234691;9358.251339892928;9688.982656820932;9693.061387722342;9724.805503890024;9787.559559819967;51809;52169.217461737346;52779.20831187612;52992.20511692251;53054.93890122099;72206;77052.37685738874;77368.61315693386;79558;79601;13539.187648740713;13702.86297137016;13754.31228438594;13879;14016.369263383376;13934.930325348309;14131.222782747047;14228.075175113998;15464.5360639181;15867.28597213105;13937.233452160228;14059.718805624036;14339.641508961942;14392.568222953329;14600.853991460375;13969.022168448111;14279.64300892486;14319.642008949859;14616.926915365355;14620.195889226196;13278.601641950758;13872.375743091383;14228.857711422754;14641.121532708072;15844.76232856485;10562.78874422492;10995.835062474229;10998.945005274922;11139.665810025712;11779.88220117788;12199.634010979686;12372.752544949231;12464.688382790502;12705.55530556463;12708.173968692005 17:22:32 INFO - PROCESS | 27067 | __end_tp_report 17:22:32 INFO - PROCESS | 27067 | __start_cc_report 17:22:32 INFO - PROCESS | 27067 | _x_x_mozilla_cycle_collect,863 17:22:32 INFO - PROCESS | 27067 | __end_cc_report 17:22:32 INFO - PROCESS | 27067 | __startTimestamp1478827352216__endTimestamp 17:22:32 INFO - PROCESS | 27067 | 17:22:32 INFO - PROCESS | 27067 | ------- Summary: start ------- 17:22:32 INFO - PROCESS | 27067 | Number of tests: 6 17:22:32 INFO - PROCESS | 27067 | 17:22:32 INFO - PROCESS | 27067 | [#0] dojo.html Cycles:165 Average:5488.40 Median:5697.20 stddev:636.16 (11.2%) stddev-sans-first:609.79 17:22:32 INFO - PROCESS | 27067 | Values: 3095.6 3105.8 3113.5 3122.6 3124.9 5491.1 5561.8 5591.7 5598.9 5620.2 5559.3 5608.9 5613.5 5615.2 5631.6 5052.1 5075.9 5110.7 5155.5 5158.8 4918.4 4940.7 5123.3 5172.9 5177.6 4967.1 4969.0 4995.5 4998.3 5050.4 5054.2 5059.4 5121.9 5126.0 5158.0 4806.1 5008.1 5036.6 5074.3 5098.8 5329.7 5403.1 5418.8 5428.1 5498.6 5858.2 5874.6 5960.9 5962.8 5965.7 5801.9 5950.9 5981.0 5994.6 5994.9 5369.5 5404.8 5420.0 5437.9 5478.0 5911.7 5932.6 5947.8 5951.3 5951.7 5675.1 5676.7 5680.6 5684.6 5684.7 5806.7 5823.2 5845.4 5846.8 5917.4 5835.1 5859.6 5865.9 5868.2 5872.3 5844.6 5892.6 5900.4 5906.5 5925.6 4391.9 4435.1 4453.1 4482.1 4488.9 4009.5 4044.6 4056.5 4074.2 4081.0 5697.2 5711.8 5714.2 5714.6 5752.1 5803.9 5838.2 5856.4 5861.4 6010.4 5634.5 5636.0 5638.7 5660.9 5674.7 4982.5 4982.6 4988.9 4989.7 5020.0 5851.9 6004.1 6127.3 6133.4 6153.2 5974.2 6015.5 6017.5 6051.9 6055.1 6219.8 6238.8 6243.8 6244.3 6268.3 5622.2 5643.5 5872.1 5912.7 5917.7 5868.3 5888.0 5905.0 5919.2 5982.8 5790.3 5797.3 5798.9 5804.3 5804.7 5622.8 5782.5 5783.4 5793.8 5794.0 5562.3 5694.2 5739.4 5755.0 5841.0 5828.8 5873.6 5891.2 5907.0 5911.4 5580.8 5645.4 5739.4 5760.5 5762.5 17:22:32 INFO - PROCESS | 27067 | 17:22:32 INFO - PROCESS | 27067 | [#1] ext.html Cycles:180 Average:65669.47 Median:21994.08 stddev:130424.09 (593.0%) stddev-sans-first:130698.45 17:22:32 INFO - PROCESS | 27067 | Values: 593.7 622.4 645.5 651.6 658.6 34722.0 47295.0 48288.5 48344.3 48357.8 48378.0 48822.8 48824.8 48873.0 48914.3 8567.3 9472.3 9590.6 9604.7 9607.3 8479.7 11106.2 11359.3 11376.8 11674.0 11545.0 11595.5 11598.8 11610.8 11623.1 11423.4 11485.5 11498.7 11503.4 11504.0 11143.7 11209.5 11210.9 11217.6 11226.2 45129.5 45408.5 45430.0 45490.5 45495.0 3704.1 3712.9 3796.3 3828.3 3981.0 33930.9 35008.0 35297.1 35813.8 36193.8 4023.2 4085.8 4170.7 4204.6 4226.6 375380.0 379043.0 379568.0 379900.0 380371.0 66215.3 66564.0 66621.7 66673.7 66804.3 40513.2 41530.8 41598.0 43764.0 43860.0 18964.2 19193.0 19194.9 19203.4 19203.6 13198.5 13438.8 13925.4 14728.6 14899.0 20535.1 20733.8 20743.4 20965.7 20994.3 7475.3 7502.4 7534.9 7578.9 7673.7 1036.4 1051.6 1126.0 1172.7 1180.1 34442.8 34564.0 34661.7 34728.5 34946.7 1151.3 1158.3 1174.9 1195.9 1234.3 17459.3 18290.5 18381.4 18412.9 18516.4 612633.0 618176.0 619880.0 620267.0 620786.0 63934.0 64060.0 64126.7 64188.7 64526.7 1325.0 1356.6 1408.6 1423.8 1477.1 32083.4 36623.8 36859.6 36866.6 37028.4 55786.4 56255.0 56316.0 56351.4 56469.0 444736.0 445975.0 446758.0 446894.0 448041.0 33342.2 34241.8 34252.3 34370.5 34640.3 52497.0 52622.0 52874.5 56808.7 57426.7 45066.0 46691.8 47301.5 47422.8 47857.5 44534.6 45332.5 45613.0 46345.3 47402.0 45534.5 46401.5 46752.5 46781.0 46824.5 38689.6 40007.2 40278.4 40736.4 41172.6 30745.5 30810.5 31100.0 31714.0 32564.3 17:22:32 INFO - PROCESS | 27067 | 17:22:32 INFO - PROCESS | 27067 | [#2] jquery.html Cycles:180 Average:31582.30 Median:12127.00 stddev:88936.50 (733.4%) stddev-sans-first:89155.16 17:22:32 INFO - PROCESS | 27067 | Values: 392.2 426.7 437.3 443.3 445.2 16679.5 21542.8 21784.5 21788.5 22069.0 19902.9 20344.7 20353.2 20427.6 20636.4 5619.7 6106.6 6221.9 6266.2 6287.3 6018.6 6057.5 6150.4 6227.2 6229.1 6088.5 6091.2 6093.7 6095.6 6099.5 5911.7 6068.0 6081.1 6168.9 6185.1 5564.5 5569.4 5573.8 5576.5 5577.9 24598.6 24787.3 24963.4 24995.0 25145.9 2600.5 2776.8 2788.5 2870.4 2972.7 14963.5 15434.4 15534.8 15992.9 16128.8 1987.1 2004.8 2079.5 2122.1 2185.0 86958.6 88750.0 89243.6 90797.0 91625.1 10795.9 10797.2 10876.9 10921.0 11037.9 14960.9 14994.9 15055.1 15077.7 15105.6 8233.9 8311.0 8369.9 8455.7 8509.4 8256.8 8393.2 8436.2 8497.2 8532.4 4645.7 4739.3 4933.0 5036.5 5087.4 1882.7 1888.9 1900.7 1924.7 1956.9 921.8 1019.1 1019.8 1041.7 1061.4 19804.9 20508.0 20576.8 20799.4 20906.3 1011.7 1044.1 1053.8 1065.6 1070.4 10731.0 10857.8 11305.5 11344.2 11567.2 528585.0 539538.0 542048.0 543332.0 543791.0 94024.5 97698.5 101523.0 103139.5 103275.0 1030.6 1036.9 1072.1 1072.7 1072.8 18614.3 18751.2 19001.4 19061.0 19191.0 42063.8 43085.0 44083.6 44649.3 45086.5 58937.7 59291.4 62125.7 62577.7 64050.0 9238.8 9412.2 9476.2 9635.9 9639.4 14500.9 14530.3 14578.1 15071.5 15114.2 12261.8 12383.3 12648.4 12796.6 12927.9 12356.7 12362.2 12462.1 12686.4 12701.7 12638.3 12765.9 12938.8 12977.1 13131.3 9142.3 9304.5 9431.2 9479.4 9490.9 10836.8 11032.0 11062.6 11331.0 11423.5 17:22:32 INFO - PROCESS | 27067 | 17:22:32 INFO - PROCESS | 27067 | [#3] mootools.html Cycles:180 Average:6355.17 Median:4711.25 stddev:3713.37 (78.8%) stddev-sans-first:3704.98 17:22:32 INFO - PROCESS | 27067 | Values: 1382.0 1525.9 1556.1 1694.0 1789.5 8415.0 9401.2 9648.9 9652.5 9972.6 9747.6 9813.5 9816.4 9838.0 9845.6 2535.4 2561.9 2562.1 2562.7 2564.4 2477.9 2585.2 2587.7 2588.4 2589.2 2516.9 2526.8 2585.2 2590.5 2592.4 2507.4 2508.8 2515.4 2540.1 2548.8 2503.7 2509.0 2518.2 2521.8 2539.7 9599.0 9647.4 9705.2 9718.8 9722.2 5523.1 5686.0 5686.4 5772.5 5811.0 3583.4 3624.1 3634.9 3635.0 3685.5 1187.0 1188.0 1196.5 1198.9 2199.5 13433.5 13886.6 13916.9 13926.2 13948.2 4717.6 4945.7 5010.2 5155.4 5156.9 10882.1 10885.5 10890.4 10891.2 10901.7 5790.0 5802.4 5803.0 5804.4 5805.3 4429.9 4651.9 4696.8 4704.9 5434.5 4203.8 4250.2 4360.2 4372.2 4391.4 1269.4 1283.1 1293.3 1296.1 1300.9 957.3 960.7 962.4 1013.7 1039.2 6363.9 6566.0 6585.7 6590.0 6673.4 510.1 511.1 517.2 527.7 529.5 4265.7 4277.6 4348.8 4390.9 4454.9 12012.5 12342.3 12472.9 12485.4 12498.5 12378.8 12402.6 12410.2 12413.9 12415.8 8597.4 8957.7 8968.1 9166.6 9190.6 6656.6 6727.5 6773.2 6896.3 6956.2 11002.7 11358.1 11518.3 11539.7 11599.4 10303.3 10671.6 11757.9 11831.5 11832.2 8588.9 8797.9 8880.6 8909.1 8963.3 9040.7 9175.2 9395.5 9398.3 9398.6 8169.1 8234.1 8283.3 8345.5 8548.0 8059.0 8087.8 8258.8 8319.8 8666.6 7253.8 7524.8 7566.5 7637.9 7642.5 5429.2 5433.3 5436.5 5470.1 5549.4 5554.3 5573.4 5581.8 5640.3 5699.0 17:22:32 INFO - PROCESS | 27067 | 17:22:32 INFO - PROCESS | 27067 | [#4] prototype.html Cycles:180 Average:4817.82 Median:5524.53 stddev:1396.38 (25.3%) stddev-sans-first:1359.53 17:22:32 INFO - PROCESS | 27067 | Values: 355.1 356.4 362.3 372.3 372.4 5488.1 5558.9 5566.8 5575.4 5579.6 5182.9 5209.1 5281.2 5351.2 5513.9 4283.3 4284.5 4287.5 4288.0 4303.0 4273.5 4307.1 4352.6 4355.7 4357.9 4167.8 4238.0 4254.7 4269.2 4286.5 4230.5 4244.6 4247.8 4249.8 4260.9 3781.8 3800.4 3801.6 3803.1 3813.4 5259.7 5366.5 5377.1 5383.5 5393.8 4898.9 4910.9 4923.8 4925.0 4933.7 5690.4 5703.5 5716.6 5739.5 5744.3 4129.2 4159.7 4189.7 4195.5 4201.4 5912.4 5923.8 5926.1 5936.0 5950.6 4211.8 4226.0 4254.5 4265.3 4268.2 4138.0 4268.5 4293.1 4299.5 4303.6 4818.8 4825.4 4832.3 4836.5 4837.4 5740.8 5752.5 5762.7 5769.1 5771.9 1419.2 1431.9 1492.5 1494.9 1521.8 968.3 989.3 1003.0 1004.2 1009.0 5549.9 5581.9 5603.6 5636.6 5651.8 5686.2 5896.5 5897.6 5902.6 5910.4 5514.3 5517.4 5523.2 5524.8 5534.4 3084.2 3095.5 3099.7 3100.4 3126.2 5856.9 5906.7 5969.4 5992.3 6070.2 5904.9 5912.4 5918.6 5931.7 5966.0 5772.7 5998.6 6028.8 6064.8 6071.8 5693.2 5766.7 5769.9 5772.5 5811.6 5728.7 5731.4 5756.2 5790.2 5806.7 5841.4 5903.7 5949.8 5997.2 6054.4 4210.6 4218.9 4226.3 4235.3 4256.6 5787.4 5799.1 5804.0 5829.0 5844.7 5455.5 5633.7 5639.5 5672.7 5672.9 5661.8 5664.6 5670.2 5686.5 5702.6 5457.6 5524.3 5554.3 5582.9 5652.9 5702.0 5719.5 5741.4 5751.1 5767.9 5500.8 5566.1 5596.1 5621.9 5649.7 17:22:32 INFO - PROCESS | 27067 | 17:22:32 INFO - PROCESS | 27067 | [#5] yui.html Cycles:180 Average:21583.47 Median:4756.79 stddev:31333.93 (658.7%) stddev-sans-first:31382.92 17:22:32 INFO - PROCESS | 27067 | Values: 786.2 791.9 799.3 800.3 856.7 15323.3 16490.2 16496.0 16531.3 16577.3 12969.7 12982.2 13022.2 13041.7 13051.2 710.5 724.0 838.2 844.7 862.2 859.2 860.3 861.0 861.8 862.1 903.0 903.9 905.8 906.5 908.0 746.3 757.9 871.5 883.9 898.8 660.1 663.7 665.2 666.4 666.9 13689.9 13776.9 13780.0 13796.9 13797.3 13512.9 13581.2 13801.3 14036.6 14036.9 13010.3 13106.5 13205.5 13318.3 13343.3 4462.3 4484.2 4494.8 4513.5 4578.6 87655.0 88988.0 89028.6 89181.1 89558.6 11132.3 11211.9 11303.8 11757.5 11888.5 22586.4 22666.7 22716.7 22743.5 22972.8 8527.4 8592.6 8754.3 8806.9 8934.5 4717.7 4823.5 4845.2 5044.9 5117.1 3392.7 3539.1 3546.2 3547.0 3551.4 531.3 595.4 611.6 616.6 624.4 635.9 695.9 698.6 721.8 743.7 9413.9 9603.0 9632.3 9697.7 9758.7 668.0 681.8 683.1 696.4 731.8 4542.0 4750.0 4763.6 4791.3 4799.7 99155.5 101215.5 103711.0 135683.0 139490.0 97353.0 100565.0 101637.0 102412.0 104819.0 87259.0 87501.1 87875.6 90259.0 93839.5 9358.3 9689.0 9693.1 9724.8 9787.6 51809.0 52169.2 52779.2 52992.2 53054.9 72206.0 77052.4 77368.6 79558.0 79601.0 13539.2 13702.9 13754.3 13879.0 14016.4 13934.9 14131.2 14228.1 15464.5 15867.3 13937.2 14059.7 14339.6 14392.6 14600.9 13969.0 14279.6 14319.6 14616.9 14620.2 13278.6 13872.4 14228.9 14641.1 15844.8 10562.8 10995.8 10998.9 11139.7 11779.9 12199.6 12372.8 12464.7 12705.6 12708.2 17:22:32 INFO - PROCESS | 27067 | -------- Summary: end -------- 17:22:32 INFO - PROCESS | 27067 | 17:22:32 INFO - TEST-INFO | 27067: exit 0 17:22:32 INFO - TEST-OK | dromaeo_css | took 1113324ms 17:22:32 INFO - TEST-START | kraken 17:22:32 INFO - operating with platform_type : linux_ 17:22:32 INFO - Initialising browser for kraken test... 17:22:33 INFO - TEST-INFO | started process 27388 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVp9GB_/profile http://localhost:45109/getInfo.html) 17:22:36 INFO - PROCESS | 27388 | __metrics Screen width/height:1600/1200 17:22:36 INFO - PROCESS | 27388 | colorDepth:24 17:22:36 INFO - PROCESS | 27388 | Browser inner width/height: 1024/697 17:22:36 INFO - PROCESS | 27388 | __metrics 17:22:37 INFO - PROCESS | 27388 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 17:22:37 INFO - PROCESS | 27388 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:22:37 INFO - PROCESS | 27388 | console.error: PushService: 17:22:37 INFO - PROCESS | 27388 | stateChangeProcessEnqueue: Error transitioning state 17:22:37 INFO - PROCESS | 27388 | UnknownError 17:22:38 INFO - TEST-INFO | 27388: exit 0 17:22:38 INFO - Browser initialized. 17:22:38 INFO - Running cycle 1/1 for kraken test... 17:22:38 INFO - TEST-INFO | started process 27477 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpVp9GB_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:22:50 INFO - PROCESS | 27477 | RSS: Main: 144560128 17:22:50 INFO - PROCESS | 27477 | 17:22:52 INFO - PROCESS | 27477 | Cycle 1(1): loaded http://localhost:45109/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45109/tests/kraken/driver.html?testName=audio-beat-detection) 17:22:53 INFO - PROCESS | 27477 | RSS: Main: 185991168 17:22:53 INFO - PROCESS | 27477 | 17:22:56 INFO - PROCESS | 27477 | Cycle 1(1): loaded http://localhost:45109/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45109/tests/kraken/driver.html?testName=audio-dft) 17:22:56 INFO - PROCESS | 27477 | RSS: Main: 176336896 17:22:56 INFO - PROCESS | 27477 | 17:22:59 INFO - PROCESS | 27477 | Cycle 1(1): loaded http://localhost:45109/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45109/tests/kraken/driver.html?testName=audio-fft) 17:23:00 INFO - PROCESS | 27477 | RSS: Main: 267538432 17:23:00 INFO - PROCESS | 27477 | 17:23:02 INFO - PROCESS | 27477 | Cycle 1(1): loaded http://localhost:45109/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45109/tests/kraken/driver.html?testName=audio-oscillator) 17:23:02 INFO - PROCESS | 27477 | RSS: Main: 173600768 17:23:02 INFO - PROCESS | 27477 | 17:23:04 INFO - PROCESS | 27477 | Cycle 1(1): loaded http://localhost:45109/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45109/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:23:05 INFO - PROCESS | 27477 | RSS: Main: 175890432 17:23:05 INFO - PROCESS | 27477 | 17:23:08 INFO - PROCESS | 27477 | Cycle 1(1): loaded http://localhost:45109/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45109/tests/kraken/driver.html?testName=imaging-darkroom) 17:23:09 INFO - PROCESS | 27477 | RSS: Main: 211107840 17:23:09 INFO - PROCESS | 27477 | 17:23:12 INFO - PROCESS | 27477 | Cycle 1(1): loaded http://localhost:45109/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45109/tests/kraken/driver.html?testName=imaging-desaturate) 17:23:13 INFO - PROCESS | 27477 | RSS: Main: 210202624 17:23:13 INFO - PROCESS | 27477 | 17:23:15 INFO - PROCESS | 27477 | Cycle 1(1): loaded http://localhost:45109/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45109/tests/kraken/driver.html?testName=json-parse-financial) 17:23:16 INFO - PROCESS | 27477 | RSS: Main: 210030592 17:23:16 INFO - PROCESS | 27477 | 17:23:17 INFO - PROCESS | 27477 | Cycle 1(1): loaded http://localhost:45109/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45109/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:23:18 INFO - PROCESS | 27477 | RSS: Main: 171847680 17:23:18 INFO - PROCESS | 27477 | 17:23:20 INFO - PROCESS | 27477 | Cycle 1(1): loaded http://localhost:45109/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45109/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:23:20 INFO - PROCESS | 27477 | RSS: Main: 178655232 17:23:20 INFO - PROCESS | 27477 | 17:23:22 INFO - PROCESS | 27477 | Cycle 1(1): loaded http://localhost:45109/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45109/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:23:23 INFO - PROCESS | 27477 | RSS: Main: 188862464 17:23:23 INFO - PROCESS | 27477 | 17:23:25 INFO - PROCESS | 27477 | Cycle 1(1): loaded http://localhost:45109/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45109/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:23:26 INFO - PROCESS | 27477 | RSS: Main: 196956160 17:23:26 INFO - PROCESS | 27477 | 17:23:29 INFO - PROCESS | 27477 | Cycle 1(1): loaded http://localhost:45109/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45109/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:23:29 INFO - PROCESS | 27477 | RSS: Main: 194179072 17:23:29 INFO - PROCESS | 27477 | 17:23:31 INFO - PROCESS | 27477 | Cycle 1(1): loaded http://localhost:45109/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45109/tests/kraken/driver.html?testName=ai-astar) 17:23:31 INFO - PROCESS | 27477 | RSS: Main: 243249152 17:23:31 INFO - PROCESS | 27477 | 17:23:31 INFO - PROCESS | 27477 | __start_tp_report 17:23:31 INFO - PROCESS | 27477 | _x_x_mozilla_page_load 17:23:31 INFO - PROCESS | 27477 | _x_x_mozilla_page_load_details 17:23:31 INFO - PROCESS | 27477 | |i|pagename|runs| 17:23:31 INFO - PROCESS | 27477 | |0;ai-astar;110;111;109;111;111;112;111;112;112;112 17:23:31 INFO - PROCESS | 27477 | |1;audio-beat-detection;145;121;118;149;119;120;118;116;149;119 17:23:31 INFO - PROCESS | 27477 | |2;audio-dft;161;159;160;161;161;159;160;161;161;160 17:23:31 INFO - PROCESS | 27477 | |3;audio-fft;76;74;76;109;75;74;75;76;108;76 17:23:31 INFO - PROCESS | 27477 | |4;audio-oscillator;82;82;115;82;81;81;106;82;80;109 17:23:31 INFO - PROCESS | 27477 | |5;imaging-gaussian-blur;116;117;116;117;116;117;117;117;117;117 17:23:31 INFO - PROCESS | 27477 | |6;imaging-darkroom;146;146;145;145;146;145;146;145;146;145 17:23:31 INFO - PROCESS | 27477 | |7;imaging-desaturate;96;95;95;95;95;95;95;96;95;95 17:23:31 INFO - PROCESS | 27477 | |8;json-parse-financial;53;50;52;50;50;50;52;49;51;49 17:23:31 INFO - PROCESS | 27477 | |9;json-stringify-tinderbox;58;57;67;57;58;57;58;58;58;57 17:23:31 INFO - PROCESS | 27477 | |10;stanford-crypto-aes;72;72;74;75;81;74;73;73;73;74 17:23:31 INFO - PROCESS | 27477 | |11;stanford-crypto-ccm;124;123;127;117;129;121;128;117;166;120 17:23:31 INFO - PROCESS | 27477 | |12;stanford-crypto-pbkdf2;151;150;151;148;151;149;149;152;150;153 17:23:31 INFO - PROCESS | 27477 | |13;stanford-crypto-sha256-iterative;66;64;66;63;62;64;63;62;62;61 17:23:31 INFO - PROCESS | 27477 | __end_tp_report 17:23:31 INFO - PROCESS | 27477 | __start_cc_report 17:23:31 INFO - PROCESS | 27477 | _x_x_mozilla_cycle_collect,1626 17:23:31 INFO - PROCESS | 27477 | __end_cc_report 17:23:31 INFO - PROCESS | 27477 | __startTimestamp1478827411657__endTimestamp 17:23:31 INFO - PROCESS | 27477 | 17:23:31 INFO - PROCESS | 27477 | ------- Summary: start ------- 17:23:31 INFO - PROCESS | 27477 | Number of tests: 14 17:23:31 INFO - PROCESS | 27477 | 17:23:31 INFO - PROCESS | 27477 | [#0] ai-astar Cycles:10 Average:111.10 Median:111.50 stddev:0.99 (0.9%) stddev-sans-first:0.97 17:23:31 INFO - PROCESS | 27477 | Values: 110.0 111.0 109.0 111.0 111.0 112.0 111.0 112.0 112.0 112.0 17:23:31 INFO - PROCESS | 27477 | 17:23:31 INFO - PROCESS | 27477 | [#1] audio-beat-detection Cycles:10 Average:127.40 Median:120.50 stddev:14.09 (11.7%) stddev-sans-first:13.43 17:23:31 INFO - PROCESS | 27477 | Values: 145.0 121.0 118.0 149.0 119.0 120.0 118.0 116.0 149.0 119.0 17:23:31 INFO - PROCESS | 27477 | 17:23:31 INFO - PROCESS | 27477 | [#2] audio-dft Cycles:10 Average:160.30 Median:161.00 stddev:0.82 (0.5%) stddev-sans-first:0.83 17:23:31 INFO - PROCESS | 27477 | Values: 161.0 159.0 160.0 161.0 161.0 159.0 160.0 161.0 161.0 160.0 17:23:31 INFO - PROCESS | 27477 | 17:23:31 INFO - PROCESS | 27477 | [#3] audio-fft Cycles:10 Average:81.90 Median:75.50 stddev:14.04 (18.6%) stddev-sans-first:14.73 17:23:31 INFO - PROCESS | 27477 | Values: 76.0 74.0 76.0 109.0 75.0 74.0 75.0 76.0 108.0 76.0 17:23:31 INFO - PROCESS | 27477 | 17:23:31 INFO - PROCESS | 27477 | [#4] audio-oscillator Cycles:10 Average:90.00 Median:81.50 stddev:13.98 (17.2%) stddev-sans-first:14.53 17:23:31 INFO - PROCESS | 27477 | Values: 82.0 82.0 115.0 82.0 81.0 81.0 106.0 82.0 80.0 109.0 17:23:31 INFO - PROCESS | 27477 | 17:23:31 INFO - PROCESS | 27477 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.44 17:23:31 INFO - PROCESS | 27477 | Values: 116.0 117.0 116.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 17:23:31 INFO - PROCESS | 27477 | 17:23:31 INFO - PROCESS | 27477 | [#6] imaging-darkroom Cycles:10 Average:145.50 Median:146.00 stddev:0.53 (0.4%) stddev-sans-first:0.53 17:23:31 INFO - PROCESS | 27477 | Values: 146.0 146.0 145.0 145.0 146.0 145.0 146.0 145.0 146.0 145.0 17:23:31 INFO - PROCESS | 27477 | 17:23:31 INFO - PROCESS | 27477 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 17:23:31 INFO - PROCESS | 27477 | Values: 96.0 95.0 95.0 95.0 95.0 95.0 95.0 96.0 95.0 95.0 17:23:31 INFO - PROCESS | 27477 | 17:23:31 INFO - PROCESS | 27477 | [#8] json-parse-financial Cycles:10 Average:50.60 Median:50.50 stddev:1.35 (2.7%) stddev-sans-first:1.12 17:23:31 INFO - PROCESS | 27477 | Values: 53.0 50.0 52.0 50.0 50.0 50.0 52.0 49.0 51.0 49.0 17:23:31 INFO - PROCESS | 27477 | 17:23:31 INFO - PROCESS | 27477 | [#9] json-stringify-tinderbox Cycles:10 Average:58.50 Median:58.00 stddev:3.03 (5.2%) stddev-sans-first:3.21 17:23:31 INFO - PROCESS | 27477 | Values: 58.0 57.0 67.0 57.0 58.0 57.0 58.0 58.0 58.0 57.0 17:23:31 INFO - PROCESS | 27477 | 17:23:31 INFO - PROCESS | 27477 | [#10] stanford-crypto-aes Cycles:10 Average:74.10 Median:74.00 stddev:2.60 (3.5%) stddev-sans-first:2.65 17:23:31 INFO - PROCESS | 27477 | Values: 72.0 72.0 74.0 75.0 81.0 74.0 73.0 73.0 73.0 74.0 17:23:31 INFO - PROCESS | 27477 | 17:23:31 INFO - PROCESS | 27477 | [#11] stanford-crypto-ccm Cycles:10 Average:127.20 Median:125.50 stddev:14.28 (11.4%) stddev-sans-first:15.10 17:23:31 INFO - PROCESS | 27477 | Values: 124.0 123.0 127.0 117.0 129.0 121.0 128.0 117.0 166.0 120.0 17:23:31 INFO - PROCESS | 27477 | 17:23:31 INFO - PROCESS | 27477 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:150.40 Median:151.00 stddev:1.51 (1.0%) stddev-sans-first:1.58 17:23:31 INFO - PROCESS | 27477 | Values: 151.0 150.0 151.0 148.0 151.0 149.0 149.0 152.0 150.0 153.0 17:23:31 INFO - PROCESS | 27477 | 17:23:31 INFO - PROCESS | 27477 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:63.30 Median:63.50 stddev:1.70 (2.7%) stddev-sans-first:1.50 17:23:31 INFO - PROCESS | 27477 | Values: 66.0 64.0 66.0 63.0 62.0 64.0 63.0 62.0 62.0 61.0 17:23:31 INFO - PROCESS | 27477 | -------- Summary: end -------- 17:23:31 INFO - PROCESS | 27477 | 17:23:32 INFO - TEST-INFO | 27477: exit 0 17:23:32 INFO - TEST-OK | kraken | took 59817ms 17:23:32 INFO - SUITE-END | took 1173s 17:23:32 INFO - Completed test suite (00:19:33) 17:23:32 INFO - javascript benchmark 17:23:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3095.613048368954, 3105.844707764612, 3113.4551453495633, 3122.609673790776, 3124.89062882799, 5491.066518691822, 5561.805336813212, 5591.720413979305, 5598.916016259749, 5620.241267428901, 5559.305086864142, 5608.859778505539, 5613.494785469307, 5615.2138700581845, 5631.57763167763, 5052.065367906931, 5075.9492405076035, 5110.744462776865, 5155.5360017598405, 5158.845234642967, 4918.434380046305, 4940.728259945701, 5123.257127716479, 5172.870678233036, 5177.585793136559, 4967.05625931072, 4968.975155124219, 4995.525425084629, 4998.325226094467, 5050.419201791786, 5054.166062599685, 5059.367579052619, 5121.897562048775, 5126.0, 5157.9941911327305, 4806.0628177505405, 5008.12357837377, 5036.571891389236, 5074.314967479417, 5098.796048158069, 5329.706866122362, 5403.054465468527, 5418.756155972991, 5428.0500912340185, 5498.560115190775, 5858.238429004245, 5874.647521148702, 5960.940390596125, 5962.821115366546, 5965.701714914237, 5801.9129713054335, 5950.9107363389585, 5981.0, 5994.550408719363, 5994.940050599525, 5369.516743493065, 5404.83785486436, 5420.0, 5437.918431223535, 5478.01395748765, 5911.704414779244, 5932.614380065368, 5947.82156535296, 5951.315598706117, 5951.702414879325, 5675.1487276907965, 5676.68778217202, 5680.573956953245, 5684.63049901755, 5684.715764211773, 5806.65160090396, 5823.242978412779, 5845.357010728816, 5846.766129354864, 5917.408259174048, 5835.066389377679, 5859.619124756877, 5865.912011319833, 5868.178455016301, 5872.295324560994, 5844.649321040752, 5892.558058145656, 5900.350961394243, 5906.527477801766, 5925.555583331267, 4391.912161756747, 4435.090806384684, 4453.131639330364, 4482.103579284156, 4488.910221795546, 4009.4787677602308, 4044.5550989391145, 4056.533498647634, 4074.1851629673592, 4080.959190408117, 5697.202391665171, 5711.8000869969355, 5714.228579141846, 5714.628549144292, 5752.108423194411, 5803.883922321615, 5838.241028666246, 5856.443637854397, 5861.443162899518, 6010.398960103955, 5634.464725851038, 5636.0, 5638.661680299195, 5660.915086273709, 5674.744636491368, 4982.451930287665, 4982.551570358685, 4988.925166122511, 4989.700617962933, 5020.0, 5851.912221316684, 6004.099385092271, 6127.325994140641, 6133.417325354084, 6153.1693221415435, 5974.163617093697, 6015.518758499397, 6017.488513476404, 6051.939480605138, 6055.091736239599, 6219.844503887439, 6238.781642642486, 6243.7814676486105, 6244.250689917239, 6268.310485846644, 5622.184783206494, 5643.463870932255, 5872.119182122716, 5912.733926973383, 5917.733701983335, 5868.3251426086545, 5888.0, 5904.9704751475965, 5919.200907877382, 5982.820515384546, 5790.276215473066, 5797.275340582427, 5798.913016304843, 5804.2744656917885, 5804.709764511673, 5622.8313150605545, 5782.479576838105, 5783.392743761844, 5793.797217097382, 5794.015017447128, 5562.332520097614, 5694.231278777478, 5739.368669446274, 5754.971225143848, 5841.0, 5828.766849326149, 5873.5594830388745, 5891.1752354669525, 5907.025340818888, 5911.379305172895, 5580.804671836466, 5645.350784659652, 5739.368669446274, 5760.481556659921, 5762.4525670061275], "value": 5444.08425825632, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [593.691280534122, 622.4273668225234, 645.4674893213095, 651.6220592056608, 658.6410406328549, 34722.0, 47295.0, 48288.51711482884, 48344.27483587749, 48357.75821120898, 48378.0, 48822.755886220606, 48824.75587622066, 48873.0, 48914.26628600565, 8567.271781898515, 9472.289578281621, 9590.568424420882, 9604.711858644218, 9607.27945404094, 8479.74560763175, 11106.167037472182, 11359.261647992855, 11376.829347559793, 11674.0, 11545.0, 11595.478203480821, 11598.768024639503, 11610.825837612443, 11623.12826538013, 11423.428828558539, 11485.540578376855, 11498.71253218672, 11503.424828758529, 11504.02215811657, 11143.665690029313, 11209.495572699248, 11210.887891121147, 11217.551297948072, 11226.15803814709, 45129.54870451286, 45408.54591454109, 45430.0, 45490.545094548965, 45495.0, 3704.1480459494196, 3712.9133087670066, 3796.3356412627954, 3828.3109040372724, 3980.960190398088, 33930.90950728594, 35008.0, 35297.1175720609, 35813.82093089518, 36193.81903090468, 4023.2355852387955, 4085.795710214507, 4170.666346692257, 4204.62158405745, 4226.598473145046, 375380.0, 379043.0, 379568.0, 379900.0, 380371.0, 66215.33784662189, 66564.0, 66621.66689166523, 66673.66663166654, 66804.33195668078, 40513.18973620511, 41530.79234603868, 41598.0, 43764.0, 43860.0, 18964.241430342907, 19193.0, 19194.904025479784, 19203.423897283104, 19203.615927681647, 13198.472061117447, 13438.798418023738, 13925.442982280596, 14728.631784205267, 14899.0, 20535.075921583564, 20733.792662073487, 20743.377698669065, 20965.68551471729, 20994.265200717902, 7475.278059650952, 7502.437317201123, 7534.886976695354, 7578.924210757822, 7673.700308867129, 1036.3729943384294, 1051.642263553633, 1125.971850703739, 1172.6716519374588, 1180.1326025371345, 34442.82778586141, 34564.0, 34661.65338346635, 34728.47907281393, 34946.650533494845, 1151.2804497189256, 1158.3108050709814, 1174.8590169179581, 1195.946182421793, 1234.2841152131741, 17459.30162793474, 18290.451286461426, 18381.448556543324, 18412.907935460236, 18516.351927682466, 612633.0, 618176.0, 619880.0, 620267.0, 620786.0, 63934.0, 64060.0, 64126.67936660287, 64188.679056604415, 64526.677366612865, 1324.9536266230637, 1356.5658989123385, 1408.6055904347004, 1423.786432035203, 1477.106350657858, 32083.35833283368, 36623.81688091542, 36859.63140368562, 36866.63133368739, 37028.44457333088, 55786.442135579746, 56255.0, 56316.0, 56351.43648563462, 56469.0, 444736.0, 445975.0, 446758.0, 446894.0, 448041.0, 33342.16644583905, 34241.82879085688, 34252.31495370029, 34370.484442733876, 34640.30719385648, 52497.0, 52622.0, 52874.47125528695, 56808.715956419954, 57426.7128664354, 45066.0, 46691.766541167075, 47301.52698473109, 47422.76288618535, 47857.52142478531, 44534.55465445434, 45332.546674534155, 45613.0, 46345.30482042839, 47402.0, 45534.54465455303, 46401.53598463972, 46752.532474676176, 46781.0, 46824.53175468338, 38689.6131038686, 40007.19985600213, 40278.39582406325, 40736.42019621952, 41172.588274116875, 30745.538816917317, 30810.537841931942, 31100.0, 31714.0, 32564.34871302608], "value": 22061.98870766556, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.248843464765, 426.7183658785202, 437.3287004448173, 443.3372108697497, 445.22753023504225, 16679.49961501154, 21542.784572154273, 21784.45538861527, 21788.45528861779, 22069.0, 19902.900485497554, 20344.69482957757, 20353.185872565078, 20427.591448171028, 20636.38090857273, 5619.7471113799775, 6106.572539922207, 6221.937780622204, 6266.216722909637, 6287.308396076451, 6018.638881667092, 6057.51539876811, 6150.446459818615, 6227.159333489966, 6229.09678096678, 6088.482478989291, 6091.208142941411, 6093.695315234243, 6095.603785753934, 6099.48154406876, 5911.6748578828365, 6068.0, 6081.148639190517, 6168.8766224675255, 6185.103160041814, 5564.4991950724625, 5569.415211402826, 5573.832785016456, 5576.470235327678, 5577.9163312550345, 24598.63102053453, 24787.25638230856, 24963.375915602075, 24995.0, 25145.874270628712, 2600.54490464168, 2776.8195067320557, 2788.484130435876, 2870.397216584499, 2972.65814431341, 14963.476278330316, 15434.382624695, 15534.844651553452, 15992.920035399864, 16128.75806862898, 1987.0958713785194, 2004.8195662390308, 2079.511314841011, 2122.1087143399654, 2185.0, 86958.60683991252, 88750.0, 89243.55378223132, 90797.0, 91625.08374916232, 10795.892041079489, 10797.190210734068, 10876.891231087746, 10921.0, 11037.889621103846, 14960.925195373953, 14994.850051499563, 15055.096694198382, 15077.698446031018, 15105.622359441102, 8233.876491852627, 8310.958445207856, 8369.916300836914, 8455.74632761018, 8509.404341696021, 8256.793580160424, 8393.202645748644, 8436.24073833358, 8497.192766687154, 8532.402731808837, 4645.6980296280635, 4739.289106634033, 4933.01339732055, 5036.471170527115, 5087.364079490064, 1882.6799444094263, 1888.8583356248337, 1900.7338972543855, 1924.70167124096, 1956.9021548922497, 921.764949937775, 1019.143919107938, 1019.8419245017034, 1041.6770801051698, 1061.400308825511, 19804.90097549503, 20508.0, 20576.794232057786, 20799.44213383813, 20906.26828061041, 1011.6509804117568, 1044.09685621938, 1053.7839742852698, 1065.5790962569743, 1070.3577853287966, 10731.014762269006, 10857.782844343068, 11305.516824340188, 11344.205905586694, 11567.248128871597, 528585.0, 539538.0, 542048.0, 543332.0, 543791.0, 94024.52987735017, 97698.511507442, 101523.0, 103139.48430257801, 103275.0, 1030.6289735695143, 1036.8807587127426, 1072.0726571515738, 1072.6674730833467, 1072.8390741388698, 18614.34849780251, 18751.249950001846, 19001.43534946735, 19061.0, 19191.0, 42063.7896810514, 43085.0, 44083.55916440795, 44649.33026004677, 45086.54913450955, 58937.70531147317, 59291.407085928586, 62125.68937155285, 62577.68711156415, 64050.0, 9238.815223695354, 9412.22339409862, 9476.241900648069, 9635.903640963501, 9639.421634701941, 14500.85499144995, 14530.346134423766, 14578.125312481285, 15071.4724984625, 15114.168720720467, 12261.816072759095, 12383.257004579755, 12648.367581620698, 12796.552120675731, 12927.935360323138, 12356.691082723004, 12362.196457230611, 12462.127651064338, 12686.365681716063, 12701.745965080461, 12638.304893230961, 12765.93617031909, 12938.822954238132, 12977.091603588036, 13131.343432828511, 9142.26861851037, 9304.534773261175, 9431.198348140484, 9479.359780930336, 9490.85763713531, 10836.783264334827, 11031.951964563354, 11062.612808551663, 11331.0, 11423.543058277575], "value": 9878.38166124565, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1381.9930900345496, 1525.9465918692845, 1556.0663601838894, 1693.9576510587242, 1789.4631610516842, 8415.0, 9401.247900167988, 9648.85526717099, 9652.517374131283, 9972.551235194416, 9747.561359738811, 9813.509324533763, 9816.361936474124, 9838.0, 9845.556949937252, 2535.4295283561237, 2561.9103331383403, 2562.090457887451, 2562.7052888917733, 2564.384547708546, 2477.8513289202615, 2585.1985884375904, 2587.6636037315125, 2588.4046669265986, 2589.2361753282735, 2516.9119080832174, 2526.7978561715017, 2585.237354980276, 2590.5466543354837, 2592.364870606704, 2507.4107584717576, 2508.76166764158, 2515.421453065796, 2540.0982651158756, 2548.7833534149436, 2503.7371076037116, 2509.04656230633, 2518.2067648690786, 2521.760432758885, 2539.707933587624, 9599.0, 9647.372920760128, 9705.223582113415, 9718.757031074208, 9722.222222222204, 5523.116301391758, 5686.0, 5686.402927692616, 5772.451617096369, 5811.0, 3583.4266517357096, 3624.0758606555423, 3634.854605815764, 3635.0, 3685.4840322354894, 1187.0147777344782, 1187.994060029703, 1196.5094311332398, 1198.8980936620399, 2199.4941163532476, 13433.529826456028, 13886.583402497941, 13916.930415347959, 13926.234057126956, 13948.2328471934, 4717.575418212377, 4945.67853089548, 5010.248462730619, 5155.355580552431, 5156.8968620627375, 10882.129429645767, 10885.510152043176, 10890.401027943539, 10891.183161262938, 10901.672949811518, 5790.0, 5802.390748971382, 5803.042497987869, 5804.361520232771, 5805.274340707411, 4429.889252768642, 4651.86044418668, 4696.81212751493, 4704.929426058612, 5434.456554344534, 4203.76879271643, 4250.192463431938, 4360.236958532244, 4372.191144638188, 4391.429114215131, 1269.4224128021729, 1283.140296001681, 1293.2822283632674, 1296.0538806671184, 1300.9154404963647, 957.2676902169845, 960.6589660670501, 962.3696478806393, 1013.7110923386799, 1039.18424037132, 6363.936360636427, 6565.967170164118, 6585.670716464253, 6589.967050164719, 6673.366030227121, 510.1149505607768, 511.08260672093684, 517.1905967161381, 527.7203082366352, 529.4652401074942, 4265.658747300208, 4277.550857159953, 4348.782560871944, 4390.912181756347, 4454.888627784331, 12012.519499220109, 12342.259464432163, 12472.87527124717, 12485.375731213402, 12498.50005999768, 12378.81431778524, 12402.627921162379, 12410.193337433038, 12413.93793031029, 12415.813762793565, 8597.441166324168, 8957.686480973134, 8968.10318968098, 9166.633334666672, 9190.632374704937, 6656.600603963778, 6727.529072964846, 6773.153355830521, 6896.344847239504, 6956.234814170539, 11002.669919902415, 11358.148138889452, 11518.251313664585, 11539.711507212387, 11599.420028998686, 10303.278770486293, 10671.57313707443, 11757.882421175678, 11831.46758395857, 11832.230904991147, 8588.914110858812, 8797.912020879709, 8880.644774208959, 8909.064548222343, 8963.327750418614, 9040.728778136667, 9175.220106290773, 9395.530223488662, 9398.2951278653, 9398.624055037722, 8169.142240064708, 8234.094249632655, 8283.29591984664, 8345.54099524532, 8547.95726021366, 8059.0, 8087.8382432349845, 8258.752237432887, 8319.833603328021, 8666.566671666265, 7253.8186545336785, 7524.774256772305, 7566.546007239584, 7637.923620763943, 7642.541447513167, 5429.158480435539, 5433.320834895638, 5436.510714035756, 5470.124780035176, 5549.445055494418, 5554.333479982428, 5573.439436957497, 5581.804636837691, 5640.266765320482, 5699.031164701928], "value": 4969.492383138016, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.14055984517483, 356.4243746349645, 362.2935276211388, 372.2796388987308, 372.3725522494595, 5488.134034428299, 5558.861028474285, 5566.805161819337, 5575.386707462176, 5579.609427340083, 5182.948170518293, 5209.082913568404, 5281.18141688038, 5351.197320401932, 5513.862153446166, 4283.250431174548, 4284.464441944757, 4287.464066991633, 4287.957120428787, 4302.956970430287, 4273.529911709709, 4307.052448461349, 4352.6300264477395, 4355.716878402894, 4357.912841743163, 4167.791340511248, 4238.025254191522, 4254.680898932577, 4269.188854117723, 4286.507051689049, 4230.471191101113, 4244.575542445762, 4247.7893800266775, 4249.766262855542, 4260.914781704364, 3781.7541859779026, 3800.37293846515, 3801.581825999138, 3803.0872590578338, 3813.427985802152, 5259.658122222043, 5366.4633536646415, 5377.139657654796, 5383.542398896098, 5393.78424863005, 4898.926516102261, 4910.90178196434, 4923.75381230937, 4925.0, 4933.703977761345, 5690.40250773667, 5703.54371650267, 5716.599028408079, 5739.483446489794, 5744.310682718059, 4129.15352352767, 4159.708820382574, 4189.706720529564, 4195.454590903163, 4201.390798334224, 5912.368995796872, 5923.763049477972, 5926.140709597084, 5935.970320148414, 5950.5834591578605, 4211.768352740629, 4226.0, 4254.510731265944, 4265.296226122717, 4268.189044081614, 4137.958620413818, 4268.530461649216, 4293.141371725605, 4299.462567179103, 4303.569643035734, 4818.831340903052, 4825.418401155365, 4832.299316599144, 4836.46798852126, 4837.37114175155, 5740.770369185269, 5752.539796816328, 5762.683052432073, 5769.134629805562, 5771.85570360736, 1419.2123371528694, 1431.935562899672, 1492.5074725340612, 1494.9103053816805, 1521.764126560385, 968.2544440780559, 989.3322007644811, 1003.0320740485466, 1004.217513912796, 1008.9809292614513, 5549.94551035302, 5581.888362232733, 5603.551715862721, 5636.619028338084, 5651.773929042793, 5686.175504551899, 5896.528277737771, 5897.616654917502, 5902.6458412495385, 5910.438508341701, 5514.28314319144, 5517.393086760453, 5523.226748255248, 5524.806631767869, 5534.391216966131, 3084.228942764309, 3095.5296234288535, 3099.705527974839, 3100.3644252928098, 3126.1871913302252, 5856.941430585724, 5906.704664766745, 5969.432903874125, 5992.333411631782, 6070.180525629072, 5904.940950590439, 5912.438318359749, 5918.638713096012, 5931.733072011683, 5966.0, 5772.740226689725, 5998.580099393088, 6028.758849645965, 6064.848378790565, 6071.817845464643, 5693.202951586698, 5766.740496677576, 5769.94230057694, 5772.509336706259, 5811.622244554091, 5728.713564321851, 5731.426857314235, 5756.194132821324, 5790.160426738015, 5806.738696758741, 5841.4367235986865, 5903.7048147593305, 5949.787114957874, 5997.220361352996, 6054.364291749479, 4210.621044106045, 4218.915621687611, 4226.260404429151, 4235.30117530604, 4256.553061928576, 5787.421257874179, 5799.101139323411, 5803.970980145073, 5829.0, 5844.6785426801935, 5455.536279416294, 5633.6619802811965, 5639.464250896158, 5672.744726487235, 5672.893785711746, 5661.830145095653, 5664.631798933056, 5670.206171136127, 5686.545076393796, 5702.6293290935955, 5457.563394928476, 5524.254225679482, 5554.2779438672715, 5582.916256256078, 5652.85191627179, 5702.0, 5719.48524632785, 5741.3971532988435, 5751.051076572318, 5767.8558036049435, 5500.80747173847, 5566.051881841323, 5596.132599447093, 5621.8594535136945, 5649.745761440663], "value": 4379.253366398414, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [786.2098590916132, 791.9485233459828, 799.308598062676, 800.2717527050384, 856.6616186606288, 15323.310451029702, 16490.175491225422, 16496.010239385647, 16531.338746450143, 16577.33690652374, 12969.675758106052, 12982.221066736003, 13022.218666879993, 13041.67395815105, 13051.151675141109, 710.4706993289981, 724.0117239967433, 838.1618381618382, 844.6579135450153, 862.2239984014375, 859.1794835931702, 860.3418384935499, 861.014138811061, 861.7845538615346, 862.1464749897619, 902.9819403611924, 903.9005709371996, 905.7735566108473, 906.5150144672601, 907.9818403631923, 746.2500187311745, 757.9166291707935, 871.4771137317622, 883.8762573239752, 898.7753061734567, 660.115445303291, 663.6615326183633, 665.2416245480157, 666.3902529185789, 666.8999650052531, 13689.931550342184, 13776.862231377658, 13780.0, 13796.931015344959, 13797.310134493235, 13512.932435337858, 13581.185128892299, 13801.309934503235, 14036.578902632937, 14036.859631403657, 13010.349482525931, 13106.541271055563, 13205.537806176833, 13318.334083295797, 13343.266120363376, 4462.308342206963, 4484.1928452878465, 4494.775261236925, 4513.50351461339, 4578.587927086545, 87654.99999999936, 88988.0, 89028.55485722594, 89181.10818891793, 89558.5522072392, 11132.276402033842, 11211.943940280247, 11303.773924521463, 11757.529698811952, 11888.465019074163, 22586.435339116655, 22666.66000010001, 22716.65925011126, 22743.545129097656, 22972.770272297064, 8527.403081784216, 8592.570371481526, 8754.299656027502, 8806.911930880737, 8934.50860202682, 4717.693349932312, 4823.517648235148, 4845.224764037737, 5044.949550504448, 5117.130087885069, 3392.6776956189124, 3539.0975301298004, 3546.184377593168, 3547.024568243702, 3551.449525323579, 531.3039917707815, 595.4135176850798, 611.62996387186, 616.6146158650844, 624.3506752976854, 635.9236891572948, 695.9025736396909, 698.6332175607846, 721.8448033672786, 743.7471259771595, 9413.90586094144, 9602.95198524003, 9632.27757918159, 9697.660581879742, 9758.658446954465, 667.9563997644847, 681.7852376501386, 683.1290105115918, 696.4010950582496, 731.7950973727364, 4542.000759832831, 4749.976250118727, 4763.595094416944, 4791.281307803857, 4799.66402351832, 99155.50422247843, 101215.49392252992, 103711.0, 135683.0, 139490.0, 97353.0, 100565.0, 101637.0, 102412.0, 104819.0, 87259.0, 87501.12498875057, 87875.56062219777, 90259.0, 93839.53080234691, 9358.251339892928, 9688.982656820932, 9693.061387722342, 9724.805503890024, 9787.559559819967, 51809.0, 52169.217461737346, 52779.20831187612, 52992.20511692251, 53054.93890122099, 72206.0, 77052.37685738874, 77368.61315693386, 79558.0, 79601.0, 13539.187648740713, 13702.86297137016, 13754.31228438594, 13879.0, 14016.369263383376, 13934.930325348309, 14131.222782747047, 14228.075175113998, 15464.5360639181, 15867.28597213105, 13937.233452160228, 14059.718805624036, 14339.641508961942, 14392.568222953329, 14600.853991460375, 13969.022168448111, 14279.64300892486, 14319.642008949859, 14616.926915365355, 14620.195889226196, 13278.601641950758, 13872.375743091383, 14228.857711422754, 14641.121532708072, 15844.76232856485, 10562.78874422492, 10995.835062474229, 10998.945005274922, 11139.665810025712, 11779.88220117788, 12199.634010979686, 12372.752544949231, 12464.688382790502, 12705.55530556463, 12708.173968692005], "value": 7751.040420334634, "unit": "score"}], "name": "dromaeo_css", "value": 7648.250742156225, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 111.0, 109.0, 111.0, 111.0, 112.0, 111.0, 112.0, 112.0, 112.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 121.0, 118.0, 149.0, 119.0, 120.0, 118.0, 116.0, 149.0, 119.0], "value": 127.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 159.0, 160.0, 161.0, 161.0, 159.0, 160.0, 161.0, 161.0, 160.0], "value": 160.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 74.0, 76.0, 109.0, 75.0, 74.0, 75.0, 76.0, 108.0, 76.0], "value": 81.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 115.0, 82.0, 81.0, 81.0, 106.0, 82.0, 80.0, 109.0], "value": 90.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 145.0, 145.0, 146.0, 145.0, 146.0, 145.0, 146.0, 145.0], "value": 145.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 95.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 50.0, 52.0, 50.0, 50.0, 50.0, 52.0, 49.0, 51.0, 49.0], "value": 50.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 67.0, 57.0, 58.0, 57.0, 58.0, 58.0, 58.0, 57.0], "value": 58.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 72.0, 74.0, 75.0, 81.0, 74.0, 73.0, 73.0, 73.0, 74.0], "value": 74.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 123.0, 127.0, 117.0, 129.0, 121.0, 128.0, 117.0, 166.0, 120.0], "value": 127.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 150.0, 151.0, 148.0, 151.0, 149.0, 149.0, 152.0, 150.0, 153.0], "value": 150.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 66.0, 63.0, 62.0, 64.0, 63.0, 62.0, 62.0, 61.0], "value": 63.3, "unit": "score"}], "name": "kraken", "value": 1452.2000000000003, "alertThreshold": 2.0}]} 17:23:32 INFO - Return code: 0 17:23:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:23:32 INFO - # TBPL SUCCESS # 17:23:32 INFO - Running post-action listener: _resource_record_post_action 17:23:32 INFO - [mozharness: 2016-11-11 01:23:32.992348Z] Finished run-tests step (success) 17:23:32 INFO - Running post-run listener: _resource_record_post_run 17:23:33 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 358555648; Read time: 12; Write time: 231256 17:23:33 INFO - TinderboxPrint: CPU usage
12.9% 17:23:33 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 12 17:23:33 INFO - TinderboxPrint: I/O write bytes / time
358,555,648 / 231,256 17:23:33 INFO - TinderboxPrint: CPU idle
8,262.2 (87.1%) 17:23:33 INFO - TinderboxPrint: CPU user
1,185.8 (12.5%) 17:23:33 INFO - TinderboxPrint: Swap in / out
0 / 0 17:23:33 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 107384832; Read time: 0; Write time: 70516 17:23:33 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 250019840; Read time: 0; Write time: 160632 17:23:33 INFO - Running post-run listener: _upload_blobber_files 17:23:33 INFO - Blob upload gear active. 17:23:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:23:33 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 17:23:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:23:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:23:34 INFO - (blobuploader) - INFO - Open directory for files ... 17:23:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:23:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:23:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:23:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:23:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:23:34 INFO - (blobuploader) - INFO - Done attempting. 17:23:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:23:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:23:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:23:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:23:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:23:35 INFO - (blobuploader) - INFO - Done attempting. 17:23:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:23:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:23:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:23:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:23:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:23:38 INFO - (blobuploader) - INFO - Done attempting. 17:23:38 INFO - (blobuploader) - INFO - Iteration through files over. 17:23:38 INFO - Return code: 0 17:23:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:23:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:23:38 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a2a2cd5942b87cbbfae2e223c23e1e3bb2e80d9ea749a97847f178b544dc316bffb1fd9f72e879fb6fc84fe99a6ba088a306238eef9ae09eef848582bb51e60f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/49507a0e14cddd0253afa498ac01b0bae000e4eb051d3535484e33189a610ff4f7b4baae2cad3213013d2ed1144eeca8b75ff789cbcb8797a229992dbbf15ff2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2e2e8df94c0661924a37420a314b0eae5ad1c788e95500516b267e69926ef315c034189b291176720b1dba0b5585d73a18c4f54f91d3b3ebc85ff4c5a21b692d"} 17:23:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:23:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:23:38 INFO - Contents: 17:23:38 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a2a2cd5942b87cbbfae2e223c23e1e3bb2e80d9ea749a97847f178b544dc316bffb1fd9f72e879fb6fc84fe99a6ba088a306238eef9ae09eef848582bb51e60f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/49507a0e14cddd0253afa498ac01b0bae000e4eb051d3535484e33189a610ff4f7b4baae2cad3213013d2ed1144eeca8b75ff789cbcb8797a229992dbbf15ff2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2e2e8df94c0661924a37420a314b0eae5ad1c788e95500516b267e69926ef315c034189b291176720b1dba0b5585d73a18c4f54f91d3b3ebc85ff4c5a21b692d"} 17:23:38 INFO - Running post-run listener: copy_logs_to_upload_dir 17:23:38 INFO - Copying logs to upload dir... 17:23:38 INFO - mkdir: /builds/slave/test/build/upload/logs 17:23:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1232.915320 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-11-10 17:23:37.376678) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-10 17:23:37.380528) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1478826071.42805-465473151 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a2a2cd5942b87cbbfae2e223c23e1e3bb2e80d9ea749a97847f178b544dc316bffb1fd9f72e879fb6fc84fe99a6ba088a306238eef9ae09eef848582bb51e60f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/49507a0e14cddd0253afa498ac01b0bae000e4eb051d3535484e33189a610ff4f7b4baae2cad3213013d2ed1144eeca8b75ff789cbcb8797a229992dbbf15ff2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2e2e8df94c0661924a37420a314b0eae5ad1c788e95500516b267e69926ef315c034189b291176720b1dba0b5585d73a18c4f54f91d3b3ebc85ff4c5a21b692d"} build_url:https://queue.taskcluster.net/v1/task/VzphWR2ESeavuGTJ7NawUQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011254 build_url: 'https://queue.taskcluster.net/v1/task/VzphWR2ESeavuGTJ7NawUQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a2a2cd5942b87cbbfae2e223c23e1e3bb2e80d9ea749a97847f178b544dc316bffb1fd9f72e879fb6fc84fe99a6ba088a306238eef9ae09eef848582bb51e60f", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/49507a0e14cddd0253afa498ac01b0bae000e4eb051d3535484e33189a610ff4f7b4baae2cad3213013d2ed1144eeca8b75ff789cbcb8797a229992dbbf15ff2", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2e2e8df94c0661924a37420a314b0eae5ad1c788e95500516b267e69926ef315c034189b291176720b1dba0b5585d73a18c4f54f91d3b3ebc85ff4c5a21b692d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-10 17:23:37.407862) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-10 17:23:37.408316) ========= 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=5ac5dba3e01a12f1ff77ab15000001b1-1478826071.42805-465473151 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004510 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-10 17:23:37.492016) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-10 17:23:37.492361) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-10 17:23:37.492662) ========= ========= Total master_lag: 0.20 =========