builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-067 starttime: 1478387757.73 results: success (0) buildid: 20161105060620 builduid: b916449ac61a4df986f58c0f4ee2b98e revision: 070cf059bb70aa991d50b4d266987adf26685915 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-05 16:15:57.729806) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-05 16:15:57.731267) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-05 16:15:57.731552) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-05 16:15:57.826013) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 16:15:57.826295) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1478387628.380639-2077778747 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-05 16:15:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 86.5M=0s 2016-11-05 16:15:59 (86.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.117269 ========= master_lag: 0.10 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 16:15:58.043611) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 16:15:58.044250) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1478387628.380639-2077778747 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.015884 ========= master_lag: 0.19 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 16:15:58.250746) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 16:15:58.251104) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 070cf059bb70aa991d50b4d266987adf26685915 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 070cf059bb70aa991d50b4d266987adf26685915 --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=e0e61f6f900f4dc42e3e8956000001ad-1478387628.380639-2077778747 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-05 16:15:59,435 truncating revision to first 12 chars 2016-11-05 16:15:59,435 Setting DEBUG logging. 2016-11-05 16:15:59,435 attempt 1/10 2016-11-05 16:15:59,435 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/070cf059bb70?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-05 16:15:59,795 unpacking tar archive at: mozilla-aurora-070cf059bb70/testing/mozharness/ program finished with exit code 0 elapsedTime=0.749939 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 16:15:59.017393) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-05 16:15:59.017709) ========= script_repo_revision: 070cf059bb70aa991d50b4d266987adf26685915 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-05 16:15:59.018098) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-05 16:15:59.018678) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-05 16:15:59.028752) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-11-05 16:15:59.029128) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=e0e61f6f900f4dc42e3e8956000001ad-1478387628.380639-2077778747 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:16:00 INFO - MultiFileLogger online at 20161105 16:16:00 in /builds/slave/test 16:16:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 16:16:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:16:00 INFO - {'append_to_log': False, 16:16:00 INFO - 'base_work_dir': '/builds/slave/test', 16:16:00 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 16:16:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:16:00 INFO - 'branch': 'Mozilla-Aurora', 16:16:00 INFO - 'buildbot_json_path': 'buildprops.json', 16:16:00 INFO - 'config_files': ('talos/linux_config.py',), 16:16:00 INFO - 'default_actions': ('clobber', 16:16:00 INFO - 'read-buildbot-config', 16:16:00 INFO - 'download-and-extract', 16:16:00 INFO - 'populate-webroot', 16:16:00 INFO - 'create-virtualenv', 16:16:00 INFO - 'install', 16:16:00 INFO - 'run-tests'), 16:16:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:16:00 INFO - 'download_minidump_stackwalk': True, 16:16:00 INFO - 'download_symbols': 'ondemand', 16:16:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:16:00 INFO - 'tooltool.py': '/tools/tooltool.py', 16:16:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:16:00 INFO - '/tools/misc-python/virtualenv.py')}, 16:16:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:16:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:16:00 INFO - 'installer_path': 'installer.exe', 16:16:00 INFO - 'log_level': 'info', 16:16:00 INFO - 'log_name': 'talos', 16:16:00 INFO - 'log_to_console': True, 16:16:00 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:16:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:16:00 INFO - 'opt_config_files': (), 16:16:00 INFO - 'pip_index': False, 16:16:00 INFO - 'sps_profile': False, 16:16:00 INFO - 'sps_profile_interval': 0, 16:16:00 INFO - 'suite': 'dromaeojs', 16:16:00 INFO - 'system_bits': '32', 16:16:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:16:00 INFO - 'title': 'talos-linux64-ix-067', 16:16:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:16:00 INFO - 'use_talos_json': True, 16:16:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:16:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:16:00 INFO - 'work_dir': 'build'} 16:16:00 INFO - [mozharness: 2016-11-05 23:16:00.303366Z] Running clobber step. 16:16:00 INFO - Running pre-action listener: _resource_record_pre_action 16:16:00 INFO - Running main action method: clobber 16:16:00 INFO - rmtree: /builds/slave/test/build 16:16:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:16:00 INFO - Running post-action listener: _resource_record_post_action 16:16:00 INFO - [mozharness: 2016-11-05 23:16:00.764468Z] Finished clobber step (success) 16:16:00 INFO - [mozharness: 2016-11-05 23:16:00.764574Z] Running read-buildbot-config step. 16:16:00 INFO - Running pre-action listener: _resource_record_pre_action 16:16:00 INFO - Running main action method: read_buildbot_config 16:16:00 INFO - Using buildbot properties: 16:16:00 INFO - { 16:16:00 INFO - "project": "", 16:16:00 INFO - "product": "firefox", 16:16:00 INFO - "script_repo_revision": "production", 16:16:00 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 16:16:00 INFO - "repository": "", 16:16:00 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 16:16:00 INFO - "buildid": "20161105060620", 16:16:00 INFO - "pgo_build": "True", 16:16:00 INFO - "basedir": "/builds/slave/test", 16:16:00 INFO - "buildnumber": 856, 16:16:00 INFO - "slavename": "talos-linux64-ix-067", 16:16:00 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:16:00 INFO - "platform": "ubuntu64_hw", 16:16:00 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 16:16:00 INFO - "branch": "mozilla-aurora", 16:16:00 INFO - "repo_path": "releases/mozilla-aurora", 16:16:00 INFO - "revision": "070cf059bb70aa991d50b4d266987adf26685915", 16:16:00 INFO - "stage_platform": "linux64-pgo", 16:16:00 INFO - "builduid": "b916449ac61a4df986f58c0f4ee2b98e", 16:16:00 INFO - "slavebuilddir": "test" 16:16:00 INFO - } 16:16:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/Gba5JSWXQxO2nh2P28t9aw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 16:16:00 INFO - Running post-action listener: _resource_record_post_action 16:16:00 INFO - [mozharness: 2016-11-05 23:16:00.767002Z] Finished read-buildbot-config step (success) 16:16:00 INFO - [mozharness: 2016-11-05 23:16:00.767091Z] Running download-and-extract step. 16:16:00 INFO - Running pre-action listener: _resource_record_pre_action 16:16:00 INFO - Running main action method: download_and_extract 16:16:00 INFO - mkdir: /builds/slave/test/build/tests 16:16:00 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')]} 16:16:00 INFO - trying https://queue.taskcluster.net/v1/task/Gba5JSWXQxO2nh2P28t9aw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 16:16:00 INFO - Downloading https://queue.taskcluster.net/v1/task/Gba5JSWXQxO2nh2P28t9aw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 16:16:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Gba5JSWXQxO2nh2P28t9aw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 16:16:01 INFO - Downloaded 1361 bytes. 16:16:01 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 16:16:01 INFO - Using the following test package requirements: 16:16:01 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 16:16:01 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 16:16:01 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 16:16:01 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 16:16:01 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 16:16:01 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 16:16:01 INFO - u'jsshell-linux-x86_64.zip'], 16:16:01 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 16:16:01 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 16:16:01 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 16:16:01 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 16:16:01 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 16:16:01 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 16:16:01 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 16:16:01 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 16:16:01 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 16:16:01 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 16:16:01 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 16:16:01 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:16:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Gba5JSWXQxO2nh2P28t9aw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 16:16:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Gba5JSWXQxO2nh2P28t9aw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:16:01 INFO - Fetch https://queue.taskcluster.net/v1/task/Gba5JSWXQxO2nh2P28t9aw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 16:16:01 INFO - Expected file size: 21894888 16:16:06 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 16:16:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Gba5JSWXQxO2nh2P28t9aw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 16:16:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Gba5JSWXQxO2nh2P28t9aw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:16:06 INFO - Fetch https://queue.taskcluster.net/v1/task/Gba5JSWXQxO2nh2P28t9aw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 16:16:06 INFO - Expected file size: 21894888 16:16:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Gba5JSWXQxO2nh2P28t9aw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 16:16:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Gba5JSWXQxO2nh2P28t9aw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:16:11 INFO - Fetch https://queue.taskcluster.net/v1/task/Gba5JSWXQxO2nh2P28t9aw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 16:16:12 INFO - Expected file size: 14414089 16:16:12 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')]} 16:16:12 INFO - trying https://queue.taskcluster.net/v1/task/Gba5JSWXQxO2nh2P28t9aw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 16:16:12 INFO - Downloading https://queue.taskcluster.net/v1/task/Gba5JSWXQxO2nh2P28t9aw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 16:16:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Gba5JSWXQxO2nh2P28t9aw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 16:16:13 INFO - Downloaded 63996098 bytes. 16:16:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Gba5JSWXQxO2nh2P28t9aw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 16:16:13 INFO - mkdir: /builds/slave/test/properties 16:16:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:16:13 INFO - Writing to file /builds/slave/test/properties/build_url 16:16:13 INFO - Contents: 16:16:13 INFO - build_url:https://queue.taskcluster.net/v1/task/Gba5JSWXQxO2nh2P28t9aw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 16:16:14 INFO - Running post-action listener: _resource_record_post_action 16:16:14 INFO - Running post-action listener: set_extra_try_arguments 16:16:14 INFO - [mozharness: 2016-11-05 23:16:14.388238Z] Finished download-and-extract step (success) 16:16:14 INFO - [mozharness: 2016-11-05 23:16:14.388505Z] Running populate-webroot step. 16:16:14 INFO - Running pre-action listener: _resource_record_pre_action 16:16:14 INFO - Running main action method: populate_webroot 16:16:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:16:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:16:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:16:14 INFO - '--tppagecycles', 16:16:14 INFO - '7'], 16:16:14 INFO - 'tests': ['tsvgm']}}, 16:16:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:16:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:16:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:16:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:16:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:16:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:16:14 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:14 INFO - 'talos_options': ['--disable-e10s'], 16:16:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:16:14 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:16:14 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:14 INFO - 'talos_options': ['--disable-e10s'], 16:16:14 INFO - 'tests': ['damp', 'tps']}, 16:16:14 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:14 INFO - 'tests': ['damp', 'tps']}, 16:16:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:16:14 INFO - 'tests': ['dromaeo_dom']}, 16:16:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:16:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:16:14 INFO - 'tests': ['basic_compositor_video']}, 16:16:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:16:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:16:14 INFO - 'tests': ['a11yr', 16:16:14 INFO - 'ts_paint', 16:16:14 INFO - 'tpaint', 16:16:14 INFO - 'sessionrestore', 16:16:14 INFO - 'sessionrestore_no_auto_restore', 16:16:14 INFO - 'tabpaint']}, 16:16:14 INFO - 'other-e10s': {'tests': ['a11yr', 16:16:14 INFO - 'ts_paint', 16:16:14 INFO - 'tpaint', 16:16:14 INFO - 'sessionrestore', 16:16:14 INFO - 'sessionrestore_no_auto_restore', 16:16:14 INFO - 'tabpaint']}, 16:16:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:16:14 INFO - 'ts_paint', 16:16:14 INFO - 'tpaint', 16:16:14 INFO - 'sessionrestore', 16:16:14 INFO - 'sessionrestore_no_auto_restore', 16:16:14 INFO - 'tabpaint']}, 16:16:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:16:14 INFO - 'ts_paint', 16:16:14 INFO - 'tpaint', 16:16:14 INFO - 'sessionrestore', 16:16:14 INFO - 'sessionrestore_no_auto_restore', 16:16:14 INFO - 'tabpaint']}, 16:16:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:16:14 INFO - 'tests': ['a11yr', 16:16:14 INFO - 'ts_paint', 16:16:14 INFO - 'tpaint', 16:16:14 INFO - 'sessionrestore', 16:16:14 INFO - 'sessionrestore_no_auto_restore', 16:16:14 INFO - 'tabpaint']}, 16:16:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:16:14 INFO - 'tests': ['a11yr', 16:16:14 INFO - 'ts_paint', 16:16:14 INFO - 'tpaint', 16:16:14 INFO - 'sessionrestore', 16:16:14 INFO - 'sessionrestore_no_auto_restore', 16:16:14 INFO - 'tabpaint']}, 16:16:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:16:14 INFO - 'tests': ['tsvgx', 16:16:14 INFO - 'tsvgr_opacity', 16:16:14 INFO - 'tart', 16:16:14 INFO - 'tscrollx', 16:16:14 INFO - 'cart']}, 16:16:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:16:14 INFO - 'tsvgr_opacity', 16:16:14 INFO - 'tart', 16:16:14 INFO - 'tscrollx', 16:16:14 INFO - 'cart']}, 16:16:14 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:14 INFO - 'talos_options': ['--disable-e10s'], 16:16:14 INFO - 'tests': ['tp5o']}, 16:16:14 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:14 INFO - 'tests': ['tp5o']}, 16:16:14 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:14 INFO - 'talos_options': ['--disable-e10s', 16:16:14 INFO - '--xperf_path', 16:16:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:16:14 INFO - 'tests': ['tp5n']}, 16:16:14 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:16:14 INFO - 'talos_options': ['--xperf_path', 16:16:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:16:14 INFO - 'tests': ['tp5n']}}, 16:16:14 INFO - 'talos.zip': {'path': '', 16:16:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:16:14 INFO - Running post-action listener: _resource_record_post_action 16:16:14 INFO - [mozharness: 2016-11-05 23:16:14.409391Z] Finished populate-webroot step (success) 16:16:14 INFO - [mozharness: 2016-11-05 23:16:14.409480Z] Running create-virtualenv step. 16:16:14 INFO - Running pre-action listener: _resource_record_pre_action 16:16:14 INFO - Running main action method: create_virtualenv 16:16:14 INFO - Creating virtualenv /builds/slave/test/build/venv 16:16:14 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 16:16:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:16:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:16:14 INFO - Using real prefix '/usr' 16:16:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:16:15 INFO - Installing distribute.............................................................................................................................................................................................done. 16:16:16 INFO - Installing pip.................done. 16:16:16 INFO - Return code: 0 16:16:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:16: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')]} 16:16:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16: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')]} 16:16:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f276c588f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfbd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087f60>, '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 0x1088d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10891d0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1478387628.380639-2077778747', '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 16:16: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', 'pip>=1.5'] in /builds/slave/test/build 16:16: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 pip>=1.5 16:16:16 INFO - Using env: {'DISPLAY': ':0', 16:16:16 INFO - 'HOME': '/home/cltbld', 16:16:16 INFO - 'LANG': 'en_US.UTF-8', 16:16:16 INFO - 'LANGUAGE': 'en_US:en', 16:16:16 INFO - 'LOGNAME': 'cltbld', 16:16:16 INFO - 'MAIL': '/var/mail/cltbld', 16:16:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:16 INFO - 'MOZ_NO_REMOTE': '1', 16:16:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:16:16 INFO - 'NO_EM_RESTART': '1', 16:16:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:16:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:16 INFO - 'PWD': '/builds/slave/test', 16:16:16 INFO - 'SHELL': '/bin/bash', 16:16:16 INFO - 'SHLVL': '1', 16:16:16 INFO - 'TERM': 'linux', 16:16:16 INFO - 'TMOUT': '86400', 16:16:16 INFO - 'USER': 'cltbld', 16:16:16 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1478387628.380639-2077778747', 16:16:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:16 INFO - '_': '/tools/buildbot/bin/python'} 16:16:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:17 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 16:16:17 INFO - Cleaning up... 16:16:17 INFO - Return code: 0 16:16:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:16:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:16:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:16:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:17 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 0x7f276c588f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfbd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087f60>, '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 0x1088d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10891d0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1478387628.380639-2077778747', '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 16:16:17 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 16:16:17 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 16:16:17 INFO - Using env: {'DISPLAY': ':0', 16:16:17 INFO - 'HOME': '/home/cltbld', 16:16:17 INFO - 'LANG': 'en_US.UTF-8', 16:16:17 INFO - 'LANGUAGE': 'en_US:en', 16:16:17 INFO - 'LOGNAME': 'cltbld', 16:16:17 INFO - 'MAIL': '/var/mail/cltbld', 16:16:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:17 INFO - 'MOZ_NO_REMOTE': '1', 16:16:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:16:17 INFO - 'NO_EM_RESTART': '1', 16:16:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:16:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:17 INFO - 'PWD': '/builds/slave/test', 16:16:17 INFO - 'SHELL': '/bin/bash', 16:16:17 INFO - 'SHLVL': '1', 16:16:17 INFO - 'TERM': 'linux', 16:16:17 INFO - 'TMOUT': '86400', 16:16:17 INFO - 'USER': 'cltbld', 16:16:17 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1478387628.380639-2077778747', 16:16:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:17 INFO - '_': '/tools/buildbot/bin/python'} 16:16:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:17 INFO - Downloading/unpacking psutil>=3.1.1 16:16:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:16:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:16:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:16:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:16:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:16:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:16:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:16:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:16:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:16:19 INFO - Installing collected packages: psutil 16:16:19 INFO - Running setup.py install for psutil 16:16:19 INFO - building 'psutil._psutil_linux' extension 16:16:19 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 16:16:19 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 16:16:20 INFO - building 'psutil._psutil_posix' extension 16:16:20 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 16:16:20 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 16:16:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:16:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:16:20 INFO - Successfully installed psutil 16:16:20 INFO - Cleaning up... 16:16:20 INFO - Return code: 0 16:16:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:16:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:16:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:16:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f276c588f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfbd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087f60>, '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 0x1088d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10891d0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1478387628.380639-2077778747', '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 16:16:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:16:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:16:20 INFO - Using env: {'DISPLAY': ':0', 16:16:20 INFO - 'HOME': '/home/cltbld', 16:16:20 INFO - 'LANG': 'en_US.UTF-8', 16:16:20 INFO - 'LANGUAGE': 'en_US:en', 16:16:20 INFO - 'LOGNAME': 'cltbld', 16:16:20 INFO - 'MAIL': '/var/mail/cltbld', 16:16:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:20 INFO - 'MOZ_NO_REMOTE': '1', 16:16:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:16:20 INFO - 'NO_EM_RESTART': '1', 16:16:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:16:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:20 INFO - 'PWD': '/builds/slave/test', 16:16:20 INFO - 'SHELL': '/bin/bash', 16:16:20 INFO - 'SHLVL': '1', 16:16:20 INFO - 'TERM': 'linux', 16:16:20 INFO - 'TMOUT': '86400', 16:16:20 INFO - 'USER': 'cltbld', 16:16:20 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1478387628.380639-2077778747', 16:16:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:20 INFO - '_': '/tools/buildbot/bin/python'} 16:16:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:16:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:16:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:16:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:16:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:16:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:16:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:16:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:16:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:16:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:16:22 INFO - Installing collected packages: mozsystemmonitor 16:16:22 INFO - Running setup.py install for mozsystemmonitor 16:16:22 INFO - Successfully installed mozsystemmonitor 16:16:22 INFO - Cleaning up... 16:16:22 INFO - Return code: 0 16:16:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:16:22 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')]} 16:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:22 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')]} 16:16:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:22 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 0x7f276c588f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfbd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087f60>, '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 0x1088d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10891d0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1478387628.380639-2077778747', '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 16:16:22 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 16:16:22 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 16:16:22 INFO - Using env: {'DISPLAY': ':0', 16:16:22 INFO - 'HOME': '/home/cltbld', 16:16:22 INFO - 'LANG': 'en_US.UTF-8', 16:16:22 INFO - 'LANGUAGE': 'en_US:en', 16:16:22 INFO - 'LOGNAME': 'cltbld', 16:16:22 INFO - 'MAIL': '/var/mail/cltbld', 16:16:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:22 INFO - 'MOZ_NO_REMOTE': '1', 16:16:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:16:22 INFO - 'NO_EM_RESTART': '1', 16:16:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:16:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:22 INFO - 'PWD': '/builds/slave/test', 16:16:22 INFO - 'SHELL': '/bin/bash', 16:16:22 INFO - 'SHLVL': '1', 16:16:22 INFO - 'TERM': 'linux', 16:16:22 INFO - 'TMOUT': '86400', 16:16:22 INFO - 'USER': 'cltbld', 16:16:22 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1478387628.380639-2077778747', 16:16:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:22 INFO - '_': '/tools/buildbot/bin/python'} 16:16:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:22 INFO - Downloading/unpacking jsonschema==2.5.1 16:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:16:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:16:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:16:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:16:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:16:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:16:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:16: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 16:16: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 16:16: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 16:16: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 16:16: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 16:16: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 16:16:25 INFO - Downloading functools32-3.2.3-2.tar.gz 16:16:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:16:25 INFO - warning: no files found matching '*.txt' 16:16:25 INFO - no previously-included directories found matching 'build' 16:16:25 INFO - no previously-included directories found matching 'dist' 16:16:25 INFO - no previously-included directories found matching '.git*' 16:16:25 INFO - Installing collected packages: jsonschema, functools32 16:16:25 INFO - Running setup.py install for functools32 16:16:25 INFO - warning: no files found matching '*.txt' 16:16:25 INFO - no previously-included directories found matching 'build' 16:16:25 INFO - no previously-included directories found matching 'dist' 16:16:25 INFO - no previously-included directories found matching '.git*' 16:16:25 INFO - Successfully installed jsonschema functools32 16:16:25 INFO - Cleaning up... 16:16:25 INFO - Return code: 0 16:16:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:16:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:16:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f276c588f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfbd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087f60>, '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 0x1088d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10891d0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1478387628.380639-2077778747', '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 16:16:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:16:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:16:25 INFO - Using env: {'DISPLAY': ':0', 16:16:25 INFO - 'HOME': '/home/cltbld', 16:16:25 INFO - 'LANG': 'en_US.UTF-8', 16:16:25 INFO - 'LANGUAGE': 'en_US:en', 16:16:25 INFO - 'LOGNAME': 'cltbld', 16:16:25 INFO - 'MAIL': '/var/mail/cltbld', 16:16:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:25 INFO - 'MOZ_NO_REMOTE': '1', 16:16:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:16:25 INFO - 'NO_EM_RESTART': '1', 16:16:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:16:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:25 INFO - 'PWD': '/builds/slave/test', 16:16:25 INFO - 'SHELL': '/bin/bash', 16:16:25 INFO - 'SHLVL': '1', 16:16:25 INFO - 'TERM': 'linux', 16:16:25 INFO - 'TMOUT': '86400', 16:16:25 INFO - 'USER': 'cltbld', 16:16:25 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1478387628.380639-2077778747', 16:16:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:25 INFO - '_': '/tools/buildbot/bin/python'} 16:16:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:16:25 INFO - Cleaning up... 16:16:25 INFO - Return code: 0 16:16:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:16:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:16:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f276c588f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfbd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087f60>, '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 0x1088d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10891d0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1478387628.380639-2077778747', '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 16:16:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:16:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:16:25 INFO - Using env: {'DISPLAY': ':0', 16:16:25 INFO - 'HOME': '/home/cltbld', 16:16:25 INFO - 'LANG': 'en_US.UTF-8', 16:16:25 INFO - 'LANGUAGE': 'en_US:en', 16:16:25 INFO - 'LOGNAME': 'cltbld', 16:16:25 INFO - 'MAIL': '/var/mail/cltbld', 16:16:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:25 INFO - 'MOZ_NO_REMOTE': '1', 16:16:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:16:25 INFO - 'NO_EM_RESTART': '1', 16:16:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:16:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:25 INFO - 'PWD': '/builds/slave/test', 16:16:25 INFO - 'SHELL': '/bin/bash', 16:16:25 INFO - 'SHLVL': '1', 16:16:25 INFO - 'TERM': 'linux', 16:16:25 INFO - 'TMOUT': '86400', 16:16:25 INFO - 'USER': 'cltbld', 16:16:25 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1478387628.380639-2077778747', 16:16:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:25 INFO - '_': '/tools/buildbot/bin/python'} 16:16:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:25 INFO - Downloading/unpacking blobuploader==1.2.4 16:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:16:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:16:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:16:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:16:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:16:27 INFO - Downloading blobuploader-1.2.4.tar.gz 16:16:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:16:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:16: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 16:16: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 16:16: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 16:16: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 16:16: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 16:16: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 16:16:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:16:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:16:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:16:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:16:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:16:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:16:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:16:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:16:28 INFO - Downloading docopt-0.6.1.tar.gz 16:16:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:16:28 INFO - Installing collected packages: blobuploader, requests, docopt 16:16:28 INFO - Running setup.py install for blobuploader 16:16:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:16:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:16:28 INFO - Running setup.py install for requests 16:16:28 INFO - Running setup.py install for docopt 16:16:28 INFO - Successfully installed blobuploader requests docopt 16:16:28 INFO - Cleaning up... 16:16:29 INFO - Return code: 0 16:16:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:16:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:16:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:16:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:29 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 0x7f276c588f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfbd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087f60>, '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 0x1088d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10891d0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1478387628.380639-2077778747', '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 16:16:29 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 16:16:29 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 16:16:29 INFO - Using env: {'DISPLAY': ':0', 16:16:29 INFO - 'HOME': '/home/cltbld', 16:16:29 INFO - 'LANG': 'en_US.UTF-8', 16:16:29 INFO - 'LANGUAGE': 'en_US:en', 16:16:29 INFO - 'LOGNAME': 'cltbld', 16:16:29 INFO - 'MAIL': '/var/mail/cltbld', 16:16:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:29 INFO - 'MOZ_NO_REMOTE': '1', 16:16:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:16:29 INFO - 'NO_EM_RESTART': '1', 16:16:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:16:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:29 INFO - 'PWD': '/builds/slave/test', 16:16:29 INFO - 'SHELL': '/bin/bash', 16:16:29 INFO - 'SHLVL': '1', 16:16:29 INFO - 'TERM': 'linux', 16:16:29 INFO - 'TMOUT': '86400', 16:16:29 INFO - 'USER': 'cltbld', 16:16:29 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1478387628.380639-2077778747', 16:16:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:29 INFO - '_': '/tools/buildbot/bin/python'} 16:16:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:16:29 INFO - Running setup.py (path:/tmp/pip-CYU0MK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:16:29 INFO - Running setup.py (path:/tmp/pip-bUmf_V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:16:29 INFO - Running setup.py (path:/tmp/pip-YL8QFd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:16:29 INFO - Running setup.py (path:/tmp/pip-0ppxfw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:16:29 INFO - Running setup.py (path:/tmp/pip-V48Lws-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:16:29 INFO - Running setup.py (path:/tmp/pip-C5BjjH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:16:29 INFO - Running setup.py (path:/tmp/pip-Fpt0DJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:16:29 INFO - Running setup.py (path:/tmp/pip-aSP5Zk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:16:29 INFO - Running setup.py (path:/tmp/pip-JhVLvo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:16:29 INFO - Running setup.py (path:/tmp/pip-Mk24Bw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:16:29 INFO - Running setup.py (path:/tmp/pip-grdX58-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:16:30 INFO - Running setup.py (path:/tmp/pip-6jCkCa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:16:30 INFO - Running setup.py (path:/tmp/pip-CEXBQ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:16:30 INFO - Running setup.py (path:/tmp/pip-qk9eqN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:16:30 INFO - Running setup.py (path:/tmp/pip-9i7Vod-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:16:30 INFO - Running setup.py (path:/tmp/pip-G5HKTi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:16:30 INFO - Running setup.py (path:/tmp/pip-DgjNmV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:16:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:16:30 INFO - Running setup.py install for manifestparser 16:16:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:16:30 INFO - Running setup.py install for mozcrash 16:16:30 INFO - Running setup.py install for mozdebug 16:16:30 INFO - Running setup.py install for mozdevice 16:16:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:16:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:16:30 INFO - Running setup.py install for mozfile 16:16:30 INFO - Running setup.py install for mozhttpd 16:16:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:16:31 INFO - Running setup.py install for mozinfo 16:16:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:16:31 INFO - Running setup.py install for mozInstall 16:16:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:16:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:16:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:16:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:16:31 INFO - Running setup.py install for mozleak 16:16:31 INFO - Running setup.py install for mozlog 16:16:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:16:31 INFO - Running setup.py install for moznetwork 16:16:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:16:31 INFO - Running setup.py install for mozprocess 16:16:31 INFO - Running setup.py install for mozprofile 16:16:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:16:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:16:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:16:31 INFO - Running setup.py install for mozrunner 16:16:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:16:31 INFO - Running setup.py install for mozscreenshot 16:16:32 INFO - Running setup.py install for moztest 16:16:32 INFO - Running setup.py install for mozversion 16:16:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:16:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:16:32 INFO - Cleaning up... 16:16:32 INFO - Return code: 0 16:16:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:16:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:16:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:16:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:32 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 0x7f276c588f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfbd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087f60>, '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 0x1088d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10891d0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1478387628.380639-2077778747', '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 16:16:32 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 16:16:32 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 16:16:32 INFO - Using env: {'DISPLAY': ':0', 16:16:32 INFO - 'HOME': '/home/cltbld', 16:16:32 INFO - 'LANG': 'en_US.UTF-8', 16:16:32 INFO - 'LANGUAGE': 'en_US:en', 16:16:32 INFO - 'LOGNAME': 'cltbld', 16:16:32 INFO - 'MAIL': '/var/mail/cltbld', 16:16:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:32 INFO - 'MOZ_NO_REMOTE': '1', 16:16:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:16:32 INFO - 'NO_EM_RESTART': '1', 16:16:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:16:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:32 INFO - 'PWD': '/builds/slave/test', 16:16:32 INFO - 'SHELL': '/bin/bash', 16:16:32 INFO - 'SHLVL': '1', 16:16:32 INFO - 'TERM': 'linux', 16:16:32 INFO - 'TMOUT': '86400', 16:16:32 INFO - 'USER': 'cltbld', 16:16:32 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1478387628.380639-2077778747', 16:16:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:32 INFO - '_': '/tools/buildbot/bin/python'} 16:16:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:16:32 INFO - Running setup.py (path:/tmp/pip-YAzaFe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:16:32 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)) 16:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:16:32 INFO - Running setup.py (path:/tmp/pip-Wx9Wwl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:16:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:16:32 INFO - Running setup.py (path:/tmp/pip-unXLk8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:16:32 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)) 16:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:16:32 INFO - Running setup.py (path:/tmp/pip-97rFgr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:16:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:16:32 INFO - Running setup.py (path:/tmp/pip-k6_lI7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:16:32 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)) 16:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:16:32 INFO - Running setup.py (path:/tmp/pip-dfS0kt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:16:32 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)) 16:16:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:16:32 INFO - Running setup.py (path:/tmp/pip-YTDL1A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:16:33 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)) 16:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:16:33 INFO - Running setup.py (path:/tmp/pip-6FTA3B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:16:33 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)) 16:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:16:33 INFO - Running setup.py (path:/tmp/pip-X98dod-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:16:33 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)) 16:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:16:33 INFO - Running setup.py (path:/tmp/pip-a29ZbW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:16:33 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)) 16:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:16:33 INFO - Running setup.py (path:/tmp/pip-FTbvsq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:16:33 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)) 16:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:16:33 INFO - Running setup.py (path:/tmp/pip-fUoF8u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:16:33 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)) 16:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:16:33 INFO - Running setup.py (path:/tmp/pip-aiw0if-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:16:33 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)) 16:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:16:33 INFO - Running setup.py (path:/tmp/pip-N6ATWd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:16:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 16:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:16:33 INFO - Running setup.py (path:/tmp/pip-T3bqrE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:16:33 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)) 16:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:16:33 INFO - Running setup.py (path:/tmp/pip-lz_kqO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:16:33 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)) 16:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:16:33 INFO - Running setup.py (path:/tmp/pip-xb809j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:16:33 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)) 16:16:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:16:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:16:33 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)) 16:16:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:16:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:16:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:16:33 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 16:16:33 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 16:16:33 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 16:16:33 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 16:16:33 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 16:16:33 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 16:16:35 INFO - Downloading blessings-1.6.tar.gz 16:16:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:16:35 INFO - Installing collected packages: blessings 16:16:35 INFO - Running setup.py install for blessings 16:16:35 INFO - Successfully installed blessings 16:16:35 INFO - Cleaning up... 16:16:35 INFO - Return code: 0 16:16:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:16:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:16:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:16:36 INFO - Reading from file tmpfile_stdout 16:16:36 INFO - Current package versions: 16:16:36 INFO - argparse == 1.2.1 16:16:36 INFO - blessings == 1.6 16:16:36 INFO - blobuploader == 1.2.4 16:16:36 INFO - docopt == 0.6.1 16:16:36 INFO - functools32 == 3.2.3-2 16:16:36 INFO - jsonschema == 2.5.1 16:16:36 INFO - manifestparser == 1.1 16:16:36 INFO - mozInstall == 1.12 16:16:36 INFO - mozcrash == 0.17 16:16:36 INFO - mozdebug == 0.1 16:16:36 INFO - mozdevice == 0.48 16:16:36 INFO - mozfile == 1.2 16:16:36 INFO - mozhttpd == 0.7 16:16:36 INFO - mozinfo == 0.9 16:16:36 INFO - mozleak == 0.1 16:16:36 INFO - mozlog == 3.4 16:16:36 INFO - moznetwork == 0.27 16:16:36 INFO - mozprocess == 0.23 16:16:36 INFO - mozprofile == 0.28 16:16:36 INFO - mozrunner == 6.12 16:16:36 INFO - mozscreenshot == 0.1 16:16:36 INFO - mozsystemmonitor == 0.3 16:16:36 INFO - moztest == 0.7 16:16:36 INFO - mozversion == 1.4 16:16:36 INFO - psutil == 3.1.1 16:16:36 INFO - requests == 1.2.3 16:16:36 INFO - wsgiref == 0.1.2 16:16:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:16:36 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')]} 16:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:36 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')]} 16:16:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:36 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 0x7f276c588f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfbd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087f60>, '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 0x1088d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10891d0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1478387628.380639-2077778747', '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 16:16:36 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 16:16:36 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 16:16:36 INFO - Using env: {'DISPLAY': ':0', 16:16:36 INFO - 'HOME': '/home/cltbld', 16:16:36 INFO - 'LANG': 'en_US.UTF-8', 16:16:36 INFO - 'LANGUAGE': 'en_US:en', 16:16:36 INFO - 'LOGNAME': 'cltbld', 16:16:36 INFO - 'MAIL': '/var/mail/cltbld', 16:16:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:36 INFO - 'MOZ_NO_REMOTE': '1', 16:16:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:16:36 INFO - 'NO_EM_RESTART': '1', 16:16:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:16:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:36 INFO - 'PWD': '/builds/slave/test', 16:16:36 INFO - 'SHELL': '/bin/bash', 16:16:36 INFO - 'SHLVL': '1', 16:16:36 INFO - 'TERM': 'linux', 16:16:36 INFO - 'TMOUT': '86400', 16:16:36 INFO - 'USER': 'cltbld', 16:16:36 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1478387628.380639-2077778747', 16:16:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:36 INFO - '_': '/tools/buildbot/bin/python'} 16:16:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:36 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)) 16:16:36 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)) 16:16:36 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)) 16:16:36 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)) 16:16:36 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)) 16:16:36 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)) 16:16:36 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)) 16:16:36 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)) 16:16:36 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)) 16:16:36 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)) 16:16:36 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)) 16:16:36 INFO - Cleaning up... 16:16:36 INFO - Return code: 0 16:16:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:16:36 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')]} 16:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:16:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:36 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')]} 16:16:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:16:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:16:36 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 0x7f276c588f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xfbd8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108aca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1087f60>, '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 0x1088d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10891d0>, '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': 'e0e61f6f900f4dc42e3e8956000001ad-1478387628.380639-2077778747', '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 16:16:36 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 16:16:36 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 16:16:36 INFO - Using env: {'DISPLAY': ':0', 16:16:36 INFO - 'HOME': '/home/cltbld', 16:16:36 INFO - 'LANG': 'en_US.UTF-8', 16:16:36 INFO - 'LANGUAGE': 'en_US:en', 16:16:36 INFO - 'LOGNAME': 'cltbld', 16:16:36 INFO - 'MAIL': '/var/mail/cltbld', 16:16:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:36 INFO - 'MOZ_NO_REMOTE': '1', 16:16:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:16:36 INFO - 'NO_EM_RESTART': '1', 16:16:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:16:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:36 INFO - 'PWD': '/builds/slave/test', 16:16:36 INFO - 'SHELL': '/bin/bash', 16:16:36 INFO - 'SHLVL': '1', 16:16:36 INFO - 'TERM': 'linux', 16:16:36 INFO - 'TMOUT': '86400', 16:16:36 INFO - 'USER': 'cltbld', 16:16:36 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1478387628.380639-2077778747', 16:16:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:36 INFO - '_': '/tools/buildbot/bin/python'} 16:16:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:16:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:16:36 INFO - Cleaning up... 16:16:36 INFO - Return code: 0 16:16:36 INFO - Running post-action listener: _resource_record_post_action 16:16:36 INFO - Running post-action listener: _start_resource_monitoring 16:16:36 INFO - Starting resource monitoring. 16:16:36 INFO - [mozharness: 2016-11-05 23:16:36.637426Z] Finished create-virtualenv step (success) 16:16:36 INFO - [mozharness: 2016-11-05 23:16:36.637879Z] Running install step. 16:16:36 INFO - Running pre-action listener: _resource_record_pre_action 16:16:36 INFO - Running main action method: install 16:16:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:16:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:16:36 INFO - Reading from file tmpfile_stdout 16:16:36 INFO - Detecting whether we're running mozinstall >=1.0... 16:16:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:16:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:16:36 INFO - Reading from file tmpfile_stdout 16:16:36 INFO - Output received: 16:16:36 INFO - Usage: mozinstall [options] installer 16:16:36 INFO - Options: 16:16:36 INFO - -h, --help show this help message and exit 16:16:36 INFO - -d DEST, --destination=DEST 16:16:36 INFO - Directory to install application into. [default: 16:16:36 INFO - "/builds/slave/test"] 16:16:36 INFO - --app=APP Application being installed. [default: firefox] 16:16:36 INFO - mkdir: /builds/slave/test/build/application 16:16:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:16:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 16:16:52 INFO - Reading from file tmpfile_stdout 16:16:52 INFO - Output received: 16:16:52 INFO - /builds/slave/test/build/application/firefox/firefox 16:16:52 INFO - Running post-action listener: _resource_record_post_action 16:16:52 INFO - [mozharness: 2016-11-05 23:16:52.902296Z] Finished install step (success) 16:16:52 INFO - [mozharness: 2016-11-05 23:16:52.902628Z] Running run-tests step. 16:16:52 INFO - Running pre-action listener: _resource_record_pre_action 16:16:52 INFO - Running main action method: run_tests 16:16:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:16:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:16:52 INFO - Python 2.7.3 16:16:52 INFO - Return code: 0 16:16:52 INFO - grabbing minidump binary from tooltool 16:16: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')]} 16:16: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 0x1087f60>, '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 0x1088d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10891d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:16: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 16:16: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 16:16: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 16:16:52 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:16:52 INFO - Return code: 0 16:16:52 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:16:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:16:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:16:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:16:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:16:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Gba5JSWXQxO2nh2P28t9aw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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 16:16:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Gba5JSWXQxO2nh2P28t9aw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-067 --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 16:16:52 INFO - Using env: {'DISPLAY': ':0', 16:16:52 INFO - 'HOME': '/home/cltbld', 16:16:52 INFO - 'LANG': 'en_US.UTF-8', 16:16:52 INFO - 'LANGUAGE': 'en_US:en', 16:16:52 INFO - 'LOGNAME': 'cltbld', 16:16:52 INFO - 'MAIL': '/var/mail/cltbld', 16:16:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:16:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:16:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:16:52 INFO - 'MOZ_NO_REMOTE': '1', 16:16:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:16:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:16:52 INFO - 'NO_EM_RESTART': '1', 16:16:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:16:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:16:52 INFO - 'PWD': '/builds/slave/test', 16:16:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:16:52 INFO - 'SHELL': '/bin/bash', 16:16:52 INFO - 'SHLVL': '1', 16:16:52 INFO - 'TERM': 'linux', 16:16:52 INFO - 'TMOUT': '86400', 16:16:52 INFO - 'USER': 'cltbld', 16:16:52 INFO - 'XDG_SESSION_COOKIE': 'e0e61f6f900f4dc42e3e8956000001ad-1478387628.380639-2077778747', 16:16:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:16:52 INFO - '_': '/tools/buildbot/bin/python'} 16:16:52 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Gba5JSWXQxO2nh2P28t9aw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-067', '--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 16:16:53 INFO - mozversion application_buildid: 20161105060620 16:16:53 INFO - mozversion application_changeset: 070cf059bb70aa991d50b4d266987adf26685915 16:16:53 INFO - mozversion application_display_name: FirefoxDeveloperEdition 16:16:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:16:53 INFO - mozversion application_name: Firefox 16:16:53 INFO - mozversion application_remotingname: firefox-dev 16:16:53 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 16:16:53 INFO - mozversion application_vendor: Mozilla 16:16:53 INFO - mozversion application_version: 51.0a2 16:16:53 INFO - mozversion platform_buildid: 20161105060620 16:16:53 INFO - mozversion platform_changeset: 070cf059bb70aa991d50b4d266987adf26685915 16:16:53 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 16:16:53 INFO - mozversion platform_version: 51.0a2 16:16:53 INFO - using testdate: 1478387813 16:16:53 INFO - actual date: 1478387813 16:16:53 INFO - starting webserver on 'localhost:42735' 16:16:53 INFO - SUITE-START | Running 2 tests 16:16:53 INFO - TEST-START | dromaeo_css 16:16:53 INFO - operating with platform_type : linux_ 16:16:53 INFO - Initialising browser for dromaeo_css test... 16:16:53 INFO - TEST-INFO | started process 18047 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCtLhUS/profile http://localhost:42735/getInfo.html) 16:16:56 INFO - PROCESS | 18047 | __metrics Screen width/height:1600/1200 16:16:56 INFO - PROCESS | 18047 | colorDepth:24 16:16:56 INFO - PROCESS | 18047 | Browser inner width/height: 1024/697 16:16:56 INFO - PROCESS | 18047 | __metrics 16:16:57 INFO - PROCESS | 18047 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:16:57 INFO - PROCESS | 18047 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:16:57 INFO - PROCESS | 18047 | console.error: PushService: 16:16:57 INFO - PROCESS | 18047 | stateChangeProcessEnqueue: Error transitioning state 16:16:57 INFO - PROCESS | 18047 | UnknownError 16:16:58 INFO - TEST-INFO | 18047: exit 0 16:16:58 INFO - Browser initialized. 16:16:58 INFO - Running cycle 1/1 for dromaeo_css test... 16:16:58 INFO - TEST-INFO | started process 18139 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpCtLhUS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:17:10 INFO - PROCESS | 18139 | RSS: Main: 145289216 16:17:10 INFO - PROCESS | 18139 | 16:18:01 INFO - PROCESS | 18139 | 1478387881219 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] 16:20:09 INFO - PROCESS | 18139 | Cycle 1(1): loaded http://localhost:42735/tests/dromaeo/cssquery-dojo.html (next: http://localhost:42735/tests/dromaeo/cssquery-ext.html) 16:20:11 INFO - PROCESS | 18139 | RSS: Main: 296210432 16:20:11 INFO - PROCESS | 18139 | 16:23:13 INFO - PROCESS | 18139 | Cycle 1(1): loaded http://localhost:42735/tests/dromaeo/cssquery-ext.html (next: http://localhost:42735/tests/dromaeo/cssquery-jquery.html) 16:23:14 INFO - PROCESS | 18139 | RSS: Main: 231907328 16:23:14 INFO - PROCESS | 18139 | 16:26:16 INFO - PROCESS | 18139 | Cycle 1(1): loaded http://localhost:42735/tests/dromaeo/cssquery-jquery.html (next: http://localhost:42735/tests/dromaeo/cssquery-mootools.html) 16:26:16 INFO - PROCESS | 18139 | RSS: Main: 174010368 16:26:16 INFO - PROCESS | 18139 | 16:29:18 INFO - PROCESS | 18139 | Cycle 1(1): loaded http://localhost:42735/tests/dromaeo/cssquery-mootools.html (next: http://localhost:42735/tests/dromaeo/cssquery-prototype.html) 16:29:19 INFO - PROCESS | 18139 | RSS: Main: 202022912 16:29:19 INFO - PROCESS | 18139 | 16:32:21 INFO - PROCESS | 18139 | Cycle 1(1): loaded http://localhost:42735/tests/dromaeo/cssquery-prototype.html (next: http://localhost:42735/tests/dromaeo/cssquery-yui.html) 16:32:22 INFO - PROCESS | 18139 | RSS: Main: 178372608 16:32:22 INFO - PROCESS | 18139 | 16:35:25 INFO - PROCESS | 18139 | Cycle 1(1): loaded http://localhost:42735/tests/dromaeo/cssquery-yui.html (next: http://localhost:42735/tests/dromaeo/cssquery-dojo.html) 16:35:25 INFO - PROCESS | 18139 | RSS: Main: 266706944 16:35:25 INFO - PROCESS | 18139 | 16:35:25 INFO - PROCESS | 18139 | __start_tp_report 16:35:25 INFO - PROCESS | 18139 | _x_x_mozilla_page_load 16:35:25 INFO - PROCESS | 18139 | _x_x_mozilla_page_load_details 16:35:25 INFO - PROCESS | 18139 | |i|pagename|runs| 16:35:25 INFO - PROCESS | 18139 | |0;dojo.html;3108.5803416538765;3165.082126183407;3167.7624178186643;3183.172375182452;3204.198950262434;5562.554995600352;5575.191597218401;5603.3836278009485;5631.042722737135;5678.6308889922175;5491.313585801775;5556.055470570002;5596.832095037145;5607.775688972436;5644.943550564493;5048.318477005594;5058.671186372892;5095.566876815456;5132.178851383779;5137.049645815518;5071.670341427814;5104.515071068261;5152.484751524837;5160.019596276714;5162.303089082963;5032.798688052473;5043.974780126095;5072.4673909239555;5074.3657042869645;5080.288759573663;5070.138076527;5104.208847628605;5106.387233531963;5164.070467315882;5191.896162076738;5003.624728145403;5060.898782024339;5108.259302401131;5117.334746482933;5125.026245013472;5413.323334583177;5448.7548060336985;5468.316460442445;5470.617056806025;5536.086545719952;5873.588848780587;5896.64620122794;5903.173555702205;5931.911021334683;5937.346891841894;5965.671888046156;5967.1347654589845;5972.790952316648;5980.581359304851;5985.820425387245;5422.701751403672;5460.726963651801;5538.529225015838;5596.944030559643;5605.355384130796;6099.268087829489;6124.816255512342;6134.4172303631085;6135.815925522242;6164.260288765377;5883.941160588425;5892.057270836645;5896.941030589725;5899.380565040609;5944.256967879015;5807.216025836458;5871.060630299217;5891.499222566044;5962.940370596239;5968.104784282393;6006.039033754579;6006.219191505076;6006.339302676702;6014.789482368096;6035.486983606443;5974.193483879708;6016.608920420159;6033.0347144457555;6045.637261764308;6058.21243238385;4533.659975501851;4558.635309175258;4559.270516717309;4565.634749220055;4630.120277147331;4103.97948010264;4105.055837157411;4107.63031327176;4117.794110294473;4122.628963393249;5711.857203569861;5722.484976352148;5734.913976290359;5736.827895163152;5740.799072032459;5884.558658100659;5922.555808314393;5939.287285525765;6072.51419886408;6081.726322315418;5637.238972738628;5640.576956728261;5651.971740141355;5682.034054210794;5729.541636669056;5145.4854514549;5153.896922061538;5155.407128180307;5155.845324640267;5220.060389129993;6042.516598672184;6145;6196.8760624787255;6203.100550420254;6204.7828326008375;5916.674582897982;5962.522998160223;6023.969880150571;6044.848878778066;6130.7241174146375;6200.410960958701;6249.125122482947;6253.593516421418;6255.812325630239;6258.249010118815;5941.287045554561;5950.553708471795;5960.821175364746;5961.433663801932;6000.429959153874;5888.793892213752;5915.6450612961635;5931.377205393372;5947.256592925884;6013.458788709037;5862.208601838868;5911.615744976562;5914.704264786829;5929.703514824155;5949.077892926603;5884.7940322088525;5894.322152952465;5895.7052147393315;5898;5901.498372638373;5646.9152962704775;5686.118651609007;5782.942170578409;5787.8553036174435;5835.037218859011;5902.852428689317;5979.372165922516;5986.9401305986385;6005.87988240224;6018.699065046818;5618.044932361427;5835.620684655653;5838.708064596668;5841.532677385714;5849.298084229919 16:35:25 INFO - PROCESS | 18139 | |1;ext.html;583.2359608912325;613.4969325153376;630.4798541203506;643.7457204404263;655.3184687924557;37109.81445092774;45273.28360298384;46161.999999999956;46242.53757462424;50160;46309;46759;46768.2984755229;46791.76604116983;46809;9245.583948722307;9395.436273823558;9399;9400;9400.388974716656;8680.56597170142;8914.687985920495;11067.446627668625;11328.09375249978;11347.092232621371;11464;11563.768724625503;11563.884361156406;11579.247348922336;11581.131415143856;8749.737507874774;11299.096072314236;11358.091352691847;11363.14776391774;11375.033122184583;8650;10787.514561844657;11140.49867755953;11146.721331966684;11544.076473882069;44737.776311118236;45188.77405612951;45212.77393613044;45218.32172517415;45269;3872.3223435898603;4203.936940945889;4483.843065492692;4499.910001799978;4556.339330797049;34096.147596310046;34181.48727769085;34452.48321275183;34575.654243457495;34693.82653086718;4691.62467002639;4715.221988371914;4752.833650822239;4772.5227477252;4778.378810754614;354745;354887;354898;355110;355955;61303.386966129765;61603;61632.69183654053;61643.383566163764;61649.69175154096;40287.59712402897;40902.181956360706;41149.794251029154;42989;43068.353974690406;20109.09509072066;20271.797282027284;20284;20290;20294.79705202929;14282.35729392159;14590.927045364917;15622.453214137451;15690.686186276438;15728.370865165265;18693.719594206097;20053;20811.79188208129;20924.163033478795;21325.36023919285;7419.258074192538;7504.249575042561;7532.811679708051;7633.5419874807685;7643.923560764432;968.6706519783307;1030.907218350352;1108.800415925125;1130.7568872692411;1183.201339096124;31999.280241119486;34062;34416.13959650978;34466;34686.65313346885;1151.5969410706352;1155.381870699172;1217.7564487102616;1241.6647505173553;1277.6678063703505;17791.24565677547;17913.552161195814;18031.819681803277;18063.27746890136;18099.095045247686;554190;556624;560250;562691;562821;61246;61538.0769288461;61780;61854.072188917205;61896.38103618996;1313.4614807928704;1350.473315406992;1357.1449986508437;1381.3369582600408;1442.7619442792031;33208;34904;35164;35349.64650353567;35697.82151089228;52690.47014103684;52718;52938.47061529335;53032.73483632557;53048.204276936645;407391.9630401829;407558;408532;408883;409796;31217.687823122385;31286.687133128376;31549.8422507886;31585.8420707895;31786.205344866565;51030;51296.74351628218;51471;51611;51839.74080129575;41830.79084604557;43350;43591.782041089595;44078;44863.775681121384;41944;42300;42300.78849605855;44159;44189.55810441983;42274.788626056674;42972.71081867549;43158.35262471002;43772.56227437812;43810.78094609507;35497.645023549434;38118.94992630381;38497;38572.6142738569;38663.42004869985;29329.787659455385;29445.705542945154;29513.557296640138;29537.55693664554;29755.702442975293 16:35:25 INFO - PROCESS | 18139 | |2;jquery.html;402.8630265709658;417.07617626956295;417.6804744370555;430.99138017239653;452.47286910748977;15531.301091450883;19296.614067718656;20923.16307347708;21263.6810447843;21447.67828482574;20111.899440502777;20455.181792728312;20754.584908301862;21094.367168984954;21293.36119916405;5955.731992060357;5983.401659834003;6231.376862313755;6239.750409983595;6245.531585131133;6072.635641861502;6112.724927378256;6147.692615369214;6148.754049838001;6151.846203844895;5871.2073870027425;6066.302375226861;6115.755369785204;6125.5712100153005;6126.969365153168;6188.690565471731;6205.720742566596;6220.68896555175;6222.284437289702;6227;5874.823755287348;6041.516678665696;6085.056816193497;6093.573449858467;6093.756249750005;15037.548873533811;15309;19466.416007519656;24645.38386540351;24872.626910596355;2469.5431345201014;2595.2344058502786;2679.0355472030055;2689.798265130123;2836.3192833719963;10966.56133754657;10970.177236707279;11144.442777861155;11271.267367621078;11440.256383335056;1966.3904189701238;2030.98984505078;2031.2788959919164;2060.6187855246676;2115.989420052905;77553.61223193904;87857;88982;90264;90461.54769226178;10397.58409663605;11725.941370293265;11741.88258117425;11994.820077698841;12033.63899083029;14989.0257133286;15036;15804.841951580567;15815.288312025987;15815.683686326209;8352.707655232038;8602.655893764178;8979;8986.236169925629;9082.591283392263;8771.298296136294;8907.331950103768;8950.373473856898;9168.495732734631;9175.908240917639;4834.274858771142;4933.940842397119;4977.32806071183;5021.698698078127;5055.469175736568;2037.3582321568658;2062.6390381683145;2109.873407595549;2111.7571479279773;2141;952.8142012307673;1024.6977141743203;1048.6906362623045;1053.7471006958274;1074.0742585848736;20232.69650955237;20643.380698579065;20812.89593552053;21252.78747212539;21356.679649805264;1012.6961911426542;1018.2973748113777;1019.454591793387;1080.8756992945912;1113.7995160871121;11148.888511114947;11710.168094512712;11745.823812642817;11787.705307367212;11835.88164118365;498143;499369;503328;505758;507603;62400.375996239454;90629.54685226663;91476.54261728782;97732.51133744286;99527.50236248918;1040.70339953113;1070.4273213830718;1073.6564299424265;1095.3482677806692;1095.7315457712912;18614.255429782657;19298;19369.51576210606;19374.70937935904;19430.708539371637;31364.843175783975;43128.35307470453;43671.34492982544;44788.55211447974;45124.0975180488;49145.75427122841;55290.44709552853;60541;61487.69256153869;61531.38468615257;7833.76498705017;8406.444807710594;8874.60064297121;8914.465132092095;8939.150780675825;9005.14451127142;9588.856167157637;13155.802662959872;13378.197308161542;13589.388477518338;11569.88430115688;11572.36352000648;11696.415179240834;11975.580854670045;12315.322657253937;11085.334879907232;11303.434828258718;11701.76596468083;11785.469653865768;12072;11353.829692554453;11640.825387619023;11663.475143618387;11889.28664280146;12015.219010764273;8461.365397595328;8548.145185481402;8642.790009398816;8669.783255418664;8716.43343182691;10579.62971296001;12108.334041627795;12296.75406491883;12322.199057061262;12685.8097128545 16:35:25 INFO - PROCESS | 18139 | |3;mootools.html;1544.374528316032;1573.6695293988262;1718.86249100072;1836.137015602668;1851.3520267906235;9095.909040909597;9769.609215631375;9773.26700497462;9773.413595184276;9798.510074496282;8481.745547633565;8646.394752367336;9166.86249706255;9419.340646154771;9626.229901607872;2565.640810286561;2600.2459286806898;2600.3369140869054;2603.973960260402;2604.4791041791623;2539.746025397464;2601.128621911662;2601.128621911662;2602.4795040991785;2604.9479010419873;2524.0913271222344;2533.1260715053277;2565.6023316385895;2568.511982723286;2568.845869247851;2530.936726581841;2535.6450096986437;2544.440223150904;2554.2081954594137;2560.8719564021726;2442.0476014354417;2465.4251335563504;2468.7654672806057;2470.4441500662388;2491.7134529529153;9503.28725345602;9619.13427791495;9619.663311784125;9625.278104142217;9630.807383852283;5612.045952188138;5736.1682556029145;5741.052726300156;5742.109972954198;5742.368339482654;3511.877084302063;3535.4696795480627;3596.226811235597;3672.7429079964413;3689;1181.5155786127732;1226.4480983557346;1247.3638444393334;1259.6347059352868;1279.6544932868073;12692.048096392808;12692.365381730877;12730.299833509152;12731.045171612073;12739.171953822972;4761.000379840475;5091.9236211456855;5104.821331253388;5131.948680513147;5158.45836187195;10701.197410194267;10717.196210284259;10729.409882456384;10756.56973721058;10762;5708.9714551427805;5743.224664670216;5751;5761;5769.826905192851;4442.977785111054;5283.867903302448;5324.760385782649;5342.813001544927;5493.423190565013;4254.744715317091;4317.978410107929;4391.6486681065435;4416.845410410684;4436.556344365602;1411;1412.1809350576846;1424.8717615414662;1439.5177615498926;1453.2806260900923;1011.6408674920444;1013.5894962540183;1034.2398337222135;1034.2398337222135;1035.7410647338165;5957.344692083867;6098.69506524672;6173.413525715139;6229;6275.121482992385;954.9618015279311;972.0376826941418;981.2052237687353;990.2028866762325;1039.6361273554196;4144.70987030905;4272.6795490338345;4294.76378799169;4308.9353659695125;4366.585174408426;11844.466998985066;12221.144519883523;12292.569760058355;12309.69225769345;12330.753384932252;12233.571824986082;12240.387980600934;12243.75512489763;12247.693807654701;12253.571125010581;8466.788330291669;8753.562321884008;8760;8959.955200223958;8960.910390896008;5927.644341339534;6022.096685497036;6100.420460056288;6125.509959203164;6163.414475624809;10134.493275336352;10329.690109296733;10372.585096596353;10441.477926103817;10443.060124588823;10466.372017678963;10606;10630.25588208818;10683.786324273626;10914.454277286262;7694.9615251925625;7747.651355688895;8358.916410835815;8368.958155209186;8552.871706924278;9348.672796452092;9377.953110234404;9417.246620270498;9437.433753974783;9442.905570944202;8257.545834979133;8443.704470343508;8446.493210407396;8462;8612.397132200686;8219.835603287782;8233.135520770473;8254.958725206336;8272.793180170544;8281.3788965829;7398.556086634819;7482.251774822692;7567.31894129531;7645.732399366218;7785.688572457038;5152.793888244429;5318.707471088973;5322.973385133205;5335.439778823323;5346.304980352529;5536.0865457200725;5540.944590554204;5592.3848376677715;5732.197492350991;5794.275715535558 16:35:25 INFO - PROCESS | 18139 | |4;prototype.html;357.33000623830304;361.0774471226017;365.9871904483344;368.9594144644089;373.2497679663875;5265.86835329117;5482.671039737608;5570.941521110991;5611.3546942101675;5624.550035997126;5337.653052551582;5436.891262174754;5472.425395333483;5474.534664553517;5478.45215478452;4349.934750978738;4351.195028919644;4362.476502819666;4371.978140109295;4375.7374557526655;4211.368294755795;4316.683411559488;4352.173087113454;4367.628751556122;4369.541198174194;4285.721428107178;4297.162053399588;4302.698811083225;4302.935455968163;4308.267594508925;4294.904799276164;4302.720323178999;4318.352247162935;4333.653307735405;4337.457817772778;3644.6355364463607;3885.7668539887695;3885.931368873554;3895.18201177753;3912.5500567434824;5348.582625604218;5369.221462887898;5369.570434365281;5443.2107344435235;5467.480589344006;4977.303177555145;4983.451820299764;4987.451380348159;4994.9250761238245;5012.498750124995;5463.738284138379;5724.742386592613;5806.912896306559;5811.6803575803315;5846.649201047951;4204.159168166379;4205.3481710334945;4209.389638502431;4213.536510983789;4225.281702110648;6021.200141934021;6046;6046.007226422948;6049.6975151242705;6050.939490605126;4236.364545318227;4268.679849011337;4302.118065796567;4318.503372112247;4369.890752731144;4321.980558081015;4341.15347507233;4342.5006124296115;4345.956540434619;4353.477582690034;4932.235503496964;4946.332245146931;4948.802047918115;4948.826791062295;4960.553550180501;5742.425757424224;5753.971230143822;5767.8558036049435;5843.678597677169;5871.853203669857;1488.218685190262;1501.902376345534;1511.7883496310526;1513.52324017934;1518.6886688228888;941.2206238919923;958.1711819276276;970.7330484116924;975.2588033094758;979.1579241852002;4966.652334336562;4993.750312484434;5005.274235235943;5028.924566131511;5071.340725705634;5249.895002099936;5256.763445644955;5269.341332333458;5280.049591073644;5288.471152884681;5017.89964200714;5036.949630503721;5060.9119039406705;5232.293640358503;5668.773249070074;3141.434541782455;3155.4793459078996;3168.683131686859;3170.8743396094515;3175.4442972480188;6014.7047975017285;6119.2350956130485;6132.662703551258;6151.907721384183;6161.507079433634;5980.611260268069;5996.850078748066;6046.697665116727;6047.516198704093;6049.909251361233;6120.908186377208;6142.631442113398;6145.692715364214;6149.169862068653;6180.598261112923;5608.186812912023;5930.229070220844;5942.910856337245;5980.401959803985;5986.401359864153;5906.350301466921;5908.050343386586;5908.911366329423;5911.261092363455;5912.290525137011;5836.445537673914;6150.907736383869;6156.569040167145;6160.168377269011;6170.5372097092;4250.086231460251;4302.397664326934;4330.198913201068;4390.956090439055;4415.2935530315;6068.423499767515;6087.178230938766;6089.48239399656;6093.390660934048;6099.512039036777;5708.514776244066;5768.2501274834;5810.501484361765;5945.672987985702;5949.810037992333;5782.380933338672;5795.942040579709;5804.2744656917885;5815.651060936358;5818.389069147848;5787.334456537552;5824.359775140537;5825.446582574648;5840.386759390203;5841.853953651193;5575.581831362745;5842.305731337864;5856.385079566584;5861.1794348792055;5863.384344643921;5705.144228365778;5718.628289161191;5727.8496826744295;5729.541636668973;5733.853229354229 16:35:25 INFO - PROCESS | 18139 | |5;yui.html;843.5782108945527;848.7241646464901;871.1506281375658;886.8536691445912;912.2337236721153;15503.534893953185;15902.761458578116;15995.920020399883;16044;16122;16077.11575863327;16220.594485137848;16449.91775041126;16452.506424807245;16653;891.0821853490899;902.823949329881;904.6200595749768;905.4612505559181;905.9954700226523;725.0646665801111;732.088549755555;867.119873328572;883.3728053082319;893.4371346052011;891.2468963725673;897.9865302020474;898.0121865947439;898.3890954151175;902.5938327752505;894.8657701344786;895.4492986813128;896.2650626486251;896.3814967672317;900.5272232078146;549.9037668408052;552.864548185693;560.7392562458435;562.3617194484249;666.7666316789133;16140.515784526482;16617.750733739005;16625.08562029088;16665.500034998968;16668.916655416768;15035;15463.690726185414;15599.220038998004;16012.119333436656;16109.355625774955;13232;13523.120997135155;13601.047926645138;13704.45182192711;14325.49860754879;4680.321353403771;4729.243321068647;4735.360726301974;4784.497627749106;4930.630202734809;84949.15050849474;85740.14259857385;86106.56946715289;86655.13344866534;86758.56620716857;12268.631941041784;12358;12526.624201273977;12657.93671031639;12868.356582170854;24030.27909162728;24032;24052.15817446416;24057.398565036015;24066.63900041501;8878.911210887938;8994.910050899538;9128.132827381389;9184.494852783027;9381.29640276982;5239.161734122598;5257.421683614799;5341.252224688546;5361.035013697572;5418.051840927822;3587.318409502186;3720.479132921393;3721.813909304524;3725.8695945642316;3727.105494681311;621.8806148931905;623.3205805671839;623.9968800155971;625.0592857749059;630.7224821078711;600.573592749153;647.1554621219354;754.7207533212746;781.4256521456725;792.1405275276389;10209.897901021044;10301.484925753832;10649.361038337725;10667.786644267071;10715.83926241107;663.9302873198342;697.2609034423426;702.6346299924011;739.8224426137796;747.5327920049968;5017.347744793226;5057.216131499624;5061.974690126526;5082;5123.897522049613;124058.37970810088;126196;129400;129688;135643.32178339045;88131.55934220288;90279;111238;112893.43553282181;114033.4298328503;26937.191884243504;26997.32506687309;38948.415773763416;66097;86279.56860215784;10468.738281543023;10927.147365429684;11059.281146725438;11156.44217789091;11366.829497557708;47105.293420598035;53906.73046634742;54792.72603636956;54805;55615.16577251425;50774.49225507698;69212.96180557397;71957;75425.6228718853;77021.61489192519;14329.641758955693;14517.419303228175;14565.126092434488;14810.407583696962;14981.325840336993;16290.18549072518;16291.511254661906;16584.66830663404;16628.852803344525;17148.914255428645;15029.774553381927;15325.923370383523;15609.765853512434;15762.448314308944;15906;11328.37693926809;13942.860571394562;14183.54593608052;15175;15917.602059948595;14658;15027.474038408604;15037.398504059714;15227.238638068275;15257;12212.633620991384;12513.499460021498;12567.87432125667;13237.735245294847;13422.597322080353;12617.179883307555;12665.303408312631;12850.871491284968;12955.157914735511;12983.221006739626 16:35:25 INFO - PROCESS | 18139 | __end_tp_report 16:35:25 INFO - PROCESS | 18139 | __start_cc_report 16:35:25 INFO - PROCESS | 18139 | _x_x_mozilla_cycle_collect,1296 16:35:25 INFO - PROCESS | 18139 | __end_cc_report 16:35:25 INFO - PROCESS | 18139 | __startTimestamp1478388925300__endTimestamp 16:35:25 INFO - PROCESS | 18139 | 16:35:25 INFO - PROCESS | 18139 | ------- Summary: start ------- 16:35:25 INFO - PROCESS | 18139 | Number of tests: 6 16:35:25 INFO - PROCESS | 18139 | 16:35:25 INFO - PROCESS | 18139 | [#0] dojo.html Cycles:165 Average:5562.94 Median:5835.62 stddev:640.20 (11.0%) stddev-sans-first:612.53 16:35:25 INFO - PROCESS | 18139 | Values: 3108.6 3165.1 3167.8 3183.2 3204.2 5562.6 5575.2 5603.4 5631.0 5678.6 5491.3 5556.1 5596.8 5607.8 5644.9 5048.3 5058.7 5095.6 5132.2 5137.0 5071.7 5104.5 5152.5 5160.0 5162.3 5032.8 5044.0 5072.5 5074.4 5080.3 5070.1 5104.2 5106.4 5164.1 5191.9 5003.6 5060.9 5108.3 5117.3 5125.0 5413.3 5448.8 5468.3 5470.6 5536.1 5873.6 5896.6 5903.2 5931.9 5937.3 5965.7 5967.1 5972.8 5980.6 5985.8 5422.7 5460.7 5538.5 5596.9 5605.4 6099.3 6124.8 6134.4 6135.8 6164.3 5883.9 5892.1 5896.9 5899.4 5944.3 5807.2 5871.1 5891.5 5962.9 5968.1 6006.0 6006.2 6006.3 6014.8 6035.5 5974.2 6016.6 6033.0 6045.6 6058.2 4533.7 4558.6 4559.3 4565.6 4630.1 4104.0 4105.1 4107.6 4117.8 4122.6 5711.9 5722.5 5734.9 5736.8 5740.8 5884.6 5922.6 5939.3 6072.5 6081.7 5637.2 5640.6 5652.0 5682.0 5729.5 5145.5 5153.9 5155.4 5155.8 5220.1 6042.5 6145.0 6196.9 6203.1 6204.8 5916.7 5962.5 6024.0 6044.8 6130.7 6200.4 6249.1 6253.6 6255.8 6258.2 5941.3 5950.6 5960.8 5961.4 6000.4 5888.8 5915.6 5931.4 5947.3 6013.5 5862.2 5911.6 5914.7 5929.7 5949.1 5884.8 5894.3 5895.7 5898.0 5901.5 5646.9 5686.1 5782.9 5787.9 5835.0 5902.9 5979.4 5986.9 6005.9 6018.7 5618.0 5835.6 5838.7 5841.5 5849.3 16:35:25 INFO - PROCESS | 18139 | 16:35:25 INFO - PROCESS | 18139 | [#1] ext.html Cycles:180 Average:61334.37 Median:408707.50 stddev:118873.07 (29.1%) stddev-sans-first:119119.03 16:35:25 INFO - PROCESS | 18139 | Values: 583.2 613.5 630.5 643.7 655.3 37109.8 45273.3 46162.0 46242.5 50160.0 46309.0 46759.0 46768.3 46791.8 46809.0 9245.6 9395.4 9399.0 9400.0 9400.4 8680.6 8914.7 11067.4 11328.1 11347.1 11464.0 11563.8 11563.9 11579.2 11581.1 8749.7 11299.1 11358.1 11363.1 11375.0 8650.0 10787.5 11140.5 11146.7 11544.1 44737.8 45188.8 45212.8 45218.3 45269.0 3872.3 4203.9 4483.8 4499.9 4556.3 34096.1 34181.5 34452.5 34575.7 34693.8 4691.6 4715.2 4752.8 4772.5 4778.4 354745.0 354887.0 354898.0 355110.0 355955.0 61303.4 61603.0 61632.7 61643.4 61649.7 40287.6 40902.2 41149.8 42989.0 43068.4 20109.1 20271.8 20284.0 20290.0 20294.8 14282.4 14590.9 15622.5 15690.7 15728.4 18693.7 20053.0 20811.8 20924.2 21325.4 7419.3 7504.2 7532.8 7633.5 7643.9 968.7 1030.9 1108.8 1130.8 1183.2 31999.3 34062.0 34416.1 34466.0 34686.7 1151.6 1155.4 1217.8 1241.7 1277.7 17791.2 17913.6 18031.8 18063.3 18099.1 554190.0 556624.0 560250.0 562691.0 562821.0 61246.0 61538.1 61780.0 61854.1 61896.4 1313.5 1350.5 1357.1 1381.3 1442.8 33208.0 34904.0 35164.0 35349.6 35697.8 52690.5 52718.0 52938.5 53032.7 53048.2 407392.0 407558.0 408532.0 408883.0 409796.0 31217.7 31286.7 31549.8 31585.8 31786.2 51030.0 51296.7 51471.0 51611.0 51839.7 41830.8 43350.0 43591.8 44078.0 44863.8 41944.0 42300.0 42300.8 44159.0 44189.6 42274.8 42972.7 43158.4 43772.6 43810.8 35497.6 38118.9 38497.0 38572.6 38663.4 29329.8 29445.7 29513.6 29537.6 29755.7 16:35:25 INFO - PROCESS | 18139 | 16:35:25 INFO - PROCESS | 18139 | [#2] jquery.html Cycles:180 Average:29651.46 Median:13557.46 stddev:82861.44 (611.2%) stddev-sans-first:83064.79 16:35:25 INFO - PROCESS | 18139 | Values: 402.9 417.1 417.7 431.0 452.5 15531.3 19296.6 20923.2 21263.7 21447.7 20111.9 20455.2 20754.6 21094.4 21293.4 5955.7 5983.4 6231.4 6239.8 6245.5 6072.6 6112.7 6147.7 6148.8 6151.8 5871.2 6066.3 6115.8 6125.6 6127.0 6188.7 6205.7 6220.7 6222.3 6227.0 5874.8 6041.5 6085.1 6093.6 6093.8 15037.5 15309.0 19466.4 24645.4 24872.6 2469.5 2595.2 2679.0 2689.8 2836.3 10966.6 10970.2 11144.4 11271.3 11440.3 1966.4 2031.0 2031.3 2060.6 2116.0 77553.6 87857.0 88982.0 90264.0 90461.5 10397.6 11725.9 11741.9 11994.8 12033.6 14989.0 15036.0 15804.8 15815.3 15815.7 8352.7 8602.7 8979.0 8986.2 9082.6 8771.3 8907.3 8950.4 9168.5 9175.9 4834.3 4933.9 4977.3 5021.7 5055.5 2037.4 2062.6 2109.9 2111.8 2141.0 952.8 1024.7 1048.7 1053.7 1074.1 20232.7 20643.4 20812.9 21252.8 21356.7 1012.7 1018.3 1019.5 1080.9 1113.8 11148.9 11710.2 11745.8 11787.7 11835.9 498143.0 499369.0 503328.0 505758.0 507603.0 62400.4 90629.5 91476.5 97732.5 99527.5 1040.7 1070.4 1073.7 1095.3 1095.7 18614.3 19298.0 19369.5 19374.7 19430.7 31364.8 43128.4 43671.3 44788.6 45124.1 49145.8 55290.4 60541.0 61487.7 61531.4 7833.8 8406.4 8874.6 8914.5 8939.2 9005.1 9588.9 13155.8 13378.2 13589.4 11569.9 11572.4 11696.4 11975.6 12315.3 11085.3 11303.4 11701.8 11785.5 12072.0 11353.8 11640.8 11663.5 11889.3 12015.2 8461.4 8548.1 8642.8 8669.8 8716.4 10579.6 12108.3 12296.8 12322.2 12685.8 16:35:25 INFO - PROCESS | 18139 | 16:35:25 INFO - PROCESS | 18139 | [#3] mootools.html Cycles:180 Average:6222.94 Median:4439.77 stddev:3516.85 (79.2%) stddev-sans-first:3509.14 16:35:25 INFO - PROCESS | 18139 | Values: 1544.4 1573.7 1718.9 1836.1 1851.4 9095.9 9769.6 9773.3 9773.4 9798.5 8481.7 8646.4 9166.9 9419.3 9626.2 2565.6 2600.2 2600.3 2604.0 2604.5 2539.7 2601.1 2601.1 2602.5 2604.9 2524.1 2533.1 2565.6 2568.5 2568.8 2530.9 2535.6 2544.4 2554.2 2560.9 2442.0 2465.4 2468.8 2470.4 2491.7 9503.3 9619.1 9619.7 9625.3 9630.8 5612.0 5736.2 5741.1 5742.1 5742.4 3511.9 3535.5 3596.2 3672.7 3689.0 1181.5 1226.4 1247.4 1259.6 1279.7 12692.0 12692.4 12730.3 12731.0 12739.2 4761.0 5091.9 5104.8 5131.9 5158.5 10701.2 10717.2 10729.4 10756.6 10762.0 5709.0 5743.2 5751.0 5761.0 5769.8 4443.0 5283.9 5324.8 5342.8 5493.4 4254.7 4318.0 4391.6 4416.8 4436.6 1411.0 1412.2 1424.9 1439.5 1453.3 1011.6 1013.6 1034.2 1034.2 1035.7 5957.3 6098.7 6173.4 6229.0 6275.1 955.0 972.0 981.2 990.2 1039.6 4144.7 4272.7 4294.8 4308.9 4366.6 11844.5 12221.1 12292.6 12309.7 12330.8 12233.6 12240.4 12243.8 12247.7 12253.6 8466.8 8753.6 8760.0 8960.0 8960.9 5927.6 6022.1 6100.4 6125.5 6163.4 10134.5 10329.7 10372.6 10441.5 10443.1 10466.4 10606.0 10630.3 10683.8 10914.5 7695.0 7747.7 8358.9 8369.0 8552.9 9348.7 9378.0 9417.2 9437.4 9442.9 8257.5 8443.7 8446.5 8462.0 8612.4 8219.8 8233.1 8255.0 8272.8 8281.4 7398.6 7482.3 7567.3 7645.7 7785.7 5152.8 5318.7 5323.0 5335.4 5346.3 5536.1 5540.9 5592.4 5732.2 5794.3 16:35:25 INFO - PROCESS | 18139 | 16:35:25 INFO - PROCESS | 18139 | [#4] prototype.html Cycles:180 Average:4859.35 Median:5453.47 stddev:1415.65 (26.0%) stddev-sans-first:1378.70 16:35:25 INFO - PROCESS | 18139 | Values: 357.3 361.1 366.0 369.0 373.2 5265.9 5482.7 5570.9 5611.4 5624.6 5337.7 5436.9 5472.4 5474.5 5478.5 4349.9 4351.2 4362.5 4372.0 4375.7 4211.4 4316.7 4352.2 4367.6 4369.5 4285.7 4297.2 4302.7 4302.9 4308.3 4294.9 4302.7 4318.4 4333.7 4337.5 3644.6 3885.8 3885.9 3895.2 3912.6 5348.6 5369.2 5369.6 5443.2 5467.5 4977.3 4983.5 4987.5 4994.9 5012.5 5463.7 5724.7 5806.9 5811.7 5846.6 4204.2 4205.3 4209.4 4213.5 4225.3 6021.2 6046.0 6046.0 6049.7 6050.9 4236.4 4268.7 4302.1 4318.5 4369.9 4322.0 4341.2 4342.5 4346.0 4353.5 4932.2 4946.3 4948.8 4948.8 4960.6 5742.4 5754.0 5767.9 5843.7 5871.9 1488.2 1501.9 1511.8 1513.5 1518.7 941.2 958.2 970.7 975.3 979.2 4966.7 4993.8 5005.3 5028.9 5071.3 5249.9 5256.8 5269.3 5280.0 5288.5 5017.9 5036.9 5060.9 5232.3 5668.8 3141.4 3155.5 3168.7 3170.9 3175.4 6014.7 6119.2 6132.7 6151.9 6161.5 5980.6 5996.9 6046.7 6047.5 6049.9 6120.9 6142.6 6145.7 6149.2 6180.6 5608.2 5930.2 5942.9 5980.4 5986.4 5906.4 5908.1 5908.9 5911.3 5912.3 5836.4 6150.9 6156.6 6160.2 6170.5 4250.1 4302.4 4330.2 4391.0 4415.3 6068.4 6087.2 6089.5 6093.4 6099.5 5708.5 5768.3 5810.5 5945.7 5949.8 5782.4 5795.9 5804.3 5815.7 5818.4 5787.3 5824.4 5825.4 5840.4 5841.9 5575.6 5842.3 5856.4 5861.2 5863.4 5705.1 5718.6 5727.8 5729.5 5733.9 16:35:25 INFO - PROCESS | 18139 | 16:35:25 INFO - PROCESS | 18139 | [#5] yui.html Cycles:180 Average:21174.19 Median:3653.90 stddev:30462.90 (833.7%) stddev-sans-first:30510.11 16:35:25 INFO - PROCESS | 18139 | Values: 843.6 848.7 871.2 886.9 912.2 15503.5 15902.8 15995.9 16044.0 16122.0 16077.1 16220.6 16449.9 16452.5 16653.0 891.1 902.8 904.6 905.5 906.0 725.1 732.1 867.1 883.4 893.4 891.2 898.0 898.0 898.4 902.6 894.9 895.4 896.3 896.4 900.5 549.9 552.9 560.7 562.4 666.8 16140.5 16617.8 16625.1 16665.5 16668.9 15035.0 15463.7 15599.2 16012.1 16109.4 13232.0 13523.1 13601.0 13704.5 14325.5 4680.3 4729.2 4735.4 4784.5 4930.6 84949.2 85740.1 86106.6 86655.1 86758.6 12268.6 12358.0 12526.6 12657.9 12868.4 24030.3 24032.0 24052.2 24057.4 24066.6 8878.9 8994.9 9128.1 9184.5 9381.3 5239.2 5257.4 5341.3 5361.0 5418.1 3587.3 3720.5 3721.8 3725.9 3727.1 621.9 623.3 624.0 625.1 630.7 600.6 647.2 754.7 781.4 792.1 10209.9 10301.5 10649.4 10667.8 10715.8 663.9 697.3 702.6 739.8 747.5 5017.3 5057.2 5062.0 5082.0 5123.9 124058.4 126196.0 129400.0 129688.0 135643.3 88131.6 90279.0 111238.0 112893.4 114033.4 26937.2 26997.3 38948.4 66097.0 86279.6 10468.7 10927.1 11059.3 11156.4 11366.8 47105.3 53906.7 54792.7 54805.0 55615.2 50774.5 69213.0 71957.0 75425.6 77021.6 14329.6 14517.4 14565.1 14810.4 14981.3 16290.2 16291.5 16584.7 16628.9 17148.9 15029.8 15325.9 15609.8 15762.4 15906.0 11328.4 13942.9 14183.5 15175.0 15917.6 14658.0 15027.5 15037.4 15227.2 15257.0 12212.6 12513.5 12567.9 13237.7 13422.6 12617.2 12665.3 12850.9 12955.2 12983.2 16:35:25 INFO - PROCESS | 18139 | -------- Summary: end -------- 16:35:25 INFO - PROCESS | 18139 | 16:35:26 INFO - TEST-INFO | 18139: exit 0 16:35:26 INFO - TEST-OK | dromaeo_css | took 1112852ms 16:35:26 INFO - TEST-START | kraken 16:35:26 INFO - operating with platform_type : linux_ 16:35:26 INFO - Initialising browser for kraken test... 16:35:26 INFO - TEST-INFO | started process 18461 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4RMOMz/profile http://localhost:42735/getInfo.html) 16:35:29 INFO - PROCESS | 18461 | __metrics Screen width/height:1600/1200 16:35:29 INFO - PROCESS | 18461 | colorDepth:24 16:35:29 INFO - PROCESS | 18461 | Browser inner width/height: 1024/697 16:35:29 INFO - PROCESS | 18461 | __metrics 16:35:30 INFO - PROCESS | 18461 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:35:30 INFO - PROCESS | 18461 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:35:30 INFO - PROCESS | 18461 | console.error: PushService: 16:35:30 INFO - PROCESS | 18461 | stateChangeProcessEnqueue: Error transitioning state 16:35:30 INFO - PROCESS | 18461 | UnknownError 16:35:31 INFO - TEST-INFO | 18461: exit 0 16:35:31 INFO - Browser initialized. 16:35:31 INFO - Running cycle 1/1 for kraken test... 16:35:31 INFO - TEST-INFO | started process 18551 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp4RMOMz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:35:42 INFO - PROCESS | 18551 | RSS: Main: 145362944 16:35:42 INFO - PROCESS | 18551 | 16:35:45 INFO - PROCESS | 18551 | Cycle 1(1): loaded http://localhost:42735/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:42735/tests/kraken/driver.html?testName=audio-beat-detection) 16:35:46 INFO - PROCESS | 18551 | RSS: Main: 187326464 16:35:46 INFO - PROCESS | 18551 | 16:35:48 INFO - PROCESS | 18551 | Cycle 1(1): loaded http://localhost:42735/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:42735/tests/kraken/driver.html?testName=audio-dft) 16:35:49 INFO - PROCESS | 18551 | RSS: Main: 176619520 16:35:49 INFO - PROCESS | 18551 | 16:35:52 INFO - PROCESS | 18551 | Cycle 1(1): loaded http://localhost:42735/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:42735/tests/kraken/driver.html?testName=audio-fft) 16:35:52 INFO - PROCESS | 18551 | RSS: Main: 267243520 16:35:52 INFO - PROCESS | 18551 | 16:35:54 INFO - PROCESS | 18551 | Cycle 1(1): loaded http://localhost:42735/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:42735/tests/kraken/driver.html?testName=audio-oscillator) 16:35:55 INFO - PROCESS | 18551 | RSS: Main: 174514176 16:35:55 INFO - PROCESS | 18551 | 16:35:57 INFO - PROCESS | 18551 | Cycle 1(1): loaded http://localhost:42735/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:42735/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:35:58 INFO - PROCESS | 18551 | RSS: Main: 176496640 16:35:58 INFO - PROCESS | 18551 | 16:36:01 INFO - PROCESS | 18551 | Cycle 1(1): loaded http://localhost:42735/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:42735/tests/kraken/driver.html?testName=imaging-darkroom) 16:36:01 INFO - PROCESS | 18551 | RSS: Main: 211787776 16:36:01 INFO - PROCESS | 18551 | 16:36:05 INFO - PROCESS | 18551 | Cycle 1(1): loaded http://localhost:42735/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:42735/tests/kraken/driver.html?testName=imaging-desaturate) 16:36:05 INFO - PROCESS | 18551 | RSS: Main: 210661376 16:36:05 INFO - PROCESS | 18551 | 16:36:08 INFO - PROCESS | 18551 | Cycle 1(1): loaded http://localhost:42735/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:42735/tests/kraken/driver.html?testName=json-parse-financial) 16:36:09 INFO - PROCESS | 18551 | RSS: Main: 210427904 16:36:09 INFO - PROCESS | 18551 | 16:36:10 INFO - PROCESS | 18551 | Cycle 1(1): loaded http://localhost:42735/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:42735/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:36:11 INFO - PROCESS | 18551 | RSS: Main: 171819008 16:36:11 INFO - PROCESS | 18551 | 16:36:12 INFO - PROCESS | 18551 | Cycle 1(1): loaded http://localhost:42735/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:42735/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:36:13 INFO - PROCESS | 18551 | RSS: Main: 178728960 16:36:13 INFO - PROCESS | 18551 | 16:36:15 INFO - PROCESS | 18551 | Cycle 1(1): loaded http://localhost:42735/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:42735/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:36:16 INFO - PROCESS | 18551 | RSS: Main: 188792832 16:36:16 INFO - PROCESS | 18551 | 16:36:18 INFO - PROCESS | 18551 | Cycle 1(1): loaded http://localhost:42735/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:42735/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:36:19 INFO - PROCESS | 18551 | RSS: Main: 198279168 16:36:19 INFO - PROCESS | 18551 | 16:36:21 INFO - PROCESS | 18551 | Cycle 1(1): loaded http://localhost:42735/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:42735/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:36:22 INFO - PROCESS | 18551 | RSS: Main: 194596864 16:36:22 INFO - PROCESS | 18551 | 16:36:24 INFO - PROCESS | 18551 | Cycle 1(1): loaded http://localhost:42735/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:42735/tests/kraken/driver.html?testName=ai-astar) 16:36:24 INFO - PROCESS | 18551 | RSS: Main: 245702656 16:36:24 INFO - PROCESS | 18551 | 16:36:24 INFO - PROCESS | 18551 | __start_tp_report 16:36:24 INFO - PROCESS | 18551 | _x_x_mozilla_page_load 16:36:24 INFO - PROCESS | 18551 | _x_x_mozilla_page_load_details 16:36:24 INFO - PROCESS | 18551 | |i|pagename|runs| 16:36:24 INFO - PROCESS | 18551 | |0;ai-astar;110;111;112;111;112;114;110;111;110;111 16:36:24 INFO - PROCESS | 18551 | |1;audio-beat-detection;147;117;121;160;120;121;119;119;153;122 16:36:24 INFO - PROCESS | 18551 | |2;audio-dft;162;161;161;161;162;161;163;162;165;160 16:36:24 INFO - PROCESS | 18551 | |3;audio-fft;77;75;75;117;76;76;75;76;98;75 16:36:24 INFO - PROCESS | 18551 | |4;audio-oscillator;83;82;123;81;81;81;102;82;81;117 16:36:24 INFO - PROCESS | 18551 | |5;imaging-gaussian-blur;117;116;117;117;117;117;117;118;118;117 16:36:24 INFO - PROCESS | 18551 | |6;imaging-darkroom;146;146;146;146;147;145;145;145;145;145 16:36:24 INFO - PROCESS | 18551 | |7;imaging-desaturate;95;94;95;95;95;94;95;95;94;96 16:36:24 INFO - PROCESS | 18551 | |8;json-parse-financial;51;49;50;49;54;50;49;49;49;49 16:36:24 INFO - PROCESS | 18551 | |9;json-stringify-tinderbox;59;61;59;60;59;59;60;59;60;59 16:36:24 INFO - PROCESS | 18551 | |10;stanford-crypto-aes;78;74;73;73;79;74;74;73;73;74 16:36:24 INFO - PROCESS | 18551 | |11;stanford-crypto-ccm;123;129;132;121;123;152;120;119;155;122 16:36:24 INFO - PROCESS | 18551 | |12;stanford-crypto-pbkdf2;156;157;154;156;155;158;153;156;152;155 16:36:24 INFO - PROCESS | 18551 | |13;stanford-crypto-sha256-iterative;63;62;63;62;63;61;62;65;60;62 16:36:24 INFO - PROCESS | 18551 | __end_tp_report 16:36:24 INFO - PROCESS | 18551 | __start_cc_report 16:36:24 INFO - PROCESS | 18551 | _x_x_mozilla_cycle_collect,1640 16:36:24 INFO - PROCESS | 18551 | __end_cc_report 16:36:24 INFO - PROCESS | 18551 | __startTimestamp1478388984352__endTimestamp 16:36:24 INFO - PROCESS | 18551 | 16:36:24 INFO - PROCESS | 18551 | ------- Summary: start ------- 16:36:24 INFO - PROCESS | 18551 | Number of tests: 14 16:36:24 INFO - PROCESS | 18551 | 16:36:24 INFO - PROCESS | 18551 | [#0] ai-astar Cycles:10 Average:111.20 Median:111.00 stddev:1.23 (1.1%) stddev-sans-first:1.22 16:36:24 INFO - PROCESS | 18551 | Values: 110.0 111.0 112.0 111.0 112.0 114.0 110.0 111.0 110.0 111.0 16:36:24 INFO - PROCESS | 18551 | 16:36:24 INFO - PROCESS | 18551 | [#1] audio-beat-detection Cycles:10 Average:129.90 Median:121.50 stddev:16.52 (13.6%) stddev-sans-first:16.32 16:36:24 INFO - PROCESS | 18551 | Values: 147.0 117.0 121.0 160.0 120.0 121.0 119.0 119.0 153.0 122.0 16:36:24 INFO - PROCESS | 18551 | 16:36:24 INFO - PROCESS | 18551 | [#2] audio-dft Cycles:10 Average:161.80 Median:162.00 stddev:1.40 (0.9%) stddev-sans-first:1.48 16:36:24 INFO - PROCESS | 18551 | Values: 162.0 161.0 161.0 161.0 162.0 161.0 163.0 162.0 165.0 160.0 16:36:24 INFO - PROCESS | 18551 | 16:36:24 INFO - PROCESS | 18551 | [#3] audio-fft Cycles:10 Average:82.00 Median:76.00 stddev:14.18 (18.7%) stddev-sans-first:14.93 16:36:24 INFO - PROCESS | 18551 | Values: 77.0 75.0 75.0 117.0 76.0 76.0 75.0 76.0 98.0 75.0 16:36:24 INFO - PROCESS | 18551 | 16:36:24 INFO - PROCESS | 18551 | [#4] audio-oscillator Cycles:10 Average:91.30 Median:81.00 stddev:16.49 (20.4%) stddev-sans-first:17.21 16:36:24 INFO - PROCESS | 18551 | Values: 83.0 82.0 123.0 81.0 81.0 81.0 102.0 82.0 81.0 117.0 16:36:24 INFO - PROCESS | 18551 | 16:36:24 INFO - PROCESS | 18551 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.57 (0.5%) stddev-sans-first:0.60 16:36:24 INFO - PROCESS | 18551 | Values: 117.0 116.0 117.0 117.0 117.0 117.0 117.0 118.0 118.0 117.0 16:36:24 INFO - PROCESS | 18551 | 16:36:24 INFO - PROCESS | 18551 | [#6] imaging-darkroom Cycles:10 Average:145.60 Median:146.00 stddev:0.70 (0.5%) stddev-sans-first:0.73 16:36:24 INFO - PROCESS | 18551 | Values: 146.0 146.0 146.0 146.0 147.0 145.0 145.0 145.0 145.0 145.0 16:36:24 INFO - PROCESS | 18551 | 16:36:24 INFO - PROCESS | 18551 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 16:36:24 INFO - PROCESS | 18551 | Values: 95.0 94.0 95.0 95.0 95.0 94.0 95.0 95.0 94.0 96.0 16:36:24 INFO - PROCESS | 18551 | 16:36:24 INFO - PROCESS | 18551 | [#8] json-parse-financial Cycles:10 Average:49.90 Median:49.50 stddev:1.60 (3.2%) stddev-sans-first:1.64 16:36:24 INFO - PROCESS | 18551 | Values: 51.0 49.0 50.0 49.0 54.0 50.0 49.0 49.0 49.0 49.0 16:36:24 INFO - PROCESS | 18551 | 16:36:24 INFO - PROCESS | 18551 | [#9] json-stringify-tinderbox Cycles:10 Average:59.50 Median:59.50 stddev:0.71 (1.2%) stddev-sans-first:0.73 16:36:24 INFO - PROCESS | 18551 | Values: 59.0 61.0 59.0 60.0 59.0 59.0 60.0 59.0 60.0 59.0 16:36:24 INFO - PROCESS | 18551 | 16:36:24 INFO - PROCESS | 18551 | [#10] stanford-crypto-aes Cycles:10 Average:74.50 Median:74.00 stddev:2.17 (2.9%) stddev-sans-first:1.90 16:36:24 INFO - PROCESS | 18551 | Values: 78.0 74.0 73.0 73.0 79.0 74.0 74.0 73.0 73.0 74.0 16:36:24 INFO - PROCESS | 18551 | 16:36:24 INFO - PROCESS | 18551 | [#11] stanford-crypto-ccm Cycles:10 Average:129.60 Median:126.00 stddev:13.23 (10.5%) stddev-sans-first:13.82 16:36:24 INFO - PROCESS | 18551 | Values: 123.0 129.0 132.0 121.0 123.0 152.0 120.0 119.0 155.0 122.0 16:36:24 INFO - PROCESS | 18551 | 16:36:24 INFO - PROCESS | 18551 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.20 Median:156.00 stddev:1.81 (1.2%) stddev-sans-first:1.90 16:36:24 INFO - PROCESS | 18551 | Values: 156.0 157.0 154.0 156.0 155.0 158.0 153.0 156.0 152.0 155.0 16:36:24 INFO - PROCESS | 18551 | 16:36:24 INFO - PROCESS | 18551 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.30 Median:62.50 stddev:1.34 (2.1%) stddev-sans-first:1.39 16:36:24 INFO - PROCESS | 18551 | Values: 63.0 62.0 63.0 62.0 63.0 61.0 62.0 65.0 60.0 62.0 16:36:24 INFO - PROCESS | 18551 | -------- Summary: end -------- 16:36:24 INFO - PROCESS | 18551 | 16:36:25 INFO - TEST-INFO | 18551: exit 0 16:36:25 INFO - TEST-OK | kraken | took 59313ms 16:36:25 INFO - SUITE-END | took 1172s 16:36:25 INFO - Completed test suite (00:19:32) 16:36:25 INFO - javascript benchmark 16:36:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3108.5803416538765, 3165.082126183407, 3167.7624178186643, 3183.172375182452, 3204.198950262434, 5562.554995600352, 5575.191597218401, 5603.3836278009485, 5631.042722737135, 5678.6308889922175, 5491.313585801775, 5556.055470570002, 5596.832095037145, 5607.775688972436, 5644.943550564493, 5048.318477005594, 5058.671186372892, 5095.566876815456, 5132.178851383779, 5137.049645815518, 5071.670341427814, 5104.515071068261, 5152.484751524837, 5160.019596276714, 5162.303089082963, 5032.798688052473, 5043.974780126095, 5072.4673909239555, 5074.3657042869645, 5080.288759573663, 5070.138076527, 5104.208847628605, 5106.387233531963, 5164.070467315882, 5191.896162076738, 5003.624728145403, 5060.898782024339, 5108.259302401131, 5117.334746482933, 5125.026245013472, 5413.323334583177, 5448.7548060336985, 5468.316460442445, 5470.617056806025, 5536.086545719952, 5873.588848780587, 5896.64620122794, 5903.173555702205, 5931.911021334683, 5937.346891841894, 5965.671888046156, 5967.1347654589845, 5972.790952316648, 5980.581359304851, 5985.820425387245, 5422.701751403672, 5460.726963651801, 5538.529225015838, 5596.944030559643, 5605.355384130796, 6099.268087829489, 6124.816255512342, 6134.4172303631085, 6135.815925522242, 6164.260288765377, 5883.941160588425, 5892.057270836645, 5896.941030589725, 5899.380565040609, 5944.256967879015, 5807.216025836458, 5871.060630299217, 5891.499222566044, 5962.940370596239, 5968.104784282393, 6006.039033754579, 6006.219191505076, 6006.339302676702, 6014.789482368096, 6035.486983606443, 5974.193483879708, 6016.608920420159, 6033.0347144457555, 6045.637261764308, 6058.21243238385, 4533.659975501851, 4558.635309175258, 4559.270516717309, 4565.634749220055, 4630.120277147331, 4103.97948010264, 4105.055837157411, 4107.63031327176, 4117.794110294473, 4122.628963393249, 5711.857203569861, 5722.484976352148, 5734.913976290359, 5736.827895163152, 5740.799072032459, 5884.558658100659, 5922.555808314393, 5939.287285525765, 6072.51419886408, 6081.726322315418, 5637.238972738628, 5640.576956728261, 5651.971740141355, 5682.034054210794, 5729.541636669056, 5145.4854514549, 5153.896922061538, 5155.407128180307, 5155.845324640267, 5220.060389129993, 6042.516598672184, 6145.0, 6196.8760624787255, 6203.100550420254, 6204.7828326008375, 5916.674582897982, 5962.522998160223, 6023.969880150571, 6044.848878778066, 6130.7241174146375, 6200.410960958701, 6249.125122482947, 6253.593516421418, 6255.812325630239, 6258.249010118815, 5941.287045554561, 5950.553708471795, 5960.821175364746, 5961.433663801932, 6000.429959153874, 5888.793892213752, 5915.6450612961635, 5931.377205393372, 5947.256592925884, 6013.458788709037, 5862.208601838868, 5911.615744976562, 5914.704264786829, 5929.703514824155, 5949.077892926603, 5884.7940322088525, 5894.322152952465, 5895.7052147393315, 5898.0, 5901.498372638373, 5646.9152962704775, 5686.118651609007, 5782.942170578409, 5787.8553036174435, 5835.037218859011, 5902.852428689317, 5979.372165922516, 5986.9401305986385, 6005.87988240224, 6018.699065046818, 5618.044932361427, 5835.620684655653, 5838.708064596668, 5841.532677385714, 5849.298084229919], "value": 5518.600518148425, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [583.2359608912325, 613.4969325153376, 630.4798541203506, 643.7457204404263, 655.3184687924557, 37109.81445092774, 45273.28360298384, 46161.999999999956, 46242.53757462424, 50160.0, 46309.0, 46759.0, 46768.2984755229, 46791.76604116983, 46809.0, 9245.583948722307, 9395.436273823558, 9399.0, 9400.0, 9400.388974716656, 8680.56597170142, 8914.687985920495, 11067.446627668625, 11328.09375249978, 11347.092232621371, 11464.0, 11563.768724625503, 11563.884361156406, 11579.247348922336, 11581.131415143856, 8749.737507874774, 11299.096072314236, 11358.091352691847, 11363.14776391774, 11375.033122184583, 8650.0, 10787.514561844657, 11140.49867755953, 11146.721331966684, 11544.076473882069, 44737.776311118236, 45188.77405612951, 45212.77393613044, 45218.32172517415, 45269.0, 3872.3223435898603, 4203.936940945889, 4483.843065492692, 4499.910001799978, 4556.339330797049, 34096.147596310046, 34181.48727769085, 34452.48321275183, 34575.654243457495, 34693.82653086718, 4691.62467002639, 4715.221988371914, 4752.833650822239, 4772.5227477252, 4778.378810754614, 354745.0, 354887.0, 354898.0, 355110.0, 355955.0, 61303.386966129765, 61603.0, 61632.69183654053, 61643.383566163764, 61649.69175154096, 40287.59712402897, 40902.181956360706, 41149.794251029154, 42989.0, 43068.353974690406, 20109.09509072066, 20271.797282027284, 20284.0, 20290.0, 20294.79705202929, 14282.35729392159, 14590.927045364917, 15622.453214137451, 15690.686186276438, 15728.370865165265, 18693.719594206097, 20053.0, 20811.79188208129, 20924.163033478795, 21325.36023919285, 7419.258074192538, 7504.249575042561, 7532.811679708051, 7633.5419874807685, 7643.923560764432, 968.6706519783307, 1030.907218350352, 1108.800415925125, 1130.7568872692411, 1183.201339096124, 31999.280241119486, 34062.0, 34416.13959650978, 34466.0, 34686.65313346885, 1151.5969410706352, 1155.381870699172, 1217.7564487102616, 1241.6647505173553, 1277.6678063703505, 17791.24565677547, 17913.552161195814, 18031.819681803277, 18063.27746890136, 18099.095045247686, 554190.0, 556624.0, 560250.0, 562691.0, 562821.0, 61246.0, 61538.0769288461, 61780.0, 61854.072188917205, 61896.38103618996, 1313.4614807928704, 1350.473315406992, 1357.1449986508437, 1381.3369582600408, 1442.7619442792031, 33208.0, 34904.0, 35164.0, 35349.64650353567, 35697.82151089228, 52690.47014103684, 52718.0, 52938.47061529335, 53032.73483632557, 53048.204276936645, 407391.9630401829, 407558.0, 408532.0, 408883.0, 409796.0, 31217.687823122385, 31286.687133128376, 31549.8422507886, 31585.8420707895, 31786.205344866565, 51030.0, 51296.74351628218, 51471.0, 51611.0, 51839.74080129575, 41830.79084604557, 43350.0, 43591.782041089595, 44078.0, 44863.775681121384, 41944.0, 42300.0, 42300.78849605855, 44159.0, 44189.55810441983, 42274.788626056674, 42972.71081867549, 43158.35262471002, 43772.56227437812, 43810.78094609507, 35497.645023549434, 38118.94992630381, 38497.0, 38572.6142738569, 38663.42004869985, 29329.787659455385, 29445.705542945154, 29513.557296640138, 29537.55693664554, 29755.702442975293], "value": 21522.33890017174, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [402.8630265709658, 417.07617626956295, 417.6804744370555, 430.99138017239653, 452.47286910748977, 15531.301091450883, 19296.614067718656, 20923.16307347708, 21263.6810447843, 21447.67828482574, 20111.899440502777, 20455.181792728312, 20754.584908301862, 21094.367168984954, 21293.36119916405, 5955.731992060357, 5983.401659834003, 6231.376862313755, 6239.750409983595, 6245.531585131133, 6072.635641861502, 6112.724927378256, 6147.692615369214, 6148.754049838001, 6151.846203844895, 5871.2073870027425, 6066.302375226861, 6115.755369785204, 6125.5712100153005, 6126.969365153168, 6188.690565471731, 6205.720742566596, 6220.68896555175, 6222.284437289702, 6227.0, 5874.823755287348, 6041.516678665696, 6085.056816193497, 6093.573449858467, 6093.756249750005, 15037.548873533811, 15309.0, 19466.416007519656, 24645.38386540351, 24872.626910596355, 2469.5431345201014, 2595.2344058502786, 2679.0355472030055, 2689.798265130123, 2836.3192833719963, 10966.56133754657, 10970.177236707279, 11144.442777861155, 11271.267367621078, 11440.256383335056, 1966.3904189701238, 2030.98984505078, 2031.2788959919164, 2060.6187855246676, 2115.989420052905, 77553.61223193904, 87857.0, 88982.0, 90264.0, 90461.54769226178, 10397.58409663605, 11725.941370293265, 11741.88258117425, 11994.820077698841, 12033.63899083029, 14989.0257133286, 15036.0, 15804.841951580567, 15815.288312025987, 15815.683686326209, 8352.707655232038, 8602.655893764178, 8979.0, 8986.236169925629, 9082.591283392263, 8771.298296136294, 8907.331950103768, 8950.373473856898, 9168.495732734631, 9175.908240917639, 4834.274858771142, 4933.940842397119, 4977.32806071183, 5021.698698078127, 5055.469175736568, 2037.3582321568658, 2062.6390381683145, 2109.873407595549, 2111.7571479279773, 2141.0, 952.8142012307673, 1024.6977141743203, 1048.6906362623045, 1053.7471006958274, 1074.0742585848736, 20232.69650955237, 20643.380698579065, 20812.89593552053, 21252.78747212539, 21356.679649805264, 1012.6961911426542, 1018.2973748113777, 1019.454591793387, 1080.8756992945912, 1113.7995160871121, 11148.888511114947, 11710.168094512712, 11745.823812642817, 11787.705307367212, 11835.88164118365, 498143.0, 499369.0, 503328.0, 505758.0, 507603.0, 62400.375996239454, 90629.54685226663, 91476.54261728782, 97732.51133744286, 99527.50236248918, 1040.70339953113, 1070.4273213830718, 1073.6564299424265, 1095.3482677806692, 1095.7315457712912, 18614.255429782657, 19298.0, 19369.51576210606, 19374.70937935904, 19430.708539371637, 31364.843175783975, 43128.35307470453, 43671.34492982544, 44788.55211447974, 45124.0975180488, 49145.75427122841, 55290.44709552853, 60541.0, 61487.69256153869, 61531.38468615257, 7833.76498705017, 8406.444807710594, 8874.60064297121, 8914.465132092095, 8939.150780675825, 9005.14451127142, 9588.856167157637, 13155.802662959872, 13378.197308161542, 13589.388477518338, 11569.88430115688, 11572.36352000648, 11696.415179240834, 11975.580854670045, 12315.322657253937, 11085.334879907232, 11303.434828258718, 11701.76596468083, 11785.469653865768, 12072.0, 11353.829692554453, 11640.825387619023, 11663.475143618387, 11889.28664280146, 12015.219010764273, 8461.365397595328, 8548.145185481402, 8642.790009398816, 8669.783255418664, 8716.43343182691, 10579.62971296001, 12108.334041627795, 12296.75406491883, 12322.199057061262, 12685.8097128545], "value": 9590.30485025584, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1544.374528316032, 1573.6695293988262, 1718.86249100072, 1836.137015602668, 1851.3520267906235, 9095.909040909597, 9769.609215631375, 9773.26700497462, 9773.413595184276, 9798.510074496282, 8481.745547633565, 8646.394752367336, 9166.86249706255, 9419.340646154771, 9626.229901607872, 2565.640810286561, 2600.2459286806898, 2600.3369140869054, 2603.973960260402, 2604.4791041791623, 2539.746025397464, 2601.128621911662, 2601.128621911662, 2602.4795040991785, 2604.9479010419873, 2524.0913271222344, 2533.1260715053277, 2565.6023316385895, 2568.511982723286, 2568.845869247851, 2530.936726581841, 2535.6450096986437, 2544.440223150904, 2554.2081954594137, 2560.8719564021726, 2442.0476014354417, 2465.4251335563504, 2468.7654672806057, 2470.4441500662388, 2491.7134529529153, 9503.28725345602, 9619.13427791495, 9619.663311784125, 9625.278104142217, 9630.807383852283, 5612.045952188138, 5736.1682556029145, 5741.052726300156, 5742.109972954198, 5742.368339482654, 3511.877084302063, 3535.4696795480627, 3596.226811235597, 3672.7429079964413, 3689.0, 1181.5155786127732, 1226.4480983557346, 1247.3638444393334, 1259.6347059352868, 1279.6544932868073, 12692.048096392808, 12692.365381730877, 12730.299833509152, 12731.045171612073, 12739.171953822972, 4761.000379840475, 5091.9236211456855, 5104.821331253388, 5131.948680513147, 5158.45836187195, 10701.197410194267, 10717.196210284259, 10729.409882456384, 10756.56973721058, 10762.0, 5708.9714551427805, 5743.224664670216, 5751.0, 5761.0, 5769.826905192851, 4442.977785111054, 5283.867903302448, 5324.760385782649, 5342.813001544927, 5493.423190565013, 4254.744715317091, 4317.978410107929, 4391.6486681065435, 4416.845410410684, 4436.556344365602, 1411.0, 1412.1809350576846, 1424.8717615414662, 1439.5177615498926, 1453.2806260900923, 1011.6408674920444, 1013.5894962540183, 1034.2398337222135, 1034.2398337222135, 1035.7410647338165, 5957.344692083867, 6098.69506524672, 6173.413525715139, 6229.0, 6275.121482992385, 954.9618015279311, 972.0376826941418, 981.2052237687353, 990.2028866762325, 1039.6361273554196, 4144.70987030905, 4272.6795490338345, 4294.76378799169, 4308.9353659695125, 4366.585174408426, 11844.466998985066, 12221.144519883523, 12292.569760058355, 12309.69225769345, 12330.753384932252, 12233.571824986082, 12240.387980600934, 12243.75512489763, 12247.693807654701, 12253.571125010581, 8466.788330291669, 8753.562321884008, 8760.0, 8959.955200223958, 8960.910390896008, 5927.644341339534, 6022.096685497036, 6100.420460056288, 6125.509959203164, 6163.414475624809, 10134.493275336352, 10329.690109296733, 10372.585096596353, 10441.477926103817, 10443.060124588823, 10466.372017678963, 10606.0, 10630.25588208818, 10683.786324273626, 10914.454277286262, 7694.9615251925625, 7747.651355688895, 8358.916410835815, 8368.958155209186, 8552.871706924278, 9348.672796452092, 9377.953110234404, 9417.246620270498, 9437.433753974783, 9442.905570944202, 8257.545834979133, 8443.704470343508, 8446.493210407396, 8462.0, 8612.397132200686, 8219.835603287782, 8233.135520770473, 8254.958725206336, 8272.793180170544, 8281.3788965829, 7398.556086634819, 7482.251774822692, 7567.31894129531, 7645.732399366218, 7785.688572457038, 5152.793888244429, 5318.707471088973, 5322.973385133205, 5335.439778823323, 5346.304980352529, 5536.0865457200725, 5540.944590554204, 5592.3848376677715, 5732.197492350991, 5794.275715535558], "value": 4996.760877556896, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [357.33000623830304, 361.0774471226017, 365.9871904483344, 368.9594144644089, 373.2497679663875, 5265.86835329117, 5482.671039737608, 5570.941521110991, 5611.3546942101675, 5624.550035997126, 5337.653052551582, 5436.891262174754, 5472.425395333483, 5474.534664553517, 5478.45215478452, 4349.934750978738, 4351.195028919644, 4362.476502819666, 4371.978140109295, 4375.7374557526655, 4211.368294755795, 4316.683411559488, 4352.173087113454, 4367.628751556122, 4369.541198174194, 4285.721428107178, 4297.162053399588, 4302.698811083225, 4302.935455968163, 4308.267594508925, 4294.904799276164, 4302.720323178999, 4318.352247162935, 4333.653307735405, 4337.457817772778, 3644.6355364463607, 3885.7668539887695, 3885.931368873554, 3895.18201177753, 3912.5500567434824, 5348.582625604218, 5369.221462887898, 5369.570434365281, 5443.2107344435235, 5467.480589344006, 4977.303177555145, 4983.451820299764, 4987.451380348159, 4994.9250761238245, 5012.498750124995, 5463.738284138379, 5724.742386592613, 5806.912896306559, 5811.6803575803315, 5846.649201047951, 4204.159168166379, 4205.3481710334945, 4209.389638502431, 4213.536510983789, 4225.281702110648, 6021.200141934021, 6046.0, 6046.007226422948, 6049.6975151242705, 6050.939490605126, 4236.364545318227, 4268.679849011337, 4302.118065796567, 4318.503372112247, 4369.890752731144, 4321.980558081015, 4341.15347507233, 4342.5006124296115, 4345.956540434619, 4353.477582690034, 4932.235503496964, 4946.332245146931, 4948.802047918115, 4948.826791062295, 4960.553550180501, 5742.425757424224, 5753.971230143822, 5767.8558036049435, 5843.678597677169, 5871.853203669857, 1488.218685190262, 1501.902376345534, 1511.7883496310526, 1513.52324017934, 1518.6886688228888, 941.2206238919923, 958.1711819276276, 970.7330484116924, 975.2588033094758, 979.1579241852002, 4966.652334336562, 4993.750312484434, 5005.274235235943, 5028.924566131511, 5071.340725705634, 5249.895002099936, 5256.763445644955, 5269.341332333458, 5280.049591073644, 5288.471152884681, 5017.89964200714, 5036.949630503721, 5060.9119039406705, 5232.293640358503, 5668.773249070074, 3141.434541782455, 3155.4793459078996, 3168.683131686859, 3170.8743396094515, 3175.4442972480188, 6014.7047975017285, 6119.2350956130485, 6132.662703551258, 6151.907721384183, 6161.507079433634, 5980.611260268069, 5996.850078748066, 6046.697665116727, 6047.516198704093, 6049.909251361233, 6120.908186377208, 6142.631442113398, 6145.692715364214, 6149.169862068653, 6180.598261112923, 5608.186812912023, 5930.229070220844, 5942.910856337245, 5980.401959803985, 5986.401359864153, 5906.350301466921, 5908.050343386586, 5908.911366329423, 5911.261092363455, 5912.290525137011, 5836.445537673914, 6150.907736383869, 6156.569040167145, 6160.168377269011, 6170.5372097092, 4250.086231460251, 4302.397664326934, 4330.198913201068, 4390.956090439055, 4415.2935530315, 6068.423499767515, 6087.178230938766, 6089.48239399656, 6093.390660934048, 6099.512039036777, 5708.514776244066, 5768.2501274834, 5810.501484361765, 5945.672987985702, 5949.810037992333, 5782.380933338672, 5795.942040579709, 5804.2744656917885, 5815.651060936358, 5818.389069147848, 5787.334456537552, 5824.359775140537, 5825.446582574648, 5840.386759390203, 5841.853953651193, 5575.581831362745, 5842.305731337864, 5856.385079566584, 5861.1794348792055, 5863.384344643921, 5705.144228365778, 5718.628289161191, 5727.8496826744295, 5729.541636668973, 5733.853229354229], "value": 4413.685347687538, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [843.5782108945527, 848.7241646464901, 871.1506281375658, 886.8536691445912, 912.2337236721153, 15503.534893953185, 15902.761458578116, 15995.920020399883, 16044.0, 16122.0, 16077.11575863327, 16220.594485137848, 16449.91775041126, 16452.506424807245, 16653.0, 891.0821853490899, 902.823949329881, 904.6200595749768, 905.4612505559181, 905.9954700226523, 725.0646665801111, 732.088549755555, 867.119873328572, 883.3728053082319, 893.4371346052011, 891.2468963725673, 897.9865302020474, 898.0121865947439, 898.3890954151175, 902.5938327752505, 894.8657701344786, 895.4492986813128, 896.2650626486251, 896.3814967672317, 900.5272232078146, 549.9037668408052, 552.864548185693, 560.7392562458435, 562.3617194484249, 666.7666316789133, 16140.515784526482, 16617.750733739005, 16625.08562029088, 16665.500034998968, 16668.916655416768, 15035.0, 15463.690726185414, 15599.220038998004, 16012.119333436656, 16109.355625774955, 13232.0, 13523.120997135155, 13601.047926645138, 13704.45182192711, 14325.49860754879, 4680.321353403771, 4729.243321068647, 4735.360726301974, 4784.497627749106, 4930.630202734809, 84949.15050849474, 85740.14259857385, 86106.56946715289, 86655.13344866534, 86758.56620716857, 12268.631941041784, 12358.0, 12526.624201273977, 12657.93671031639, 12868.356582170854, 24030.27909162728, 24032.0, 24052.15817446416, 24057.398565036015, 24066.63900041501, 8878.911210887938, 8994.910050899538, 9128.132827381389, 9184.494852783027, 9381.29640276982, 5239.161734122598, 5257.421683614799, 5341.252224688546, 5361.035013697572, 5418.051840927822, 3587.318409502186, 3720.479132921393, 3721.813909304524, 3725.8695945642316, 3727.105494681311, 621.8806148931905, 623.3205805671839, 623.9968800155971, 625.0592857749059, 630.7224821078711, 600.573592749153, 647.1554621219354, 754.7207533212746, 781.4256521456725, 792.1405275276389, 10209.897901021044, 10301.484925753832, 10649.361038337725, 10667.786644267071, 10715.83926241107, 663.9302873198342, 697.2609034423426, 702.6346299924011, 739.8224426137796, 747.5327920049968, 5017.347744793226, 5057.216131499624, 5061.974690126526, 5082.0, 5123.897522049613, 124058.37970810088, 126196.0, 129400.0, 129688.0, 135643.32178339045, 88131.55934220288, 90279.0, 111238.0, 112893.43553282181, 114033.4298328503, 26937.191884243504, 26997.32506687309, 38948.415773763416, 66097.0, 86279.56860215784, 10468.738281543023, 10927.147365429684, 11059.281146725438, 11156.44217789091, 11366.829497557708, 47105.293420598035, 53906.73046634742, 54792.72603636956, 54805.0, 55615.16577251425, 50774.49225507698, 69212.96180557397, 71957.0, 75425.6228718853, 77021.61489192519, 14329.641758955693, 14517.419303228175, 14565.126092434488, 14810.407583696962, 14981.325840336993, 16290.18549072518, 16291.511254661906, 16584.66830663404, 16628.852803344525, 17148.914255428645, 15029.774553381927, 15325.923370383523, 15609.765853512434, 15762.448314308944, 15906.0, 11328.37693926809, 13942.860571394562, 14183.54593608052, 15175.0, 15917.602059948595, 14658.0, 15027.474038408604, 15037.398504059714, 15227.238638068275, 15257.0, 12212.633620991384, 12513.499460021498, 12567.87432125667, 13237.735245294847, 13422.597322080353, 12617.179883307555, 12665.303408312631, 12850.871491284968, 12955.157914735511, 12983.221006739626], "value": 8025.0973793982575, "unit": "score"}], "name": "dromaeo_css", "value": 7657.536262976299, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 111.0, 112.0, 111.0, 112.0, 114.0, 110.0, 111.0, 110.0, 111.0], "value": 111.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 117.0, 121.0, 160.0, 120.0, 121.0, 119.0, 119.0, 153.0, 122.0], "value": 129.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 161.0, 161.0, 162.0, 161.0, 163.0, 162.0, 165.0, 160.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 75.0, 75.0, 117.0, 76.0, 76.0, 75.0, 76.0, 98.0, 75.0], "value": 82.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 123.0, 81.0, 81.0, 81.0, 102.0, 82.0, 81.0, 117.0], "value": 91.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0, 118.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 146.0, 146.0, 146.0, 147.0, 145.0, 145.0, 145.0, 145.0, 145.0], "value": 145.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 94.0, 96.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 49.0, 50.0, 49.0, 54.0, 50.0, 49.0, 49.0, 49.0, 49.0], "value": 49.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 61.0, 59.0, 60.0, 59.0, 59.0, 60.0, 59.0, 60.0, 59.0], "value": 59.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 74.0, 73.0, 73.0, 79.0, 74.0, 74.0, 73.0, 73.0, 74.0], "value": 74.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 129.0, 132.0, 121.0, 123.0, 152.0, 120.0, 119.0, 155.0, 122.0], "value": 129.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 157.0, 154.0, 156.0, 155.0, 158.0, 153.0, 156.0, 152.0, 155.0], "value": 155.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 63.0, 62.0, 63.0, 61.0, 62.0, 65.0, 60.0, 62.0], "value": 62.3, "unit": "score"}], "name": "kraken", "value": 1464.6999999999998, "alertThreshold": 2.0}]} 16:36:25 INFO - Return code: 0 16:36:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:36:25 INFO - # TBPL SUCCESS # 16:36:25 INFO - Running post-action listener: _resource_record_post_action 16:36:25 INFO - [mozharness: 2016-11-05 23:36:25.660189Z] Finished run-tests step (success) 16:36:25 INFO - Running post-run listener: _resource_record_post_run 16:36:25 INFO - Total resource usage - Wall time: 1188s; CPU: 13.0%; Read bytes: 0; Write bytes: 346857472; Read time: 0; Write time: 205708 16:36:25 INFO - TinderboxPrint: CPU usage
12.9% 16:36:25 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 16:36:25 INFO - TinderboxPrint: I/O write bytes / time
346,857,472 / 205,708 16:36:25 INFO - TinderboxPrint: CPU idle
8,253.7 (87.0%) 16:36:25 INFO - TinderboxPrint: CPU user
1,185.8 (12.5%) 16:36:25 INFO - TinderboxPrint: Swap in / out
0 / 0 16:36:25 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 107335680; Read time: 0; Write time: 65744 16:36:25 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 0; Write bytes: 239521792; Read time: 0; Write time: 139964 16:36:26 INFO - Running post-run listener: _upload_blobber_files 16:36:26 INFO - Blob upload gear active. 16:36:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:36:26 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 16:36:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:36:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:36:26 INFO - (blobuploader) - INFO - Open directory for files ... 16:36:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:36:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:36:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:36:30 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:36:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:36:30 INFO - (blobuploader) - INFO - Done attempting. 16:36:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:36:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:36:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:36:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:36:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:36:31 INFO - (blobuploader) - INFO - Done attempting. 16:36:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:36:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:36:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:36:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:36:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:36:32 INFO - (blobuploader) - INFO - Done attempting. 16:36:32 INFO - (blobuploader) - INFO - Iteration through files over. 16:36:32 INFO - Return code: 0 16:36:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:36:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:36:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a380906e3c966b254c6081811487f84017f52f4fbc52ef22b554a0dc7b010aed615b312752b1bb597034b31573c95f3fedc3fc93c734d2317655757558e7c35a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b3da40108dd95af917fb61eaf6d50696e5c08b6a0059c2c7ff4d47e4f78ae2a3dbe4367aa538635bbd25b006cc3dac1f4fd30b20d961b946389c1d21fecc1480", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8943e6f0a4a842a05f8133965358008677bdb6b6be3f91c1f67750bdd838273b8550a1ce1750ae16743c9afff3651e1354d65b47d79f833590a10dc7f6a6104c"} 16:36:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:36:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:36:32 INFO - Contents: 16:36:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a380906e3c966b254c6081811487f84017f52f4fbc52ef22b554a0dc7b010aed615b312752b1bb597034b31573c95f3fedc3fc93c734d2317655757558e7c35a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b3da40108dd95af917fb61eaf6d50696e5c08b6a0059c2c7ff4d47e4f78ae2a3dbe4367aa538635bbd25b006cc3dac1f4fd30b20d961b946389c1d21fecc1480", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8943e6f0a4a842a05f8133965358008677bdb6b6be3f91c1f67750bdd838273b8550a1ce1750ae16743c9afff3651e1354d65b47d79f833590a10dc7f6a6104c"} 16:36:32 INFO - Running post-run listener: copy_logs_to_upload_dir 16:36:32 INFO - Copying logs to upload dir... 16:36:32 INFO - mkdir: /builds/slave/test/build/upload/logs 16:36:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1232.138513 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-11-05 16:36:31.165472) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-05 16:36:31.168713) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1478387628.380639-2077778747 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a380906e3c966b254c6081811487f84017f52f4fbc52ef22b554a0dc7b010aed615b312752b1bb597034b31573c95f3fedc3fc93c734d2317655757558e7c35a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b3da40108dd95af917fb61eaf6d50696e5c08b6a0059c2c7ff4d47e4f78ae2a3dbe4367aa538635bbd25b006cc3dac1f4fd30b20d961b946389c1d21fecc1480", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8943e6f0a4a842a05f8133965358008677bdb6b6be3f91c1f67750bdd838273b8550a1ce1750ae16743c9afff3651e1354d65b47d79f833590a10dc7f6a6104c"} build_url:https://queue.taskcluster.net/v1/task/Gba5JSWXQxO2nh2P28t9aw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011436 build_url: 'https://queue.taskcluster.net/v1/task/Gba5JSWXQxO2nh2P28t9aw/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a380906e3c966b254c6081811487f84017f52f4fbc52ef22b554a0dc7b010aed615b312752b1bb597034b31573c95f3fedc3fc93c734d2317655757558e7c35a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b3da40108dd95af917fb61eaf6d50696e5c08b6a0059c2c7ff4d47e4f78ae2a3dbe4367aa538635bbd25b006cc3dac1f4fd30b20d961b946389c1d21fecc1480", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8943e6f0a4a842a05f8133965358008677bdb6b6be3f91c1f67750bdd838273b8550a1ce1750ae16743c9afff3651e1354d65b47d79f833590a10dc7f6a6104c"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-05 16:36:31.193797) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 16:36:31.194131) ========= 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=e0e61f6f900f4dc42e3e8956000001ad-1478387628.380639-2077778747 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004423 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-05 16:36:31.277149) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-05 16:36:31.277495) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-05 16:36:31.277820) ========= ========= Total master_lag: 0.40 =========