builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-051 starttime: 1481062132.81 results: success (0) buildid: 20161206123003 builduid: 319704bc26d34ff6a463631181316788 revision: 0d7431e24546049ee2bd0fb1e05a5b124ed01f81 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-06 14:08:52.815296) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-06 14:08:52.815683) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-06 14:08:52.815961) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-06 14:08:52.844255) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-06 14:08:52.844521) ========= 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-1481059395.754471-975443180 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-06 14:08:54-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 42.3M=0s 2016-12-06 14:08:54 (42.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.165605 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-06 14:08:53.023570) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-06 14:08:53.023850) ========= 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-1481059395.754471-975443180 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017563 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-06 14:08:53.072938) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-06 14:08:53.073252) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 0d7431e24546049ee2bd0fb1e05a5b124ed01f81 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 0d7431e24546049ee2bd0fb1e05a5b124ed01f81 --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-1481059395.754471-975443180 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-06 14:08:55,068 truncating revision to first 12 chars 2016-12-06 14:08:55,068 Setting DEBUG logging. 2016-12-06 14:08:55,068 attempt 1/10 2016-12-06 14:08:55,068 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/0d7431e24546?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-06 14:08:55,382 unpacking tar archive at: mozilla-inbound-0d7431e24546/testing/mozharness/ program finished with exit code 0 elapsedTime=0.613885 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-06 14:08:53.700808) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-06 14:08:53.701152) ========= script_repo_revision: 0d7431e24546049ee2bd0fb1e05a5b124ed01f81 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-06 14:08:53.701558) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-06 14:08:53.701829) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-06 14:08:53.712010) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-12-06 14:08:53.712366) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 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-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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-1481059395.754471-975443180 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 14:08:55 INFO - MultiFileLogger online at 20161206 14:08:55 in /builds/slave/test 14:08:55 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 14:08:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:08:55 INFO - {'append_to_log': False, 14:08:55 INFO - 'base_work_dir': '/builds/slave/test', 14:08:55 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 14:08:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:08:55 INFO - 'branch': 'Mozilla-Inbound', 14:08:55 INFO - 'buildbot_json_path': 'buildprops.json', 14:08:55 INFO - 'config_files': ('talos/linux_config.py',), 14:08:55 INFO - 'default_actions': ('clobber', 14:08:55 INFO - 'read-buildbot-config', 14:08:55 INFO - 'download-and-extract', 14:08:55 INFO - 'populate-webroot', 14:08:55 INFO - 'create-virtualenv', 14:08:55 INFO - 'install', 14:08:55 INFO - 'run-tests'), 14:08:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:08:55 INFO - 'download_minidump_stackwalk': True, 14:08:55 INFO - 'download_symbols': 'ondemand', 14:08:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:08:55 INFO - 'tooltool.py': '/tools/tooltool.py', 14:08:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:08:55 INFO - '/tools/misc-python/virtualenv.py')}, 14:08:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:08:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:08:55 INFO - 'installer_path': 'installer.exe', 14:08:55 INFO - 'log_level': 'info', 14:08:55 INFO - 'log_name': 'talos', 14:08:55 INFO - 'log_to_console': True, 14:08:55 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 14:08:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 14:08:55 INFO - 'opt_config_files': (), 14:08:55 INFO - 'pip_index': False, 14:08:55 INFO - 'sps_profile': False, 14:08:55 INFO - 'sps_profile_interval': 0, 14:08:55 INFO - 'suite': 'dromaeojs', 14:08:55 INFO - 'system_bits': '32', 14:08:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:08:55 INFO - 'title': 'talos-linux64-ix-051', 14:08:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:08:55 INFO - 'use_talos_json': True, 14:08:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:08:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:08:55 INFO - 'work_dir': 'build'} 14:08:55 INFO - [mozharness: 2016-12-06 22:08:55.841974Z] Running clobber step. 14:08:55 INFO - Running pre-action listener: _resource_record_pre_action 14:08:55 INFO - Running main action method: clobber 14:08:55 INFO - rmtree: /builds/slave/test/build 14:08:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:08:56 INFO - Running post-action listener: _resource_record_post_action 14:08:56 INFO - [mozharness: 2016-12-06 22:08:56.823698Z] Finished clobber step (success) 14:08:56 INFO - [mozharness: 2016-12-06 22:08:56.823797Z] Running read-buildbot-config step. 14:08:56 INFO - Running pre-action listener: _resource_record_pre_action 14:08:56 INFO - Running main action method: read_buildbot_config 14:08:56 INFO - Using buildbot properties: 14:08:56 INFO - { 14:08:56 INFO - "project": "", 14:08:56 INFO - "product": "firefox", 14:08:56 INFO - "script_repo_revision": "production", 14:08:56 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 14:08:56 INFO - "repository": "", 14:08:56 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 14:08:56 INFO - "buildid": "20161206123003", 14:08:56 INFO - "pgo_build": "True", 14:08:56 INFO - "basedir": "/builds/slave/test", 14:08:56 INFO - "buildnumber": 2198, 14:08:56 INFO - "slavename": "talos-linux64-ix-051", 14:08:56 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 14:08:56 INFO - "platform": "ubuntu64_hw", 14:08:56 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 14:08:56 INFO - "branch": "mozilla-inbound", 14:08:56 INFO - "repo_path": "integration/mozilla-inbound", 14:08:56 INFO - "revision": "0d7431e24546049ee2bd0fb1e05a5b124ed01f81", 14:08:56 INFO - "stage_platform": "linux64-pgo", 14:08:56 INFO - "builduid": "319704bc26d34ff6a463631181316788", 14:08:56 INFO - "slavebuilddir": "test" 14:08:56 INFO - } 14:08:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/AJJNpGB5Q3maw7AaqrumBQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2. 14:08:56 INFO - Running post-action listener: _resource_record_post_action 14:08:56 INFO - [mozharness: 2016-12-06 22:08:56.826205Z] Finished read-buildbot-config step (success) 14:08:56 INFO - [mozharness: 2016-12-06 22:08:56.826293Z] Running download-and-extract step. 14:08:56 INFO - Running pre-action listener: _resource_record_pre_action 14:08:56 INFO - Running main action method: download_and_extract 14:08:56 INFO - mkdir: /builds/slave/test/build/tests 14:08:56 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')]} 14:08:56 INFO - trying https://queue.taskcluster.net/v1/task/AJJNpGB5Q3maw7AaqrumBQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 14:08:56 INFO - Downloading https://queue.taskcluster.net/v1/task/AJJNpGB5Q3maw7AaqrumBQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 14:08:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AJJNpGB5Q3maw7AaqrumBQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 14:08:57 INFO - Downloaded 1361 bytes. 14:08:57 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.test_packages.json 14:08:57 INFO - Using the following test package requirements: 14:08:57 INFO - {u'common': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 14:08:57 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:08:57 INFO - u'firefox-53.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 14:08:57 INFO - u'gtest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:08:57 INFO - u'firefox-53.0a1.en-US.linux-x86_64.gtest.tests.zip'], 14:08:57 INFO - u'jittest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:08:57 INFO - u'jsshell-linux-x86_64.zip'], 14:08:57 INFO - u'mochitest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:08:57 INFO - u'firefox-53.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 14:08:57 INFO - u'mozbase': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'], 14:08:57 INFO - u'reftest': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:08:57 INFO - u'firefox-53.0a1.en-US.linux-x86_64.reftest.tests.zip'], 14:08:57 INFO - u'talos': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:08:57 INFO - u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'], 14:08:57 INFO - u'web-platform': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:08:57 INFO - u'firefox-53.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 14:08:57 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', 14:08:57 INFO - u'firefox-53.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 14:08:57 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 14:08:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AJJNpGB5Q3maw7AaqrumBQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 14:08:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AJJNpGB5Q3maw7AaqrumBQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:08:57 INFO - Fetch https://queue.taskcluster.net/v1/task/AJJNpGB5Q3maw7AaqrumBQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 14:08:58 INFO - Expected file size: 21781956 14:08:58 INFO - Obtained file size: 21781956 14:09:03 INFO - Downloading packages: [u'firefox-53.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 14:09:03 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AJJNpGB5Q3maw7AaqrumBQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip 14:09:03 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AJJNpGB5Q3maw7AaqrumBQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 14:09:03 INFO - Fetch https://queue.taskcluster.net/v1/task/AJJNpGB5Q3maw7AaqrumBQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.common.tests.zip into memory 14:09:03 INFO - Expected file size: 21781956 14:09:03 INFO - Obtained file size: 21781956 14:09:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AJJNpGB5Q3maw7AaqrumBQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip 14:09:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AJJNpGB5Q3maw7AaqrumBQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 14:09:08 INFO - Fetch https://queue.taskcluster.net/v1/task/AJJNpGB5Q3maw7AaqrumBQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.talos.tests.zip into memory 14:09:09 INFO - Expected file size: 14409734 14:09:09 INFO - Obtained file size: 14409734 14:09:10 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')]} 14:09:10 INFO - trying https://queue.taskcluster.net/v1/task/AJJNpGB5Q3maw7AaqrumBQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 14:09:10 INFO - Downloading https://queue.taskcluster.net/v1/task/AJJNpGB5Q3maw7AaqrumBQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 14:09:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AJJNpGB5Q3maw7AaqrumBQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 14:09:12 INFO - Downloaded 59832788 bytes. 14:09:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AJJNpGB5Q3maw7AaqrumBQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 14:09:12 INFO - mkdir: /builds/slave/test/properties 14:09:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:09:12 INFO - Writing to file /builds/slave/test/properties/build_url 14:09:12 INFO - Contents: 14:09:12 INFO - build_url:https://queue.taskcluster.net/v1/task/AJJNpGB5Q3maw7AaqrumBQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 14:09:13 INFO - Running post-action listener: _resource_record_post_action 14:09:13 INFO - Running post-action listener: set_extra_try_arguments 14:09:13 INFO - [mozharness: 2016-12-06 22:09:13.062945Z] Finished download-and-extract step (success) 14:09:13 INFO - [mozharness: 2016-12-06 22:09:13.063173Z] Running populate-webroot step. 14:09:13 INFO - Running pre-action listener: _resource_record_pre_action 14:09:13 INFO - Running main action method: populate_webroot 14:09:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:09:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:09:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:09:13 INFO - '--tppagecycles', 14:09:13 INFO - '7'], 14:09:13 INFO - 'tests': ['tsvgm']}}, 14:09:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:09:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:09:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:09:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:09:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:09:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:09:13 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 14:09:13 INFO - 'talos_options': ['--disable-e10s'], 14:09:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:09:13 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 14:09:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:09:13 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 14:09:13 INFO - 'talos_options': ['--disable-e10s'], 14:09:13 INFO - 'tests': ['damp', 'tps']}, 14:09:13 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 14:09:13 INFO - 'tests': ['damp', 'tps']}, 14:09:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:09:13 INFO - 'tests': ['dromaeo_dom']}, 14:09:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:09:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:09:13 INFO - 'tests': ['basic_compositor_video']}, 14:09:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:09:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:09:13 INFO - 'tests': ['a11yr', 14:09:13 INFO - 'ts_paint', 14:09:13 INFO - 'tpaint', 14:09:13 INFO - 'sessionrestore', 14:09:13 INFO - 'sessionrestore_no_auto_restore', 14:09:13 INFO - 'tabpaint']}, 14:09:13 INFO - 'other-e10s': {'tests': ['a11yr', 14:09:13 INFO - 'ts_paint', 14:09:13 INFO - 'tpaint', 14:09:13 INFO - 'sessionrestore', 14:09:13 INFO - 'sessionrestore_no_auto_restore', 14:09:13 INFO - 'tabpaint']}, 14:09:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:09:13 INFO - 'ts_paint', 14:09:13 INFO - 'tpaint', 14:09:13 INFO - 'sessionrestore', 14:09:13 INFO - 'sessionrestore_no_auto_restore', 14:09:13 INFO - 'tabpaint']}, 14:09:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:09:13 INFO - 'ts_paint', 14:09:13 INFO - 'tpaint', 14:09:13 INFO - 'sessionrestore', 14:09:13 INFO - 'sessionrestore_no_auto_restore', 14:09:13 INFO - 'tabpaint']}, 14:09:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:09:13 INFO - 'tests': ['a11yr', 14:09:13 INFO - 'ts_paint', 14:09:13 INFO - 'tpaint', 14:09:13 INFO - 'sessionrestore', 14:09:13 INFO - 'sessionrestore_no_auto_restore', 14:09:13 INFO - 'tabpaint']}, 14:09:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:09:13 INFO - 'tests': ['a11yr', 14:09:13 INFO - 'ts_paint', 14:09:13 INFO - 'tpaint', 14:09:13 INFO - 'sessionrestore', 14:09:13 INFO - 'sessionrestore_no_auto_restore', 14:09:13 INFO - 'tabpaint']}, 14:09:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:09:13 INFO - 'tests': ['tsvgx', 14:09:13 INFO - 'tsvgr_opacity', 14:09:13 INFO - 'tart', 14:09:13 INFO - 'tscrollx', 14:09:13 INFO - 'cart', 14:09:13 INFO - 'tsvg_static']}, 14:09:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:09:13 INFO - 'tsvgr_opacity', 14:09:13 INFO - 'tart', 14:09:13 INFO - 'tscrollx', 14:09:13 INFO - 'cart', 14:09:13 INFO - 'tsvg_static']}, 14:09:13 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 14:09:13 INFO - 'talos_options': ['--disable-e10s'], 14:09:13 INFO - 'tests': ['tp5o']}, 14:09:13 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 14:09:13 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 14:09:13 INFO - 'talos_options': ['--disable-e10s', 14:09:13 INFO - '--xperf_path', 14:09:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:09:13 INFO - 'tests': ['tp5n']}, 14:09:13 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 14:09:13 INFO - 'talos_options': ['--xperf_path', 14:09:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:09:13 INFO - 'tests': ['tp5n']}}, 14:09:13 INFO - 'talos.zip': {'path': '', 14:09:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:09:13 INFO - Running post-action listener: _resource_record_post_action 14:09:13 INFO - [mozharness: 2016-12-06 22:09:13.079219Z] Finished populate-webroot step (success) 14:09:13 INFO - [mozharness: 2016-12-06 22:09:13.079307Z] Running create-virtualenv step. 14:09:13 INFO - Running pre-action listener: _resource_record_pre_action 14:09:13 INFO - Running main action method: create_virtualenv 14:09:13 INFO - Creating virtualenv /builds/slave/test/build/venv 14:09:13 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 14:09:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:09:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:09:13 INFO - Using real prefix '/usr' 14:09:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:09:13 INFO - Installing distribute.............................................................................................................................................................................................done. 14:09:15 INFO - Installing pip.................done. 14:09:15 INFO - Return code: 0 14:09:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:09:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:09:15 INFO - Reading from file tmpfile_stdout 14:09:15 INFO - Output received: 14:09:15 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 14:09:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:09:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:09:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:09:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe83757e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27dd120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286df20>, '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 0x27dac20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27daf90>, '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-1481059395.754471-975443180', '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 14:09:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:09:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:09:15 INFO - Using env: {'DISPLAY': ':0', 14:09:15 INFO - 'HOME': '/home/cltbld', 14:09:15 INFO - 'LANG': 'en_US.UTF-8', 14:09:15 INFO - 'LANGUAGE': 'en_US:en', 14:09:15 INFO - 'LOGNAME': 'cltbld', 14:09:15 INFO - 'MAIL': '/var/mail/cltbld', 14:09:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:15 INFO - 'MOZ_NO_REMOTE': '1', 14:09:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:09:15 INFO - 'NO_EM_RESTART': '1', 14:09:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:09:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:15 INFO - 'PWD': '/builds/slave/test', 14:09:15 INFO - 'SHELL': '/bin/bash', 14:09:15 INFO - 'SHLVL': '1', 14:09:15 INFO - 'TERM': 'linux', 14:09:15 INFO - 'TMOUT': '86400', 14:09:15 INFO - 'USER': 'cltbld', 14:09:15 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1481059395.754471-975443180', 14:09:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:15 INFO - '_': '/tools/buildbot/bin/python'} 14:09:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:16 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 14:09:16 INFO - Cleaning up... 14:09:16 INFO - Return code: 0 14:09:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:09:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:09:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:16 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 0x7ffe83757e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27dd120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286df20>, '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 0x27dac20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27daf90>, '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-1481059395.754471-975443180', '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 14:09:16 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 14:09:16 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 14:09:16 INFO - Using env: {'DISPLAY': ':0', 14:09:16 INFO - 'HOME': '/home/cltbld', 14:09:16 INFO - 'LANG': 'en_US.UTF-8', 14:09:16 INFO - 'LANGUAGE': 'en_US:en', 14:09:16 INFO - 'LOGNAME': 'cltbld', 14:09:16 INFO - 'MAIL': '/var/mail/cltbld', 14:09:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:16 INFO - 'MOZ_NO_REMOTE': '1', 14:09:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:09:16 INFO - 'NO_EM_RESTART': '1', 14:09:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:09:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:16 INFO - 'PWD': '/builds/slave/test', 14:09:16 INFO - 'SHELL': '/bin/bash', 14:09:16 INFO - 'SHLVL': '1', 14:09:16 INFO - 'TERM': 'linux', 14:09:16 INFO - 'TMOUT': '86400', 14:09:16 INFO - 'USER': 'cltbld', 14:09:16 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1481059395.754471-975443180', 14:09:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:16 INFO - '_': '/tools/buildbot/bin/python'} 14:09:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:16 INFO - Downloading/unpacking psutil>=3.1.1 14:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:09:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:09:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:09:18 INFO - Installing collected packages: psutil 14:09:18 INFO - Running setup.py install for psutil 14:09:18 INFO - building 'psutil._psutil_linux' extension 14:09:18 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 14:09:18 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 14:09:18 INFO - building 'psutil._psutil_posix' extension 14:09:18 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 14:09:18 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 14:09:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:09:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:09:18 INFO - Successfully installed psutil 14:09:18 INFO - Cleaning up... 14:09:19 INFO - Return code: 0 14:09:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:09:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:09:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:09:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe83757e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27dd120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286df20>, '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 0x27dac20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27daf90>, '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-1481059395.754471-975443180', '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 14:09:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:09:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:09:19 INFO - Using env: {'DISPLAY': ':0', 14:09:19 INFO - 'HOME': '/home/cltbld', 14:09:19 INFO - 'LANG': 'en_US.UTF-8', 14:09:19 INFO - 'LANGUAGE': 'en_US:en', 14:09:19 INFO - 'LOGNAME': 'cltbld', 14:09:19 INFO - 'MAIL': '/var/mail/cltbld', 14:09:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:19 INFO - 'MOZ_NO_REMOTE': '1', 14:09:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:09:19 INFO - 'NO_EM_RESTART': '1', 14:09:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:09:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:19 INFO - 'PWD': '/builds/slave/test', 14:09:19 INFO - 'SHELL': '/bin/bash', 14:09:19 INFO - 'SHLVL': '1', 14:09:19 INFO - 'TERM': 'linux', 14:09:19 INFO - 'TMOUT': '86400', 14:09:19 INFO - 'USER': 'cltbld', 14:09:19 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1481059395.754471-975443180', 14:09:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:19 INFO - '_': '/tools/buildbot/bin/python'} 14:09:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:09:19 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 14:09:19 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 14:09:19 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 14:09:19 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 14:09:19 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 14:09:19 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 14:09:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:09:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:09:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:09:21 INFO - Installing collected packages: mozsystemmonitor 14:09:21 INFO - Running setup.py install for mozsystemmonitor 14:09:21 INFO - Successfully installed mozsystemmonitor 14:09:21 INFO - Cleaning up... 14:09:21 INFO - Return code: 0 14:09:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:09:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:09:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:09:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:21 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 0x7ffe83757e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27dd120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286df20>, '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 0x27dac20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27daf90>, '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-1481059395.754471-975443180', '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 14:09:21 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 14:09:21 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 14:09:21 INFO - Using env: {'DISPLAY': ':0', 14:09:21 INFO - 'HOME': '/home/cltbld', 14:09:21 INFO - 'LANG': 'en_US.UTF-8', 14:09:21 INFO - 'LANGUAGE': 'en_US:en', 14:09:21 INFO - 'LOGNAME': 'cltbld', 14:09:21 INFO - 'MAIL': '/var/mail/cltbld', 14:09:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:21 INFO - 'MOZ_NO_REMOTE': '1', 14:09:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:09:21 INFO - 'NO_EM_RESTART': '1', 14:09:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:09:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:21 INFO - 'PWD': '/builds/slave/test', 14:09:21 INFO - 'SHELL': '/bin/bash', 14:09:21 INFO - 'SHLVL': '1', 14:09:21 INFO - 'TERM': 'linux', 14:09:21 INFO - 'TMOUT': '86400', 14:09:21 INFO - 'USER': 'cltbld', 14:09:21 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1481059395.754471-975443180', 14:09:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:21 INFO - '_': '/tools/buildbot/bin/python'} 14:09:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:21 INFO - Downloading/unpacking jsonschema==2.5.1 14:09:21 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 14:09:21 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 14:09:21 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 14:09:21 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 14:09:21 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 14:09:21 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 14:09:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:09:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:23 INFO - Downloading functools32-3.2.3-2.tar.gz 14:09:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:09:23 INFO - warning: no files found matching '*.txt' 14:09:23 INFO - no previously-included directories found matching 'build' 14:09:23 INFO - no previously-included directories found matching 'dist' 14:09:23 INFO - no previously-included directories found matching '.git*' 14:09:23 INFO - Installing collected packages: jsonschema, functools32 14:09:23 INFO - Running setup.py install for functools32 14:09:23 INFO - warning: no files found matching '*.txt' 14:09:23 INFO - no previously-included directories found matching 'build' 14:09:23 INFO - no previously-included directories found matching 'dist' 14:09:23 INFO - no previously-included directories found matching '.git*' 14:09:24 INFO - Successfully installed jsonschema functools32 14:09:24 INFO - Cleaning up... 14:09:24 INFO - Return code: 0 14:09:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:09:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:09:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:09:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:24 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 0x7ffe83757e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27dd120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286df20>, '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 0x27dac20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27daf90>, '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-1481059395.754471-975443180', '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 14:09:24 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 14:09:24 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 14:09:24 INFO - Using env: {'DISPLAY': ':0', 14:09:24 INFO - 'HOME': '/home/cltbld', 14:09:24 INFO - 'LANG': 'en_US.UTF-8', 14:09:24 INFO - 'LANGUAGE': 'en_US:en', 14:09:24 INFO - 'LOGNAME': 'cltbld', 14:09:24 INFO - 'MAIL': '/var/mail/cltbld', 14:09:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:24 INFO - 'MOZ_NO_REMOTE': '1', 14:09:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:09:24 INFO - 'NO_EM_RESTART': '1', 14:09:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:09:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:24 INFO - 'PWD': '/builds/slave/test', 14:09:24 INFO - 'SHELL': '/bin/bash', 14:09:24 INFO - 'SHLVL': '1', 14:09:24 INFO - 'TERM': 'linux', 14:09:24 INFO - 'TMOUT': '86400', 14:09:24 INFO - 'USER': 'cltbld', 14:09:24 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1481059395.754471-975443180', 14:09:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:24 INFO - '_': '/tools/buildbot/bin/python'} 14:09:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:09:24 INFO - Cleaning up... 14:09:24 INFO - Return code: 0 14:09:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:09:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:09:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:09:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:24 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 0x7ffe83757e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27dd120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286df20>, '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 0x27dac20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27daf90>, '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-1481059395.754471-975443180', '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 14:09:24 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 14:09:24 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 14:09:24 INFO - Using env: {'DISPLAY': ':0', 14:09:24 INFO - 'HOME': '/home/cltbld', 14:09:24 INFO - 'LANG': 'en_US.UTF-8', 14:09:24 INFO - 'LANGUAGE': 'en_US:en', 14:09:24 INFO - 'LOGNAME': 'cltbld', 14:09:24 INFO - 'MAIL': '/var/mail/cltbld', 14:09:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:24 INFO - 'MOZ_NO_REMOTE': '1', 14:09:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:09:24 INFO - 'NO_EM_RESTART': '1', 14:09:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:09:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:24 INFO - 'PWD': '/builds/slave/test', 14:09:24 INFO - 'SHELL': '/bin/bash', 14:09:24 INFO - 'SHLVL': '1', 14:09:24 INFO - 'TERM': 'linux', 14:09:24 INFO - 'TMOUT': '86400', 14:09:24 INFO - 'USER': 'cltbld', 14:09:24 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1481059395.754471-975443180', 14:09:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:24 INFO - '_': '/tools/buildbot/bin/python'} 14:09:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:24 INFO - Downloading/unpacking blobuploader==1.2.4 14:09: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 14:09: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 14:09: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 14:09: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 14:09: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 14:09: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 14:09:26 INFO - Downloading blobuploader-1.2.4.tar.gz 14:09:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:09:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:09: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 14:09: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 14:09: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 14:09: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 14:09: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 14:09: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 14:09:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:09:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:09:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:09:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:09:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:09:27 INFO - Downloading docopt-0.6.1.tar.gz 14:09:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:09:27 INFO - Installing collected packages: blobuploader, requests, docopt 14:09:27 INFO - Running setup.py install for blobuploader 14:09:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:09:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:09:27 INFO - Running setup.py install for requests 14:09:27 INFO - Running setup.py install for docopt 14:09:27 INFO - Successfully installed blobuploader requests docopt 14:09:27 INFO - Cleaning up... 14:09:27 INFO - Return code: 0 14:09:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:09:27 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')]} 14:09:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:27 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')]} 14:09:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:27 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 0x7ffe83757e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27dd120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286df20>, '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 0x27dac20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27daf90>, '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-1481059395.754471-975443180', '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 14:09:27 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 14:09:27 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 14:09:27 INFO - Using env: {'DISPLAY': ':0', 14:09:27 INFO - 'HOME': '/home/cltbld', 14:09:27 INFO - 'LANG': 'en_US.UTF-8', 14:09:27 INFO - 'LANGUAGE': 'en_US:en', 14:09:27 INFO - 'LOGNAME': 'cltbld', 14:09:27 INFO - 'MAIL': '/var/mail/cltbld', 14:09:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:27 INFO - 'MOZ_NO_REMOTE': '1', 14:09:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:09:27 INFO - 'NO_EM_RESTART': '1', 14:09:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:09:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:27 INFO - 'PWD': '/builds/slave/test', 14:09:27 INFO - 'SHELL': '/bin/bash', 14:09:27 INFO - 'SHLVL': '1', 14:09:27 INFO - 'TERM': 'linux', 14:09:27 INFO - 'TMOUT': '86400', 14:09:27 INFO - 'USER': 'cltbld', 14:09:27 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1481059395.754471-975443180', 14:09:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:27 INFO - '_': '/tools/buildbot/bin/python'} 14:09:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:09:28 INFO - Running setup.py (path:/tmp/pip-1oPNuv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:09:28 INFO - Running setup.py (path:/tmp/pip-IRmeUp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:09:28 INFO - Running setup.py (path:/tmp/pip-OuX4Sw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:09:28 INFO - Running setup.py (path:/tmp/pip-RNfC3a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:09:28 INFO - Running setup.py (path:/tmp/pip-v34QDZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:09:28 INFO - Running setup.py (path:/tmp/pip-xJbW1e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:09:28 INFO - Running setup.py (path:/tmp/pip-50XzFp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:09:28 INFO - Running setup.py (path:/tmp/pip-ZmyqG4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:09:28 INFO - Running setup.py (path:/tmp/pip-HbFaid-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:09:28 INFO - Running setup.py (path:/tmp/pip-XgwaEq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:09:28 INFO - Running setup.py (path:/tmp/pip-dqQWOP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:09:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:09:28 INFO - Running setup.py (path:/tmp/pip-cT7_yz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:09:29 INFO - Running setup.py (path:/tmp/pip-aNQAL5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:09:29 INFO - Running setup.py (path:/tmp/pip-0oe4eq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:09:29 INFO - Running setup.py (path:/tmp/pip-1Ef4Ob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:09:29 INFO - Running setup.py (path:/tmp/pip-XeJXT0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:09:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:09:29 INFO - Running setup.py (path:/tmp/pip-Q7ErfW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:09:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:09:29 INFO - Running setup.py install for manifestparser 14:09:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:09:29 INFO - Running setup.py install for mozcrash 14:09:29 INFO - Running setup.py install for mozdebug 14:09:29 INFO - Running setup.py install for mozdevice 14:09:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:09:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:09:29 INFO - Running setup.py install for mozfile 14:09:29 INFO - Running setup.py install for mozhttpd 14:09:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:09:29 INFO - Running setup.py install for mozinfo 14:09:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:09:30 INFO - Running setup.py install for mozInstall 14:09:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:09:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:09:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:09:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:09:30 INFO - Running setup.py install for mozleak 14:09:30 INFO - Running setup.py install for mozlog 14:09:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:09:30 INFO - Running setup.py install for moznetwork 14:09:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:09:30 INFO - Running setup.py install for mozprocess 14:09:30 INFO - Running setup.py install for mozprofile 14:09:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:09:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:09:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:09:30 INFO - Running setup.py install for mozrunner 14:09:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:09:30 INFO - Running setup.py install for mozscreenshot 14:09:30 INFO - Running setup.py install for moztest 14:09:31 INFO - Running setup.py install for mozversion 14:09:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:09:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:09:31 INFO - Cleaning up... 14:09:31 INFO - Return code: 0 14:09:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:09: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')]} 14:09:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09: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')]} 14:09:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:31 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 0x7ffe83757e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27dd120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286df20>, '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 0x27dac20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27daf90>, '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-1481059395.754471-975443180', '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 14:09:31 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 14:09:31 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 14:09:31 INFO - Using env: {'DISPLAY': ':0', 14:09:31 INFO - 'HOME': '/home/cltbld', 14:09:31 INFO - 'LANG': 'en_US.UTF-8', 14:09:31 INFO - 'LANGUAGE': 'en_US:en', 14:09:31 INFO - 'LOGNAME': 'cltbld', 14:09:31 INFO - 'MAIL': '/var/mail/cltbld', 14:09:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:31 INFO - 'MOZ_NO_REMOTE': '1', 14:09:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:09:31 INFO - 'NO_EM_RESTART': '1', 14:09:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:09:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:31 INFO - 'PWD': '/builds/slave/test', 14:09:31 INFO - 'SHELL': '/bin/bash', 14:09:31 INFO - 'SHLVL': '1', 14:09:31 INFO - 'TERM': 'linux', 14:09:31 INFO - 'TMOUT': '86400', 14:09:31 INFO - 'USER': 'cltbld', 14:09:31 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1481059395.754471-975443180', 14:09:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:31 INFO - '_': '/tools/buildbot/bin/python'} 14:09:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:09:31 INFO - Running setup.py (path:/tmp/pip-Y1Y_KW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:09:31 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)) 14:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:09:31 INFO - Running setup.py (path:/tmp/pip-FwlJTx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:09:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:09:31 INFO - Running setup.py (path:/tmp/pip-QTcNIO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:09:31 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)) 14:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:09:31 INFO - Running setup.py (path:/tmp/pip-6NG571-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:09:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:09:31 INFO - Running setup.py (path:/tmp/pip-ySxM6C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:09:31 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)) 14:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:09:31 INFO - Running setup.py (path:/tmp/pip-pva4Qq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:09:31 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)) 14:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:09:31 INFO - Running setup.py (path:/tmp/pip-Rza1rQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:09:31 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)) 14:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:09:31 INFO - Running setup.py (path:/tmp/pip-WbouJl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:09:31 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)) 14:09:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:09:31 INFO - Running setup.py (path:/tmp/pip-fsgupi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:09:32 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)) 14:09:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:09:32 INFO - Running setup.py (path:/tmp/pip-ZMu0Wa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:09:32 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)) 14:09:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:09:32 INFO - Running setup.py (path:/tmp/pip-wMjZuY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:09:32 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)) 14:09:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:09:32 INFO - Running setup.py (path:/tmp/pip-z93oew-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:09:32 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)) 14:09:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:09:32 INFO - Running setup.py (path:/tmp/pip-TqtyiR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:09:32 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)) 14:09:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:09:32 INFO - Running setup.py (path:/tmp/pip-mRBMxw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:09:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 14:09:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:09:32 INFO - Running setup.py (path:/tmp/pip-BV6D5i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:09:32 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)) 14:09:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:09:32 INFO - Running setup.py (path:/tmp/pip-wJ7e_r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:09:32 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)) 14:09:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:09:32 INFO - Running setup.py (path:/tmp/pip-3ef_Yn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:09:32 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)) 14:09:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:09:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:09:32 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)) 14:09:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:09:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:09:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:09: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 14:09: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 14:09: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 14:09: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 14:09: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 14:09: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 14:09:34 INFO - Downloading blessings-1.6.tar.gz 14:09:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:09:34 INFO - Installing collected packages: blessings 14:09:34 INFO - Running setup.py install for blessings 14:09:34 INFO - Successfully installed blessings 14:09:34 INFO - Cleaning up... 14:09:34 INFO - Return code: 0 14:09:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:09:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:09:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:09:35 INFO - Reading from file tmpfile_stdout 14:09:35 INFO - Current package versions: 14:09:35 INFO - argparse == 1.2.1 14:09:35 INFO - blessings == 1.6 14:09:35 INFO - blobuploader == 1.2.4 14:09:35 INFO - docopt == 0.6.1 14:09:35 INFO - functools32 == 3.2.3-2 14:09:35 INFO - jsonschema == 2.5.1 14:09:35 INFO - manifestparser == 1.1 14:09:35 INFO - mozInstall == 1.12 14:09:35 INFO - mozcrash == 1.0 14:09:35 INFO - mozdebug == 0.1 14:09:35 INFO - mozdevice == 0.49 14:09:35 INFO - mozfile == 1.2 14:09:35 INFO - mozhttpd == 0.7 14:09:35 INFO - mozinfo == 0.9 14:09:35 INFO - mozleak == 0.1 14:09:35 INFO - mozlog == 3.4 14:09:35 INFO - moznetwork == 0.27 14:09:35 INFO - mozprocess == 0.23 14:09:35 INFO - mozprofile == 0.28 14:09:35 INFO - mozrunner == 6.13 14:09:35 INFO - mozscreenshot == 0.1 14:09:35 INFO - mozsystemmonitor == 0.3 14:09:35 INFO - moztest == 0.7 14:09:35 INFO - mozversion == 1.4 14:09:35 INFO - psutil == 3.1.1 14:09:35 INFO - requests == 1.2.3 14:09:35 INFO - wsgiref == 0.1.2 14:09:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:09: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')]} 14:09:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09: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')]} 14:09:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:35 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 0x7ffe83757e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27dd120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286df20>, '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 0x27dac20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27daf90>, '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-1481059395.754471-975443180', '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 14:09:35 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 14:09:35 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 14:09:35 INFO - Using env: {'DISPLAY': ':0', 14:09:35 INFO - 'HOME': '/home/cltbld', 14:09:35 INFO - 'LANG': 'en_US.UTF-8', 14:09:35 INFO - 'LANGUAGE': 'en_US:en', 14:09:35 INFO - 'LOGNAME': 'cltbld', 14:09:35 INFO - 'MAIL': '/var/mail/cltbld', 14:09:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:35 INFO - 'MOZ_NO_REMOTE': '1', 14:09:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:09:35 INFO - 'NO_EM_RESTART': '1', 14:09:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:09:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:35 INFO - 'PWD': '/builds/slave/test', 14:09:35 INFO - 'SHELL': '/bin/bash', 14:09:35 INFO - 'SHLVL': '1', 14:09:35 INFO - 'TERM': 'linux', 14:09:35 INFO - 'TMOUT': '86400', 14:09:35 INFO - 'USER': 'cltbld', 14:09:35 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1481059395.754471-975443180', 14:09:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:35 INFO - '_': '/tools/buildbot/bin/python'} 14:09:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:35 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)) 14:09:35 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)) 14:09:35 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)) 14:09:35 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)) 14:09:35 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)) 14:09:35 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)) 14:09:35 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)) 14:09:35 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)) 14:09:35 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)) 14:09:35 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)) 14:09:35 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)) 14:09:35 INFO - Cleaning up... 14:09:35 INFO - Return code: 0 14:09:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:09: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')]} 14:09:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:09:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09: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')]} 14:09:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:09:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:09:35 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 0x7ffe83757e30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x263fa80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x27dd120>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x286df20>, '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 0x27dac20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27daf90>, '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-1481059395.754471-975443180', '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 14:09:35 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 14:09:35 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 14:09:35 INFO - Using env: {'DISPLAY': ':0', 14:09:35 INFO - 'HOME': '/home/cltbld', 14:09:35 INFO - 'LANG': 'en_US.UTF-8', 14:09:35 INFO - 'LANGUAGE': 'en_US:en', 14:09:35 INFO - 'LOGNAME': 'cltbld', 14:09:35 INFO - 'MAIL': '/var/mail/cltbld', 14:09:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:35 INFO - 'MOZ_NO_REMOTE': '1', 14:09:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:09:35 INFO - 'NO_EM_RESTART': '1', 14:09:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:09:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:35 INFO - 'PWD': '/builds/slave/test', 14:09:35 INFO - 'SHELL': '/bin/bash', 14:09:35 INFO - 'SHLVL': '1', 14:09:35 INFO - 'TERM': 'linux', 14:09:35 INFO - 'TMOUT': '86400', 14:09:35 INFO - 'USER': 'cltbld', 14:09:35 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1481059395.754471-975443180', 14:09:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:35 INFO - '_': '/tools/buildbot/bin/python'} 14:09:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:09:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:09:35 INFO - Cleaning up... 14:09:35 INFO - Return code: 0 14:09:35 INFO - Running post-action listener: _resource_record_post_action 14:09:35 INFO - Running post-action listener: _start_resource_monitoring 14:09:35 INFO - Starting resource monitoring. 14:09:35 INFO - [mozharness: 2016-12-06 22:09:35.581265Z] Finished create-virtualenv step (success) 14:09:35 INFO - [mozharness: 2016-12-06 22:09:35.581781Z] Running install step. 14:09:35 INFO - Running pre-action listener: _resource_record_pre_action 14:09:35 INFO - Running main action method: install 14:09:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:09:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:09:35 INFO - Reading from file tmpfile_stdout 14:09:35 INFO - Detecting whether we're running mozinstall >=1.0... 14:09:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:09:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:09:35 INFO - Reading from file tmpfile_stdout 14:09:35 INFO - Output received: 14:09:35 INFO - Usage: mozinstall [options] installer 14:09:35 INFO - Options: 14:09:35 INFO - -h, --help show this help message and exit 14:09:35 INFO - -d DEST, --destination=DEST 14:09:35 INFO - Directory to install application into. [default: 14:09:35 INFO - "/builds/slave/test"] 14:09:35 INFO - --app=APP Application being installed. [default: firefox] 14:09:35 INFO - mkdir: /builds/slave/test/build/application 14:09:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 14:09:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 14:09:52 INFO - Reading from file tmpfile_stdout 14:09:52 INFO - Output received: 14:09:52 INFO - /builds/slave/test/build/application/firefox/firefox 14:09:52 INFO - Running post-action listener: _resource_record_post_action 14:09:52 INFO - [mozharness: 2016-12-06 22:09:52.867334Z] Finished install step (success) 14:09:52 INFO - [mozharness: 2016-12-06 22:09:52.867575Z] Running run-tests step. 14:09:52 INFO - Running pre-action listener: _resource_record_pre_action 14:09:52 INFO - Running main action method: run_tests 14:09:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:09:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:09:52 INFO - Python 2.7.3 14:09:52 INFO - Return code: 0 14:09:52 INFO - grabbing minidump binary from tooltool 14:09:52 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')]} 14:09:52 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 0x286df20>, '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 0x27dac20>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x27daf90>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:09:52 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 14:09:52 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 14:09:52 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 14:09:52 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:09:52 INFO - Return code: 0 14:09:52 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 14:09:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:09:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:09:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 14:09:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:09:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AJJNpGB5Q3maw7AaqrumBQ/artifacts/public/build/firefox-53.0a1.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 14:09:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/AJJNpGB5Q3maw7AaqrumBQ/artifacts/public/build/firefox-53.0a1.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 14:09:52 INFO - Using env: {'DISPLAY': ':0', 14:09:52 INFO - 'HOME': '/home/cltbld', 14:09:52 INFO - 'LANG': 'en_US.UTF-8', 14:09:52 INFO - 'LANGUAGE': 'en_US:en', 14:09:52 INFO - 'LOGNAME': 'cltbld', 14:09:52 INFO - 'MAIL': '/var/mail/cltbld', 14:09:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:09:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 14:09:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:09:52 INFO - 'MOZ_NO_REMOTE': '1', 14:09:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:09:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 14:09:52 INFO - 'NO_EM_RESTART': '1', 14:09:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 14:09:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:09:52 INFO - 'PWD': '/builds/slave/test', 14:09:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:09:52 INFO - 'SHELL': '/bin/bash', 14:09:52 INFO - 'SHLVL': '1', 14:09:52 INFO - 'TERM': 'linux', 14:09:52 INFO - 'TMOUT': '86400', 14:09:52 INFO - 'USER': 'cltbld', 14:09:52 INFO - 'XDG_SESSION_COOKIE': '5ac5dba3e01a12f1ff77ab15000001b1-1481059395.754471-975443180', 14:09:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:09:52 INFO - '_': '/tools/buildbot/bin/python'} 14:09:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/AJJNpGB5Q3maw7AaqrumBQ/artifacts/public/build/firefox-53.0a1.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 14:09:53 INFO - mozversion application_buildid: 20161206123003 14:09:53 INFO - mozversion application_changeset: 0d7431e24546049ee2bd0fb1e05a5b124ed01f81 14:09:53 INFO - mozversion application_display_name: Nightly 14:09:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:09:53 INFO - mozversion application_name: Firefox 14:09:53 INFO - mozversion application_remotingname: firefox 14:09:53 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 14:09:53 INFO - mozversion application_vendor: Mozilla 14:09:53 INFO - mozversion application_version: 53.0a1 14:09:53 INFO - mozversion platform_buildid: 20161206123003 14:09:53 INFO - mozversion platform_changeset: 0d7431e24546049ee2bd0fb1e05a5b124ed01f81 14:09:53 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 14:09:53 INFO - mozversion platform_version: 53.0a1 14:09:53 INFO - using testdate: 1481062193 14:09:53 INFO - actual date: 1481062193 14:09:53 INFO - starting webserver on 'localhost:43166' 14:09:53 INFO - SUITE-START | Running 2 tests 14:09:53 INFO - TEST-START | dromaeo_css 14:09:53 INFO - operating with platform_type : linux_ 14:09:53 INFO - Initialising browser for dromaeo_css test... 14:09:53 INFO - TEST-INFO | started process 15388 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2e6zM8/profile http://localhost:43166/getInfo.html) 14:09:57 INFO - PROCESS | 15388 | __metrics Screen width/height:1600/1200 14:09:57 INFO - PROCESS | 15388 | colorDepth:24 14:09:57 INFO - PROCESS | 15388 | Browser inner width/height: 1024/697 14:09:57 INFO - PROCESS | 15388 | __metrics 14:09:58 INFO - PROCESS | 15388 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:09:58 INFO - PROCESS | 15388 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:09:59 INFO - TEST-INFO | 15388: exit 0 14:09:59 INFO - Browser initialized. 14:09:59 INFO - Running cycle 1/1 for dromaeo_css test... 14:09:59 INFO - TEST-INFO | started process 15475 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp2e6zM8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:10:11 INFO - PROCESS | 15475 | RSS: Main: 172302336 14:10:11 INFO - PROCESS | 15475 | 14:11:02 INFO - PROCESS | 15475 | 1481062262521 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] 14:13:13 INFO - PROCESS | 15475 | Cycle 1(1): loaded http://localhost:43166/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43166/tests/dromaeo/cssquery-ext.html) 14:13:16 INFO - PROCESS | 15475 | RSS: Main: 380788736 14:13:16 INFO - PROCESS | 15475 | 14:16:18 INFO - PROCESS | 15475 | Cycle 1(1): loaded http://localhost:43166/tests/dromaeo/cssquery-ext.html (next: http://localhost:43166/tests/dromaeo/cssquery-jquery.html) 14:16:19 INFO - PROCESS | 15475 | RSS: Main: 283488256 14:16:19 INFO - PROCESS | 15475 | 14:19:21 INFO - PROCESS | 15475 | Cycle 1(1): loaded http://localhost:43166/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43166/tests/dromaeo/cssquery-mootools.html) 14:19:22 INFO - PROCESS | 15475 | RSS: Main: 264343552 14:19:22 INFO - PROCESS | 15475 | 14:22:24 INFO - PROCESS | 15475 | Cycle 1(1): loaded http://localhost:43166/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43166/tests/dromaeo/cssquery-prototype.html) 14:22:24 INFO - PROCESS | 15475 | RSS: Main: 223174656 14:22:24 INFO - PROCESS | 15475 | 14:25:27 INFO - PROCESS | 15475 | Cycle 1(1): loaded http://localhost:43166/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43166/tests/dromaeo/cssquery-yui.html) 14:25:28 INFO - PROCESS | 15475 | RSS: Main: 220585984 14:25:28 INFO - PROCESS | 15475 | 14:28:31 INFO - PROCESS | 15475 | Cycle 1(1): loaded http://localhost:43166/tests/dromaeo/cssquery-yui.html (next: http://localhost:43166/tests/dromaeo/cssquery-dojo.html) 14:28:31 INFO - PROCESS | 15475 | RSS: Main: 318939136 14:28:31 INFO - PROCESS | 15475 | 14:28:31 INFO - PROCESS | 15475 | __start_tp_report 14:28:31 INFO - PROCESS | 15475 | _x_x_mozilla_page_load 14:28:31 INFO - PROCESS | 15475 | _x_x_mozilla_page_load_details 14:28:31 INFO - PROCESS | 15475 | |i|pagename|runs| 14:28:31 INFO - PROCESS | 15475 | |0;dojo.html;3322.4850148227024;3361.9914025792255;3364.461686130218;3399.1672040350118;3421.5551978242825;5337.06601344765;5365.543928766055;5446.972765136179;5469.015577196104;5469.042917489438;5461.863453413656;5463.726813659321;5500.80747173849;5530.253415788868;5589.8602534936745;4952.653314268003;4983.626228032894;4987.800487980495;5017.874553136165;5094.083065048289;5021.196608542634;5057.190849464087;5077.720725360122;5082.771275292621;5115.693058416488;5031.84904452867;5054.418741844698;5107.106256405133;5121.641485096045;5154.974225128869;4854.344663470421;4887.975560122212;4900.6569540132205;4926.2364333528;4968.975155124202;4961.801527938915;4970.726610036447;4980.128477516457;4999.450060493343;5040.269160971675;5420.340214961703;5469.316335458067;5484.095124304485;5485.6434331768305;5548.278723765925;5644.520215781622;5687.289088863892;5771.192033115367;5890.646561206298;5907.113932910055;5859.472647461706;5904.291485021825;5909.438603332676;5943.940560594382;5956.225690660186;5502.147167189092;5509.531689806331;5511.503964643161;5515.696636684981;5515.862103447446;5793.188953546507;5817.534597232211;5973;5977.103434484775;5991.850203744854;5745.770169193185;5809.622374545641;5813.563982701315;5843.912341314884;5892.322382926019;5866;5930.733117009745;5931.6441013539325;5938.8218353449465;5939.13882487037;5672.801451949162;5883.676397798162;5887.705614719247;5887.911681324784;5890.705464726747;5647.548196144298;5796.768129274782;5808.332041815161;5812.244408226903;5906.3207731110615;4520.0507893342765;4521.796519156645;4540.977295113569;4559.407277054028;4566.246569316026;4078.4494093297617;4084.36692312692;4101.282275601758;4114.423980642712;4133.731307465006;5335.173048177461;5386.434424385461;5523.944760552424;5576.86057848557;5595.496405323541;5872.882542349129;5894.498967587718;5912.438318359749;5952.940470595238;5967.701614919323;5499.1476321170285;5555.944440555623;5561.916571251434;5563.165525171175;5586.413426590149;4933.260010998379;5001.349824522788;5057.115004874132;5113.51421614946;5152.819651312186;5911.940880591225;5960.314563825043;5966.373530779292;5988.820335389945;6057.7879774207695;5796.333421656564;5802.274715660543;5806.2451881255165;5807.62250453736;5821.76712931478;5824.679642619697;5976.282846058501;5977.252843394575;5980.162777211107;5982.88034239304;5608.579356548194;5679.375268720355;5713.657180569179;5724.227229323988;5807.390224026416;5849.444302791228;5872.207252020922;5886.20536227621;5893.410658934073;5896.49879760208;5686.772529098956;5738.942610574008;5745.597808153556;5753.165790960204;5757.510611598061;5573.331200255995;5620.690862002629;5621.184928185308;5716.657000579979;5717.514011308919;5637.971810141087;5644.209810626598;5644.745986430538;5666.263385759825;5666.9716651416475;5717.771289148387;5739.995500787262;5744.626599271034;5749.396313387034;5753.942460575341;5647.0964645656495;5689.829305120853;5693.886122277614;5697.601167918206;5734 14:28:31 INFO - PROCESS | 15475 | |1;ext.html;588.3586890289585;610.3042531514074;628.7202195023214;642.5919541091406;644.2204932032241;31029.689703102962;33398.332033359315;33451;33490.49764253538;33511.832440837796;42310.153796924045;43462.565374346326;43618.12763744723;43671.12657746851;43813.34279985795;8413.789655258606;8545.359098067669;8549.529775862326;8554.35842311829;8555.144485551456;7847.568383738892;8431.915680843205;8513.702020429286;8519.957400213021;8522.446041007346;7868.4098692598;10016.749581260454;10194.490275486232;10247.692569222936;10486.842697359545;10201.081902628763;10256.179505639531;10259.17926565873;10260.692179234635;10266.281360304782;10027.197824174049;10091.444970526618;10099.293049486465;10107.140893024101;10110.69667909964;38401.23197536061;38430.23139537222;38678;38687;38734.61265387338;3366.9663303366897;3372.4435468147726;3381.6956473917467;3435.707964822993;3632.291703117893;29276.707232927612;29929.781614378586;30200;30262.394752104836;30264.697353026408;4088.100617864065;4094.4063110849056;4167.52073511547;4209.326507758744;4347.956520434786;310562;311113;312823;314138;315937;53553.464465354846;53782.69738420917;53811.73094134504;53837;53972;56867.431325687045;56906;56935;56997.715011424676;57003.14495282574;15228.162451065298;15518.689626207413;15607.843921560867;15615.297311621005;15617.921910390376;11540.134489913122;11554.248973816675;11565.595204167814;11571.074314054855;11573.479193436315;13621.182729036289;13943.790843137362;14594.41622335116;14812.555623331318;15364.539063827879;6101.176341193971;6102.816915492543;6118.265808103056;6242.250929888442;6263.467605253513;907.950062746542;1065.5471424644495;1074.586284280542;1105.3423213188141;1107.7175320293434;29863.85068074646;30051;30387.848060759556;30775.2306192347;30822.69177308243;1047.607147319755;1080.6866008857344;1089.2756317049073;1095.8246680531076;1104.2491106047964;15543;16123.677526449404;16247.431339903047;16268.430604929008;16416.425425110065;428703;434046;434128;434308;434394;48636;48977.51022489801;52132.47867521352;53430.46569534333;53687.73156134194;1311.3377744239099;1312.3831799054428;1328.14334754085;1343.9596812095654;1347.2253454263837;29100.417991640472;31140.37719245648;31462.685373145974;31568.842155789072;32030.35939281248;47841;48284.27573586329;48298.27552586644;48327;48397.75801120972;374766;375153;376582;378088;378459;37262.25475490529;37647.2470550582;37669.81165094157;38436.42345364766;38580.228395432496;40047.72557792692;40398.59601403948;43885;45421.77289113533;45650.77174614106;36577.26845463129;37150.25699485941;37655.62344376521;37978;38165.236695266496;37390.626093739804;37847.62152378441;38036;38493.4225986616;39175.41236881506;38172.427413588266;38784.2243155141;38795.2240955185;39029.70673225886;39068.413973790986;32437.837810810794;34162.48756268708;34506;34710.30579388449;34773.82613086919;26686.86656566705;26906.596401053608;26961.191164265103;26975.865120675055;27092.32269193207 14:28:31 INFO - PROCESS | 15475 | |2;jquery.html;373.6394379423859;376.40527965814033;390.28772490205375;398.24731257922525;413.1942711712162;13336;14415.92792036041;16191.838081619195;18583.628327433442;18718.906405467955;11955.880441195586;17352.219150138244;17491.688123390733;17694;17783.82216177838;4921.458639549638;5149.665271757342;5149.922751158736;5151.124308867502;5153.536181743642;4913.8771530711665;5038.496150384951;5059.544640982311;5095.515925987044;5139.717315547643;5100.744962751847;5105.3873535175835;5108.84673459795;5109.591232701375;5111.207762796754;4993.775280112403;5033.874153146164;5038.697678139287;5091.058154241458;5098.184290513518;4897.363342765453;4921.753912304406;4938.506149385069;4938.876528086791;4943.579795717368;13572.932135339359;15577.766333505006;21504.354869353945;21790.47466677333;21898.89050554753;2224.4994876152896;2303.1363238785457;2340.403197184707;2440.389902524369;2666.9333266668486;9935.304528682995;9990.850137247948;10017.148542373907;10063.19494440443;10349.534270957825;1837.255911355903;1916.9137388817396;1926.8265856073021;1944.3292064237814;1950.249154075691;75028;77548;79223.60388198022;79544;80070.59964700139;9825.45959972194;10044.849327260097;10074.798504029879;10503.789924201474;10898.727531811768;14691.85308146905;14694.632634184018;14705.926470367795;14708.55874323772;14717.19055451939;7556.58438785872;7812.921870781333;7899.210078992055;7984.121746607869;8105.189481051965;7539.434542409232;7617;7644.388448924073;7900.091489478668;7939.6824127035425;4485.75328356937;4515.977420112944;4548.658850586219;4587.444784400912;4637.907241855144;1819.426880532628;1892.5457890106275;1915.3870761356511;1950.882947023183;1983.5140390604106;851.2168804699692;982.3810999070682;994.174834887042;1009.399407352624;1010.7978404319166;17918.641627167384;18302;18863.717044244346;18933;19219.903900480407;965.8696076029646;971.5870754929127;975.9121679048919;975.9170470509944;1021.5505177721927;9804.558794854249;10383.325083869675;10472.266941314036;10525.84211236832;10572.577096916037;390427;392394;396410;396782;399618;76435;81892.59053704693;82795;85810;86078.56960715157;1039.599754094664;1047.664747280863;1064.9254552181274;1083.2092572422175;1096.7587130831205;16488.25802838899;16569.33722651129;17011.65976680484;17404.303827846743;17496.91251543734;30367.848160759055;33537.49693754547;39939;40176;42286.65700145003;53622;55368.72315638396;55807;55918.44081559295;57620.71189644025;7481.551106933602;7535.811604709706;7537.623118844145;7694.692212311445;7792.532448052908;8788.604512797066;11295.096392288762;11472.483738231633;11560.42197890119;11666.066714662977;9995;10123.848142277724;10241.43672098012;10329.690109296733;10481.37111773296;9885.901140988499;9966;10185.08334249921;10254.128399086161;10303.639372622221;10030.044141392358;10108.39349639024;10285.125764309818;10486.790264194826;10546.750557502279;7607.315341619281;7641.732539361095;7746.65140068687;7867.370610351044;7888.132305446511;10005.899941000496;10075.34510256831;10207.693769186937;10254.487275636338;10392.532336045047 14:28:31 INFO - PROCESS | 15475 | |3;mootools.html;1312.0684314136963;1418.0782491380605;1431.8496557861424;1568.898021628594;1729.4984454508185;7407.81480462988;7910.248526389998;8321.83356332873;8336.249737523622;8338.166183381667;8054.194580541943;8190.91809081909;8194.631241594128;8343.374246931475;8350;1968.9507762305914;2022.3225219551468;2238.563480121377;2285.6342985122383;2287.462446325112;2237.8097861681777;2298.8275879309035;2300.0224904415554;2300.4133945843787;2305.988470057647;2188.0153930731153;2200.7359116905996;2202.559488102378;2207.128184367174;2207.9006444710026;2255.334676270504;2271.8864056797092;2272.397814579138;2273.6816845641624;2275.397019789757;2198;2220.222921977311;2235.9329220123423;2238.742544607375;2243.596152692515;8286.792830179233;8331.16688331168;8412.8317433651;8425.199606037417;8428.157184281585;5171.3277273954145;5307.708076055816;5308.309919710413;5314.362276526803;5318.9202161967605;3240.303334783033;3520.507129001942;3572.5534308211472;3598.5681718193723;3598.9640103599154;1040.4381633917676;1099.2030777686193;1113.5434471866495;1127.780082883838;1150.2983180260035;11008;11309.660710178709;11342.262752921117;11490.36802975836;11714.882851171464;4452.554744525586;4663.906721865544;4687;4751.881202969954;4768.403949506312;10051.69844904654;10051.798964020678;10052.949735251277;10066.69799906004;10069.446180459998;5299.072662284085;5299.072662284085;5299.814506492255;5302.840914772486;5305.946940530623;4772.49888761682;4861.246506791453;4862.149123903303;4866.46468888422;4920.188168952082;3718.5537735471917;3797.981010094987;3811.2949104415216;3841.7887016214377;3893.2213557288655;1247.25788156047;1257.1451413039222;1263.7899734674008;1283.6983308922395;1295.378218455147;779.4660657449665;893.2005854759954;915.4598786715869;926.3191554207652;956.0439560439561;5536.778528858881;5724.599278050579;5785.10330898703;5878.118282257696;5929.407059294036;875.2341701011615;876.7413612984185;901.1844280925773;907.5326207003399;908.5003248213457;3799.7340186186702;3871.05159235989;3875.5155605549307;3907.9804600976813;3929.31237033518;10190;10451.738706532245;10526.105281051196;10531.210159238088;10554.947225263824;10264.332818366782;10403.063724264852;10408.427536485415;10435.68692939213;10444.425556594308;7409.925900740924;7723.9613801931755;7725.072991241087;7730.613469326624;7886.211378862068;5684.516816070515;5705.201361513739;5730.197772311796;5766.913496297643;5825.417458254141;8977.775555611162;9252.398594091363;9337.299702522474;9391.906080939376;9396.201322887358;9463.038285880128;9526.380785248937;9598.808023839623;9696.6121355145;9839.311248212556;7207.675654595451;7470.887936680845;7624.809379765328;7640.8471830564185;7649;8082.515049097073;8113.634886430243;8118.391120666091;8120.959395202986;8156.877646835183;7280.526765760209;7326.084239470067;7358.558486490828;7384.150822655463;7565.735199268;6863.245043045161;6975.79072627822;7019.789406317818;7043.2604576518725;7128.6792094356915;6673;6699.899501507579;6746.561473504206;6756.324367563205;6760.18877734675;4782.856514304576;4843.2735089736825;4854.466008739107;4901.681390709592;4957.107720610325;4410.867673969786;4423.911521769611;4477.1493416250805;4606.410788278039;4707.246840505502 14:28:31 INFO - PROCESS | 15475 | |4;prototype.html;335.6760725899507;335.80691102615987;343.31851275218696;347.51868661903256;347.86781023211176;5235.293235413218;5287.312649355583;5287.603429742775;5299.390570084442;5315.7873685052555;5211.921821172686;5223.582113430926;5227.660202086871;5231.450697676746;5257.421683614799;3928.6464218220285;3996.1018516204863;4042.1713548722587;4066.8373265069363;4089.9182016359655;4116.176764647083;4123.505179378464;4130.256553820311;4131.338985762279;4131.607497287753;4102.425660407545;4106.548279689232;4108.198901214264;4108.260513107654;4122.979385103085;3786.6970642348547;3875.883723488314;4066.369712694537;4112.650424713903;4121.381792731085;3629.310431018098;3646.835892384849;3651.1602331463646;3658.213484100931;3675.2833197526493;5056.519630635084;5103.8213662522;5116.978793376596;5125.359884836854;5129.410117836459;4614.861554153381;4709.340692303081;4731.384919960417;4731.739754313511;4753.572178503951;5449.455054494559;5452.427495113036;5456.236126942231;5465.207544905967;5475.698836563987;4044.8786536403954;4049.602887003979;4049.655779258737;4050.6354428101317;4053.6757059435467;5416.295881535415;5536.972315138439;5625.184348269478;5631.0708733059;5673.602847800656;4122.134351786099;4122.484689413824;4128.422020917074;4131.483564554431;4137.441445404892;4141.6065473779945;4167.937480937789;4169.374593810952;4177.811998460076;4186.539480657125;4558.49856515783;4644.8374306899095;4699.318598803155;4700.2949557566635;4705.55297246761;5414.675119492843;5447.591430642718;5450.236966824568;5453.918191227135;5488.835334939959;1391.4573316406609;1391.997761611638;1392.4151856220412;1399.2024546008881;1402.6914078902626;931.6599441203912;981.4307701533094;986.7089208683456;990.153418826907;991.1822746234385;5059.190529515334;5075.974620126875;5176.767045482962;5208.317716376069;5321.388040375329;5507.173923911365;5558.333000040021;5589.273394458694;5607.523360514402;5626.409227031103;5177.352830896138;5281.577473802086;5289.2859463972645;5295.761690723926;5297.231901374356;2860.599516067752;2863.2555535560487;2884.307766136112;2896.305661188186;2901.7533509651917;5579;5608.49523542875;5619.157126431068;5634.408387119375;5639.943600564024;5249.396319423239;5434.456554344534;5550.8057217997175;5582.525485333792;5608.579356548275;5471.534919531805;5506.174073888948;5510.8071217507195;5529.391766905556;5649.994252040548;5336.519713225828;5358.097875254384;5435.918461223164;5438.437823225793;5441.5374693151525;5191.870203244949;5393.217983392465;5393.927584673546;5399.5950303726595;5406.324209473816;5588.050031494575;5591.161325801162;5594.720263986866;5618.426840484671;5625.103071917883;3985.5851172834386;4014.6186112319283;4032.495938007749;4036.737612055207;4038.4749982502085;5193.870513089615;5419.214213939035;5425.565954723534;5440.809571664972;5449.7275136243825;5307.9469205309;5311.840644780663;5313.362396512443;5314.654547454403;5326.573874089986;5120.889535934717;5155.190421220369;5277.340332458442;5329.094054010905;5378.757955891916;5223.112070948024;5229.084910140787;5244.501772331622;5250.632455728062;5262.315898933114;5244.213367994831;5328.57371410294;5328.8401347959625;5354.705491198021;5392.784288628411;5380.892382152257;5407.972960135332;5412.215228791882;5413.512783849473;5414.592206026383 14:28:31 INFO - PROCESS | 15475 | |5;yui.html;721.2136284506341;737.9040724705789;754.2570567990529;821.2731732416811;830.8213734047179;14621.488247911311;16145;16179.867409281354;16190.595235119128;16197.75703364449;14743.631409214775;15017.924910375461;15022.924885375558;15027.098374097535;15034.323455444504;667.0827612033454;684.090160087083;775.9495632783851;776.6699152860026;789.8222899847545;788.909275433324;799.6081919859256;805.730080423059;806.2139414071263;807.1565214350987;847.1570787066877;847.4110493207223;848.6562942008466;849.354490587152;849.817289282804;704.5174055771762;718.5509056839476;819.7049062337554;846.3821410370402;846.7544412120478;603.7977277612006;606.7997560804968;607.3137354789104;607.471499795181;609.5672072828297;15877.047377157407;15903.284352204179;15921.522354329389;15928.76106858398;15946.28241729125;13758.724825503534;14017.509387171502;14309.642258943506;14535.927320363437;14729.410823567045;13154;13257.204567725967;13309.933450332783;13584.592462226148;13592.25242611656;4388.648908087345;4526.275795872644;4597.517260687613;4637.837675681369;4660.6970546914345;82381.58809205976;82498;82916.17083829205;83023;83328.58335708283;11221.831672524919;11272;11680.300322129286;11734.11994100446;11902.047836173086;22531.211407600982;22584.435389115075;22588.887055564617;22597.774022259895;22648.660270095963;8040.2361775631225;8044.758657240292;8105.2705256526015;8173.79565510867;8364.456310339809;4275.315949448073;4281.293586558245;4301.118270754488;4311.8706438806885;4379.211741886491;3449.862005519751;3534.151803567177;3541.9291614167573;3547.8758243461357;3548.698360639323;584.6784268652224;593.4510577715596;598.775459202799;602.3825578781696;607.802464199137;631.7157279224275;641.4066988036049;655.1908393134476;660.4650233311033;662.29134825736;8573;8874.29005679544;8904.510251936075;8986.640534378683;9175;536.4421002157712;603.2535808833403;627.5074066857495;630.7729217481703;663.3200969006807;4308.784560771949;4568;4596.326526544467;4627.282771170474;4689.132510485571;87815;88270.55864720636;88909.55545222362;90768;90983;57956.4204357951;71426.28573714197;73841;77237;77458;65476.67261663566;65835.73314134858;66032.33967660261;66495.66752166208;66610;8213.753587392388;8274.255317021498;8380.455270407405;8595.398322117391;8601.483910965362;39746.80126599348;45681.771591141835;45989.77005114953;46669.53330466788;47391;62378.68810655917;65419;65600;66295.01114966559;69843;10369.377837329785;10622.415767132881;10737.785244294913;11002.889971100187;11140.777184456427;11758.176927614984;11788.559015206303;12291.262524248574;12689.984801216066;13089.541866035024;11442.054850675238;11808.468618912342;12055.397230138633;12181.08641851847;12280;11924.344161071225;12099;12719.173253738478;12980.286084265079;12993.610191694264;11008.449577521253;11964.64106076819;11999.700007499883;12011.63965081049;12147.149699521302;9594.66418675343;9809.166220871306;9956.800863982535;10230.181585472996;10698.358098514114;8673.974080994414;9447.858282125637;10157.136643385395;10183.439910804977;10201.897981020391 14:28:31 INFO - PROCESS | 15475 | __end_tp_report 14:28:31 INFO - PROCESS | 15475 | __start_cc_report 14:28:31 INFO - PROCESS | 15475 | _x_x_mozilla_cycle_collect,2241 14:28:31 INFO - PROCESS | 15475 | __end_cc_report 14:28:31 INFO - PROCESS | 15475 | __startTimestamp1481063311475__endTimestamp 14:28:31 INFO - PROCESS | 15475 | 14:28:31 INFO - PROCESS | 15475 | ------- Summary: start ------- 14:28:31 INFO - PROCESS | 15475 | Number of tests: 6 14:28:31 INFO - PROCESS | 15475 | 14:28:31 INFO - PROCESS | 15475 | [#0] dojo.html Cycles:165 Average:5448.27 Median:5644.75 stddev:573.63 (10.2%) stddev-sans-first:550.62 14:28:31 INFO - PROCESS | 15475 | Values: 3322.5 3362.0 3364.5 3399.2 3421.6 5337.1 5365.5 5447.0 5469.0 5469.0 5461.9 5463.7 5500.8 5530.3 5589.9 4952.7 4983.6 4987.8 5017.9 5094.1 5021.2 5057.2 5077.7 5082.8 5115.7 5031.8 5054.4 5107.1 5121.6 5155.0 4854.3 4888.0 4900.7 4926.2 4969.0 4961.8 4970.7 4980.1 4999.5 5040.3 5420.3 5469.3 5484.1 5485.6 5548.3 5644.5 5687.3 5771.2 5890.6 5907.1 5859.5 5904.3 5909.4 5943.9 5956.2 5502.1 5509.5 5511.5 5515.7 5515.9 5793.2 5817.5 5973.0 5977.1 5991.9 5745.8 5809.6 5813.6 5843.9 5892.3 5866.0 5930.7 5931.6 5938.8 5939.1 5672.8 5883.7 5887.7 5887.9 5890.7 5647.5 5796.8 5808.3 5812.2 5906.3 4520.1 4521.8 4541.0 4559.4 4566.2 4078.4 4084.4 4101.3 4114.4 4133.7 5335.2 5386.4 5523.9 5576.9 5595.5 5872.9 5894.5 5912.4 5952.9 5967.7 5499.1 5555.9 5561.9 5563.2 5586.4 4933.3 5001.3 5057.1 5113.5 5152.8 5911.9 5960.3 5966.4 5988.8 6057.8 5796.3 5802.3 5806.2 5807.6 5821.8 5824.7 5976.3 5977.3 5980.2 5982.9 5608.6 5679.4 5713.7 5724.2 5807.4 5849.4 5872.2 5886.2 5893.4 5896.5 5686.8 5738.9 5745.6 5753.2 5757.5 5573.3 5620.7 5621.2 5716.7 5717.5 5638.0 5644.2 5644.7 5666.3 5667.0 5717.8 5740.0 5744.6 5749.4 5753.9 5647.1 5689.8 5693.9 5697.6 5734.0 14:28:31 INFO - PROCESS | 15475 | 14:28:31 INFO - PROCESS | 15475 | [#1] ext.html Cycles:180 Average:53288.17 Median:37326.44 stddev:99495.56 (266.6%) stddev-sans-first:99695.99 14:28:31 INFO - PROCESS | 15475 | Values: 588.4 610.3 628.7 642.6 644.2 31029.7 33398.3 33451.0 33490.5 33511.8 42310.2 43462.6 43618.1 43671.1 43813.3 8413.8 8545.4 8549.5 8554.4 8555.1 7847.6 8431.9 8513.7 8520.0 8522.4 7868.4 10016.7 10194.5 10247.7 10486.8 10201.1 10256.2 10259.2 10260.7 10266.3 10027.2 10091.4 10099.3 10107.1 10110.7 38401.2 38430.2 38678.0 38687.0 38734.6 3367.0 3372.4 3381.7 3435.7 3632.3 29276.7 29929.8 30200.0 30262.4 30264.7 4088.1 4094.4 4167.5 4209.3 4348.0 310562.0 311113.0 312823.0 314138.0 315937.0 53553.5 53782.7 53811.7 53837.0 53972.0 56867.4 56906.0 56935.0 56997.7 57003.1 15228.2 15518.7 15607.8 15615.3 15617.9 11540.1 11554.2 11565.6 11571.1 11573.5 13621.2 13943.8 14594.4 14812.6 15364.5 6101.2 6102.8 6118.3 6242.3 6263.5 908.0 1065.5 1074.6 1105.3 1107.7 29863.9 30051.0 30387.8 30775.2 30822.7 1047.6 1080.7 1089.3 1095.8 1104.2 15543.0 16123.7 16247.4 16268.4 16416.4 428703.0 434046.0 434128.0 434308.0 434394.0 48636.0 48977.5 52132.5 53430.5 53687.7 1311.3 1312.4 1328.1 1344.0 1347.2 29100.4 31140.4 31462.7 31568.8 32030.4 47841.0 48284.3 48298.3 48327.0 48397.8 374766.0 375153.0 376582.0 378088.0 378459.0 37262.3 37647.2 37669.8 38436.4 38580.2 40047.7 40398.6 43885.0 45421.8 45650.8 36577.3 37150.3 37655.6 37978.0 38165.2 37390.6 37847.6 38036.0 38493.4 39175.4 38172.4 38784.2 38795.2 39029.7 39068.4 32437.8 34162.5 34506.0 34710.3 34773.8 26686.9 26906.6 26961.2 26975.9 27092.3 14:28:31 INFO - PROCESS | 15475 | 14:28:31 INFO - PROCESS | 15475 | [#2] jquery.html Cycles:180 Average:25103.48 Median:391410.50 stddev:65576.15 (16.8%) stddev-sans-first:65733.82 14:28:31 INFO - PROCESS | 15475 | Values: 373.6 376.4 390.3 398.2 413.2 13336.0 14415.9 16191.8 18583.6 18718.9 11955.9 17352.2 17491.7 17694.0 17783.8 4921.5 5149.7 5149.9 5151.1 5153.5 4913.9 5038.5 5059.5 5095.5 5139.7 5100.7 5105.4 5108.8 5109.6 5111.2 4993.8 5033.9 5038.7 5091.1 5098.2 4897.4 4921.8 4938.5 4938.9 4943.6 13572.9 15577.8 21504.4 21790.5 21898.9 2224.5 2303.1 2340.4 2440.4 2666.9 9935.3 9990.9 10017.1 10063.2 10349.5 1837.3 1916.9 1926.8 1944.3 1950.2 75028.0 77548.0 79223.6 79544.0 80070.6 9825.5 10044.8 10074.8 10503.8 10898.7 14691.9 14694.6 14705.9 14708.6 14717.2 7556.6 7812.9 7899.2 7984.1 8105.2 7539.4 7617.0 7644.4 7900.1 7939.7 4485.8 4516.0 4548.7 4587.4 4637.9 1819.4 1892.5 1915.4 1950.9 1983.5 851.2 982.4 994.2 1009.4 1010.8 17918.6 18302.0 18863.7 18933.0 19219.9 965.9 971.6 975.9 975.9 1021.6 9804.6 10383.3 10472.3 10525.8 10572.6 390427.0 392394.0 396410.0 396782.0 399618.0 76435.0 81892.6 82795.0 85810.0 86078.6 1039.6 1047.7 1064.9 1083.2 1096.8 16488.3 16569.3 17011.7 17404.3 17496.9 30367.8 33537.5 39939.0 40176.0 42286.7 53622.0 55368.7 55807.0 55918.4 57620.7 7481.6 7535.8 7537.6 7694.7 7792.5 8788.6 11295.1 11472.5 11560.4 11666.1 9995.0 10123.8 10241.4 10329.7 10481.4 9885.9 9966.0 10185.1 10254.1 10303.6 10030.0 10108.4 10285.1 10486.8 10546.8 7607.3 7641.7 7746.7 7867.4 7888.1 10005.9 10075.3 10207.7 10254.5 10392.5 14:28:31 INFO - PROCESS | 15475 | 14:28:31 INFO - PROCESS | 15475 | [#3] mootools.html Cycles:180 Average:5536.00 Median:4864.31 stddev:3090.97 (63.5%) stddev-sans-first:3083.33 14:28:31 INFO - PROCESS | 15475 | Values: 1312.1 1418.1 1431.8 1568.9 1729.5 7407.8 7910.2 8321.8 8336.2 8338.2 8054.2 8190.9 8194.6 8343.4 8350.0 1969.0 2022.3 2238.6 2285.6 2287.5 2237.8 2298.8 2300.0 2300.4 2306.0 2188.0 2200.7 2202.6 2207.1 2207.9 2255.3 2271.9 2272.4 2273.7 2275.4 2198.0 2220.2 2235.9 2238.7 2243.6 8286.8 8331.2 8412.8 8425.2 8428.2 5171.3 5307.7 5308.3 5314.4 5318.9 3240.3 3520.5 3572.6 3598.6 3599.0 1040.4 1099.2 1113.5 1127.8 1150.3 11008.0 11309.7 11342.3 11490.4 11714.9 4452.6 4663.9 4687.0 4751.9 4768.4 10051.7 10051.8 10052.9 10066.7 10069.4 5299.1 5299.1 5299.8 5302.8 5305.9 4772.5 4861.2 4862.1 4866.5 4920.2 3718.6 3798.0 3811.3 3841.8 3893.2 1247.3 1257.1 1263.8 1283.7 1295.4 779.5 893.2 915.5 926.3 956.0 5536.8 5724.6 5785.1 5878.1 5929.4 875.2 876.7 901.2 907.5 908.5 3799.7 3871.1 3875.5 3908.0 3929.3 10190.0 10451.7 10526.1 10531.2 10554.9 10264.3 10403.1 10408.4 10435.7 10444.4 7409.9 7724.0 7725.1 7730.6 7886.2 5684.5 5705.2 5730.2 5766.9 5825.4 8977.8 9252.4 9337.3 9391.9 9396.2 9463.0 9526.4 9598.8 9696.6 9839.3 7207.7 7470.9 7624.8 7640.8 7649.0 8082.5 8113.6 8118.4 8121.0 8156.9 7280.5 7326.1 7358.6 7384.2 7565.7 6863.2 6975.8 7019.8 7043.3 7128.7 6673.0 6699.9 6746.6 6756.3 6760.2 4782.9 4843.3 4854.5 4901.7 4957.1 4410.9 4423.9 4477.1 4606.4 4707.2 14:28:31 INFO - PROCESS | 15475 | 14:28:31 INFO - PROCESS | 15475 | [#4] prototype.html Cycles:180 Average:4563.63 Median:5239.75 stddev:1305.25 (24.9%) stddev-sans-first:1269.75 14:28:31 INFO - PROCESS | 15475 | Values: 335.7 335.8 343.3 347.5 347.9 5235.3 5287.3 5287.6 5299.4 5315.8 5211.9 5223.6 5227.7 5231.5 5257.4 3928.6 3996.1 4042.2 4066.8 4089.9 4116.2 4123.5 4130.3 4131.3 4131.6 4102.4 4106.5 4108.2 4108.3 4123.0 3786.7 3875.9 4066.4 4112.7 4121.4 3629.3 3646.8 3651.2 3658.2 3675.3 5056.5 5103.8 5117.0 5125.4 5129.4 4614.9 4709.3 4731.4 4731.7 4753.6 5449.5 5452.4 5456.2 5465.2 5475.7 4044.9 4049.6 4049.7 4050.6 4053.7 5416.3 5537.0 5625.2 5631.1 5673.6 4122.1 4122.5 4128.4 4131.5 4137.4 4141.6 4167.9 4169.4 4177.8 4186.5 4558.5 4644.8 4699.3 4700.3 4705.6 5414.7 5447.6 5450.2 5453.9 5488.8 1391.5 1392.0 1392.4 1399.2 1402.7 931.7 981.4 986.7 990.2 991.2 5059.2 5076.0 5176.8 5208.3 5321.4 5507.2 5558.3 5589.3 5607.5 5626.4 5177.4 5281.6 5289.3 5295.8 5297.2 2860.6 2863.3 2884.3 2896.3 2901.8 5579.0 5608.5 5619.2 5634.4 5639.9 5249.4 5434.5 5550.8 5582.5 5608.6 5471.5 5506.2 5510.8 5529.4 5650.0 5336.5 5358.1 5435.9 5438.4 5441.5 5191.9 5393.2 5393.9 5399.6 5406.3 5588.1 5591.2 5594.7 5618.4 5625.1 3985.6 4014.6 4032.5 4036.7 4038.5 5193.9 5419.2 5425.6 5440.8 5449.7 5307.9 5311.8 5313.4 5314.7 5326.6 5120.9 5155.2 5277.3 5329.1 5378.8 5223.1 5229.1 5244.5 5250.6 5262.3 5244.2 5328.6 5328.8 5354.7 5392.8 5380.9 5408.0 5412.2 5413.5 5414.6 14:28:31 INFO - PROCESS | 15475 | 14:28:31 INFO - PROCESS | 15475 | [#5] yui.html Cycles:180 Average:18262.57 Median:29246.43 stddev:24636.32 (84.2%) stddev-sans-first:24670.22 14:28:31 INFO - PROCESS | 15475 | Values: 721.2 737.9 754.3 821.3 830.8 14621.5 16145.0 16179.9 16190.6 16197.8 14743.6 15017.9 15022.9 15027.1 15034.3 667.1 684.1 775.9 776.7 789.8 788.9 799.6 805.7 806.2 807.2 847.2 847.4 848.7 849.4 849.8 704.5 718.6 819.7 846.4 846.8 603.8 606.8 607.3 607.5 609.6 15877.0 15903.3 15921.5 15928.8 15946.3 13758.7 14017.5 14309.6 14535.9 14729.4 13154.0 13257.2 13309.9 13584.6 13592.3 4388.6 4526.3 4597.5 4637.8 4660.7 82381.6 82498.0 82916.2 83023.0 83328.6 11221.8 11272.0 11680.3 11734.1 11902.0 22531.2 22584.4 22588.9 22597.8 22648.7 8040.2 8044.8 8105.3 8173.8 8364.5 4275.3 4281.3 4301.1 4311.9 4379.2 3449.9 3534.2 3541.9 3547.9 3548.7 584.7 593.5 598.8 602.4 607.8 631.7 641.4 655.2 660.5 662.3 8573.0 8874.3 8904.5 8986.6 9175.0 536.4 603.3 627.5 630.8 663.3 4308.8 4568.0 4596.3 4627.3 4689.1 87815.0 88270.6 88909.6 90768.0 90983.0 57956.4 71426.3 73841.0 77237.0 77458.0 65476.7 65835.7 66032.3 66495.7 66610.0 8213.8 8274.3 8380.5 8595.4 8601.5 39746.8 45681.8 45989.8 46669.5 47391.0 62378.7 65419.0 65600.0 66295.0 69843.0 10369.4 10622.4 10737.8 11002.9 11140.8 11758.2 11788.6 12291.3 12690.0 13089.5 11442.1 11808.5 12055.4 12181.1 12280.0 11924.3 12099.0 12719.2 12980.3 12993.6 11008.4 11964.6 11999.7 12011.6 12147.1 9594.7 9809.2 9956.8 10230.2 10698.4 8674.0 9447.9 10157.1 10183.4 10201.9 14:28:31 INFO - PROCESS | 15475 | -------- Summary: end -------- 14:28:31 INFO - PROCESS | 15475 | 14:28:32 INFO - TEST-INFO | 15475: exit 0 14:28:32 INFO - TEST-OK | dromaeo_css | took 1119308ms 14:28:32 INFO - TEST-START | kraken 14:28:32 INFO - operating with platform_type : linux_ 14:28:32 INFO - Initialising browser for kraken test... 14:28:32 INFO - TEST-INFO | started process 15784 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_FdId_/profile http://localhost:43166/getInfo.html) 14:28:36 INFO - PROCESS | 15784 | __metrics Screen width/height:1600/1200 14:28:36 INFO - PROCESS | 15784 | colorDepth:24 14:28:36 INFO - PROCESS | 15784 | Browser inner width/height: 1024/697 14:28:36 INFO - PROCESS | 15784 | __metrics 14:28:37 INFO - PROCESS | 15784 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:28:37 INFO - PROCESS | 15784 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:28:38 INFO - TEST-INFO | 15784: exit 0 14:28:38 INFO - Browser initialized. 14:28:38 INFO - Running cycle 1/1 for kraken test... 14:28:38 INFO - TEST-INFO | started process 15869 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp_FdId_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:28:50 INFO - PROCESS | 15869 | RSS: Main: 172560384 14:28:50 INFO - PROCESS | 15869 | 14:28:53 INFO - PROCESS | 15869 | Cycle 1(1): loaded http://localhost:43166/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43166/tests/kraken/driver.html?testName=audio-beat-detection) 14:28:54 INFO - PROCESS | 15869 | RSS: Main: 229945344 14:28:54 INFO - PROCESS | 15869 | 14:28:56 INFO - PROCESS | 15869 | Cycle 1(1): loaded http://localhost:43166/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43166/tests/kraken/driver.html?testName=audio-dft) 14:28:57 INFO - PROCESS | 15869 | RSS: Main: 216682496 14:28:57 INFO - PROCESS | 15869 | 14:29:00 INFO - PROCESS | 15869 | Cycle 1(1): loaded http://localhost:43166/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43166/tests/kraken/driver.html?testName=audio-fft) 14:29:00 INFO - PROCESS | 15869 | RSS: Main: 298868736 14:29:00 INFO - PROCESS | 15869 | 14:29:03 INFO - PROCESS | 15869 | Cycle 1(1): loaded http://localhost:43166/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43166/tests/kraken/driver.html?testName=audio-oscillator) 14:29:03 INFO - PROCESS | 15869 | RSS: Main: 209321984 14:29:03 INFO - PROCESS | 15869 | 14:29:05 INFO - PROCESS | 15869 | Cycle 1(1): loaded http://localhost:43166/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43166/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:29:06 INFO - PROCESS | 15869 | RSS: Main: 213475328 14:29:06 INFO - PROCESS | 15869 | 14:29:09 INFO - PROCESS | 15869 | Cycle 1(1): loaded http://localhost:43166/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43166/tests/kraken/driver.html?testName=imaging-darkroom) 14:29:10 INFO - PROCESS | 15869 | RSS: Main: 252571648 14:29:10 INFO - PROCESS | 15869 | 14:29:13 INFO - PROCESS | 15869 | Cycle 1(1): loaded http://localhost:43166/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43166/tests/kraken/driver.html?testName=imaging-desaturate) 14:29:14 INFO - PROCESS | 15869 | RSS: Main: 253005824 14:29:14 INFO - PROCESS | 15869 | 14:29:16 INFO - PROCESS | 15869 | Cycle 1(1): loaded http://localhost:43166/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43166/tests/kraken/driver.html?testName=json-parse-financial) 14:29:17 INFO - PROCESS | 15869 | RSS: Main: 251445248 14:29:17 INFO - PROCESS | 15869 | 14:29:18 INFO - PROCESS | 15869 | Cycle 1(1): loaded http://localhost:43166/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43166/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:29:19 INFO - PROCESS | 15869 | RSS: Main: 213061632 14:29:19 INFO - PROCESS | 15869 | 14:29:21 INFO - PROCESS | 15869 | Cycle 1(1): loaded http://localhost:43166/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43166/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:29:22 INFO - PROCESS | 15869 | RSS: Main: 221401088 14:29:22 INFO - PROCESS | 15869 | 14:29:24 INFO - PROCESS | 15869 | Cycle 1(1): loaded http://localhost:43166/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43166/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:29:24 INFO - PROCESS | 15869 | RSS: Main: 229732352 14:29:24 INFO - PROCESS | 15869 | 14:29:27 INFO - PROCESS | 15869 | Cycle 1(1): loaded http://localhost:43166/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43166/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:29:28 INFO - PROCESS | 15869 | RSS: Main: 238858240 14:29:28 INFO - PROCESS | 15869 | 14:29:30 INFO - PROCESS | 15869 | Cycle 1(1): loaded http://localhost:43166/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43166/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:29:31 INFO - PROCESS | 15869 | RSS: Main: 235499520 14:29:31 INFO - PROCESS | 15869 | 14:29:33 INFO - PROCESS | 15869 | Cycle 1(1): loaded http://localhost:43166/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43166/tests/kraken/driver.html?testName=ai-astar) 14:29:33 INFO - PROCESS | 15869 | RSS: Main: 286076928 14:29:33 INFO - PROCESS | 15869 | 14:29:33 INFO - PROCESS | 15869 | __start_tp_report 14:29:33 INFO - PROCESS | 15869 | _x_x_mozilla_page_load 14:29:33 INFO - PROCESS | 15869 | _x_x_mozilla_page_load_details 14:29:33 INFO - PROCESS | 15869 | |i|pagename|runs| 14:29:33 INFO - PROCESS | 15869 | |0;ai-astar;110;110;111;113;110;111;111;111;112;111 14:29:33 INFO - PROCESS | 15869 | |1;audio-beat-detection;154;130;128;161;132;123;132;132;152;125 14:29:33 INFO - PROCESS | 15869 | |2;audio-dft;161;160;160;162;160;162;162;161;161;161 14:29:33 INFO - PROCESS | 15869 | |3;audio-fft;75;76;76;111;77;76;76;76;94;75 14:29:33 INFO - PROCESS | 15869 | |4;audio-oscillator;82;82;114;83;83;82;100;83;83;104 14:29:33 INFO - PROCESS | 15869 | |5;imaging-gaussian-blur;116;117;116;117;117;118;116;117;118;117 14:29:33 INFO - PROCESS | 15869 | |6;imaging-darkroom;146;147;146;145;146;146;146;147;148;146 14:29:33 INFO - PROCESS | 15869 | |7;imaging-desaturate;95;96;95;95;96;97;95;95;95;95 14:29:33 INFO - PROCESS | 15869 | |8;json-parse-financial;52;53;52;51;53;52;53;53;55;54 14:29:33 INFO - PROCESS | 15869 | |9;json-stringify-tinderbox;63;63;63;63;64;63;62;62;62;63 14:29:33 INFO - PROCESS | 15869 | |10;stanford-crypto-aes;81;81;81;81;81;83;81;81;80;90 14:29:33 INFO - PROCESS | 15869 | |11;stanford-crypto-ccm;132;132;130;149;132;140;127;129;174;146 14:29:33 INFO - PROCESS | 15869 | |12;stanford-crypto-pbkdf2;167;172;168;171;167;166;166;167;166;164 14:29:33 INFO - PROCESS | 15869 | |13;stanford-crypto-sha256-iterative;66;65;67;65;65;67;69;65;64;64 14:29:33 INFO - PROCESS | 15869 | __end_tp_report 14:29:33 INFO - PROCESS | 15869 | __start_cc_report 14:29:33 INFO - PROCESS | 15869 | _x_x_mozilla_cycle_collect,1697 14:29:33 INFO - PROCESS | 15869 | __end_cc_report 14:29:33 INFO - PROCESS | 15869 | __startTimestamp1481063373358__endTimestamp 14:29:33 INFO - PROCESS | 15869 | 14:29:33 INFO - PROCESS | 15869 | ------- Summary: start ------- 14:29:33 INFO - PROCESS | 15869 | Number of tests: 14 14:29:33 INFO - PROCESS | 15869 | 14:29:33 INFO - PROCESS | 15869 | [#0] ai-astar Cycles:10 Average:111.00 Median:111.00 stddev:0.94 (0.8%) stddev-sans-first:0.93 14:29:33 INFO - PROCESS | 15869 | Values: 110.0 110.0 111.0 113.0 110.0 111.0 111.0 111.0 112.0 111.0 14:29:33 INFO - PROCESS | 15869 | 14:29:33 INFO - PROCESS | 15869 | [#1] audio-beat-detection Cycles:10 Average:136.90 Median:132.00 stddev:13.48 (10.2%) stddev-sans-first:12.80 14:29:33 INFO - PROCESS | 15869 | Values: 154.0 130.0 128.0 161.0 132.0 123.0 132.0 132.0 152.0 125.0 14:29:33 INFO - PROCESS | 15869 | 14:29:33 INFO - PROCESS | 15869 | [#2] audio-dft Cycles:10 Average:161.00 Median:161.00 stddev:0.82 (0.5%) stddev-sans-first:0.87 14:29:33 INFO - PROCESS | 15869 | Values: 161.0 160.0 160.0 162.0 160.0 162.0 162.0 161.0 161.0 161.0 14:29:33 INFO - PROCESS | 15869 | 14:29:33 INFO - PROCESS | 15869 | [#3] audio-fft Cycles:10 Average:81.20 Median:76.00 stddev:11.93 (15.7%) stddev-sans-first:12.44 14:29:33 INFO - PROCESS | 15869 | Values: 75.0 76.0 76.0 111.0 77.0 76.0 76.0 76.0 94.0 75.0 14:29:33 INFO - PROCESS | 15869 | 14:29:33 INFO - PROCESS | 15869 | [#4] audio-oscillator Cycles:10 Average:89.60 Median:82.50 stddev:11.82 (14.3%) stddev-sans-first:12.22 14:29:33 INFO - PROCESS | 15869 | Values: 82.0 82.0 114.0 83.0 83.0 82.0 100.0 83.0 83.0 104.0 14:29:33 INFO - PROCESS | 15869 | 14:29:33 INFO - PROCESS | 15869 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.74 (0.6%) stddev-sans-first:0.71 14:29:33 INFO - PROCESS | 15869 | Values: 116.0 117.0 116.0 117.0 117.0 118.0 116.0 117.0 118.0 117.0 14:29:33 INFO - PROCESS | 15869 | 14:29:33 INFO - PROCESS | 15869 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:0.82 (0.6%) stddev-sans-first:0.87 14:29:33 INFO - PROCESS | 15869 | Values: 146.0 147.0 146.0 145.0 146.0 146.0 146.0 147.0 148.0 146.0 14:29:33 INFO - PROCESS | 15869 | 14:29:33 INFO - PROCESS | 15869 | [#7] imaging-desaturate Cycles:10 Average:95.40 Median:95.00 stddev:0.70 (0.7%) stddev-sans-first:0.73 14:29:33 INFO - PROCESS | 15869 | Values: 95.0 96.0 95.0 95.0 96.0 97.0 95.0 95.0 95.0 95.0 14:29:33 INFO - PROCESS | 15869 | 14:29:33 INFO - PROCESS | 15869 | [#8] json-parse-financial Cycles:10 Average:52.80 Median:53.00 stddev:1.14 (2.1%) stddev-sans-first:1.17 14:29:33 INFO - PROCESS | 15869 | Values: 52.0 53.0 52.0 51.0 53.0 52.0 53.0 53.0 55.0 54.0 14:29:33 INFO - PROCESS | 15869 | 14:29:33 INFO - PROCESS | 15869 | [#9] json-stringify-tinderbox Cycles:10 Average:62.80 Median:63.00 stddev:0.63 (1.0%) stddev-sans-first:0.67 14:29:33 INFO - PROCESS | 15869 | Values: 63.0 63.0 63.0 63.0 64.0 63.0 62.0 62.0 62.0 63.0 14:29:33 INFO - PROCESS | 15869 | 14:29:33 INFO - PROCESS | 15869 | [#10] stanford-crypto-aes Cycles:10 Average:82.00 Median:81.00 stddev:2.91 (3.6%) stddev-sans-first:3.06 14:29:33 INFO - PROCESS | 15869 | Values: 81.0 81.0 81.0 81.0 81.0 83.0 81.0 81.0 80.0 90.0 14:29:33 INFO - PROCESS | 15869 | 14:29:33 INFO - PROCESS | 15869 | [#11] stanford-crypto-ccm Cycles:10 Average:139.10 Median:136.00 stddev:14.33 (10.5%) stddev-sans-first:14.96 14:29:33 INFO - PROCESS | 15869 | Values: 132.0 132.0 130.0 149.0 132.0 140.0 127.0 129.0 174.0 146.0 14:29:33 INFO - PROCESS | 15869 | 14:29:33 INFO - PROCESS | 15869 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.40 Median:167.00 stddev:2.41 (1.4%) stddev-sans-first:2.55 14:29:33 INFO - PROCESS | 15869 | Values: 167.0 172.0 168.0 171.0 167.0 166.0 166.0 167.0 166.0 164.0 14:29:33 INFO - PROCESS | 15869 | 14:29:33 INFO - PROCESS | 15869 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.70 Median:65.50 stddev:1.57 (2.4%) stddev-sans-first:1.66 14:29:33 INFO - PROCESS | 15869 | Values: 66.0 65.0 67.0 65.0 65.0 67.0 69.0 65.0 64.0 64.0 14:29:33 INFO - PROCESS | 15869 | -------- Summary: end -------- 14:29:33 INFO - PROCESS | 15869 | 14:29:34 INFO - TEST-INFO | 15869: exit 0 14:29:34 INFO - TEST-OK | kraken | took 62052ms 14:29:34 INFO - SUITE-END | took 1181s 14:29:34 INFO - Completed test suite (00:19:41) 14:29:34 INFO - javascript benchmark 14:29:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3322.4850148227024, 3361.9914025792255, 3364.461686130218, 3399.1672040350118, 3421.5551978242825, 5337.06601344765, 5365.543928766055, 5446.972765136179, 5469.015577196104, 5469.042917489438, 5461.863453413656, 5463.726813659321, 5500.80747173849, 5530.253415788868, 5589.8602534936745, 4952.653314268003, 4983.626228032894, 4987.800487980495, 5017.874553136165, 5094.083065048289, 5021.196608542634, 5057.190849464087, 5077.720725360122, 5082.771275292621, 5115.693058416488, 5031.84904452867, 5054.418741844698, 5107.106256405133, 5121.641485096045, 5154.974225128869, 4854.344663470421, 4887.975560122212, 4900.6569540132205, 4926.2364333528, 4968.975155124202, 4961.801527938915, 4970.726610036447, 4980.128477516457, 4999.450060493343, 5040.269160971675, 5420.340214961703, 5469.316335458067, 5484.095124304485, 5485.6434331768305, 5548.278723765925, 5644.520215781622, 5687.289088863892, 5771.192033115367, 5890.646561206298, 5907.113932910055, 5859.472647461706, 5904.291485021825, 5909.438603332676, 5943.940560594382, 5956.225690660186, 5502.147167189092, 5509.531689806331, 5511.503964643161, 5515.696636684981, 5515.862103447446, 5793.188953546507, 5817.534597232211, 5973.0, 5977.103434484775, 5991.850203744854, 5745.770169193185, 5809.622374545641, 5813.563982701315, 5843.912341314884, 5892.322382926019, 5866.0, 5930.733117009745, 5931.6441013539325, 5938.8218353449465, 5939.13882487037, 5672.801451949162, 5883.676397798162, 5887.705614719247, 5887.911681324784, 5890.705464726747, 5647.548196144298, 5796.768129274782, 5808.332041815161, 5812.244408226903, 5906.3207731110615, 4520.0507893342765, 4521.796519156645, 4540.977295113569, 4559.407277054028, 4566.246569316026, 4078.4494093297617, 4084.36692312692, 4101.282275601758, 4114.423980642712, 4133.731307465006, 5335.173048177461, 5386.434424385461, 5523.944760552424, 5576.86057848557, 5595.496405323541, 5872.882542349129, 5894.498967587718, 5912.438318359749, 5952.940470595238, 5967.701614919323, 5499.1476321170285, 5555.944440555623, 5561.916571251434, 5563.165525171175, 5586.413426590149, 4933.260010998379, 5001.349824522788, 5057.115004874132, 5113.51421614946, 5152.819651312186, 5911.940880591225, 5960.314563825043, 5966.373530779292, 5988.820335389945, 6057.7879774207695, 5796.333421656564, 5802.274715660543, 5806.2451881255165, 5807.62250453736, 5821.76712931478, 5824.679642619697, 5976.282846058501, 5977.252843394575, 5980.162777211107, 5982.88034239304, 5608.579356548194, 5679.375268720355, 5713.657180569179, 5724.227229323988, 5807.390224026416, 5849.444302791228, 5872.207252020922, 5886.20536227621, 5893.410658934073, 5896.49879760208, 5686.772529098956, 5738.942610574008, 5745.597808153556, 5753.165790960204, 5757.510611598061, 5573.331200255995, 5620.690862002629, 5621.184928185308, 5716.657000579979, 5717.514011308919, 5637.971810141087, 5644.209810626598, 5644.745986430538, 5666.263385759825, 5666.9716651416475, 5717.771289148387, 5739.995500787262, 5744.626599271034, 5749.396313387034, 5753.942460575341, 5647.0964645656495, 5689.829305120853, 5693.886122277614, 5697.601167918206, 5734.0], "value": 5413.190955425495, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [588.3586890289585, 610.3042531514074, 628.7202195023214, 642.5919541091406, 644.2204932032241, 31029.689703102962, 33398.332033359315, 33451.0, 33490.49764253538, 33511.832440837796, 42310.153796924045, 43462.565374346326, 43618.12763744723, 43671.12657746851, 43813.34279985795, 8413.789655258606, 8545.359098067669, 8549.529775862326, 8554.35842311829, 8555.144485551456, 7847.568383738892, 8431.915680843205, 8513.702020429286, 8519.957400213021, 8522.446041007346, 7868.4098692598, 10016.749581260454, 10194.490275486232, 10247.692569222936, 10486.842697359545, 10201.081902628763, 10256.179505639531, 10259.17926565873, 10260.692179234635, 10266.281360304782, 10027.197824174049, 10091.444970526618, 10099.293049486465, 10107.140893024101, 10110.69667909964, 38401.23197536061, 38430.23139537222, 38678.0, 38687.0, 38734.61265387338, 3366.9663303366897, 3372.4435468147726, 3381.6956473917467, 3435.707964822993, 3632.291703117893, 29276.707232927612, 29929.781614378586, 30200.0, 30262.394752104836, 30264.697353026408, 4088.100617864065, 4094.4063110849056, 4167.52073511547, 4209.326507758744, 4347.956520434786, 310562.0, 311113.0, 312823.0, 314138.0, 315937.0, 53553.464465354846, 53782.69738420917, 53811.73094134504, 53837.0, 53972.0, 56867.431325687045, 56906.0, 56935.0, 56997.715011424676, 57003.14495282574, 15228.162451065298, 15518.689626207413, 15607.843921560867, 15615.297311621005, 15617.921910390376, 11540.134489913122, 11554.248973816675, 11565.595204167814, 11571.074314054855, 11573.479193436315, 13621.182729036289, 13943.790843137362, 14594.41622335116, 14812.555623331318, 15364.539063827879, 6101.176341193971, 6102.816915492543, 6118.265808103056, 6242.250929888442, 6263.467605253513, 907.950062746542, 1065.5471424644495, 1074.586284280542, 1105.3423213188141, 1107.7175320293434, 29863.85068074646, 30051.0, 30387.848060759556, 30775.2306192347, 30822.69177308243, 1047.607147319755, 1080.6866008857344, 1089.2756317049073, 1095.8246680531076, 1104.2491106047964, 15543.0, 16123.677526449404, 16247.431339903047, 16268.430604929008, 16416.425425110065, 428703.0, 434046.0, 434128.0, 434308.0, 434394.0, 48636.0, 48977.51022489801, 52132.47867521352, 53430.46569534333, 53687.73156134194, 1311.3377744239099, 1312.3831799054428, 1328.14334754085, 1343.9596812095654, 1347.2253454263837, 29100.417991640472, 31140.37719245648, 31462.685373145974, 31568.842155789072, 32030.35939281248, 47841.0, 48284.27573586329, 48298.27552586644, 48327.0, 48397.75801120972, 374766.0, 375153.0, 376582.0, 378088.0, 378459.0, 37262.25475490529, 37647.2470550582, 37669.81165094157, 38436.42345364766, 38580.228395432496, 40047.72557792692, 40398.59601403948, 43885.0, 45421.77289113533, 45650.77174614106, 36577.26845463129, 37150.25699485941, 37655.62344376521, 37978.0, 38165.236695266496, 37390.626093739804, 37847.62152378441, 38036.0, 38493.4225986616, 39175.41236881506, 38172.427413588266, 38784.2243155141, 38795.2240955185, 39029.70673225886, 39068.413973790986, 32437.837810810794, 34162.48756268708, 34506.0, 34710.30579388449, 34773.82613086919, 26686.86656566705, 26906.596401053608, 26961.191164265103, 26975.865120675055, 27092.32269193207], "value": 19085.675459791066, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.6394379423859, 376.40527965814033, 390.28772490205375, 398.24731257922525, 413.1942711712162, 13336.0, 14415.92792036041, 16191.838081619195, 18583.628327433442, 18718.906405467955, 11955.880441195586, 17352.219150138244, 17491.688123390733, 17694.0, 17783.82216177838, 4921.458639549638, 5149.665271757342, 5149.922751158736, 5151.124308867502, 5153.536181743642, 4913.8771530711665, 5038.496150384951, 5059.544640982311, 5095.515925987044, 5139.717315547643, 5100.744962751847, 5105.3873535175835, 5108.84673459795, 5109.591232701375, 5111.207762796754, 4993.775280112403, 5033.874153146164, 5038.697678139287, 5091.058154241458, 5098.184290513518, 4897.363342765453, 4921.753912304406, 4938.506149385069, 4938.876528086791, 4943.579795717368, 13572.932135339359, 15577.766333505006, 21504.354869353945, 21790.47466677333, 21898.89050554753, 2224.4994876152896, 2303.1363238785457, 2340.403197184707, 2440.389902524369, 2666.9333266668486, 9935.304528682995, 9990.850137247948, 10017.148542373907, 10063.19494440443, 10349.534270957825, 1837.255911355903, 1916.9137388817396, 1926.8265856073021, 1944.3292064237814, 1950.249154075691, 75028.0, 77548.0, 79223.60388198022, 79544.0, 80070.59964700139, 9825.45959972194, 10044.849327260097, 10074.798504029879, 10503.789924201474, 10898.727531811768, 14691.85308146905, 14694.632634184018, 14705.926470367795, 14708.55874323772, 14717.19055451939, 7556.58438785872, 7812.921870781333, 7899.210078992055, 7984.121746607869, 8105.189481051965, 7539.434542409232, 7617.0, 7644.388448924073, 7900.091489478668, 7939.6824127035425, 4485.75328356937, 4515.977420112944, 4548.658850586219, 4587.444784400912, 4637.907241855144, 1819.426880532628, 1892.5457890106275, 1915.3870761356511, 1950.882947023183, 1983.5140390604106, 851.2168804699692, 982.3810999070682, 994.174834887042, 1009.399407352624, 1010.7978404319166, 17918.641627167384, 18302.0, 18863.717044244346, 18933.0, 19219.903900480407, 965.8696076029646, 971.5870754929127, 975.9121679048919, 975.9170470509944, 1021.5505177721927, 9804.558794854249, 10383.325083869675, 10472.266941314036, 10525.84211236832, 10572.577096916037, 390427.0, 392394.0, 396410.0, 396782.0, 399618.0, 76435.0, 81892.59053704693, 82795.0, 85810.0, 86078.56960715157, 1039.599754094664, 1047.664747280863, 1064.9254552181274, 1083.2092572422175, 1096.7587130831205, 16488.25802838899, 16569.33722651129, 17011.65976680484, 17404.303827846743, 17496.91251543734, 30367.848160759055, 33537.49693754547, 39939.0, 40176.0, 42286.65700145003, 53622.0, 55368.72315638396, 55807.0, 55918.44081559295, 57620.71189644025, 7481.551106933602, 7535.811604709706, 7537.623118844145, 7694.692212311445, 7792.532448052908, 8788.604512797066, 11295.096392288762, 11472.483738231633, 11560.42197890119, 11666.066714662977, 9995.0, 10123.848142277724, 10241.43672098012, 10329.690109296733, 10481.37111773296, 9885.901140988499, 9966.0, 10185.08334249921, 10254.128399086161, 10303.639372622221, 10030.044141392358, 10108.39349639024, 10285.125764309818, 10486.790264194826, 10546.750557502279, 7607.315341619281, 7641.732539361095, 7746.65140068687, 7867.370610351044, 7888.132305446511, 10005.899941000496, 10075.34510256831, 10207.693769186937, 10254.487275636338, 10392.532336045047], "value": 8507.567218585706, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1312.0684314136963, 1418.0782491380605, 1431.8496557861424, 1568.898021628594, 1729.4984454508185, 7407.81480462988, 7910.248526389998, 8321.83356332873, 8336.249737523622, 8338.166183381667, 8054.194580541943, 8190.91809081909, 8194.631241594128, 8343.374246931475, 8350.0, 1968.9507762305914, 2022.3225219551468, 2238.563480121377, 2285.6342985122383, 2287.462446325112, 2237.8097861681777, 2298.8275879309035, 2300.0224904415554, 2300.4133945843787, 2305.988470057647, 2188.0153930731153, 2200.7359116905996, 2202.559488102378, 2207.128184367174, 2207.9006444710026, 2255.334676270504, 2271.8864056797092, 2272.397814579138, 2273.6816845641624, 2275.397019789757, 2198.0, 2220.222921977311, 2235.9329220123423, 2238.742544607375, 2243.596152692515, 8286.792830179233, 8331.16688331168, 8412.8317433651, 8425.199606037417, 8428.157184281585, 5171.3277273954145, 5307.708076055816, 5308.309919710413, 5314.362276526803, 5318.9202161967605, 3240.303334783033, 3520.507129001942, 3572.5534308211472, 3598.5681718193723, 3598.9640103599154, 1040.4381633917676, 1099.2030777686193, 1113.5434471866495, 1127.780082883838, 1150.2983180260035, 11008.0, 11309.660710178709, 11342.262752921117, 11490.36802975836, 11714.882851171464, 4452.554744525586, 4663.906721865544, 4687.0, 4751.881202969954, 4768.403949506312, 10051.69844904654, 10051.798964020678, 10052.949735251277, 10066.69799906004, 10069.446180459998, 5299.072662284085, 5299.072662284085, 5299.814506492255, 5302.840914772486, 5305.946940530623, 4772.49888761682, 4861.246506791453, 4862.149123903303, 4866.46468888422, 4920.188168952082, 3718.5537735471917, 3797.981010094987, 3811.2949104415216, 3841.7887016214377, 3893.2213557288655, 1247.25788156047, 1257.1451413039222, 1263.7899734674008, 1283.6983308922395, 1295.378218455147, 779.4660657449665, 893.2005854759954, 915.4598786715869, 926.3191554207652, 956.0439560439561, 5536.778528858881, 5724.599278050579, 5785.10330898703, 5878.118282257696, 5929.407059294036, 875.2341701011615, 876.7413612984185, 901.1844280925773, 907.5326207003399, 908.5003248213457, 3799.7340186186702, 3871.05159235989, 3875.5155605549307, 3907.9804600976813, 3929.31237033518, 10190.0, 10451.738706532245, 10526.105281051196, 10531.210159238088, 10554.947225263824, 10264.332818366782, 10403.063724264852, 10408.427536485415, 10435.68692939213, 10444.425556594308, 7409.925900740924, 7723.9613801931755, 7725.072991241087, 7730.613469326624, 7886.211378862068, 5684.516816070515, 5705.201361513739, 5730.197772311796, 5766.913496297643, 5825.417458254141, 8977.775555611162, 9252.398594091363, 9337.299702522474, 9391.906080939376, 9396.201322887358, 9463.038285880128, 9526.380785248937, 9598.808023839623, 9696.6121355145, 9839.311248212556, 7207.675654595451, 7470.887936680845, 7624.809379765328, 7640.8471830564185, 7649.0, 8082.515049097073, 8113.634886430243, 8118.391120666091, 8120.959395202986, 8156.877646835183, 7280.526765760209, 7326.084239470067, 7358.558486490828, 7384.150822655463, 7565.735199268, 6863.245043045161, 6975.79072627822, 7019.789406317818, 7043.2604576518725, 7128.6792094356915, 6673.0, 6699.899501507579, 6746.561473504206, 6756.324367563205, 6760.18877734675, 4782.856514304576, 4843.2735089736825, 4854.466008739107, 4901.681390709592, 4957.107720610325, 4410.867673969786, 4423.911521769611, 4477.1493416250805, 4606.410788278039, 4707.246840505502], "value": 4452.285659180487, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [335.6760725899507, 335.80691102615987, 343.31851275218696, 347.51868661903256, 347.86781023211176, 5235.293235413218, 5287.312649355583, 5287.603429742775, 5299.390570084442, 5315.7873685052555, 5211.921821172686, 5223.582113430926, 5227.660202086871, 5231.450697676746, 5257.421683614799, 3928.6464218220285, 3996.1018516204863, 4042.1713548722587, 4066.8373265069363, 4089.9182016359655, 4116.176764647083, 4123.505179378464, 4130.256553820311, 4131.338985762279, 4131.607497287753, 4102.425660407545, 4106.548279689232, 4108.198901214264, 4108.260513107654, 4122.979385103085, 3786.6970642348547, 3875.883723488314, 4066.369712694537, 4112.650424713903, 4121.381792731085, 3629.310431018098, 3646.835892384849, 3651.1602331463646, 3658.213484100931, 3675.2833197526493, 5056.519630635084, 5103.8213662522, 5116.978793376596, 5125.359884836854, 5129.410117836459, 4614.861554153381, 4709.340692303081, 4731.384919960417, 4731.739754313511, 4753.572178503951, 5449.455054494559, 5452.427495113036, 5456.236126942231, 5465.207544905967, 5475.698836563987, 4044.8786536403954, 4049.602887003979, 4049.655779258737, 4050.6354428101317, 4053.6757059435467, 5416.295881535415, 5536.972315138439, 5625.184348269478, 5631.0708733059, 5673.602847800656, 4122.134351786099, 4122.484689413824, 4128.422020917074, 4131.483564554431, 4137.441445404892, 4141.6065473779945, 4167.937480937789, 4169.374593810952, 4177.811998460076, 4186.539480657125, 4558.49856515783, 4644.8374306899095, 4699.318598803155, 4700.2949557566635, 4705.55297246761, 5414.675119492843, 5447.591430642718, 5450.236966824568, 5453.918191227135, 5488.835334939959, 1391.4573316406609, 1391.997761611638, 1392.4151856220412, 1399.2024546008881, 1402.6914078902626, 931.6599441203912, 981.4307701533094, 986.7089208683456, 990.153418826907, 991.1822746234385, 5059.190529515334, 5075.974620126875, 5176.767045482962, 5208.317716376069, 5321.388040375329, 5507.173923911365, 5558.333000040021, 5589.273394458694, 5607.523360514402, 5626.409227031103, 5177.352830896138, 5281.577473802086, 5289.2859463972645, 5295.761690723926, 5297.231901374356, 2860.599516067752, 2863.2555535560487, 2884.307766136112, 2896.305661188186, 2901.7533509651917, 5579.0, 5608.49523542875, 5619.157126431068, 5634.408387119375, 5639.943600564024, 5249.396319423239, 5434.456554344534, 5550.8057217997175, 5582.525485333792, 5608.579356548275, 5471.534919531805, 5506.174073888948, 5510.8071217507195, 5529.391766905556, 5649.994252040548, 5336.519713225828, 5358.097875254384, 5435.918461223164, 5438.437823225793, 5441.5374693151525, 5191.870203244949, 5393.217983392465, 5393.927584673546, 5399.5950303726595, 5406.324209473816, 5588.050031494575, 5591.161325801162, 5594.720263986866, 5618.426840484671, 5625.103071917883, 3985.5851172834386, 4014.6186112319283, 4032.495938007749, 4036.737612055207, 4038.4749982502085, 5193.870513089615, 5419.214213939035, 5425.565954723534, 5440.809571664972, 5449.7275136243825, 5307.9469205309, 5311.840644780663, 5313.362396512443, 5314.654547454403, 5326.573874089986, 5120.889535934717, 5155.190421220369, 5277.340332458442, 5329.094054010905, 5378.757955891916, 5223.112070948024, 5229.084910140787, 5244.501772331622, 5250.632455728062, 5262.315898933114, 5244.213367994831, 5328.57371410294, 5328.8401347959625, 5354.705491198021, 5392.784288628411, 5380.892382152257, 5407.972960135332, 5412.215228791882, 5413.512783849473, 5414.592206026383], "value": 4154.079444791257, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [721.2136284506341, 737.9040724705789, 754.2570567990529, 821.2731732416811, 830.8213734047179, 14621.488247911311, 16145.0, 16179.867409281354, 16190.595235119128, 16197.75703364449, 14743.631409214775, 15017.924910375461, 15022.924885375558, 15027.098374097535, 15034.323455444504, 667.0827612033454, 684.090160087083, 775.9495632783851, 776.6699152860026, 789.8222899847545, 788.909275433324, 799.6081919859256, 805.730080423059, 806.2139414071263, 807.1565214350987, 847.1570787066877, 847.4110493207223, 848.6562942008466, 849.354490587152, 849.817289282804, 704.5174055771762, 718.5509056839476, 819.7049062337554, 846.3821410370402, 846.7544412120478, 603.7977277612006, 606.7997560804968, 607.3137354789104, 607.471499795181, 609.5672072828297, 15877.047377157407, 15903.284352204179, 15921.522354329389, 15928.76106858398, 15946.28241729125, 13758.724825503534, 14017.509387171502, 14309.642258943506, 14535.927320363437, 14729.410823567045, 13154.0, 13257.204567725967, 13309.933450332783, 13584.592462226148, 13592.25242611656, 4388.648908087345, 4526.275795872644, 4597.517260687613, 4637.837675681369, 4660.6970546914345, 82381.58809205976, 82498.0, 82916.17083829205, 83023.0, 83328.58335708283, 11221.831672524919, 11272.0, 11680.300322129286, 11734.11994100446, 11902.047836173086, 22531.211407600982, 22584.435389115075, 22588.887055564617, 22597.774022259895, 22648.660270095963, 8040.2361775631225, 8044.758657240292, 8105.2705256526015, 8173.79565510867, 8364.456310339809, 4275.315949448073, 4281.293586558245, 4301.118270754488, 4311.8706438806885, 4379.211741886491, 3449.862005519751, 3534.151803567177, 3541.9291614167573, 3547.8758243461357, 3548.698360639323, 584.6784268652224, 593.4510577715596, 598.775459202799, 602.3825578781696, 607.802464199137, 631.7157279224275, 641.4066988036049, 655.1908393134476, 660.4650233311033, 662.29134825736, 8573.0, 8874.29005679544, 8904.510251936075, 8986.640534378683, 9175.0, 536.4421002157712, 603.2535808833403, 627.5074066857495, 630.7729217481703, 663.3200969006807, 4308.784560771949, 4568.0, 4596.326526544467, 4627.282771170474, 4689.132510485571, 87815.0, 88270.55864720636, 88909.55545222362, 90768.0, 90983.0, 57956.4204357951, 71426.28573714197, 73841.0, 77237.0, 77458.0, 65476.67261663566, 65835.73314134858, 66032.33967660261, 66495.66752166208, 66610.0, 8213.753587392388, 8274.255317021498, 8380.455270407405, 8595.398322117391, 8601.483910965362, 39746.80126599348, 45681.771591141835, 45989.77005114953, 46669.53330466788, 47391.0, 62378.68810655917, 65419.0, 65600.0, 66295.01114966559, 69843.0, 10369.377837329785, 10622.415767132881, 10737.785244294913, 11002.889971100187, 11140.777184456427, 11758.176927614984, 11788.559015206303, 12291.262524248574, 12689.984801216066, 13089.541866035024, 11442.054850675238, 11808.468618912342, 12055.397230138633, 12181.08641851847, 12280.0, 11924.344161071225, 12099.0, 12719.173253738478, 12980.286084265079, 12993.610191694264, 11008.449577521253, 11964.64106076819, 11999.700007499883, 12011.63965081049, 12147.149699521302, 9594.66418675343, 9809.166220871306, 9956.800863982535, 10230.181585472996, 10698.358098514114, 8673.974080994414, 9447.858282125637, 10157.136643385395, 10183.439910804977, 10201.897981020391], "value": 7074.660606308463, "unit": "score"}], "name": "dromaeo_css", "value": 6973.683881877, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 110.0, 111.0, 113.0, 110.0, 111.0, 111.0, 111.0, 112.0, 111.0], "value": 111.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 130.0, 128.0, 161.0, 132.0, 123.0, 132.0, 132.0, 152.0, 125.0], "value": 136.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 160.0, 162.0, 160.0, 162.0, 162.0, 161.0, 161.0, 161.0], "value": 161.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 111.0, 77.0, 76.0, 76.0, 76.0, 94.0, 75.0], "value": 81.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 114.0, 83.0, 83.0, 82.0, 100.0, 83.0, 83.0, 104.0], "value": 89.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 117.0, 117.0, 118.0, 116.0, 117.0, 118.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 145.0, 146.0, 146.0, 146.0, 147.0, 148.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 95.0, 95.0, 96.0, 97.0, 95.0, 95.0, 95.0, 95.0], "value": 95.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 53.0, 52.0, 51.0, 53.0, 52.0, 53.0, 53.0, 55.0, 54.0], "value": 52.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 63.0, 63.0, 63.0, 64.0, 63.0, 62.0, 62.0, 62.0, 63.0], "value": 62.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 81.0, 81.0, 81.0, 83.0, 81.0, 81.0, 80.0, 90.0], "value": 82.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 132.0, 130.0, 149.0, 132.0, 140.0, 127.0, 129.0, 174.0, 146.0], "value": 139.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 172.0, 168.0, 171.0, 167.0, 166.0, 166.0, 167.0, 166.0, 164.0], "value": 167.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 65.0, 67.0, 65.0, 65.0, 67.0, 69.0, 65.0, 64.0, 64.0], "value": 65.7, "unit": "score"}], "name": "kraken", "value": 1508.1, "alertThreshold": 2.0}]} 14:29:34 INFO - Return code: 0 14:29:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:29:34 INFO - # TBPL SUCCESS # 14:29:34 INFO - Running post-action listener: _resource_record_post_action 14:29:34 INFO - [mozharness: 2016-12-06 22:29:34.662819Z] Finished run-tests step (success) 14:29:34 INFO - Running post-run listener: _resource_record_post_run 14:29:34 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 4096; Write bytes: 393277440; Read time: 16; Write time: 320952 14:29:34 INFO - TinderboxPrint: CPU usage
13.1% 14:29:34 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 14:29:34 INFO - TinderboxPrint: I/O write bytes / time
393,277,440 / 320,952 14:29:34 INFO - TinderboxPrint: CPU idle
8,300.2 (86.8%) 14:29:34 INFO - TinderboxPrint: CPU user
1,206.3 (12.6%) 14:29:34 INFO - TinderboxPrint: Swap in / out
0 / 0 14:29:34 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 103985152; Read time: 0; Write time: 124912 14:29:34 INFO - run-tests - Wall time: 1182s; CPU: 13.0%; Read bytes: 0; Write bytes: 289292288; Read time: 0; Write time: 196040 14:29:35 INFO - Running post-run listener: _upload_blobber_files 14:29:35 INFO - Blob upload gear active. 14:29:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:29:35 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 14:29:35 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-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:29:35 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-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:29:35 INFO - (blobuploader) - INFO - Open directory for files ... 14:29:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:29:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:29:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:29:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:29:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:29:36 INFO - (blobuploader) - INFO - Done attempting. 14:29:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:29:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:29:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:29:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:29:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:29:37 INFO - (blobuploader) - INFO - Done attempting. 14:29:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:29:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:29:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:29:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:29:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:29:41 INFO - (blobuploader) - INFO - Done attempting. 14:29:41 INFO - (blobuploader) - INFO - Iteration through files over. 14:29:41 INFO - Return code: 0 14:29:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:29:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:29:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/059f0f626e4c58883f4e4404a1fc4db85458f411e065970ade30fbca9d2de7a7b76494551491a5ef6faf46b9567f74ebe3f3e062232f4e495f0e21465802300a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/271cabf32783051b042f6d897191284f5da211d5b0d161e40e2addd0e90998960f5d342bb32a8013aeb54cb8e611f6f99a15843597bb2e5b0733a3134475a494", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/6cf9f44b758052d929b70abeeeffa1e00230e69729f583a326ded47df1832eb634bb77dd4fdc34377c6570a4c8fc66fb504bf8996b555c2758cb9ac9bb9dc7b2"} 14:29:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:29:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:29:41 INFO - Contents: 14:29:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/059f0f626e4c58883f4e4404a1fc4db85458f411e065970ade30fbca9d2de7a7b76494551491a5ef6faf46b9567f74ebe3f3e062232f4e495f0e21465802300a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/271cabf32783051b042f6d897191284f5da211d5b0d161e40e2addd0e90998960f5d342bb32a8013aeb54cb8e611f6f99a15843597bb2e5b0733a3134475a494", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/6cf9f44b758052d929b70abeeeffa1e00230e69729f583a326ded47df1832eb634bb77dd4fdc34377c6570a4c8fc66fb504bf8996b555c2758cb9ac9bb9dc7b2"} 14:29:41 INFO - Running post-run listener: copy_logs_to_upload_dir 14:29:41 INFO - Copying logs to upload dir... 14:29:41 INFO - mkdir: /builds/slave/test/build/upload/logs 14:29:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.541340 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-12-06 14:29:39.292591) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-06 14:29:39.296036) ========= 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-1481059395.754471-975443180 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-Inbound/sha512/059f0f626e4c58883f4e4404a1fc4db85458f411e065970ade30fbca9d2de7a7b76494551491a5ef6faf46b9567f74ebe3f3e062232f4e495f0e21465802300a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/271cabf32783051b042f6d897191284f5da211d5b0d161e40e2addd0e90998960f5d342bb32a8013aeb54cb8e611f6f99a15843597bb2e5b0733a3134475a494", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/6cf9f44b758052d929b70abeeeffa1e00230e69729f583a326ded47df1832eb634bb77dd4fdc34377c6570a4c8fc66fb504bf8996b555c2758cb9ac9bb9dc7b2"} build_url:https://queue.taskcluster.net/v1/task/AJJNpGB5Q3maw7AaqrumBQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011510 build_url: 'https://queue.taskcluster.net/v1/task/AJJNpGB5Q3maw7AaqrumBQ/artifacts/public/build/firefox-53.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/059f0f626e4c58883f4e4404a1fc4db85458f411e065970ade30fbca9d2de7a7b76494551491a5ef6faf46b9567f74ebe3f3e062232f4e495f0e21465802300a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/271cabf32783051b042f6d897191284f5da211d5b0d161e40e2addd0e90998960f5d342bb32a8013aeb54cb8e611f6f99a15843597bb2e5b0733a3134475a494", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/6cf9f44b758052d929b70abeeeffa1e00230e69729f583a326ded47df1832eb634bb77dd4fdc34377c6570a4c8fc66fb504bf8996b555c2758cb9ac9bb9dc7b2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-06 14:29:39.322813) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-06 14:29:39.323431) ========= 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-1481059395.754471-975443180 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004532 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-06 14:29:39.407756) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-06 14:29:39.408232) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-06 14:29:39.408761) ========= ========= Total master_lag: 0.19 =========