builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-046 starttime: 1478661382.48 results: success (0) buildid: 20161108173621 builduid: 083c302c289d4fffa60edb78cf695dbd revision: ae3b6bfb810476141647ad681b796ed118062412 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-08 19:16:22.480108) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-08 19:16:22.480532) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-08 19:16:22.480837) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-08 19:16:22.495562) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-08 19:16:22.495864) ========= 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=652eb03db935cfc1c15ebda0000001ad-1478659882.19157-1947756193 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-11-08 19:16:22-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 224M=0s 2016-11-08 19:16:23 (224 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.072801 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-08 19:16:22.582922) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-08 19:16:22.583227) ========= 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=652eb03db935cfc1c15ebda0000001ad-1478659882.19157-1947756193 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013868 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-08 19:16:22.632514) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-08 19:16:22.632873) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ae3b6bfb810476141647ad681b796ed118062412 --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 ae3b6bfb810476141647ad681b796ed118062412 --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=652eb03db935cfc1c15ebda0000001ad-1478659882.19157-1947756193 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-11-08 19:16:23,127 truncating revision to first 12 chars 2016-11-08 19:16:23,127 Setting DEBUG logging. 2016-11-08 19:16:23,127 attempt 1/10 2016-11-08 19:16:23,127 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/ae3b6bfb8104?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-08 19:16:23,538 unpacking tar archive at: mozilla-aurora-ae3b6bfb8104/testing/mozharness/ program finished with exit code 0 elapsedTime=0.763135 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-08 19:16:23.409232) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-08 19:16:23.409642) ========= script_repo_revision: ae3b6bfb810476141647ad681b796ed118062412 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-08 19:16:23.410233) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-08 19:16:23.410636) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-08 19:16:23.421222) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-11-08 19:16:23.421624) ========= /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=652eb03db935cfc1c15ebda0000001ad-1478659882.19157-1947756193 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:16:23 INFO - MultiFileLogger online at 20161108 19:16:23 in /builds/slave/test 19:16:23 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 19:16:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:16:23 INFO - {'append_to_log': False, 19:16:23 INFO - 'base_work_dir': '/builds/slave/test', 19:16:23 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 19:16:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:16:23 INFO - 'branch': 'Mozilla-Aurora', 19:16:23 INFO - 'buildbot_json_path': 'buildprops.json', 19:16:23 INFO - 'config_files': ('talos/linux_config.py',), 19:16:23 INFO - 'default_actions': ('clobber', 19:16:23 INFO - 'read-buildbot-config', 19:16:23 INFO - 'download-and-extract', 19:16:24 INFO - 'populate-webroot', 19:16:24 INFO - 'create-virtualenv', 19:16:24 INFO - 'install', 19:16:24 INFO - 'run-tests'), 19:16:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:16:24 INFO - 'download_minidump_stackwalk': True, 19:16:24 INFO - 'download_symbols': 'ondemand', 19:16:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:16:24 INFO - 'tooltool.py': '/tools/tooltool.py', 19:16:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:16:24 INFO - '/tools/misc-python/virtualenv.py')}, 19:16:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:16:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:16:24 INFO - 'installer_path': 'installer.exe', 19:16:24 INFO - 'log_level': 'info', 19:16:24 INFO - 'log_name': 'talos', 19:16:24 INFO - 'log_to_console': True, 19:16:24 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:16:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:16:24 INFO - 'opt_config_files': (), 19:16:24 INFO - 'pip_index': False, 19:16:24 INFO - 'sps_profile': False, 19:16:24 INFO - 'sps_profile_interval': 0, 19:16:24 INFO - 'suite': 'dromaeojs', 19:16:24 INFO - 'system_bits': '32', 19:16:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:16:24 INFO - 'title': 'talos-linux64-ix-046', 19:16:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:16:24 INFO - 'use_talos_json': True, 19:16:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:16:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:16:24 INFO - 'work_dir': 'build'} 19:16:24 INFO - [mozharness: 2016-11-09 03:16:24.002345Z] Running clobber step. 19:16:24 INFO - Running pre-action listener: _resource_record_pre_action 19:16:24 INFO - Running main action method: clobber 19:16:24 INFO - rmtree: /builds/slave/test/build 19:16:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:16:24 INFO - Running post-action listener: _resource_record_post_action 19:16:24 INFO - [mozharness: 2016-11-09 03:16:24.468779Z] Finished clobber step (success) 19:16:24 INFO - [mozharness: 2016-11-09 03:16:24.468878Z] Running read-buildbot-config step. 19:16:24 INFO - Running pre-action listener: _resource_record_pre_action 19:16:24 INFO - Running main action method: read_buildbot_config 19:16:24 INFO - Using buildbot properties: 19:16:24 INFO - { 19:16:24 INFO - "project": "", 19:16:24 INFO - "product": "firefox", 19:16:24 INFO - "script_repo_revision": "production", 19:16:24 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs", 19:16:24 INFO - "repository": "", 19:16:24 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs", 19:16:24 INFO - "buildid": "20161108173621", 19:16:24 INFO - "pgo_build": "True", 19:16:24 INFO - "basedir": "/builds/slave/test", 19:16:24 INFO - "buildnumber": 611, 19:16:24 INFO - "slavename": "talos-linux64-ix-046", 19:16:24 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 19:16:24 INFO - "platform": "ubuntu64_hw", 19:16:24 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 19:16:24 INFO - "branch": "mozilla-aurora", 19:16:24 INFO - "repo_path": "releases/mozilla-aurora", 19:16:24 INFO - "revision": "ae3b6bfb810476141647ad681b796ed118062412", 19:16:24 INFO - "stage_platform": "linux64-pgo", 19:16:24 INFO - "builduid": "083c302c289d4fffa60edb78cf695dbd", 19:16:24 INFO - "slavebuilddir": "test" 19:16:24 INFO - } 19:16:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/X7JcpTgmTr-y-M7YNHnBKQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2. 19:16:24 INFO - Running post-action listener: _resource_record_post_action 19:16:24 INFO - [mozharness: 2016-11-09 03:16:24.471312Z] Finished read-buildbot-config step (success) 19:16:24 INFO - [mozharness: 2016-11-09 03:16:24.471403Z] Running download-and-extract step. 19:16:24 INFO - Running pre-action listener: _resource_record_pre_action 19:16:24 INFO - Running main action method: download_and_extract 19:16:24 INFO - mkdir: /builds/slave/test/build/tests 19:16:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:16:24 INFO - trying https://queue.taskcluster.net/v1/task/X7JcpTgmTr-y-M7YNHnBKQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 19:16:24 INFO - Downloading https://queue.taskcluster.net/v1/task/X7JcpTgmTr-y-M7YNHnBKQ/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 19:16:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X7JcpTgmTr-y-M7YNHnBKQ/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 19:16:25 INFO - Downloaded 1361 bytes. 19:16:25 INFO - Reading from file /builds/slave/test/build/firefox-51.0a2.en-US.linux-x86_64.test_packages.json 19:16:25 INFO - Using the following test package requirements: 19:16:25 INFO - {u'common': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 19:16:25 INFO - u'cppunittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 19:16:25 INFO - u'firefox-51.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 19:16:25 INFO - u'gtest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 19:16:25 INFO - u'firefox-51.0a2.en-US.linux-x86_64.gtest.tests.zip'], 19:16:25 INFO - u'jittest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 19:16:25 INFO - u'jsshell-linux-x86_64.zip'], 19:16:25 INFO - u'mochitest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 19:16:25 INFO - u'firefox-51.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 19:16:25 INFO - u'mozbase': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'], 19:16:25 INFO - u'reftest': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 19:16:25 INFO - u'firefox-51.0a2.en-US.linux-x86_64.reftest.tests.zip'], 19:16:25 INFO - u'talos': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 19:16:25 INFO - u'firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'], 19:16:25 INFO - u'web-platform': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 19:16:25 INFO - u'firefox-51.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 19:16:25 INFO - u'xpcshell': [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip', 19:16:25 INFO - u'firefox-51.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 19:16:25 INFO - Downloading packages: [u'firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:16:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X7JcpTgmTr-y-M7YNHnBKQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 19:16:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X7JcpTgmTr-y-M7YNHnBKQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:16:25 INFO - Fetch https://queue.taskcluster.net/v1/task/X7JcpTgmTr-y-M7YNHnBKQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 19:16:25 INFO - Expected file size: 21895944 19:16:29 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 19:16:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X7JcpTgmTr-y-M7YNHnBKQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip 19:16:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X7JcpTgmTr-y-M7YNHnBKQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 19:16:29 INFO - Fetch https://queue.taskcluster.net/v1/task/X7JcpTgmTr-y-M7YNHnBKQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.common.tests.zip into memory 19:16:30 INFO - Expected file size: 21895944 19:16:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/X7JcpTgmTr-y-M7YNHnBKQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip 19:16:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/X7JcpTgmTr-y-M7YNHnBKQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 19:16:35 INFO - Fetch https://queue.taskcluster.net/v1/task/X7JcpTgmTr-y-M7YNHnBKQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.talos.tests.zip into memory 19:16:35 INFO - Expected file size: 14414089 19: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')]} 19:16:36 INFO - trying https://queue.taskcluster.net/v1/task/X7JcpTgmTr-y-M7YNHnBKQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 19:16:36 INFO - Downloading https://queue.taskcluster.net/v1/task/X7JcpTgmTr-y-M7YNHnBKQ/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 19:16:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/X7JcpTgmTr-y-M7YNHnBKQ/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 19:16:37 INFO - Downloaded 63984307 bytes. 19:16:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/X7JcpTgmTr-y-M7YNHnBKQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 19:16:37 INFO - mkdir: /builds/slave/test/properties 19:16:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:16:37 INFO - Writing to file /builds/slave/test/properties/build_url 19:16:37 INFO - Contents: 19:16:37 INFO - build_url:https://queue.taskcluster.net/v1/task/X7JcpTgmTr-y-M7YNHnBKQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 19:16:38 INFO - Running post-action listener: _resource_record_post_action 19:16:38 INFO - Running post-action listener: set_extra_try_arguments 19:16:38 INFO - [mozharness: 2016-11-09 03:16:38.103746Z] Finished download-and-extract step (success) 19:16:38 INFO - [mozharness: 2016-11-09 03:16:38.103974Z] Running populate-webroot step. 19:16:38 INFO - Running pre-action listener: _resource_record_pre_action 19:16:38 INFO - Running main action method: populate_webroot 19:16:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:16:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:16:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:16:38 INFO - '--tppagecycles', 19:16:38 INFO - '7'], 19:16:38 INFO - 'tests': ['tsvgm']}}, 19:16:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:16:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:16:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:16:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:16:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:16:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:16:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:16:38 INFO - 'talos_options': ['--disable-e10s'], 19:16:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:16:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:16:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:16:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:16:38 INFO - 'talos_options': ['--disable-e10s'], 19:16:38 INFO - 'tests': ['damp', 'tps']}, 19:16:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:16:38 INFO - 'tests': ['damp', 'tps']}, 19:16:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:16:38 INFO - 'tests': ['dromaeo_dom']}, 19:16:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:16:38 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:16:38 INFO - 'tests': ['basic_compositor_video']}, 19:16:38 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:16:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:16:38 INFO - 'tests': ['a11yr', 19:16:38 INFO - 'ts_paint', 19:16:38 INFO - 'tpaint', 19:16:38 INFO - 'sessionrestore', 19:16:38 INFO - 'sessionrestore_no_auto_restore', 19:16:38 INFO - 'tabpaint']}, 19:16:38 INFO - 'other-e10s': {'tests': ['a11yr', 19:16:38 INFO - 'ts_paint', 19:16:38 INFO - 'tpaint', 19:16:38 INFO - 'sessionrestore', 19:16:38 INFO - 'sessionrestore_no_auto_restore', 19:16:38 INFO - 'tabpaint']}, 19:16:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:16:38 INFO - 'ts_paint', 19:16:38 INFO - 'tpaint', 19:16:38 INFO - 'sessionrestore', 19:16:38 INFO - 'sessionrestore_no_auto_restore', 19:16:38 INFO - 'tabpaint']}, 19:16:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:16:38 INFO - 'ts_paint', 19:16:38 INFO - 'tpaint', 19:16:38 INFO - 'sessionrestore', 19:16:38 INFO - 'sessionrestore_no_auto_restore', 19:16:38 INFO - 'tabpaint']}, 19:16:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:16:38 INFO - 'tests': ['a11yr', 19:16:38 INFO - 'ts_paint', 19:16:38 INFO - 'tpaint', 19:16:38 INFO - 'sessionrestore', 19:16:38 INFO - 'sessionrestore_no_auto_restore', 19:16:38 INFO - 'tabpaint']}, 19:16:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:16:38 INFO - 'tests': ['a11yr', 19:16:38 INFO - 'ts_paint', 19:16:38 INFO - 'tpaint', 19:16:38 INFO - 'sessionrestore', 19:16:38 INFO - 'sessionrestore_no_auto_restore', 19:16:38 INFO - 'tabpaint']}, 19:16:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:16:38 INFO - 'tests': ['tsvgx', 19:16:38 INFO - 'tsvgr_opacity', 19:16:38 INFO - 'tart', 19:16:38 INFO - 'tscrollx', 19:16:38 INFO - 'cart']}, 19:16:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:16:38 INFO - 'tsvgr_opacity', 19:16:38 INFO - 'tart', 19:16:38 INFO - 'tscrollx', 19:16:38 INFO - 'cart']}, 19:16:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:16:38 INFO - 'talos_options': ['--disable-e10s'], 19:16:38 INFO - 'tests': ['tp5o']}, 19:16:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:16:38 INFO - 'tests': ['tp5o']}, 19:16:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:16:38 INFO - 'talos_options': ['--disable-e10s', 19:16:38 INFO - '--xperf_path', 19:16:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:16:38 INFO - 'tests': ['tp5n']}, 19:16:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:16:38 INFO - 'talos_options': ['--xperf_path', 19:16:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:16:38 INFO - 'tests': ['tp5n']}}, 19:16:38 INFO - 'talos.zip': {'path': '', 19:16:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:16:38 INFO - Running post-action listener: _resource_record_post_action 19:16:38 INFO - [mozharness: 2016-11-09 03:16:38.122085Z] Finished populate-webroot step (success) 19:16:38 INFO - [mozharness: 2016-11-09 03:16:38.122177Z] Running create-virtualenv step. 19:16:38 INFO - Running pre-action listener: _resource_record_pre_action 19:16:38 INFO - Running main action method: create_virtualenv 19:16:38 INFO - Creating virtualenv /builds/slave/test/build/venv 19:16:38 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 19:16:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:16:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:16:38 INFO - Using real prefix '/usr' 19:16:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:16:38 INFO - Installing distribute.............................................................................................................................................................................................done. 19:16:40 INFO - Installing pip.................done. 19:16:40 INFO - Return code: 0 19:16:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:16:40 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')]} 19:16:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:16:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:40 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')]} 19:16:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:16:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:40 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 0x7f92e4b9bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe73ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe70f60>, '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 0xe71d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe721d0>, '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': '652eb03db935cfc1c15ebda0000001ad-1478659882.19157-1947756193', '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 19:16:40 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 19:16:40 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 19:16:40 INFO - Using env: {'DISPLAY': ':0', 19:16:40 INFO - 'HOME': '/home/cltbld', 19:16:40 INFO - 'LANG': 'en_US.UTF-8', 19:16:40 INFO - 'LANGUAGE': 'en_US:en', 19:16:40 INFO - 'LOGNAME': 'cltbld', 19:16:40 INFO - 'MAIL': '/var/mail/cltbld', 19:16:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:16:40 INFO - 'MOZ_NO_REMOTE': '1', 19:16:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:16:40 INFO - 'NO_EM_RESTART': '1', 19:16:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:16:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:16:40 INFO - 'PWD': '/builds/slave/test', 19:16:40 INFO - 'SHELL': '/bin/bash', 19:16:40 INFO - 'SHLVL': '1', 19:16:40 INFO - 'TERM': 'linux', 19:16:40 INFO - 'TMOUT': '86400', 19:16:40 INFO - 'USER': 'cltbld', 19:16:40 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1478659882.19157-1947756193', 19:16:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:16:40 INFO - '_': '/tools/buildbot/bin/python'} 19:16:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:16:40 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 19:16:40 INFO - Cleaning up... 19:16:40 INFO - Return code: 0 19:16:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:16:40 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')]} 19:16:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:16:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:40 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')]} 19:16:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:16:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:40 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 0x7f92e4b9bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe73ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe70f60>, '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 0xe71d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe721d0>, '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': '652eb03db935cfc1c15ebda0000001ad-1478659882.19157-1947756193', '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 19:16:40 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 19:16:40 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 19:16:40 INFO - Using env: {'DISPLAY': ':0', 19:16:40 INFO - 'HOME': '/home/cltbld', 19:16:40 INFO - 'LANG': 'en_US.UTF-8', 19:16:40 INFO - 'LANGUAGE': 'en_US:en', 19:16:40 INFO - 'LOGNAME': 'cltbld', 19:16:40 INFO - 'MAIL': '/var/mail/cltbld', 19:16:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:16:40 INFO - 'MOZ_NO_REMOTE': '1', 19:16:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:16:40 INFO - 'NO_EM_RESTART': '1', 19:16:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:16:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:16:40 INFO - 'PWD': '/builds/slave/test', 19:16:40 INFO - 'SHELL': '/bin/bash', 19:16:40 INFO - 'SHLVL': '1', 19:16:40 INFO - 'TERM': 'linux', 19:16:40 INFO - 'TMOUT': '86400', 19:16:40 INFO - 'USER': 'cltbld', 19:16:40 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1478659882.19157-1947756193', 19:16:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:16:40 INFO - '_': '/tools/buildbot/bin/python'} 19:16:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:16:41 INFO - Downloading/unpacking psutil>=3.1.1 19:16:41 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 19:16:41 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 19:16:41 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 19:16:41 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 19:16:41 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 19:16:41 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 19:16:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:16:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:16:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:16:43 INFO - Installing collected packages: psutil 19:16:43 INFO - Running setup.py install for psutil 19:16:43 INFO - building 'psutil._psutil_linux' extension 19:16:43 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 19:16:43 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 19:16:43 INFO - building 'psutil._psutil_posix' extension 19:16:43 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 19:16:43 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 19:16:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:16:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:16:43 INFO - Successfully installed psutil 19:16:43 INFO - Cleaning up... 19:16:43 INFO - Return code: 0 19:16:43 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:16:43 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')]} 19:16:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:16:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:43 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')]} 19:16:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:16:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:43 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 0x7f92e4b9bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe73ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe70f60>, '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 0xe71d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe721d0>, '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': '652eb03db935cfc1c15ebda0000001ad-1478659882.19157-1947756193', '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 19:16:43 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 19:16:43 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 19:16:43 INFO - Using env: {'DISPLAY': ':0', 19:16:43 INFO - 'HOME': '/home/cltbld', 19:16:43 INFO - 'LANG': 'en_US.UTF-8', 19:16:43 INFO - 'LANGUAGE': 'en_US:en', 19:16:43 INFO - 'LOGNAME': 'cltbld', 19:16:43 INFO - 'MAIL': '/var/mail/cltbld', 19:16:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:16:43 INFO - 'MOZ_NO_REMOTE': '1', 19:16:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:16:43 INFO - 'NO_EM_RESTART': '1', 19:16:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:16:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:16:43 INFO - 'PWD': '/builds/slave/test', 19:16:43 INFO - 'SHELL': '/bin/bash', 19:16:43 INFO - 'SHLVL': '1', 19:16:43 INFO - 'TERM': 'linux', 19:16:43 INFO - 'TMOUT': '86400', 19:16:43 INFO - 'USER': 'cltbld', 19:16:43 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1478659882.19157-1947756193', 19:16:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:16:43 INFO - '_': '/tools/buildbot/bin/python'} 19:16:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:16:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:16:43 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 19:16:43 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 19:16:43 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 19:16:43 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 19:16:43 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 19:16:43 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 19:16:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:16:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:16:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:16:45 INFO - Installing collected packages: mozsystemmonitor 19:16:45 INFO - Running setup.py install for mozsystemmonitor 19:16:46 INFO - Successfully installed mozsystemmonitor 19:16:46 INFO - Cleaning up... 19:16:46 INFO - Return code: 0 19:16:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:16:46 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')]} 19:16:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:16:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:46 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')]} 19:16:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:16:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:46 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 0x7f92e4b9bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe73ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe70f60>, '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 0xe71d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe721d0>, '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': '652eb03db935cfc1c15ebda0000001ad-1478659882.19157-1947756193', '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 19:16:46 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 19:16:46 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 19:16:46 INFO - Using env: {'DISPLAY': ':0', 19:16:46 INFO - 'HOME': '/home/cltbld', 19:16:46 INFO - 'LANG': 'en_US.UTF-8', 19:16:46 INFO - 'LANGUAGE': 'en_US:en', 19:16:46 INFO - 'LOGNAME': 'cltbld', 19:16:46 INFO - 'MAIL': '/var/mail/cltbld', 19:16:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:16:46 INFO - 'MOZ_NO_REMOTE': '1', 19:16:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:16:46 INFO - 'NO_EM_RESTART': '1', 19:16:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:16:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:16:46 INFO - 'PWD': '/builds/slave/test', 19:16:46 INFO - 'SHELL': '/bin/bash', 19:16:46 INFO - 'SHLVL': '1', 19:16:46 INFO - 'TERM': 'linux', 19:16:46 INFO - 'TMOUT': '86400', 19:16:46 INFO - 'USER': 'cltbld', 19:16:46 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1478659882.19157-1947756193', 19:16:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:16:46 INFO - '_': '/tools/buildbot/bin/python'} 19:16:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:16:46 INFO - Downloading/unpacking jsonschema==2.5.1 19:16:46 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 19:16:46 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 19:16:46 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 19:16:46 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 19:16:46 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 19:16:46 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 19:16:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:16:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:16:48 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 19:16:48 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 19:16:48 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 19:16:48 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 19:16:48 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 19:16:48 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 19:16:48 INFO - Downloading functools32-3.2.3-2.tar.gz 19:16:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:16:48 INFO - warning: no files found matching '*.txt' 19:16:48 INFO - no previously-included directories found matching 'build' 19:16:48 INFO - no previously-included directories found matching 'dist' 19:16:48 INFO - no previously-included directories found matching '.git*' 19:16:48 INFO - Installing collected packages: jsonschema, functools32 19:16:48 INFO - Running setup.py install for functools32 19:16:48 INFO - warning: no files found matching '*.txt' 19:16:48 INFO - no previously-included directories found matching 'build' 19:16:48 INFO - no previously-included directories found matching 'dist' 19:16:48 INFO - no previously-included directories found matching '.git*' 19:16:48 INFO - Successfully installed jsonschema functools32 19:16:48 INFO - Cleaning up... 19:16:48 INFO - Return code: 0 19:16:48 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:16:48 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')]} 19:16:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:16:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:48 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')]} 19:16:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:16:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:48 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 0x7f92e4b9bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe73ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe70f60>, '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 0xe71d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe721d0>, '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': '652eb03db935cfc1c15ebda0000001ad-1478659882.19157-1947756193', '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 19:16:48 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 19:16:48 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 19:16:48 INFO - Using env: {'DISPLAY': ':0', 19:16:48 INFO - 'HOME': '/home/cltbld', 19:16:48 INFO - 'LANG': 'en_US.UTF-8', 19:16:48 INFO - 'LANGUAGE': 'en_US:en', 19:16:48 INFO - 'LOGNAME': 'cltbld', 19:16:48 INFO - 'MAIL': '/var/mail/cltbld', 19:16:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:16:48 INFO - 'MOZ_NO_REMOTE': '1', 19:16:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:16:48 INFO - 'NO_EM_RESTART': '1', 19:16:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:16:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:16:48 INFO - 'PWD': '/builds/slave/test', 19:16:48 INFO - 'SHELL': '/bin/bash', 19:16:48 INFO - 'SHLVL': '1', 19:16:48 INFO - 'TERM': 'linux', 19:16:48 INFO - 'TMOUT': '86400', 19:16:48 INFO - 'USER': 'cltbld', 19:16:48 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1478659882.19157-1947756193', 19:16:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:16:48 INFO - '_': '/tools/buildbot/bin/python'} 19:16:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:16:48 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:16:48 INFO - Cleaning up... 19:16:48 INFO - Return code: 0 19:16:48 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:16:48 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')]} 19:16:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:16:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:48 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')]} 19:16:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:16:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:48 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 0x7f92e4b9bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe73ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe70f60>, '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 0xe71d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe721d0>, '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': '652eb03db935cfc1c15ebda0000001ad-1478659882.19157-1947756193', '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 19:16:48 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 19:16:48 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 19:16:48 INFO - Using env: {'DISPLAY': ':0', 19:16:48 INFO - 'HOME': '/home/cltbld', 19:16:48 INFO - 'LANG': 'en_US.UTF-8', 19:16:48 INFO - 'LANGUAGE': 'en_US:en', 19:16:48 INFO - 'LOGNAME': 'cltbld', 19:16:48 INFO - 'MAIL': '/var/mail/cltbld', 19:16:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:16:48 INFO - 'MOZ_NO_REMOTE': '1', 19:16:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:16:48 INFO - 'NO_EM_RESTART': '1', 19:16:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:16:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:16:48 INFO - 'PWD': '/builds/slave/test', 19:16:48 INFO - 'SHELL': '/bin/bash', 19:16:48 INFO - 'SHLVL': '1', 19:16:48 INFO - 'TERM': 'linux', 19:16:48 INFO - 'TMOUT': '86400', 19:16:48 INFO - 'USER': 'cltbld', 19:16:48 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1478659882.19157-1947756193', 19:16:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:16:48 INFO - '_': '/tools/buildbot/bin/python'} 19:16:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:16:49 INFO - Downloading/unpacking blobuploader==1.2.4 19:16:49 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 19:16:49 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 19:16:49 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 19:16:49 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 19:16:49 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 19:16:49 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 19:16:51 INFO - Downloading blobuploader-1.2.4.tar.gz 19:16:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:16:51 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:16:51 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 19:16:51 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 19:16:51 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 19:16:51 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 19:16:51 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 19:16:51 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 19:16:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:16:51 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:16:51 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 19:16:51 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 19:16:51 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 19:16:51 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 19:16:51 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 19:16:51 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 19:16:51 INFO - Downloading docopt-0.6.1.tar.gz 19:16:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:16:51 INFO - Installing collected packages: blobuploader, requests, docopt 19:16:51 INFO - Running setup.py install for blobuploader 19:16:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:16:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:16:51 INFO - Running setup.py install for requests 19:16:52 INFO - Running setup.py install for docopt 19:16:52 INFO - Successfully installed blobuploader requests docopt 19:16:52 INFO - Cleaning up... 19:16:52 INFO - Return code: 0 19:16:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19: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')]} 19:16:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:16:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19: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')]} 19:16:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:16:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:52 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 0x7f92e4b9bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe73ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe70f60>, '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 0xe71d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe721d0>, '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': '652eb03db935cfc1c15ebda0000001ad-1478659882.19157-1947756193', '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 19:16:52 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 19:16:52 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 19:16:52 INFO - Using env: {'DISPLAY': ':0', 19:16:52 INFO - 'HOME': '/home/cltbld', 19:16:52 INFO - 'LANG': 'en_US.UTF-8', 19:16:52 INFO - 'LANGUAGE': 'en_US:en', 19:16:52 INFO - 'LOGNAME': 'cltbld', 19:16:52 INFO - 'MAIL': '/var/mail/cltbld', 19:16:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:16:52 INFO - 'MOZ_NO_REMOTE': '1', 19:16:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:16:52 INFO - 'NO_EM_RESTART': '1', 19:16:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:16:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:16:52 INFO - 'PWD': '/builds/slave/test', 19:16:52 INFO - 'SHELL': '/bin/bash', 19:16:52 INFO - 'SHLVL': '1', 19:16:52 INFO - 'TERM': 'linux', 19:16:52 INFO - 'TMOUT': '86400', 19:16:52 INFO - 'USER': 'cltbld', 19:16:52 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1478659882.19157-1947756193', 19:16:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:16:52 INFO - '_': '/tools/buildbot/bin/python'} 19:16:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:16:52 INFO - Running setup.py (path:/tmp/pip-aBMOkJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:16:52 INFO - Running setup.py (path:/tmp/pip-3BbMB3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:16:52 INFO - Running setup.py (path:/tmp/pip-H2FiRj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:16:52 INFO - Running setup.py (path:/tmp/pip-HhSc5f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:16:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:16:52 INFO - Running setup.py (path:/tmp/pip-MOg4ge-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:16:53 INFO - Running setup.py (path:/tmp/pip-OpqHPr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:16:53 INFO - Running setup.py (path:/tmp/pip-6UbG0J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:16:53 INFO - Running setup.py (path:/tmp/pip-bLtBYm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:16:53 INFO - Running setup.py (path:/tmp/pip-VEArOx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:16:53 INFO - Running setup.py (path:/tmp/pip-WrNlnu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:16:53 INFO - Running setup.py (path:/tmp/pip-jArils-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:16:53 INFO - Running setup.py (path:/tmp/pip-_6x6O6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:16:53 INFO - Running setup.py (path:/tmp/pip-Sz7VTY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:16:53 INFO - Running setup.py (path:/tmp/pip-IFbSyj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:16:53 INFO - Running setup.py (path:/tmp/pip-frypnb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:16:53 INFO - Running setup.py (path:/tmp/pip-vf81um-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:16:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:16:53 INFO - Running setup.py (path:/tmp/pip-IE8_0D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:16:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:16:53 INFO - Running setup.py install for manifestparser 19:16:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:16:53 INFO - Running setup.py install for mozcrash 19:16:54 INFO - Running setup.py install for mozdebug 19:16:54 INFO - Running setup.py install for mozdevice 19:16:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:16:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:16:54 INFO - Running setup.py install for mozfile 19:16:54 INFO - Running setup.py install for mozhttpd 19:16:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:16:54 INFO - Running setup.py install for mozinfo 19:16:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:16:54 INFO - Running setup.py install for mozInstall 19:16:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:16:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:16:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:16:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:16:54 INFO - Running setup.py install for mozleak 19:16:54 INFO - Running setup.py install for mozlog 19:16:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:16:54 INFO - Running setup.py install for moznetwork 19:16:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:16:55 INFO - Running setup.py install for mozprocess 19:16:55 INFO - Running setup.py install for mozprofile 19:16:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:16:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:16:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:16:55 INFO - Running setup.py install for mozrunner 19:16:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:16:55 INFO - Running setup.py install for mozscreenshot 19:16:55 INFO - Running setup.py install for moztest 19:16:55 INFO - Running setup.py install for mozversion 19:16:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:16:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:16:55 INFO - Cleaning up... 19:16:55 INFO - Return code: 0 19:16:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:16:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:16:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:16:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:16:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:16:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:55 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 0x7f92e4b9bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe73ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe70f60>, '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 0xe71d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe721d0>, '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': '652eb03db935cfc1c15ebda0000001ad-1478659882.19157-1947756193', '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 19:16:55 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 19:16:55 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 19:16:55 INFO - Using env: {'DISPLAY': ':0', 19:16:55 INFO - 'HOME': '/home/cltbld', 19:16:55 INFO - 'LANG': 'en_US.UTF-8', 19:16:55 INFO - 'LANGUAGE': 'en_US:en', 19:16:55 INFO - 'LOGNAME': 'cltbld', 19:16:55 INFO - 'MAIL': '/var/mail/cltbld', 19:16:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:16:55 INFO - 'MOZ_NO_REMOTE': '1', 19:16:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:16:55 INFO - 'NO_EM_RESTART': '1', 19:16:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:16:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:16:55 INFO - 'PWD': '/builds/slave/test', 19:16:55 INFO - 'SHELL': '/bin/bash', 19:16:55 INFO - 'SHLVL': '1', 19:16:55 INFO - 'TERM': 'linux', 19:16:55 INFO - 'TMOUT': '86400', 19:16:55 INFO - 'USER': 'cltbld', 19:16:55 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1478659882.19157-1947756193', 19:16:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:16:55 INFO - '_': '/tools/buildbot/bin/python'} 19:16:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:16:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:16:55 INFO - Running setup.py (path:/tmp/pip-S_DRMi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:16:55 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)) 19:16:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:16:55 INFO - Running setup.py (path:/tmp/pip-E8Q9Uz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:16:56 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)) 19:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:16:56 INFO - Running setup.py (path:/tmp/pip-pBRiET-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:16:56 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)) 19:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:16:56 INFO - Running setup.py (path:/tmp/pip-QH9IQs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:16:56 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)) 19:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:16:56 INFO - Running setup.py (path:/tmp/pip-1G_KK0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:16:56 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)) 19:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:16:56 INFO - Running setup.py (path:/tmp/pip-cZyr_b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:16:56 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)) 19:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:16:56 INFO - Running setup.py (path:/tmp/pip-dNhRVV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:16:56 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)) 19:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:16:56 INFO - Running setup.py (path:/tmp/pip-y2j597-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:16:56 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)) 19:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:16:56 INFO - Running setup.py (path:/tmp/pip-YmilAv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:16:56 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)) 19:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:16:56 INFO - Running setup.py (path:/tmp/pip-VnOMLE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:16:56 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)) 19:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:16:56 INFO - Running setup.py (path:/tmp/pip-vqeOSk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:16:56 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)) 19:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:16:56 INFO - Running setup.py (path:/tmp/pip-5mpsZR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:16:56 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)) 19:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:16:56 INFO - Running setup.py (path:/tmp/pip-wtRZfk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:16:56 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)) 19:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:16:56 INFO - Running setup.py (path:/tmp/pip-UP_w98-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:16:56 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)) 19:16:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:16:56 INFO - Running setup.py (path:/tmp/pip-t9OpFu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:16:57 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)) 19:16:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:16:57 INFO - Running setup.py (path:/tmp/pip-cBMA54-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:16:57 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)) 19:16:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:16:57 INFO - Running setup.py (path:/tmp/pip-xHF63S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:16:57 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)) 19:16:57 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)) 19:16:57 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)) 19:16:57 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)) 19:16:57 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)) 19:16:57 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)) 19:16:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:16:57 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 19:16:57 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 19:16:57 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 19:16:57 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 19:16:57 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 19:16:57 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 19:16:59 INFO - Downloading blessings-1.6.tar.gz 19:16:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:16:59 INFO - Installing collected packages: blessings 19:16:59 INFO - Running setup.py install for blessings 19:16:59 INFO - Successfully installed blessings 19:16:59 INFO - Cleaning up... 19:16:59 INFO - Return code: 0 19:16:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:16:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:16:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:16:59 INFO - Reading from file tmpfile_stdout 19:16:59 INFO - Current package versions: 19:16:59 INFO - argparse == 1.2.1 19:16:59 INFO - blessings == 1.6 19:16:59 INFO - blobuploader == 1.2.4 19:16:59 INFO - docopt == 0.6.1 19:16:59 INFO - functools32 == 3.2.3-2 19:16:59 INFO - jsonschema == 2.5.1 19:16:59 INFO - manifestparser == 1.1 19:16:59 INFO - mozInstall == 1.12 19:16:59 INFO - mozcrash == 0.17 19:16:59 INFO - mozdebug == 0.1 19:16:59 INFO - mozdevice == 0.48 19:16:59 INFO - mozfile == 1.2 19:16:59 INFO - mozhttpd == 0.7 19:16:59 INFO - mozinfo == 0.9 19:16:59 INFO - mozleak == 0.1 19:16:59 INFO - mozlog == 3.4 19:16:59 INFO - moznetwork == 0.27 19:16:59 INFO - mozprocess == 0.23 19:16:59 INFO - mozprofile == 0.28 19:16:59 INFO - mozrunner == 6.12 19:16:59 INFO - mozscreenshot == 0.1 19:16:59 INFO - mozsystemmonitor == 0.3 19:16:59 INFO - moztest == 0.7 19:16:59 INFO - mozversion == 1.4 19:16:59 INFO - psutil == 3.1.1 19:16:59 INFO - requests == 1.2.3 19:16:59 INFO - wsgiref == 0.1.2 19:16:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:16:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:16:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:16:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:16:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:16:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:59 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 0x7f92e4b9bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe73ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe70f60>, '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 0xe71d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe721d0>, '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': '652eb03db935cfc1c15ebda0000001ad-1478659882.19157-1947756193', '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 19:16:59 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 19:16:59 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 19:16:59 INFO - Using env: {'DISPLAY': ':0', 19:16:59 INFO - 'HOME': '/home/cltbld', 19:16:59 INFO - 'LANG': 'en_US.UTF-8', 19:16:59 INFO - 'LANGUAGE': 'en_US:en', 19:16:59 INFO - 'LOGNAME': 'cltbld', 19:16:59 INFO - 'MAIL': '/var/mail/cltbld', 19:16:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:16:59 INFO - 'MOZ_NO_REMOTE': '1', 19:16:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:16:59 INFO - 'NO_EM_RESTART': '1', 19:16:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:16:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:16:59 INFO - 'PWD': '/builds/slave/test', 19:16:59 INFO - 'SHELL': '/bin/bash', 19:16:59 INFO - 'SHLVL': '1', 19:16:59 INFO - 'TERM': 'linux', 19:16:59 INFO - 'TMOUT': '86400', 19:16:59 INFO - 'USER': 'cltbld', 19:16:59 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1478659882.19157-1947756193', 19:16:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:16:59 INFO - '_': '/tools/buildbot/bin/python'} 19:16:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:16:59 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)) 19:16:59 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)) 19:16:59 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)) 19:16:59 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)) 19:16:59 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)) 19:16:59 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)) 19:16:59 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)) 19:16:59 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)) 19:16:59 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)) 19:16:59 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)) 19:16:59 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)) 19:16:59 INFO - Cleaning up... 19:16:59 INFO - Return code: 0 19:16:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:16:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:16:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:16:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:16:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:16:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:16:59 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 0x7f92e4b9bf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xda68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0xe73ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0xe70f60>, '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 0xe71d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe721d0>, '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': '652eb03db935cfc1c15ebda0000001ad-1478659882.19157-1947756193', '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 19:16:59 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 19:16:59 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 19:16:59 INFO - Using env: {'DISPLAY': ':0', 19:16:59 INFO - 'HOME': '/home/cltbld', 19:16:59 INFO - 'LANG': 'en_US.UTF-8', 19:16:59 INFO - 'LANGUAGE': 'en_US:en', 19:16:59 INFO - 'LOGNAME': 'cltbld', 19:16:59 INFO - 'MAIL': '/var/mail/cltbld', 19:16:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:16:59 INFO - 'MOZ_NO_REMOTE': '1', 19:16:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:16:59 INFO - 'NO_EM_RESTART': '1', 19:16:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:16:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:16:59 INFO - 'PWD': '/builds/slave/test', 19:16:59 INFO - 'SHELL': '/bin/bash', 19:16:59 INFO - 'SHLVL': '1', 19:16:59 INFO - 'TERM': 'linux', 19:16:59 INFO - 'TMOUT': '86400', 19:16:59 INFO - 'USER': 'cltbld', 19:16:59 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1478659882.19157-1947756193', 19:16:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:16:59 INFO - '_': '/tools/buildbot/bin/python'} 19:16:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:16:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:16:59 INFO - Cleaning up... 19:17:00 INFO - Return code: 0 19:17:00 INFO - Running post-action listener: _resource_record_post_action 19:17:00 INFO - Running post-action listener: _start_resource_monitoring 19:17:00 INFO - Starting resource monitoring. 19:17:00 INFO - [mozharness: 2016-11-09 03:17:00.036772Z] Finished create-virtualenv step (success) 19:17:00 INFO - [mozharness: 2016-11-09 03:17:00.037275Z] Running install step. 19:17:00 INFO - Running pre-action listener: _resource_record_pre_action 19:17:00 INFO - Running main action method: install 19:17:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:17:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:17:00 INFO - Reading from file tmpfile_stdout 19:17:00 INFO - Detecting whether we're running mozinstall >=1.0... 19:17:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:17:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:17:00 INFO - Reading from file tmpfile_stdout 19:17:00 INFO - Output received: 19:17:00 INFO - Usage: mozinstall [options] installer 19:17:00 INFO - Options: 19:17:00 INFO - -h, --help show this help message and exit 19:17:00 INFO - -d DEST, --destination=DEST 19:17:00 INFO - Directory to install application into. [default: 19:17:00 INFO - "/builds/slave/test"] 19:17:00 INFO - --app=APP Application being installed. [default: firefox] 19:17:00 INFO - mkdir: /builds/slave/test/build/application 19:17:00 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'] 19:17:00 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 19:17:16 INFO - Reading from file tmpfile_stdout 19:17:16 INFO - Output received: 19:17:16 INFO - /builds/slave/test/build/application/firefox/firefox 19:17:16 INFO - Running post-action listener: _resource_record_post_action 19:17:16 INFO - [mozharness: 2016-11-09 03:17:16.322139Z] Finished install step (success) 19:17:16 INFO - [mozharness: 2016-11-09 03:17:16.322380Z] Running run-tests step. 19:17:16 INFO - Running pre-action listener: _resource_record_pre_action 19:17:16 INFO - Running main action method: run_tests 19:17:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:17:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:17:16 INFO - Python 2.7.3 19:17:16 INFO - Return code: 0 19:17:16 INFO - grabbing minidump binary from tooltool 19:17: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')]} 19:17:16 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 0xe70f60>, '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 0xe71d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0xe721d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:17:16 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 19:17:16 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 19:17:16 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 19:17:16 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:17:16 INFO - Return code: 0 19:17:16 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:17:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:17:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:17:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:17:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:17:16 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/X7JcpTgmTr-y-M7YNHnBKQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--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 19:17:16 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/X7JcpTgmTr-y-M7YNHnBKQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-046 --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 19:17:16 INFO - Using env: {'DISPLAY': ':0', 19:17:16 INFO - 'HOME': '/home/cltbld', 19:17:16 INFO - 'LANG': 'en_US.UTF-8', 19:17:16 INFO - 'LANGUAGE': 'en_US:en', 19:17:16 INFO - 'LOGNAME': 'cltbld', 19:17:16 INFO - 'MAIL': '/var/mail/cltbld', 19:17:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:17:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:17:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:17:16 INFO - 'MOZ_NO_REMOTE': '1', 19:17:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:17:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:17:16 INFO - 'NO_EM_RESTART': '1', 19:17:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:17:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:17:16 INFO - 'PWD': '/builds/slave/test', 19:17:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:17:16 INFO - 'SHELL': '/bin/bash', 19:17:16 INFO - 'SHLVL': '1', 19:17:16 INFO - 'TERM': 'linux', 19:17:16 INFO - 'TMOUT': '86400', 19:17:16 INFO - 'USER': 'cltbld', 19:17:16 INFO - 'XDG_SESSION_COOKIE': '652eb03db935cfc1c15ebda0000001ad-1478659882.19157-1947756193', 19:17:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:17:16 INFO - '_': '/tools/buildbot/bin/python'} 19:17:16 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/X7JcpTgmTr-y-M7YNHnBKQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-046', '--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 19:17:16 INFO - mozversion application_buildid: 20161108173621 19:17:16 INFO - mozversion application_changeset: ae3b6bfb810476141647ad681b796ed118062412 19:17:16 INFO - mozversion application_display_name: FirefoxDeveloperEdition 19:17:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:17:16 INFO - mozversion application_name: Firefox 19:17:16 INFO - mozversion application_remotingname: firefox-dev 19:17:16 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 19:17:16 INFO - mozversion application_vendor: Mozilla 19:17:16 INFO - mozversion application_version: 51.0a2 19:17:16 INFO - mozversion platform_buildid: 20161108173621 19:17:16 INFO - mozversion platform_changeset: ae3b6bfb810476141647ad681b796ed118062412 19:17:16 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 19:17:16 INFO - mozversion platform_version: 51.0a2 19:17:16 INFO - using testdate: 1478661436 19:17:16 INFO - actual date: 1478661436 19:17:16 INFO - starting webserver on 'localhost:49356' 19:17:16 INFO - SUITE-START | Running 2 tests 19:17:16 INFO - TEST-START | dromaeo_css 19:17:16 INFO - operating with platform_type : linux_ 19:17:16 INFO - Initialising browser for dromaeo_css test... 19:17:16 INFO - TEST-INFO | started process 23681 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0W5C5C/profile http://localhost:49356/getInfo.html) 19:17:20 INFO - PROCESS | 23681 | __metrics Screen width/height:1600/1200 19:17:20 INFO - PROCESS | 23681 | colorDepth:24 19:17:20 INFO - PROCESS | 23681 | Browser inner width/height: 1024/697 19:17:20 INFO - PROCESS | 23681 | __metrics 19:17:21 INFO - PROCESS | 23681 | 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" 19:17:21 INFO - PROCESS | 23681 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:17:21 INFO - PROCESS | 23681 | console.error: PushService: 19:17:21 INFO - PROCESS | 23681 | stateChangeProcessEnqueue: Error transitioning state 19:17:21 INFO - PROCESS | 23681 | UnknownError 19:17:22 INFO - TEST-INFO | 23681: exit 0 19:17:22 INFO - Browser initialized. 19:17:22 INFO - Running cycle 1/1 for dromaeo_css test... 19:17:22 INFO - TEST-INFO | started process 23770 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp0W5C5C/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:17:33 INFO - PROCESS | 23770 | RSS: Main: 144994304 19:17:33 INFO - PROCESS | 23770 | 19:18:25 INFO - PROCESS | 23770 | 1478661505056 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] 19:20:34 INFO - PROCESS | 23770 | Cycle 1(1): loaded http://localhost:49356/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49356/tests/dromaeo/cssquery-ext.html) 19:20:35 INFO - PROCESS | 23770 | RSS: Main: 244101120 19:20:35 INFO - PROCESS | 23770 | 19:23:37 INFO - PROCESS | 23770 | Cycle 1(1): loaded http://localhost:49356/tests/dromaeo/cssquery-ext.html (next: http://localhost:49356/tests/dromaeo/cssquery-jquery.html) 19:23:38 INFO - PROCESS | 23770 | RSS: Main: 226680832 19:23:38 INFO - PROCESS | 23770 | 19:26:40 INFO - PROCESS | 23770 | Cycle 1(1): loaded http://localhost:49356/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49356/tests/dromaeo/cssquery-mootools.html) 19:26:40 INFO - PROCESS | 23770 | RSS: Main: 177299456 19:26:40 INFO - PROCESS | 23770 | 19:29:42 INFO - PROCESS | 23770 | Cycle 1(1): loaded http://localhost:49356/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49356/tests/dromaeo/cssquery-prototype.html) 19:29:43 INFO - PROCESS | 23770 | RSS: Main: 225673216 19:29:43 INFO - PROCESS | 23770 | 19:32:46 INFO - PROCESS | 23770 | Cycle 1(1): loaded http://localhost:49356/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49356/tests/dromaeo/cssquery-yui.html) 19:32:46 INFO - PROCESS | 23770 | RSS: Main: 175632384 19:32:46 INFO - PROCESS | 23770 | 19:35:49 INFO - PROCESS | 23770 | Cycle 1(1): loaded http://localhost:49356/tests/dromaeo/cssquery-yui.html (next: http://localhost:49356/tests/dromaeo/cssquery-dojo.html) 19:35:49 INFO - PROCESS | 23770 | RSS: Main: 267853824 19:35:49 INFO - PROCESS | 23770 | 19:35:49 INFO - PROCESS | 23770 | __start_tp_report 19:35:49 INFO - PROCESS | 23770 | _x_x_mozilla_page_load 19:35:49 INFO - PROCESS | 23770 | _x_x_mozilla_page_load_details 19:35:49 INFO - PROCESS | 23770 | |i|pagename|runs| 19:35:49 INFO - PROCESS | 23770 | |0;dojo.html;3134.3574567213723;3172;3180.157258326544;3200.023992682231;3221.693939075787;5537.390887002426;5621;5639.802606908759;5641.661500309984;5648.096304591261;5691.772329106841;5744.971275143629;5754.079347304432;5757.712114394284;5794.710264486769;5148.330717006793;5173.818916337929;5202.193659982709;5209.426963034063;5254.369475662926;5039.974800126012;5238.188080847474;5248.475152484759;5255.132903071008;5269.683818970874;5053;5086.542211200991;5122.871928201787;5127.743612819363;5128.205128205115;5143.691378517282;5159.974200129013;5166.664166829163;5221.477852214748;5243.580513558906;5069.873253168664;5107.208382700687;5188.299579556749;5210.426853046162;5228.320318358627;5377.247185394048;5415.0523658359625;5534.363548191969;5535.501804837584;5593.552515798767;5982.162497250389;5995.940040599626;6003.579749417542;6102.725377357985;6105.419985101408;6122.571420000557;6124.295705993779;6136.079588061782;6139.2325959255095;6143.877122457526;5479.75341109651;5530.944690553123;5562.276904002454;5625.015622266087;5629.43705629442;6135.693215339215;6168.167297414881;6168.814935551941;6172.043333283258;6182.938170618326;5923.348431672513;5936.5547583931375;5938.168656388109;5939.940600594025;5940.524758019348;5828.6502809831545;5867.471927526543;5969.611975221596;6014.969925150346;6025.578209525379;6041.879162416727;6052.57631965767;6054.8183554493435;6057.363976782374;6062.9996050652035;5914.9112763308585;5978.043513037893;5978.222831031938;5978.312494063239;6001.789937352259;4532.79602417892;4581.816727330869;4600.95399046012;4635.3742244797195;4647.512011238839;4140.60664236897;4167.06241095751;4188.874333769992;4203.327467605169;4213.536510983789;5765.4811067004175;5784.363719990798;5794.420557944256;5794.62334948227;5803.738831752581;5834.358220595731;5884.47039766423;5971.313298970587;6107.755689772448;6110.786122485781;5675.177099320658;5756.769729210869;5759.769609215668;5761.971190144106;5788.131780232915;5284.233786101071;5289.20661900718;5292.497212764858;5294.682319060792;5296.973515132477;6040.36576159497;6057.454829065406;6231.158793562811;6259.71831267594;6293.905591416041;5946.226990491208;5979.880402392015;6028.728707208274;6098.542609304409;6142.9078563820685;6231.345708700521;6269.5611307208055;6283.74865005413;6286;6287.497000239879;5745.827625171251;5985;5989.1914591529585;5993.640381577119;6015.338312785678;5798.420157984167;5971.104334349882;6033.396660333931;6057.515398768;6137.447629713347;5919.585629005928;5961.672108034099;5965.045592705146;5970.223870896755;5973.581849270683;5944.048952167633;5951.494122999594;5956.017257152521;5958.136070269873;5958.463738263577;5612.354579223442;5723.914141287801;5784.942150578609;5847.152162936265;5849.210356601804;5987.790427335022;5989.9700501498955;5990.7004649768205;5999;6004.339522652592;5549.750261238335;5791.362950075572;5825.825225243249;5842.766289348378;5843.503302219359 19:35:49 INFO - PROCESS | 23770 | |1;ext.html;585.5432762445295;616.4236438929602;617.2993652204747;622.3807311724835;644.0243031806812;35866.82066589667;39062;47657.76171119139;48552.51447485524;48559.27161092586;50183.49816501834;50627.49372506283;50639.746801265945;50703.49296507034;50714.492855071534;9331.53342332884;9479.099485548863;9495.240380769557;9496.192823610001;9497.95251023744;8536.70121545746;8734.257588105018;10853.294535855184;11126.833097503504;11136.053435457996;11381.2033157679;11463.828042579367;11466.312021278709;11480.540778368897;11538.769224615502;8575.656973721043;11220.214584979054;11285.210035297532;11291;11303.095752339794;8470.49177049379;10924.126069914388;11374.60188893393;11375.715607109805;11391.031762300214;45453;45797.54202457966;45855.77072114618;45884;45907;3508.105433114565;3542.3800834853796;3647.598764135943;3686.3548878946076;3704.7221458390727;34779.30441391158;34881;35237.64762352369;36136.81931590325;36224.09439764001;4406.955930440687;4598.471175814757;4614.261718125117;4615.699979501322;4692.8122875084955;384658;386343;386435;387005;387178;39530;67287.66356168188;67688.6615566919;67716.6614166926;67804.6609766948;39356.212875742895;41835;42062;42077.579224207366;42085.1582968345;17810.91094544545;17985.460436186935;17991.550211244823;17997.640047199246;18005;13748.587542373545;14097;14195.077319974374;14242.85757142436;14252.501162459264;9096;9503.19222866064;9508.572114254875;9615.471149086727;9807.705768826947;3522.260325331709;3564.8752293669595;3603.3513967486106;3606;3643.398839191557;1039.7244730146517;1042.3641578637025;1057.7461409261875;1065.8454524094118;1069.1244239631337;35017.649823501946;35040.29919401598;35181.29637407289;35259.64740352564;35431.11422214414;1138.0099313597223;1154.4774667351862;1176.923499972499;1209.625016244967;1217.7990631545695;18177;18189.7271540927;18290.63418731618;18305.359312424;18377.356792512466;532941;540038;540208;540991;541074;64591;64925;65023;65087.34912650813;65325;1314.4150852870573;1320.7754681704134;1381.7650999330137;1398.6643205630576;1425.6720954180596;31898;36053;36235.27529449449;36320.63679363278;36558.63441365552;55573.722131389084;56243;56364;56386.1542076861;56436;405075;406742;406989;407325;407948;31189.53215701721;31905.521417179225;31998.52002220015;32256.677433225366;33867.322653547286;52030.479695202564;52590.474095258556;52783.47216527785;53094;53435.46564534305;43124.13751725011;43407.78296108625;43561;43794;45086.098278034915;43866.78066609646;43881.56118438902;44903;45717.31424028709;46489.767551162025;43214.78392608017;43928;44398.778006109766;44996;45048.54951450443;36832.44751328679;38684.41973370458;38798.2240355197;39054.6094539051;39671.2065758689;28679.569806452502;29135.70864291415;29146.56280155757;29316.56025159667;29328.41343173167 19:35:49 INFO - PROCESS | 23770 | |2;jquery.html;396.6212267284743;411.86408485199905;427.0753817984064;427.8181772746583;441.88069221310246;14612.269386530685;16422;19680.99999999998;20418.69371959422;21291.25480608175;20177.09203085861;20256.493587660316;20616.38150855473;20765.896170519165;20801.791982080176;5962.403759624046;6015.548833837469;6066.969665151668;6095.878082438348;6099;4458.152950939328;6060.424259695322;6132.570720049598;6152.753889844401;6154.630722156685;4630.467496237925;4656.627469802408;6800.659967001655;6912.308769123098;6920.238773734885;5864.70676466175;6103.725332360054;6919.377256046955;6926.68829902653;6932.584044957319;6028.457438830483;6033.245844269466;6041.063635136561;6046.09308603713;6046.123312119718;16580.50258492235;22826.31521054371;24221;24560.754392456205;24775.380615484577;2311.179531266393;2381.618940969454;2402.8558286502866;2503.849769013865;3046.451638705032;10591.205659575562;10775.943843672949;10935.726606834813;11069.059129973963;11118.888811111867;1954.6188493243824;1996.4110587376758;2027.6755719084877;2042.8570000099999;2065.4320061983076;78761;82764.58617706933;85242.57378713066;85371;85825.57087214586;11642.94178529102;11828.645140645795;11952.940235298767;12010.399480025964;12068.577599784141;16702.665946680998;16728.41450549225;16739.414120505724;16760.58098547546;16774.664506709796;8982.550872456351;9031.232345250728;9115.544222788834;9158;9262.259019278441;8077.7576672699915;8353.624086916103;8378.748637540884;8582.055973842873;9269.90730092704;5036.899262014813;5123.154679477844;5139.794408223629;5140.149546163379;5280.260763493114;2029.8477614178996;2041.57127003328;2043.8467114966436;2072.782357852434;2076.709260703503;445.24530920090785;466.8856130248111;1003.9408424112631;1057.8677665291839;1060.5015642647943;12093.637190884112;12206.267623942593;12508.124431289722;12610.243385396905;12613.117081804186;469.78859513219186;475.19928919770297;477.26024661774113;477.2650118817052;482.81411656512495;6510.7721229756735;6681.76613818514;6704.497162712816;6728.3271672832325;6893.586384816927;512754;515718;531484;532005;541391;60250.39749602448;92221;93275;104619.476902615;107065.46467267614;1033.3076838518211;1081.8647669041213;1093.2238110941162;1109.8945600167972;1115.676453828397;17867.553311166805;18107.275708971494;18350.541236469195;18572.44282671522;18613.348532801287;45601.54398456106;45728.45480495681;45802.62592122242;46221.53778462172;46302;62921.370786291554;63083.36916630959;63600.363996361295;63928;64451;5603.691796951044;7441.255874412515;7500.949867018731;8209.876851847108;8430.241278284984;11931.880681193077;11938;12033.916947474769;12187.634370968884;12393.194442361219;10701.571937122428;10721.35671859691;10728.470212553575;10782.622608208674;10816.351018938574;10213.540390682288;10349.793004140025;10767.730806729895;10916.181286403711;10930;9070.002299746891;10617.840732388866;10746.83879741789;10821.783564328827;10898.400587967737;8743.781405464913;8778.209961103532;8834.95582522109;8920.955395222982;9014.374434790292;9543.570539325887;10445.425501597485;10837;10916.78166436651;10920.18098642589 19:35:49 INFO - PROCESS | 23770 | |3;mootools.html;1429.563982985189;1500.8199016118067;1537.3389442539708;1703.6252024554594;1845.7508236388085;7866.134725180233;8623.956880215597;8815.250703690179;8816.911830881698;9164.129407706274;8944.195022447979;8963.46219226847;8985.820283594325;9019.95490022549;9026.864597031034;2443.6334549817493;2462.4213309872166;2465.716442609105;2466.70399552054;2468.728439871613;2385.0698227691214;2473.0973194783865;2487.44032592667;2490.5766019776593;2495.1142344467726;2398.9880050599722;2419.4314336131083;2466.9259922202364;2469.5307891500647;2475.801935845137;2337.0768546424238;2350.753170917055;2351.0478256306224;2353.1646265575732;2358.2453614843257;2360.5632957902844;2408;2415.613501839697;2416.32342943976;2416.9154079607306;8769.254613357873;8786.209241168262;8876.600552975131;8972.147645973622;9025.593848276778;5394.083005888969;5555.916661250085;5560.944390556083;5561.555075593943;5567.081431563827;3280.7539434542505;3298.24140447696;3338.232206592473;3342.4317865962844;3358.7984720916825;1063.622414043019;1090.7000574841902;2061.2476446097216;2111.2399536167122;2119.7456305243313;12162.148649594532;12202.389880506027;12417.379131043503;12422.875771242263;12442.875571244353;4479.395281637003;4567.725936443824;4678.2982552616695;4695.084458530554;4749.287606858929;9819.607215711292;9824.214062874953;9844.458554779556;9848.310618256653;9852.556634951445;5466.426025267369;5480.232767412565;5483.095289277225;5483.259759932438;5494.670319780826;4497.887552811206;4608.631309495232;4895.094407534618;4990.051890140861;5117.462666419972;3988.3419235826345;3998.020484981198;4068.16602596467;4098.754074755524;4103.958960410418;1227.5948936850818;1234.21010553246;1236.7093732972744;1255.0712472770085;1282.3075539208724;935.8736570563022;938.8169306985072;954.5704433005176;957.8467445208734;968.7723385004518;5783.537317014629;5794.333651630032;5820.156077368758;5836.88326233473;6015.1278064681255;480.88218386494833;481.9686720363165;482;485.6527582778331;489.0414787017415;3864.7874366909527;4029.3553031514816;4044.352903535479;4050.09087061517;4087.836486540505;10842.512086956105;11114.833277500844;11200.719982000515;11212.775744485065;11307.886921130848;11089.722756931142;11104.833427498594;11118.833217501744;11120;11181.720456988478;8339.54132522717;8416.663333466593;8417.158284171534;8419.747407577783;8434.24091831738;5654.491095801398;5765.509931655856;5796.884062318814;5820.854478638068;5906.143609176731;10163.237757168094;10236.129928955825;10272.229582781172;10282.280240383401;10337.689869303933;9716.805663886542;10168.898311016796;10175.236857235588;10410.1671866249;10477.63328283537;7072.646367681493;7245.347918687344;7555.24447555262;7928.643211055632;8114.715984940499;8344.749657510285;8507.064222935596;8635.309175265838;8735.475871447734;8740.650373984969;7329.377002954595;7424.220456852169;7503.662335195038;7589.203133670885;7669.88495172584;6997.090378250795;7065.22282548909;7099;7318.926810731825;7371.041764570571;6553.868922621617;6709.865802683821;6727.461803055646;6729.327067293231;6740.595564266159;5007.599392048554;5012.223105418666;5017.0216807722145;5058.974705126598;5069.898602028013;4798.520147985174;4883.340748998839;4955.058538877602;5042.520960508746;5043.167877300204 19:35:49 INFO - PROCESS | 23770 | |4;prototype.html;324.03223014942023;338.5311343788852;341.8512946868114;345.35074060765754;347.3487211478478;5532.28080349554;5544.140658197981;5564.38632796489;5611.438856114387;5636.985342638329;5523.6409633373805;5524.806631767889;5534.75093620787;5540.196671482632;5545.611807173499;4331.4802223733195;4336.913261734771;4356.499002614708;4365.388845561608;4375.234333991555;3827.158025234444;4053.8986525336954;4217.578242175789;4342.978285108586;4349.630281426067;4278.229918614648;4283.678724095706;4292.61366477017;4294.763787991659;4297.613214810666;4305.63402110821;4325.502567204749;4334.569592034621;4335.761533115661;4348.717333373353;3606.4229723244157;3632.8728494502566;3679.3809830861724;3799.848006079781;3835.0412396900774;5371.597130215249;5374.758135883894;5377.30614856319;5401.4868587484125;5422.75597598109;4897.265410188465;4898.804047838083;4904.215325547931;4914.557689807934;4935.80256789728;5634.295713035871;5697.4587414195585;5713.914291285634;5724.4561766632105;5729.914051289234;4129.587041295877;4138.8344466221315;4148.605882441163;4155.542890282067;4193.20329137466;5773.884522309572;5950.404959504015;6068;6074.967255566565;6079.483243924272;4036.394540818842;4243.830246790094;4303;4307.956920430818;4343.522212556616;4389.9025243689075;4391.407160033418;4415.315626077963;4417.779111044435;4444.200043992112;4885.9022819544125;4954.529319714621;4997.875053123701;5014.974925125351;5055.317532133115;5806.767729290781;5811.314718731605;5816.360200377955;5822.854428639318;5835.44563266489;1499.8350181480027;1517.370291329108;1524.7941527893593;1528.4039224702376;1539.7844301797757;935.7239614313794;977.398899676708;978.2369751593768;984.8571957066185;986.7335819328741;4952;4980.2778597103215;4991.825286115041;5032.84901452957;5036.622253330942;5212.921806172911;5235.424103348629;5254.028004819121;5282.234076059027;5300.973495132577;5054.873628159326;5055.646104772631;5092.949070509321;5312.335958005249;5698.857528561736;3194.34515924235;3221.098092534094;3221.6803901191106;3231.5152727091127;3236.1747754322496;6090.939090609126;6201.015674983962;6252.187215661935;6253.6560489172625;6259.937400626027;6118.724657390429;6125.479334256549;6137.667686279275;6142.293636231891;6147.200863887666;6162.198914141222;6162.445379915829;6167.414095660905;6171.722272497748;6242.937570624235;5754.223179870663;5924.4667979882015;5978.252718410199;5981.641101533922;6026.487748541248;5788.507976821849;5833.928955221005;5899.321578018583;5906.527477801851;5907.700305932688;5735.770569177186;5888.499477544286;5967.687108836049;6020.020090055451;6155.446009859134;4290;4321.956780432155;4330.35044743291;4350.282203436397;4355.845700889204;5812.883742325045;5973.312009595787;6010.639361638316;6020.608660437058;6033;5776.971115144397;5786.537077033911;5788;5804.738786754522;5828.76503540539;5781.21953536284;5781.942180578309;5801.6519008859605;5809.215755873072;5810.622309549865;5476.315460567429;5765.682887441231;5823.7379317929945;5831.7667293307795;5851.385604511465;5888.848361904761;5893.941060589511;5910.52715782745;5932.139839723302;5941.168236446814;5579.191017302387;5768.336641286306;5796.985527532749;5805.738741756715;5829.387914268941 19:35:49 INFO - PROCESS | 23770 | |5;yui.html;718.5078221418325;721.3039416962633;771.6836097200149;773.3658400111909;778.0313509680446;15908;16909.492715218534;17126.400575979842;17137.14314284287;17244.13779311034;15633.687326253497;15812.762808557853;16398.098104604243;17160.570985725364;17188.140592970332;713.7930000299907;737.8634952533772;738.3428748413916;828.9668413263462;858.9269912057482;855.4867079752161;868.8957325120995;870.8301881133182;871.1984973824066;871.5337294547419;922.3267015078997;922.6032805893464;922.6540047482194;924.085155695863;924.4037595750737;755.5844285642875;775.8021704465382;871.6121326009896;902.0933961368848;910.526526206369;660.0561197487591;664.6078813500059;666.0774826864779;666.0974379715465;666.796627028756;14168.079074860101;14168.149911005372;14183.929080354636;14193.503227387095;14210.076345037643;14180;14297.428102875872;14396.712065758731;14398.712025759427;14485.78271325931;13793.931030344884;13940.93029534856;13983.79024314636;14021.369038393195;14274.429022839075;4583.931241031387;4603.5166307537565;4682.906341873178;4740.312654665088;4749.073930583538;90155.54922225478;92014;92424.07575924222;93517;93526.53236733841;11189.496472658588;11437.428128593703;11464.713382165513;11647.359395233174;11946.88053119475;21069.15723371048;22238.221662242067;22290.777092229193;22291.665625015637;22327.888360558092;8592.355573332025;8596.8710469343;8619.051904290523;8819.955900220586;8934.28525717941;4784.617230621542;4824.7682691305135;4946.1344264753525;4956.083124621957;4980.452150263468;3569.375359312162;3741.1956429367815;3741.775493470346;3767.2653832502397;3772.7170462214895;591.6657088744845;623.1369553168803;627.7802769030802;628.1174949196386;629.1914889367141;698.7274962764526;722.0757430488877;766.9769906902802;768.5964868444112;801.6953557648174;10096.511505988929;10233.48832558369;10286.796401963502;11159.721006974729;11177.83233251502;723.5730918757959;743.7917383132732;783.1189911349732;792.7700966719586;797.8882956386111;4542.251233275213;4664.440267167962;4796.184648609745;4841.419029716386;4855.951440485621;104678;126358;126513;126936;127082.36458817832;94492;96353;96911.51544242374;98988;100719;57097;57279.42720572741;58558.41441585615;58871;60536.697316513135;9290.72127836166;9459.084604715727;9529.666461673809;9639.759006024906;9732.07545283197;53065.7346713264;53692.73153634207;54382;55139.897202056534;55445;75421;85500;86616;87010.56494717486;88590.55704721693;12706.682332941751;13031.348432578143;13270.27013514266;13386.397612107285;13428.664283392602;14245.501407450689;14311.642208944859;14382.280885955452;14464.493742718954;15771.44799931997;11984.28094314344;12031.204911589386;12134.33261170644;12399.070069744625;12703.555375561811;11424.600138995427;11953.043756499634;12301.138920275494;12452.439640216393;12660.683482913;12060;12234.694132646755;12356.135070545335;12460.87539124597;13159.60521184366;10610.416427096583;10690.305130166515;10824.56701731922;10891.18316126278;10964.506597202986;10417.270791044553;10880.619178328721;10997.285176463824;11250.718732031764;11529.884701152881 19:35:49 INFO - PROCESS | 23770 | __end_tp_report 19:35:49 INFO - PROCESS | 23770 | __start_cc_report 19:35:49 INFO - PROCESS | 23770 | _x_x_mozilla_cycle_collect,855 19:35:49 INFO - PROCESS | 23770 | __end_cc_report 19:35:49 INFO - PROCESS | 23770 | __startTimestamp1478662549373__endTimestamp 19:35:49 INFO - PROCESS | 23770 | 19:35:49 INFO - PROCESS | 23770 | ------- Summary: start ------- 19:35:49 INFO - PROCESS | 23770 | Number of tests: 6 19:35:49 INFO - PROCESS | 23770 | 19:35:49 INFO - PROCESS | 23770 | [#0] dojo.html Cycles:165 Average:5611.13 Median:5825.83 stddev:637.17 (10.9%) stddev-sans-first:608.78 19:35:49 INFO - PROCESS | 23770 | Values: 3134.4 3172.0 3180.2 3200.0 3221.7 5537.4 5621.0 5639.8 5641.7 5648.1 5691.8 5745.0 5754.1 5757.7 5794.7 5148.3 5173.8 5202.2 5209.4 5254.4 5040.0 5238.2 5248.5 5255.1 5269.7 5053.0 5086.5 5122.9 5127.7 5128.2 5143.7 5160.0 5166.7 5221.5 5243.6 5069.9 5107.2 5188.3 5210.4 5228.3 5377.2 5415.1 5534.4 5535.5 5593.6 5982.2 5995.9 6003.6 6102.7 6105.4 6122.6 6124.3 6136.1 6139.2 6143.9 5479.8 5530.9 5562.3 5625.0 5629.4 6135.7 6168.2 6168.8 6172.0 6182.9 5923.3 5936.6 5938.2 5939.9 5940.5 5828.7 5867.5 5969.6 6015.0 6025.6 6041.9 6052.6 6054.8 6057.4 6063.0 5914.9 5978.0 5978.2 5978.3 6001.8 4532.8 4581.8 4601.0 4635.4 4647.5 4140.6 4167.1 4188.9 4203.3 4213.5 5765.5 5784.4 5794.4 5794.6 5803.7 5834.4 5884.5 5971.3 6107.8 6110.8 5675.2 5756.8 5759.8 5762.0 5788.1 5284.2 5289.2 5292.5 5294.7 5297.0 6040.4 6057.5 6231.2 6259.7 6293.9 5946.2 5979.9 6028.7 6098.5 6142.9 6231.3 6269.6 6283.7 6286.0 6287.5 5745.8 5985.0 5989.2 5993.6 6015.3 5798.4 5971.1 6033.4 6057.5 6137.4 5919.6 5961.7 5965.0 5970.2 5973.6 5944.0 5951.5 5956.0 5958.1 5958.5 5612.4 5723.9 5784.9 5847.2 5849.2 5987.8 5990.0 5990.7 5999.0 6004.3 5549.8 5791.4 5825.8 5842.8 5843.5 19:35:49 INFO - PROCESS | 23770 | 19:35:49 INFO - PROCESS | 23770 | [#1] ext.html Cycles:180 Average:61593.14 Median:39058.30 stddev:118866.39 (304.3%) stddev-sans-first:119111.58 19:35:49 INFO - PROCESS | 23770 | Values: 585.5 616.4 617.3 622.4 644.0 35866.8 39062.0 47657.8 48552.5 48559.3 50183.5 50627.5 50639.7 50703.5 50714.5 9331.5 9479.1 9495.2 9496.2 9498.0 8536.7 8734.3 10853.3 11126.8 11136.1 11381.2 11463.8 11466.3 11480.5 11538.8 8575.7 11220.2 11285.2 11291.0 11303.1 8470.5 10924.1 11374.6 11375.7 11391.0 45453.0 45797.5 45855.8 45884.0 45907.0 3508.1 3542.4 3647.6 3686.4 3704.7 34779.3 34881.0 35237.6 36136.8 36224.1 4407.0 4598.5 4614.3 4615.7 4692.8 384658.0 386343.0 386435.0 387005.0 387178.0 39530.0 67287.7 67688.7 67716.7 67804.7 39356.2 41835.0 42062.0 42077.6 42085.2 17810.9 17985.5 17991.6 17997.6 18005.0 13748.6 14097.0 14195.1 14242.9 14252.5 9096.0 9503.2 9508.6 9615.5 9807.7 3522.3 3564.9 3603.4 3606.0 3643.4 1039.7 1042.4 1057.7 1065.8 1069.1 35017.6 35040.3 35181.3 35259.6 35431.1 1138.0 1154.5 1176.9 1209.6 1217.8 18177.0 18189.7 18290.6 18305.4 18377.4 532941.0 540038.0 540208.0 540991.0 541074.0 64591.0 64925.0 65023.0 65087.3 65325.0 1314.4 1320.8 1381.8 1398.7 1425.7 31898.0 36053.0 36235.3 36320.6 36558.6 55573.7 56243.0 56364.0 56386.2 56436.0 405075.0 406742.0 406989.0 407325.0 407948.0 31189.5 31905.5 31998.5 32256.7 33867.3 52030.5 52590.5 52783.5 53094.0 53435.5 43124.1 43407.8 43561.0 43794.0 45086.1 43866.8 43881.6 44903.0 45717.3 46489.8 43214.8 43928.0 44398.8 44996.0 45048.5 36832.4 38684.4 38798.2 39054.6 39671.2 28679.6 29135.7 29146.6 29316.6 29328.4 19:35:49 INFO - PROCESS | 23770 | 19:35:49 INFO - PROCESS | 23770 | [#2] jquery.html Cycles:180 Average:30087.84 Median:427.45 stddev:86874.22 (20324.0%) stddev-sans-first:87089.32 19:35:49 INFO - PROCESS | 23770 | Values: 396.6 411.9 427.1 427.8 441.9 14612.3 16422.0 19681.0 20418.7 21291.3 20177.1 20256.5 20616.4 20765.9 20801.8 5962.4 6015.5 6067.0 6095.9 6099.0 4458.2 6060.4 6132.6 6152.8 6154.6 4630.5 4656.6 6800.7 6912.3 6920.2 5864.7 6103.7 6919.4 6926.7 6932.6 6028.5 6033.2 6041.1 6046.1 6046.1 16580.5 22826.3 24221.0 24560.8 24775.4 2311.2 2381.6 2402.9 2503.8 3046.5 10591.2 10775.9 10935.7 11069.1 11118.9 1954.6 1996.4 2027.7 2042.9 2065.4 78761.0 82764.6 85242.6 85371.0 85825.6 11642.9 11828.6 11952.9 12010.4 12068.6 16702.7 16728.4 16739.4 16760.6 16774.7 8982.6 9031.2 9115.5 9158.0 9262.3 8077.8 8353.6 8378.7 8582.1 9269.9 5036.9 5123.2 5139.8 5140.1 5280.3 2029.8 2041.6 2043.8 2072.8 2076.7 445.2 466.9 1003.9 1057.9 1060.5 12093.6 12206.3 12508.1 12610.2 12613.1 469.8 475.2 477.3 477.3 482.8 6510.8 6681.8 6704.5 6728.3 6893.6 512754.0 515718.0 531484.0 532005.0 541391.0 60250.4 92221.0 93275.0 104619.5 107065.5 1033.3 1081.9 1093.2 1109.9 1115.7 17867.6 18107.3 18350.5 18572.4 18613.3 45601.5 45728.5 45802.6 46221.5 46302.0 62921.4 63083.4 63600.4 63928.0 64451.0 5603.7 7441.3 7500.9 8209.9 8430.2 11931.9 11938.0 12033.9 12187.6 12393.2 10701.6 10721.4 10728.5 10782.6 10816.4 10213.5 10349.8 10767.7 10916.2 10930.0 9070.0 10617.8 10746.8 10821.8 10898.4 8743.8 8778.2 8835.0 8921.0 9014.4 9543.6 10445.4 10837.0 10916.8 10920.2 19:35:49 INFO - PROCESS | 23770 | 19:35:49 INFO - PROCESS | 23770 | [#3] mootools.html Cycles:180 Average:5803.53 Median:2684.50 stddev:3288.46 (122.5%) stddev-sans-first:3281.25 19:35:49 INFO - PROCESS | 23770 | Values: 1429.6 1500.8 1537.3 1703.6 1845.8 7866.1 8624.0 8815.3 8816.9 9164.1 8944.2 8963.5 8985.8 9020.0 9026.9 2443.6 2462.4 2465.7 2466.7 2468.7 2385.1 2473.1 2487.4 2490.6 2495.1 2399.0 2419.4 2466.9 2469.5 2475.8 2337.1 2350.8 2351.0 2353.2 2358.2 2360.6 2408.0 2415.6 2416.3 2416.9 8769.3 8786.2 8876.6 8972.1 9025.6 5394.1 5555.9 5560.9 5561.6 5567.1 3280.8 3298.2 3338.2 3342.4 3358.8 1063.6 1090.7 2061.2 2111.2 2119.7 12162.1 12202.4 12417.4 12422.9 12442.9 4479.4 4567.7 4678.3 4695.1 4749.3 9819.6 9824.2 9844.5 9848.3 9852.6 5466.4 5480.2 5483.1 5483.3 5494.7 4497.9 4608.6 4895.1 4990.1 5117.5 3988.3 3998.0 4068.2 4098.8 4104.0 1227.6 1234.2 1236.7 1255.1 1282.3 935.9 938.8 954.6 957.8 968.8 5783.5 5794.3 5820.2 5836.9 6015.1 480.9 482.0 482.0 485.7 489.0 3864.8 4029.4 4044.4 4050.1 4087.8 10842.5 11114.8 11200.7 11212.8 11307.9 11089.7 11104.8 11118.8 11120.0 11181.7 8339.5 8416.7 8417.2 8419.7 8434.2 5654.5 5765.5 5796.9 5820.9 5906.1 10163.2 10236.1 10272.2 10282.3 10337.7 9716.8 10168.9 10175.2 10410.2 10477.6 7072.6 7245.3 7555.2 7928.6 8114.7 8344.7 8507.1 8635.3 8735.5 8740.7 7329.4 7424.2 7503.7 7589.2 7669.9 6997.1 7065.2 7099.0 7318.9 7371.0 6553.9 6709.9 6727.5 6729.3 6740.6 5007.6 5012.2 5017.0 5059.0 5069.9 4798.5 4883.3 4955.1 5042.5 5043.2 19:35:49 INFO - PROCESS | 23770 | 19:35:49 INFO - PROCESS | 23770 | [#4] prototype.html Cycles:180 Average:4852.82 Median:5524.22 stddev:1421.76 (25.7%) stddev-sans-first:1384.52 19:35:49 INFO - PROCESS | 23770 | Values: 324.0 338.5 341.9 345.4 347.3 5532.3 5544.1 5564.4 5611.4 5637.0 5523.6 5524.8 5534.8 5540.2 5545.6 4331.5 4336.9 4356.5 4365.4 4375.2 3827.2 4053.9 4217.6 4343.0 4349.6 4278.2 4283.7 4292.6 4294.8 4297.6 4305.6 4325.5 4334.6 4335.8 4348.7 3606.4 3632.9 3679.4 3799.8 3835.0 5371.6 5374.8 5377.3 5401.5 5422.8 4897.3 4898.8 4904.2 4914.6 4935.8 5634.3 5697.5 5713.9 5724.5 5729.9 4129.6 4138.8 4148.6 4155.5 4193.2 5773.9 5950.4 6068.0 6075.0 6079.5 4036.4 4243.8 4303.0 4308.0 4343.5 4389.9 4391.4 4415.3 4417.8 4444.2 4885.9 4954.5 4997.9 5015.0 5055.3 5806.8 5811.3 5816.4 5822.9 5835.4 1499.8 1517.4 1524.8 1528.4 1539.8 935.7 977.4 978.2 984.9 986.7 4952.0 4980.3 4991.8 5032.8 5036.6 5212.9 5235.4 5254.0 5282.2 5301.0 5054.9 5055.6 5092.9 5312.3 5698.9 3194.3 3221.1 3221.7 3231.5 3236.2 6090.9 6201.0 6252.2 6253.7 6259.9 6118.7 6125.5 6137.7 6142.3 6147.2 6162.2 6162.4 6167.4 6171.7 6242.9 5754.2 5924.5 5978.3 5981.6 6026.5 5788.5 5833.9 5899.3 5906.5 5907.7 5735.8 5888.5 5967.7 6020.0 6155.4 4290.0 4322.0 4330.4 4350.3 4355.8 5812.9 5973.3 6010.6 6020.6 6033.0 5777.0 5786.5 5788.0 5804.7 5828.8 5781.2 5781.9 5801.7 5809.2 5810.6 5476.3 5765.7 5823.7 5831.8 5851.4 5888.8 5893.9 5910.5 5932.1 5941.2 5579.2 5768.3 5797.0 5805.7 5829.4 19:35:49 INFO - PROCESS | 23770 | 19:35:49 INFO - PROCESS | 23770 | [#5] yui.html Cycles:180 Average:21091.72 Median:4711.61 stddev:30745.48 (652.5%) stddev-sans-first:30793.67 19:35:49 INFO - PROCESS | 23770 | Values: 718.5 721.3 771.7 773.4 778.0 15908.0 16909.5 17126.4 17137.1 17244.1 15633.7 15812.8 16398.1 17160.6 17188.1 713.8 737.9 738.3 829.0 858.9 855.5 868.9 870.8 871.2 871.5 922.3 922.6 922.7 924.1 924.4 755.6 775.8 871.6 902.1 910.5 660.1 664.6 666.1 666.1 666.8 14168.1 14168.1 14183.9 14193.5 14210.1 14180.0 14297.4 14396.7 14398.7 14485.8 13793.9 13940.9 13983.8 14021.4 14274.4 4583.9 4603.5 4682.9 4740.3 4749.1 90155.5 92014.0 92424.1 93517.0 93526.5 11189.5 11437.4 11464.7 11647.4 11946.9 21069.2 22238.2 22290.8 22291.7 22327.9 8592.4 8596.9 8619.1 8820.0 8934.3 4784.6 4824.8 4946.1 4956.1 4980.5 3569.4 3741.2 3741.8 3767.3 3772.7 591.7 623.1 627.8 628.1 629.2 698.7 722.1 767.0 768.6 801.7 10096.5 10233.5 10286.8 11159.7 11177.8 723.6 743.8 783.1 792.8 797.9 4542.3 4664.4 4796.2 4841.4 4856.0 104678.0 126358.0 126513.0 126936.0 127082.4 94492.0 96353.0 96911.5 98988.0 100719.0 57097.0 57279.4 58558.4 58871.0 60536.7 9290.7 9459.1 9529.7 9639.8 9732.1 53065.7 53692.7 54382.0 55139.9 55445.0 75421.0 85500.0 86616.0 87010.6 88590.6 12706.7 13031.3 13270.3 13386.4 13428.7 14245.5 14311.6 14382.3 14464.5 15771.4 11984.3 12031.2 12134.3 12399.1 12703.6 11424.6 11953.0 12301.1 12452.4 12660.7 12060.0 12234.7 12356.1 12460.9 13159.6 10610.4 10690.3 10824.6 10891.2 10964.5 10417.3 10880.6 10997.3 11250.7 11529.9 19:35:49 INFO - PROCESS | 23770 | -------- Summary: end -------- 19:35:49 INFO - PROCESS | 23770 | 19:35:50 INFO - TEST-INFO | 23770: exit 0 19:35:50 INFO - TEST-OK | dromaeo_css | took 1113547ms 19:35:50 INFO - TEST-START | kraken 19:35:50 INFO - operating with platform_type : linux_ 19:35:50 INFO - Initialising browser for kraken test... 19:35:50 INFO - TEST-INFO | started process 24092 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLVICX3/profile http://localhost:49356/getInfo.html) 19:35:54 INFO - PROCESS | 24092 | __metrics Screen width/height:1600/1200 19:35:54 INFO - PROCESS | 24092 | colorDepth:24 19:35:54 INFO - PROCESS | 24092 | Browser inner width/height: 1024/697 19:35:54 INFO - PROCESS | 24092 | __metrics 19:35:54 INFO - PROCESS | 24092 | 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" 19:35:54 INFO - PROCESS | 24092 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:35:55 INFO - PROCESS | 24092 | console.error: PushService: 19:35:55 INFO - PROCESS | 24092 | stateChangeProcessEnqueue: Error transitioning state 19:35:55 INFO - PROCESS | 24092 | UnknownError 19:35:55 INFO - TEST-INFO | 24092: exit 0 19:35:55 INFO - Browser initialized. 19:35:55 INFO - Running cycle 1/1 for kraken test... 19:35:55 INFO - TEST-INFO | started process 24186 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpLVICX3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:36:07 INFO - PROCESS | 24186 | RSS: Main: 145195008 19:36:07 INFO - PROCESS | 24186 | 19:36:09 INFO - PROCESS | 24186 | Cycle 1(1): loaded http://localhost:49356/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49356/tests/kraken/driver.html?testName=audio-beat-detection) 19:36:10 INFO - PROCESS | 24186 | RSS: Main: 186773504 19:36:10 INFO - PROCESS | 24186 | 19:36:13 INFO - PROCESS | 24186 | Cycle 1(1): loaded http://localhost:49356/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49356/tests/kraken/driver.html?testName=audio-dft) 19:36:13 INFO - PROCESS | 24186 | RSS: Main: 176168960 19:36:13 INFO - PROCESS | 24186 | 19:36:16 INFO - PROCESS | 24186 | Cycle 1(1): loaded http://localhost:49356/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49356/tests/kraken/driver.html?testName=audio-fft) 19:36:17 INFO - PROCESS | 24186 | RSS: Main: 268341248 19:36:17 INFO - PROCESS | 24186 | 19:36:19 INFO - PROCESS | 24186 | Cycle 1(1): loaded http://localhost:49356/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49356/tests/kraken/driver.html?testName=audio-oscillator) 19:36:19 INFO - PROCESS | 24186 | RSS: Main: 173731840 19:36:19 INFO - PROCESS | 24186 | 19:36:22 INFO - PROCESS | 24186 | Cycle 1(1): loaded http://localhost:49356/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49356/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:36:22 INFO - PROCESS | 24186 | RSS: Main: 175935488 19:36:22 INFO - PROCESS | 24186 | 19:36:25 INFO - PROCESS | 24186 | Cycle 1(1): loaded http://localhost:49356/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49356/tests/kraken/driver.html?testName=imaging-darkroom) 19:36:26 INFO - PROCESS | 24186 | RSS: Main: 212279296 19:36:26 INFO - PROCESS | 24186 | 19:36:29 INFO - PROCESS | 24186 | Cycle 1(1): loaded http://localhost:49356/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49356/tests/kraken/driver.html?testName=imaging-desaturate) 19:36:30 INFO - PROCESS | 24186 | RSS: Main: 210137088 19:36:30 INFO - PROCESS | 24186 | 19:36:32 INFO - PROCESS | 24186 | Cycle 1(1): loaded http://localhost:49356/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49356/tests/kraken/driver.html?testName=json-parse-financial) 19:36:33 INFO - PROCESS | 24186 | RSS: Main: 210264064 19:36:33 INFO - PROCESS | 24186 | 19:36:35 INFO - PROCESS | 24186 | Cycle 1(1): loaded http://localhost:49356/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49356/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:36:35 INFO - PROCESS | 24186 | RSS: Main: 172281856 19:36:35 INFO - PROCESS | 24186 | 19:36:37 INFO - PROCESS | 24186 | Cycle 1(1): loaded http://localhost:49356/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49356/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:36:37 INFO - PROCESS | 24186 | RSS: Main: 180113408 19:36:37 INFO - PROCESS | 24186 | 19:36:39 INFO - PROCESS | 24186 | Cycle 1(1): loaded http://localhost:49356/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49356/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:36:40 INFO - PROCESS | 24186 | RSS: Main: 188817408 19:36:40 INFO - PROCESS | 24186 | 19:36:42 INFO - PROCESS | 24186 | Cycle 1(1): loaded http://localhost:49356/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49356/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:36:43 INFO - PROCESS | 24186 | RSS: Main: 197824512 19:36:43 INFO - PROCESS | 24186 | 19:36:46 INFO - PROCESS | 24186 | Cycle 1(1): loaded http://localhost:49356/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49356/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:36:46 INFO - PROCESS | 24186 | RSS: Main: 194408448 19:36:46 INFO - PROCESS | 24186 | 19:36:48 INFO - PROCESS | 24186 | Cycle 1(1): loaded http://localhost:49356/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49356/tests/kraken/driver.html?testName=ai-astar) 19:36:48 INFO - PROCESS | 24186 | RSS: Main: 245452800 19:36:48 INFO - PROCESS | 24186 | 19:36:48 INFO - PROCESS | 24186 | __start_tp_report 19:36:48 INFO - PROCESS | 24186 | _x_x_mozilla_page_load 19:36:48 INFO - PROCESS | 24186 | _x_x_mozilla_page_load_details 19:36:48 INFO - PROCESS | 24186 | |i|pagename|runs| 19:36:48 INFO - PROCESS | 24186 | |0;ai-astar;110;113;109;110;111;111;108;110;110;110 19:36:48 INFO - PROCESS | 24186 | |1;audio-beat-detection;141;117;117;148;124;118;117;126;149;117 19:36:48 INFO - PROCESS | 24186 | |2;audio-dft;162;160;159;160;161;161;162;161;161;160 19:36:48 INFO - PROCESS | 24186 | |3;audio-fft;75;76;76;104;75;75;76;75;97;75 19:36:48 INFO - PROCESS | 24186 | |4;audio-oscillator;82;82;121;82;82;80;98;80;80;115 19:36:48 INFO - PROCESS | 24186 | |5;imaging-gaussian-blur;117;117;116;117;117;117;117;117;117;117 19:36:48 INFO - PROCESS | 24186 | |6;imaging-darkroom;147;146;145;147;147;146;146;146;147;146 19:36:48 INFO - PROCESS | 24186 | |7;imaging-desaturate;94;95;95;95;95;95;95;95;95;95 19:36:48 INFO - PROCESS | 24186 | |8;json-parse-financial;50;49;48;51;50;50;50;49;49;49 19:36:48 INFO - PROCESS | 24186 | |9;json-stringify-tinderbox;60;60;59;60;60;60;60;61;61;62 19:36:48 INFO - PROCESS | 24186 | |10;stanford-crypto-aes;74;73;73;75;81;75;73;73;73;73 19:36:48 INFO - PROCESS | 24186 | |11;stanford-crypto-ccm;122;121;121;119;120;120;126;135;159;130 19:36:48 INFO - PROCESS | 24186 | |12;stanford-crypto-pbkdf2;158;158;155;157;152;155;154;156;151;156 19:36:48 INFO - PROCESS | 24186 | |13;stanford-crypto-sha256-iterative;62;64;62;62;61;62;62;63;61;63 19:36:48 INFO - PROCESS | 24186 | __end_tp_report 19:36:48 INFO - PROCESS | 24186 | __start_cc_report 19:36:48 INFO - PROCESS | 24186 | _x_x_mozilla_cycle_collect,1610 19:36:48 INFO - PROCESS | 24186 | __end_cc_report 19:36:48 INFO - PROCESS | 24186 | __startTimestamp1478662608807__endTimestamp 19:36:48 INFO - PROCESS | 24186 | 19:36:48 INFO - PROCESS | 24186 | ------- Summary: start ------- 19:36:48 INFO - PROCESS | 24186 | Number of tests: 14 19:36:48 INFO - PROCESS | 24186 | 19:36:48 INFO - PROCESS | 24186 | [#0] ai-astar Cycles:10 Average:110.20 Median:110.00 stddev:1.32 (1.2%) stddev-sans-first:1.39 19:36:48 INFO - PROCESS | 24186 | Values: 110.0 113.0 109.0 110.0 111.0 111.0 108.0 110.0 110.0 110.0 19:36:48 INFO - PROCESS | 24186 | 19:36:48 INFO - PROCESS | 24186 | [#1] audio-beat-detection Cycles:10 Average:127.40 Median:125.00 stddev:13.38 (10.7%) stddev-sans-first:13.25 19:36:48 INFO - PROCESS | 24186 | Values: 141.0 117.0 117.0 148.0 124.0 118.0 117.0 126.0 149.0 117.0 19:36:48 INFO - PROCESS | 24186 | 19:36:48 INFO - PROCESS | 24186 | [#2] audio-dft Cycles:10 Average:160.70 Median:161.00 stddev:0.95 (0.6%) stddev-sans-first:0.88 19:36:48 INFO - PROCESS | 24186 | Values: 162.0 160.0 159.0 160.0 161.0 161.0 162.0 161.0 161.0 160.0 19:36:48 INFO - PROCESS | 24186 | 19:36:48 INFO - PROCESS | 24186 | [#3] audio-fft Cycles:10 Average:80.40 Median:75.50 stddev:10.73 (14.2%) stddev-sans-first:11.20 19:36:48 INFO - PROCESS | 24186 | Values: 75.0 76.0 76.0 104.0 75.0 75.0 76.0 75.0 97.0 75.0 19:36:48 INFO - PROCESS | 24186 | 19:36:48 INFO - PROCESS | 24186 | [#4] audio-oscillator Cycles:10 Average:90.20 Median:82.00 stddev:15.65 (19.1%) stddev-sans-first:16.32 19:36:48 INFO - PROCESS | 24186 | Values: 82.0 82.0 121.0 82.0 82.0 80.0 98.0 80.0 80.0 115.0 19:36:48 INFO - PROCESS | 24186 | 19:36:48 INFO - PROCESS | 24186 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 19:36:48 INFO - PROCESS | 24186 | Values: 117.0 117.0 116.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 19:36:48 INFO - PROCESS | 24186 | 19:36:48 INFO - PROCESS | 24186 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.50 stddev:0.67 (0.5%) stddev-sans-first:0.67 19:36:48 INFO - PROCESS | 24186 | Values: 147.0 146.0 145.0 147.0 147.0 146.0 146.0 146.0 147.0 146.0 19:36:48 INFO - PROCESS | 24186 | 19:36:48 INFO - PROCESS | 24186 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.00 19:36:48 INFO - PROCESS | 24186 | Values: 94.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 19:36:48 INFO - PROCESS | 24186 | 19:36:48 INFO - PROCESS | 24186 | [#8] json-parse-financial Cycles:10 Average:49.50 Median:50.00 stddev:0.85 (1.7%) stddev-sans-first:0.88 19:36:48 INFO - PROCESS | 24186 | Values: 50.0 49.0 48.0 51.0 50.0 50.0 50.0 49.0 49.0 49.0 19:36:48 INFO - PROCESS | 24186 | 19:36:48 INFO - PROCESS | 24186 | [#9] json-stringify-tinderbox Cycles:10 Average:60.30 Median:60.00 stddev:0.82 (1.4%) stddev-sans-first:0.87 19:36:48 INFO - PROCESS | 24186 | Values: 60.0 60.0 59.0 60.0 60.0 60.0 60.0 61.0 61.0 62.0 19:36:48 INFO - PROCESS | 24186 | 19:36:48 INFO - PROCESS | 24186 | [#10] stanford-crypto-aes Cycles:10 Average:74.30 Median:73.50 stddev:2.50 (3.4%) stddev-sans-first:2.65 19:36:48 INFO - PROCESS | 24186 | Values: 74.0 73.0 73.0 75.0 81.0 75.0 73.0 73.0 73.0 73.0 19:36:48 INFO - PROCESS | 24186 | 19:36:48 INFO - PROCESS | 24186 | [#11] stanford-crypto-ccm Cycles:10 Average:127.30 Median:124.00 stddev:12.28 (9.9%) stddev-sans-first:12.87 19:36:48 INFO - PROCESS | 24186 | Values: 122.0 121.0 121.0 119.0 120.0 120.0 126.0 135.0 159.0 130.0 19:36:48 INFO - PROCESS | 24186 | 19:36:48 INFO - PROCESS | 24186 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.20 Median:156.00 stddev:2.35 (1.5%) stddev-sans-first:2.26 19:36:48 INFO - PROCESS | 24186 | Values: 158.0 158.0 155.0 157.0 152.0 155.0 154.0 156.0 151.0 156.0 19:36:48 INFO - PROCESS | 24186 | 19:36:48 INFO - PROCESS | 24186 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.20 Median:62.00 stddev:0.92 (1.5%) stddev-sans-first:0.97 19:36:48 INFO - PROCESS | 24186 | Values: 62.0 64.0 62.0 62.0 61.0 62.0 62.0 63.0 61.0 63.0 19:36:48 INFO - PROCESS | 24186 | -------- Summary: end -------- 19:36:48 INFO - PROCESS | 24186 | 19:36:49 INFO - TEST-INFO | 24186: exit 0 19:36:49 INFO - TEST-OK | kraken | took 59855ms 19:36:49 INFO - SUITE-END | took 1173s 19:36:50 INFO - Completed test suite (00:19:33) 19:36:50 INFO - javascript benchmark 19:36:50 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3134.3574567213723, 3172.0, 3180.157258326544, 3200.023992682231, 3221.693939075787, 5537.390887002426, 5621.0, 5639.802606908759, 5641.661500309984, 5648.096304591261, 5691.772329106841, 5744.971275143629, 5754.079347304432, 5757.712114394284, 5794.710264486769, 5148.330717006793, 5173.818916337929, 5202.193659982709, 5209.426963034063, 5254.369475662926, 5039.974800126012, 5238.188080847474, 5248.475152484759, 5255.132903071008, 5269.683818970874, 5053.0, 5086.542211200991, 5122.871928201787, 5127.743612819363, 5128.205128205115, 5143.691378517282, 5159.974200129013, 5166.664166829163, 5221.477852214748, 5243.580513558906, 5069.873253168664, 5107.208382700687, 5188.299579556749, 5210.426853046162, 5228.320318358627, 5377.247185394048, 5415.0523658359625, 5534.363548191969, 5535.501804837584, 5593.552515798767, 5982.162497250389, 5995.940040599626, 6003.579749417542, 6102.725377357985, 6105.419985101408, 6122.571420000557, 6124.295705993779, 6136.079588061782, 6139.2325959255095, 6143.877122457526, 5479.75341109651, 5530.944690553123, 5562.276904002454, 5625.015622266087, 5629.43705629442, 6135.693215339215, 6168.167297414881, 6168.814935551941, 6172.043333283258, 6182.938170618326, 5923.348431672513, 5936.5547583931375, 5938.168656388109, 5939.940600594025, 5940.524758019348, 5828.6502809831545, 5867.471927526543, 5969.611975221596, 6014.969925150346, 6025.578209525379, 6041.879162416727, 6052.57631965767, 6054.8183554493435, 6057.363976782374, 6062.9996050652035, 5914.9112763308585, 5978.043513037893, 5978.222831031938, 5978.312494063239, 6001.789937352259, 4532.79602417892, 4581.816727330869, 4600.95399046012, 4635.3742244797195, 4647.512011238839, 4140.60664236897, 4167.06241095751, 4188.874333769992, 4203.327467605169, 4213.536510983789, 5765.4811067004175, 5784.363719990798, 5794.420557944256, 5794.62334948227, 5803.738831752581, 5834.358220595731, 5884.47039766423, 5971.313298970587, 6107.755689772448, 6110.786122485781, 5675.177099320658, 5756.769729210869, 5759.769609215668, 5761.971190144106, 5788.131780232915, 5284.233786101071, 5289.20661900718, 5292.497212764858, 5294.682319060792, 5296.973515132477, 6040.36576159497, 6057.454829065406, 6231.158793562811, 6259.71831267594, 6293.905591416041, 5946.226990491208, 5979.880402392015, 6028.728707208274, 6098.542609304409, 6142.9078563820685, 6231.345708700521, 6269.5611307208055, 6283.74865005413, 6286.0, 6287.497000239879, 5745.827625171251, 5985.0, 5989.1914591529585, 5993.640381577119, 6015.338312785678, 5798.420157984167, 5971.104334349882, 6033.396660333931, 6057.515398768, 6137.447629713347, 5919.585629005928, 5961.672108034099, 5965.045592705146, 5970.223870896755, 5973.581849270683, 5944.048952167633, 5951.494122999594, 5956.017257152521, 5958.136070269873, 5958.463738263577, 5612.354579223442, 5723.914141287801, 5784.942150578609, 5847.152162936265, 5849.210356601804, 5987.790427335022, 5989.9700501498955, 5990.7004649768205, 5999.0, 6004.339522652592, 5549.750261238335, 5791.362950075572, 5825.825225243249, 5842.766289348378, 5843.503302219359], "value": 5567.489883901922, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [585.5432762445295, 616.4236438929602, 617.2993652204747, 622.3807311724835, 644.0243031806812, 35866.82066589667, 39062.0, 47657.76171119139, 48552.51447485524, 48559.27161092586, 50183.49816501834, 50627.49372506283, 50639.746801265945, 50703.49296507034, 50714.492855071534, 9331.53342332884, 9479.099485548863, 9495.240380769557, 9496.192823610001, 9497.95251023744, 8536.70121545746, 8734.257588105018, 10853.294535855184, 11126.833097503504, 11136.053435457996, 11381.2033157679, 11463.828042579367, 11466.312021278709, 11480.540778368897, 11538.769224615502, 8575.656973721043, 11220.214584979054, 11285.210035297532, 11291.0, 11303.095752339794, 8470.49177049379, 10924.126069914388, 11374.60188893393, 11375.715607109805, 11391.031762300214, 45453.0, 45797.54202457966, 45855.77072114618, 45884.0, 45907.0, 3508.105433114565, 3542.3800834853796, 3647.598764135943, 3686.3548878946076, 3704.7221458390727, 34779.30441391158, 34881.0, 35237.64762352369, 36136.81931590325, 36224.09439764001, 4406.955930440687, 4598.471175814757, 4614.261718125117, 4615.699979501322, 4692.8122875084955, 384658.0, 386343.0, 386435.0, 387005.0, 387178.0, 39530.0, 67287.66356168188, 67688.6615566919, 67716.6614166926, 67804.6609766948, 39356.212875742895, 41835.0, 42062.0, 42077.579224207366, 42085.1582968345, 17810.91094544545, 17985.460436186935, 17991.550211244823, 17997.640047199246, 18005.0, 13748.587542373545, 14097.0, 14195.077319974374, 14242.85757142436, 14252.501162459264, 9096.0, 9503.19222866064, 9508.572114254875, 9615.471149086727, 9807.705768826947, 3522.260325331709, 3564.8752293669595, 3603.3513967486106, 3606.0, 3643.398839191557, 1039.7244730146517, 1042.3641578637025, 1057.7461409261875, 1065.8454524094118, 1069.1244239631337, 35017.649823501946, 35040.29919401598, 35181.29637407289, 35259.64740352564, 35431.11422214414, 1138.0099313597223, 1154.4774667351862, 1176.923499972499, 1209.625016244967, 1217.7990631545695, 18177.0, 18189.7271540927, 18290.63418731618, 18305.359312424, 18377.356792512466, 532941.0, 540038.0, 540208.0, 540991.0, 541074.0, 64591.0, 64925.0, 65023.0, 65087.34912650813, 65325.0, 1314.4150852870573, 1320.7754681704134, 1381.7650999330137, 1398.6643205630576, 1425.6720954180596, 31898.0, 36053.0, 36235.27529449449, 36320.63679363278, 36558.63441365552, 55573.722131389084, 56243.0, 56364.0, 56386.1542076861, 56436.0, 405075.0, 406742.0, 406989.0, 407325.0, 407948.0, 31189.53215701721, 31905.521417179225, 31998.52002220015, 32256.677433225366, 33867.322653547286, 52030.479695202564, 52590.474095258556, 52783.47216527785, 53094.0, 53435.46564534305, 43124.13751725011, 43407.78296108625, 43561.0, 43794.0, 45086.098278034915, 43866.78066609646, 43881.56118438902, 44903.0, 45717.31424028709, 46489.767551162025, 43214.78392608017, 43928.0, 44398.778006109766, 44996.0, 45048.54951450443, 36832.44751328679, 38684.41973370458, 38798.2240355197, 39054.6094539051, 39671.2065758689, 28679.569806452502, 29135.70864291415, 29146.56280155757, 29316.56025159667, 29328.41343173167], "value": 20623.229551051292, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [396.6212267284743, 411.86408485199905, 427.0753817984064, 427.8181772746583, 441.88069221310246, 14612.269386530685, 16422.0, 19680.99999999998, 20418.69371959422, 21291.25480608175, 20177.09203085861, 20256.493587660316, 20616.38150855473, 20765.896170519165, 20801.791982080176, 5962.403759624046, 6015.548833837469, 6066.969665151668, 6095.878082438348, 6099.0, 4458.152950939328, 6060.424259695322, 6132.570720049598, 6152.753889844401, 6154.630722156685, 4630.467496237925, 4656.627469802408, 6800.659967001655, 6912.308769123098, 6920.238773734885, 5864.70676466175, 6103.725332360054, 6919.377256046955, 6926.68829902653, 6932.584044957319, 6028.457438830483, 6033.245844269466, 6041.063635136561, 6046.09308603713, 6046.123312119718, 16580.50258492235, 22826.31521054371, 24221.0, 24560.754392456205, 24775.380615484577, 2311.179531266393, 2381.618940969454, 2402.8558286502866, 2503.849769013865, 3046.451638705032, 10591.205659575562, 10775.943843672949, 10935.726606834813, 11069.059129973963, 11118.888811111867, 1954.6188493243824, 1996.4110587376758, 2027.6755719084877, 2042.8570000099999, 2065.4320061983076, 78761.0, 82764.58617706933, 85242.57378713066, 85371.0, 85825.57087214586, 11642.94178529102, 11828.645140645795, 11952.940235298767, 12010.399480025964, 12068.577599784141, 16702.665946680998, 16728.41450549225, 16739.414120505724, 16760.58098547546, 16774.664506709796, 8982.550872456351, 9031.232345250728, 9115.544222788834, 9158.0, 9262.259019278441, 8077.7576672699915, 8353.624086916103, 8378.748637540884, 8582.055973842873, 9269.90730092704, 5036.899262014813, 5123.154679477844, 5139.794408223629, 5140.149546163379, 5280.260763493114, 2029.8477614178996, 2041.57127003328, 2043.8467114966436, 2072.782357852434, 2076.709260703503, 445.24530920090785, 466.8856130248111, 1003.9408424112631, 1057.8677665291839, 1060.5015642647943, 12093.637190884112, 12206.267623942593, 12508.124431289722, 12610.243385396905, 12613.117081804186, 469.78859513219186, 475.19928919770297, 477.26024661774113, 477.2650118817052, 482.81411656512495, 6510.7721229756735, 6681.76613818514, 6704.497162712816, 6728.3271672832325, 6893.586384816927, 512754.0, 515718.0, 531484.0, 532005.0, 541391.0, 60250.39749602448, 92221.0, 93275.0, 104619.476902615, 107065.46467267614, 1033.3076838518211, 1081.8647669041213, 1093.2238110941162, 1109.8945600167972, 1115.676453828397, 17867.553311166805, 18107.275708971494, 18350.541236469195, 18572.44282671522, 18613.348532801287, 45601.54398456106, 45728.45480495681, 45802.62592122242, 46221.53778462172, 46302.0, 62921.370786291554, 63083.36916630959, 63600.363996361295, 63928.0, 64451.0, 5603.691796951044, 7441.255874412515, 7500.949867018731, 8209.876851847108, 8430.241278284984, 11931.880681193077, 11938.0, 12033.916947474769, 12187.634370968884, 12393.194442361219, 10701.571937122428, 10721.35671859691, 10728.470212553575, 10782.622608208674, 10816.351018938574, 10213.540390682288, 10349.793004140025, 10767.730806729895, 10916.181286403711, 10930.0, 9070.002299746891, 10617.840732388866, 10746.83879741789, 10821.783564328827, 10898.400587967737, 8743.781405464913, 8778.209961103532, 8834.95582522109, 8920.955395222982, 9014.374434790292, 9543.570539325887, 10445.425501597485, 10837.0, 10916.78166436651, 10920.18098642589], "value": 8976.668883872135, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1429.563982985189, 1500.8199016118067, 1537.3389442539708, 1703.6252024554594, 1845.7508236388085, 7866.134725180233, 8623.956880215597, 8815.250703690179, 8816.911830881698, 9164.129407706274, 8944.195022447979, 8963.46219226847, 8985.820283594325, 9019.95490022549, 9026.864597031034, 2443.6334549817493, 2462.4213309872166, 2465.716442609105, 2466.70399552054, 2468.728439871613, 2385.0698227691214, 2473.0973194783865, 2487.44032592667, 2490.5766019776593, 2495.1142344467726, 2398.9880050599722, 2419.4314336131083, 2466.9259922202364, 2469.5307891500647, 2475.801935845137, 2337.0768546424238, 2350.753170917055, 2351.0478256306224, 2353.1646265575732, 2358.2453614843257, 2360.5632957902844, 2408.0, 2415.613501839697, 2416.32342943976, 2416.9154079607306, 8769.254613357873, 8786.209241168262, 8876.600552975131, 8972.147645973622, 9025.593848276778, 5394.083005888969, 5555.916661250085, 5560.944390556083, 5561.555075593943, 5567.081431563827, 3280.7539434542505, 3298.24140447696, 3338.232206592473, 3342.4317865962844, 3358.7984720916825, 1063.622414043019, 1090.7000574841902, 2061.2476446097216, 2111.2399536167122, 2119.7456305243313, 12162.148649594532, 12202.389880506027, 12417.379131043503, 12422.875771242263, 12442.875571244353, 4479.395281637003, 4567.725936443824, 4678.2982552616695, 4695.084458530554, 4749.287606858929, 9819.607215711292, 9824.214062874953, 9844.458554779556, 9848.310618256653, 9852.556634951445, 5466.426025267369, 5480.232767412565, 5483.095289277225, 5483.259759932438, 5494.670319780826, 4497.887552811206, 4608.631309495232, 4895.094407534618, 4990.051890140861, 5117.462666419972, 3988.3419235826345, 3998.020484981198, 4068.16602596467, 4098.754074755524, 4103.958960410418, 1227.5948936850818, 1234.21010553246, 1236.7093732972744, 1255.0712472770085, 1282.3075539208724, 935.8736570563022, 938.8169306985072, 954.5704433005176, 957.8467445208734, 968.7723385004518, 5783.537317014629, 5794.333651630032, 5820.156077368758, 5836.88326233473, 6015.1278064681255, 480.88218386494833, 481.9686720363165, 482.0, 485.6527582778331, 489.0414787017415, 3864.7874366909527, 4029.3553031514816, 4044.352903535479, 4050.09087061517, 4087.836486540505, 10842.512086956105, 11114.833277500844, 11200.719982000515, 11212.775744485065, 11307.886921130848, 11089.722756931142, 11104.833427498594, 11118.833217501744, 11120.0, 11181.720456988478, 8339.54132522717, 8416.663333466593, 8417.158284171534, 8419.747407577783, 8434.24091831738, 5654.491095801398, 5765.509931655856, 5796.884062318814, 5820.854478638068, 5906.143609176731, 10163.237757168094, 10236.129928955825, 10272.229582781172, 10282.280240383401, 10337.689869303933, 9716.805663886542, 10168.898311016796, 10175.236857235588, 10410.1671866249, 10477.63328283537, 7072.646367681493, 7245.347918687344, 7555.24447555262, 7928.643211055632, 8114.715984940499, 8344.749657510285, 8507.064222935596, 8635.309175265838, 8735.475871447734, 8740.650373984969, 7329.377002954595, 7424.220456852169, 7503.662335195038, 7589.203133670885, 7669.88495172584, 6997.090378250795, 7065.22282548909, 7099.0, 7318.926810731825, 7371.041764570571, 6553.868922621617, 6709.865802683821, 6727.461803055646, 6729.327067293231, 6740.595564266159, 5007.599392048554, 5012.223105418666, 5017.0216807722145, 5058.974705126598, 5069.898602028013, 4798.520147985174, 4883.340748998839, 4955.058538877602, 5042.520960508746, 5043.167877300204], "value": 4622.8607349790345, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [324.03223014942023, 338.5311343788852, 341.8512946868114, 345.35074060765754, 347.3487211478478, 5532.28080349554, 5544.140658197981, 5564.38632796489, 5611.438856114387, 5636.985342638329, 5523.6409633373805, 5524.806631767889, 5534.75093620787, 5540.196671482632, 5545.611807173499, 4331.4802223733195, 4336.913261734771, 4356.499002614708, 4365.388845561608, 4375.234333991555, 3827.158025234444, 4053.8986525336954, 4217.578242175789, 4342.978285108586, 4349.630281426067, 4278.229918614648, 4283.678724095706, 4292.61366477017, 4294.763787991659, 4297.613214810666, 4305.63402110821, 4325.502567204749, 4334.569592034621, 4335.761533115661, 4348.717333373353, 3606.4229723244157, 3632.8728494502566, 3679.3809830861724, 3799.848006079781, 3835.0412396900774, 5371.597130215249, 5374.758135883894, 5377.30614856319, 5401.4868587484125, 5422.75597598109, 4897.265410188465, 4898.804047838083, 4904.215325547931, 4914.557689807934, 4935.80256789728, 5634.295713035871, 5697.4587414195585, 5713.914291285634, 5724.4561766632105, 5729.914051289234, 4129.587041295877, 4138.8344466221315, 4148.605882441163, 4155.542890282067, 4193.20329137466, 5773.884522309572, 5950.404959504015, 6068.0, 6074.967255566565, 6079.483243924272, 4036.394540818842, 4243.830246790094, 4303.0, 4307.956920430818, 4343.522212556616, 4389.9025243689075, 4391.407160033418, 4415.315626077963, 4417.779111044435, 4444.200043992112, 4885.9022819544125, 4954.529319714621, 4997.875053123701, 5014.974925125351, 5055.317532133115, 5806.767729290781, 5811.314718731605, 5816.360200377955, 5822.854428639318, 5835.44563266489, 1499.8350181480027, 1517.370291329108, 1524.7941527893593, 1528.4039224702376, 1539.7844301797757, 935.7239614313794, 977.398899676708, 978.2369751593768, 984.8571957066185, 986.7335819328741, 4952.0, 4980.2778597103215, 4991.825286115041, 5032.84901452957, 5036.622253330942, 5212.921806172911, 5235.424103348629, 5254.028004819121, 5282.234076059027, 5300.973495132577, 5054.873628159326, 5055.646104772631, 5092.949070509321, 5312.335958005249, 5698.857528561736, 3194.34515924235, 3221.098092534094, 3221.6803901191106, 3231.5152727091127, 3236.1747754322496, 6090.939090609126, 6201.015674983962, 6252.187215661935, 6253.6560489172625, 6259.937400626027, 6118.724657390429, 6125.479334256549, 6137.667686279275, 6142.293636231891, 6147.200863887666, 6162.198914141222, 6162.445379915829, 6167.414095660905, 6171.722272497748, 6242.937570624235, 5754.223179870663, 5924.4667979882015, 5978.252718410199, 5981.641101533922, 6026.487748541248, 5788.507976821849, 5833.928955221005, 5899.321578018583, 5906.527477801851, 5907.700305932688, 5735.770569177186, 5888.499477544286, 5967.687108836049, 6020.020090055451, 6155.446009859134, 4290.0, 4321.956780432155, 4330.35044743291, 4350.282203436397, 4355.845700889204, 5812.883742325045, 5973.312009595787, 6010.639361638316, 6020.608660437058, 6033.0, 5776.971115144397, 5786.537077033911, 5788.0, 5804.738786754522, 5828.76503540539, 5781.21953536284, 5781.942180578309, 5801.6519008859605, 5809.215755873072, 5810.622309549865, 5476.315460567429, 5765.682887441231, 5823.7379317929945, 5831.7667293307795, 5851.385604511465, 5888.848361904761, 5893.941060589511, 5910.52715782745, 5932.139839723302, 5941.168236446814, 5579.191017302387, 5768.336641286306, 5796.985527532749, 5805.738741756715, 5829.387914268941], "value": 4399.694234388362, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [718.5078221418325, 721.3039416962633, 771.6836097200149, 773.3658400111909, 778.0313509680446, 15908.0, 16909.492715218534, 17126.400575979842, 17137.14314284287, 17244.13779311034, 15633.687326253497, 15812.762808557853, 16398.098104604243, 17160.570985725364, 17188.140592970332, 713.7930000299907, 737.8634952533772, 738.3428748413916, 828.9668413263462, 858.9269912057482, 855.4867079752161, 868.8957325120995, 870.8301881133182, 871.1984973824066, 871.5337294547419, 922.3267015078997, 922.6032805893464, 922.6540047482194, 924.085155695863, 924.4037595750737, 755.5844285642875, 775.8021704465382, 871.6121326009896, 902.0933961368848, 910.526526206369, 660.0561197487591, 664.6078813500059, 666.0774826864779, 666.0974379715465, 666.796627028756, 14168.079074860101, 14168.149911005372, 14183.929080354636, 14193.503227387095, 14210.076345037643, 14180.0, 14297.428102875872, 14396.712065758731, 14398.712025759427, 14485.78271325931, 13793.931030344884, 13940.93029534856, 13983.79024314636, 14021.369038393195, 14274.429022839075, 4583.931241031387, 4603.5166307537565, 4682.906341873178, 4740.312654665088, 4749.073930583538, 90155.54922225478, 92014.0, 92424.07575924222, 93517.0, 93526.53236733841, 11189.496472658588, 11437.428128593703, 11464.713382165513, 11647.359395233174, 11946.88053119475, 21069.15723371048, 22238.221662242067, 22290.777092229193, 22291.665625015637, 22327.888360558092, 8592.355573332025, 8596.8710469343, 8619.051904290523, 8819.955900220586, 8934.28525717941, 4784.617230621542, 4824.7682691305135, 4946.1344264753525, 4956.083124621957, 4980.452150263468, 3569.375359312162, 3741.1956429367815, 3741.775493470346, 3767.2653832502397, 3772.7170462214895, 591.6657088744845, 623.1369553168803, 627.7802769030802, 628.1174949196386, 629.1914889367141, 698.7274962764526, 722.0757430488877, 766.9769906902802, 768.5964868444112, 801.6953557648174, 10096.511505988929, 10233.48832558369, 10286.796401963502, 11159.721006974729, 11177.83233251502, 723.5730918757959, 743.7917383132732, 783.1189911349732, 792.7700966719586, 797.8882956386111, 4542.251233275213, 4664.440267167962, 4796.184648609745, 4841.419029716386, 4855.951440485621, 104678.0, 126358.0, 126513.0, 126936.0, 127082.36458817832, 94492.0, 96353.0, 96911.51544242374, 98988.0, 100719.0, 57097.0, 57279.42720572741, 58558.41441585615, 58871.0, 60536.697316513135, 9290.72127836166, 9459.084604715727, 9529.666461673809, 9639.759006024906, 9732.07545283197, 53065.7346713264, 53692.73153634207, 54382.0, 55139.897202056534, 55445.0, 75421.0, 85500.0, 86616.0, 87010.56494717486, 88590.55704721693, 12706.682332941751, 13031.348432578143, 13270.27013514266, 13386.397612107285, 13428.664283392602, 14245.501407450689, 14311.642208944859, 14382.280885955452, 14464.493742718954, 15771.44799931997, 11984.28094314344, 12031.204911589386, 12134.33261170644, 12399.070069744625, 12703.555375561811, 11424.600138995427, 11953.043756499634, 12301.138920275494, 12452.439640216393, 12660.683482913, 12060.0, 12234.694132646755, 12356.135070545335, 12460.87539124597, 13159.60521184366, 10610.416427096583, 10690.305130166515, 10824.56701731922, 10891.18316126278, 10964.506597202986, 10417.270791044553, 10880.619178328721, 10997.285176463824, 11250.718732031764, 11529.884701152881], "value": 7707.620176301419, "unit": "score"}], "name": "dromaeo_css", "value": 7380.2552392620955, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 113.0, 109.0, 110.0, 111.0, 111.0, 108.0, 110.0, 110.0, 110.0], "value": 110.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 117.0, 117.0, 148.0, 124.0, 118.0, 117.0, 126.0, 149.0, 117.0], "value": 127.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 159.0, 160.0, 161.0, 161.0, 162.0, 161.0, 161.0, 160.0], "value": 160.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 76.0, 76.0, 104.0, 75.0, 75.0, 76.0, 75.0, 97.0, 75.0], "value": 80.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 121.0, 82.0, 82.0, 80.0, 98.0, 80.0, 80.0, 115.0], "value": 90.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 146.0, 145.0, 147.0, 147.0, 146.0, 146.0, 146.0, 147.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 48.0, 51.0, 50.0, 50.0, 50.0, 49.0, 49.0, 49.0], "value": 49.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 59.0, 60.0, 60.0, 60.0, 60.0, 61.0, 61.0, 62.0], "value": 60.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 73.0, 73.0, 75.0, 81.0, 75.0, 73.0, 73.0, 73.0, 73.0], "value": 74.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 121.0, 121.0, 119.0, 120.0, 120.0, 126.0, 135.0, 159.0, 130.0], "value": 127.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 158.0, 155.0, 157.0, 152.0, 155.0, 154.0, 156.0, 151.0, 156.0], "value": 155.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 64.0, 62.0, 62.0, 61.0, 62.0, 62.0, 63.0, 61.0, 63.0], "value": 62.2, "unit": "score"}], "name": "kraken", "value": 1455.8000000000002, "alertThreshold": 2.0}]} 19:36:50 INFO - Return code: 0 19:36:50 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:36:50 INFO - # TBPL SUCCESS # 19:36:50 INFO - Running post-action listener: _resource_record_post_action 19:36:50 INFO - [mozharness: 2016-11-09 03:36:50.619740Z] Finished run-tests step (success) 19:36:50 INFO - Running post-run listener: _resource_record_post_run 19:36:50 INFO - Total resource usage - Wall time: 1190s; CPU: 13.0%; Read bytes: 4096; Write bytes: 369233920; Read time: 16; Write time: 271168 19:36:50 INFO - TinderboxPrint: CPU usage
13.0% 19:36:50 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 19:36:50 INFO - TinderboxPrint: I/O write bytes / time
369,233,920 / 271,168 19:36:50 INFO - TinderboxPrint: CPU idle
8,265.9 (87.0%) 19:36:50 INFO - TinderboxPrint: CPU user
1,187.7 (12.5%) 19:36:50 INFO - TinderboxPrint: Swap in / out
0 / 0 19:36:50 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 118779904; Read time: 0; Write time: 87112 19:36:50 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 0; Write bytes: 250454016; Read time: 0; Write time: 184056 19:36:51 INFO - Running post-run listener: _upload_blobber_files 19:36:51 INFO - Blob upload gear active. 19:36:51 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:36:51 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 19:36:51 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'] 19:36:51 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 19:36:51 INFO - (blobuploader) - INFO - Open directory for files ... 19:36:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:36:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:36:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:36:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:36:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:36:52 INFO - (blobuploader) - INFO - Done attempting. 19:36:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:36:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:36:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:36:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:36:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:36:53 INFO - (blobuploader) - INFO - Done attempting. 19:36:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:36:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:36:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:36:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:36:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:36:56 INFO - (blobuploader) - INFO - Done attempting. 19:36:56 INFO - (blobuploader) - INFO - Iteration through files over. 19:36:56 INFO - Return code: 0 19:36:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:36:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:36:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/04b1c5b5df930955551dfc5b2d6f3a0f9f1cce2612c6a3be5ef8a3b8deca4b76b800bdd9685aae4b07fb8e54ba5147d9f8417cd22b3c10fb52dd3d2e95a99cbe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8f0e6993ad1aa21c5fade19814f7bf9dbfc6b5da4071c5e74d952e66ca0130291d8ed558c9d8cf5ca0902e7ef439ab102570510fc371758992294f27f975d697", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a833f20d147904578d4312ecdf3a0c854696dafad3839e1ef3e4a7cc45af306b0f75cc2b1050aaf95005cef95abd0b67e7f26236dadb31afd6febd71ee19cfb0"} 19:36:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:36:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:36:56 INFO - Contents: 19:36:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/04b1c5b5df930955551dfc5b2d6f3a0f9f1cce2612c6a3be5ef8a3b8deca4b76b800bdd9685aae4b07fb8e54ba5147d9f8417cd22b3c10fb52dd3d2e95a99cbe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8f0e6993ad1aa21c5fade19814f7bf9dbfc6b5da4071c5e74d952e66ca0130291d8ed558c9d8cf5ca0902e7ef439ab102570510fc371758992294f27f975d697", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a833f20d147904578d4312ecdf3a0c854696dafad3839e1ef3e4a7cc45af306b0f75cc2b1050aaf95005cef95abd0b67e7f26236dadb31afd6febd71ee19cfb0"} 19:36:56 INFO - Running post-run listener: copy_logs_to_upload_dir 19:36:56 INFO - Copying logs to upload dir... 19:36:56 INFO - mkdir: /builds/slave/test/build/upload/logs 19:36:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1232.870485 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 32 secs) (at 2016-11-08 19:36:56.310616) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-08 19:36:56.314069) ========= 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=652eb03db935cfc1c15ebda0000001ad-1478659882.19157-1947756193 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/04b1c5b5df930955551dfc5b2d6f3a0f9f1cce2612c6a3be5ef8a3b8deca4b76b800bdd9685aae4b07fb8e54ba5147d9f8417cd22b3c10fb52dd3d2e95a99cbe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8f0e6993ad1aa21c5fade19814f7bf9dbfc6b5da4071c5e74d952e66ca0130291d8ed558c9d8cf5ca0902e7ef439ab102570510fc371758992294f27f975d697", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a833f20d147904578d4312ecdf3a0c854696dafad3839e1ef3e4a7cc45af306b0f75cc2b1050aaf95005cef95abd0b67e7f26236dadb31afd6febd71ee19cfb0"} build_url:https://queue.taskcluster.net/v1/task/X7JcpTgmTr-y-M7YNHnBKQ/artifacts/public/build/firefox-51.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009686 build_url: 'https://queue.taskcluster.net/v1/task/X7JcpTgmTr-y-M7YNHnBKQ/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/04b1c5b5df930955551dfc5b2d6f3a0f9f1cce2612c6a3be5ef8a3b8deca4b76b800bdd9685aae4b07fb8e54ba5147d9f8417cd22b3c10fb52dd3d2e95a99cbe", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8f0e6993ad1aa21c5fade19814f7bf9dbfc6b5da4071c5e74d952e66ca0130291d8ed558c9d8cf5ca0902e7ef439ab102570510fc371758992294f27f975d697", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a833f20d147904578d4312ecdf3a0c854696dafad3839e1ef3e4a7cc45af306b0f75cc2b1050aaf95005cef95abd0b67e7f26236dadb31afd6febd71ee19cfb0"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-08 19:36:56.337151) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-08 19:36:56.337441) ========= 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=652eb03db935cfc1c15ebda0000001ad-1478659882.19157-1947756193 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004529 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-08 19:36:56.422371) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-08 19:36:56.422688) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-08 19:36:56.422960) ========= ========= Total master_lag: 0.18 =========