builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-005 starttime: 1482361616.28 results: success (0) buildid: 20161221132752 builduid: c499ee514c5c40edb3298fd1726cdde8 revision: 839579d7d875c968a0403a0cee1a7fad67c6e228 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-21 15:06:56.276961) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-21 15:06:56.277405) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-21 15:06:56.277696) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-21 15:06:56.328389) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-21 15:06:56.328729) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1482361461.603230-1973869559 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-12-21 15:06:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 42.7M=0s 2016-12-21 15:06:58 (42.7 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=1.132618 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-21 15:06:57.476002) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 15:06:57.476319) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1482361461.603230-1973869559 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.019203 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 15:06:57.562304) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 15:06:57.562649) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 839579d7d875c968a0403a0cee1a7fad67c6e228 --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-beta --rev 839579d7d875c968a0403a0cee1a7fad67c6e228 --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=7fc86f70bae03e018da0b1d8000001c4-1482361461.603230-1973869559 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-12-21 15:06:58,427 truncating revision to first 12 chars 2016-12-21 15:06:58,427 Setting DEBUG logging. 2016-12-21 15:06:58,427 attempt 1/10 2016-12-21 15:06:58,427 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/839579d7d875?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-21 15:06:58,730 unpacking tar archive at: mozilla-beta-839579d7d875/testing/mozharness/ program finished with exit code 0 elapsedTime=0.636334 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 15:06:58.212552) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-21 15:06:58.212899) ========= script_repo_revision: 839579d7d875c968a0403a0cee1a7fad67c6e228 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-21 15:06:58.213431) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-21 15:06:58.213759) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-21 15:06:58.224712) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-12-21 15:06:58.225255) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=7fc86f70bae03e018da0b1d8000001c4-1482361461.603230-1973869559 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 15:06:59 INFO - MultiFileLogger online at 20161221 15:06:59 in /builds/slave/test 15:06:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 15:06:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:06:59 INFO - {'append_to_log': False, 15:06:59 INFO - 'base_work_dir': '/builds/slave/test', 15:06:59 INFO - 'blob_upload_branch': 'Mozilla-Beta', 15:06:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:06:59 INFO - 'branch': 'Mozilla-Beta', 15:06:59 INFO - 'buildbot_json_path': 'buildprops.json', 15:06:59 INFO - 'config_files': ('talos/linux_config.py',), 15:06:59 INFO - 'default_actions': ('clobber', 15:06:59 INFO - 'read-buildbot-config', 15:06:59 INFO - 'download-and-extract', 15:06:59 INFO - 'populate-webroot', 15:06:59 INFO - 'create-virtualenv', 15:06:59 INFO - 'install', 15:06:59 INFO - 'run-tests'), 15:06:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:06:59 INFO - 'download_minidump_stackwalk': True, 15:06:59 INFO - 'download_symbols': 'ondemand', 15:06:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:06:59 INFO - 'tooltool.py': '/tools/tooltool.py', 15:06:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:06:59 INFO - '/tools/misc-python/virtualenv.py')}, 15:06:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:06:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:06:59 INFO - 'installer_path': 'installer.exe', 15:06:59 INFO - 'log_level': 'info', 15:06:59 INFO - 'log_name': 'talos', 15:06:59 INFO - 'log_to_console': True, 15:06:59 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 15:06:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 15:06:59 INFO - 'opt_config_files': (), 15:06:59 INFO - 'pip_index': False, 15:06:59 INFO - 'sps_profile': False, 15:06:59 INFO - 'sps_profile_interval': 0, 15:06:59 INFO - 'suite': 'dromaeojs', 15:06:59 INFO - 'system_bits': '32', 15:06:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:06:59 INFO - 'title': 'talos-linux64-ix-005', 15:06:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:06:59 INFO - 'use_talos_json': True, 15:06:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:06:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:06:59 INFO - 'work_dir': 'build'} 15:06:59 INFO - [mozharness: 2016-12-21 23:06:59.207996Z] Running clobber step. 15:06:59 INFO - Running pre-action listener: _resource_record_pre_action 15:06:59 INFO - Running main action method: clobber 15:06:59 INFO - rmtree: /builds/slave/test/build 15:06:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:07:00 INFO - Running post-action listener: _resource_record_post_action 15:07:00 INFO - [mozharness: 2016-12-21 23:07:00.179204Z] Finished clobber step (success) 15:07:00 INFO - [mozharness: 2016-12-21 23:07:00.179305Z] Running read-buildbot-config step. 15:07:00 INFO - Running pre-action listener: _resource_record_pre_action 15:07:00 INFO - Running main action method: read_buildbot_config 15:07:00 INFO - Using buildbot properties: 15:07:00 INFO - { 15:07:00 INFO - "project": "", 15:07:00 INFO - "product": "firefox", 15:07:00 INFO - "script_repo_revision": "production", 15:07:00 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 15:07:00 INFO - "repository": "", 15:07:00 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 15:07:00 INFO - "buildid": "20161221132752", 15:07:00 INFO - "pgo_build": "True", 15:07:00 INFO - "basedir": "/builds/slave/test", 15:07:00 INFO - "buildnumber": 58, 15:07:00 INFO - "slavename": "talos-linux64-ix-005", 15:07:00 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 15:07:00 INFO - "platform": "ubuntu64_hw", 15:07:00 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 15:07:00 INFO - "branch": "mozilla-beta", 15:07:00 INFO - "repo_path": "releases/mozilla-beta", 15:07:00 INFO - "revision": "839579d7d875c968a0403a0cee1a7fad67c6e228", 15:07:00 INFO - "stage_platform": "linux64-pgo", 15:07:00 INFO - "builduid": "c499ee514c5c40edb3298fd1726cdde8", 15:07:00 INFO - "slavebuilddir": "test" 15:07:00 INFO - } 15:07:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/f4cE6Ds-TdemBHYzw5yjjw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2. 15:07:00 INFO - Running post-action listener: _resource_record_post_action 15:07:00 INFO - [mozharness: 2016-12-21 23:07:00.181770Z] Finished read-buildbot-config step (success) 15:07:00 INFO - [mozharness: 2016-12-21 23:07:00.181861Z] Running download-and-extract step. 15:07:00 INFO - Running pre-action listener: _resource_record_pre_action 15:07:00 INFO - Running main action method: download_and_extract 15:07:00 INFO - mkdir: /builds/slave/test/build/tests 15:07:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:07:00 INFO - trying https://queue.taskcluster.net/v1/task/f4cE6Ds-TdemBHYzw5yjjw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 15:07:00 INFO - Downloading https://queue.taskcluster.net/v1/task/f4cE6Ds-TdemBHYzw5yjjw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 15:07:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/f4cE6Ds-TdemBHYzw5yjjw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 15:07:00 INFO - Downloaded 1327 bytes. 15:07:00 INFO - Reading from file /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 15:07:00 INFO - Using the following test package requirements: 15:07:00 INFO - {u'common': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'], 15:07:00 INFO - u'cppunittest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 15:07:00 INFO - u'firefox-51.0.en-US.linux-x86_64.cppunittest.tests.zip'], 15:07:00 INFO - u'gtest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 15:07:00 INFO - u'firefox-51.0.en-US.linux-x86_64.gtest.tests.zip'], 15:07:00 INFO - u'jittest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 15:07:00 INFO - u'jsshell-linux-x86_64.zip'], 15:07:00 INFO - u'mochitest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 15:07:00 INFO - u'firefox-51.0.en-US.linux-x86_64.mochitest.tests.zip'], 15:07:00 INFO - u'mozbase': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'], 15:07:00 INFO - u'reftest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 15:07:00 INFO - u'firefox-51.0.en-US.linux-x86_64.reftest.tests.zip'], 15:07:00 INFO - u'talos': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 15:07:00 INFO - u'firefox-51.0.en-US.linux-x86_64.talos.tests.zip'], 15:07:00 INFO - u'web-platform': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 15:07:00 INFO - u'firefox-51.0.en-US.linux-x86_64.web-platform.tests.zip'], 15:07:00 INFO - u'xpcshell': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 15:07:00 INFO - u'firefox-51.0.en-US.linux-x86_64.xpcshell.tests.zip']} 15:07:00 INFO - Downloading packages: [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 15:07:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/f4cE6Ds-TdemBHYzw5yjjw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip 15:07:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f4cE6Ds-TdemBHYzw5yjjw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:07:00 INFO - Fetch https://queue.taskcluster.net/v1/task/f4cE6Ds-TdemBHYzw5yjjw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip into memory 15:07:01 INFO - Expected file size: 21963977 15:07:05 INFO - Downloading packages: [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', u'firefox-51.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 15:07:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/f4cE6Ds-TdemBHYzw5yjjw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip 15:07:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f4cE6Ds-TdemBHYzw5yjjw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 15:07:05 INFO - Fetch https://queue.taskcluster.net/v1/task/f4cE6Ds-TdemBHYzw5yjjw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip into memory 15:07:06 INFO - Expected file size: 21963977 15:07:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/f4cE6Ds-TdemBHYzw5yjjw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip 15:07:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/f4cE6Ds-TdemBHYzw5yjjw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 15:07:11 INFO - Fetch https://queue.taskcluster.net/v1/task/f4cE6Ds-TdemBHYzw5yjjw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip into memory 15:07:11 INFO - Expected file size: 14414089 15:07:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:07:12 INFO - trying https://queue.taskcluster.net/v1/task/f4cE6Ds-TdemBHYzw5yjjw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 15:07:12 INFO - Downloading https://queue.taskcluster.net/v1/task/f4cE6Ds-TdemBHYzw5yjjw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 15:07:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/f4cE6Ds-TdemBHYzw5yjjw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 15:07:13 INFO - Downloaded 56943259 bytes. 15:07:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/f4cE6Ds-TdemBHYzw5yjjw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 15:07:13 INFO - mkdir: /builds/slave/test/properties 15:07:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:07:13 INFO - Writing to file /builds/slave/test/properties/build_url 15:07:13 INFO - Contents: 15:07:13 INFO - build_url:https://queue.taskcluster.net/v1/task/f4cE6Ds-TdemBHYzw5yjjw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 15:07:14 INFO - Running post-action listener: _resource_record_post_action 15:07:14 INFO - Running post-action listener: set_extra_try_arguments 15:07:14 INFO - [mozharness: 2016-12-21 23:07:14.393633Z] Finished download-and-extract step (success) 15:07:14 INFO - [mozharness: 2016-12-21 23:07:14.393863Z] Running populate-webroot step. 15:07:14 INFO - Running pre-action listener: _resource_record_pre_action 15:07:14 INFO - Running main action method: populate_webroot 15:07:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:07:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:07:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:07:14 INFO - '--tppagecycles', 15:07:14 INFO - '7'], 15:07:14 INFO - 'tests': ['tsvgm']}}, 15:07:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:07:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:07:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:07:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:07:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:07:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:07:14 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:07:14 INFO - 'talos_options': ['--disable-e10s'], 15:07:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:07:14 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:07:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:07:14 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:07:14 INFO - 'talos_options': ['--disable-e10s'], 15:07:14 INFO - 'tests': ['damp', 'tps']}, 15:07:14 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:07:14 INFO - 'tests': ['damp', 'tps']}, 15:07:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:07:14 INFO - 'tests': ['dromaeo_dom']}, 15:07:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:07:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:07:14 INFO - 'tests': ['basic_compositor_video']}, 15:07:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:07:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:07:14 INFO - 'tests': ['a11yr', 15:07:14 INFO - 'ts_paint', 15:07:14 INFO - 'tpaint', 15:07:14 INFO - 'sessionrestore', 15:07:14 INFO - 'sessionrestore_no_auto_restore', 15:07:14 INFO - 'tabpaint']}, 15:07:14 INFO - 'other-e10s': {'tests': ['a11yr', 15:07:14 INFO - 'ts_paint', 15:07:14 INFO - 'tpaint', 15:07:14 INFO - 'sessionrestore', 15:07:14 INFO - 'sessionrestore_no_auto_restore', 15:07:14 INFO - 'tabpaint']}, 15:07:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:07:14 INFO - 'ts_paint', 15:07:14 INFO - 'tpaint', 15:07:14 INFO - 'sessionrestore', 15:07:14 INFO - 'sessionrestore_no_auto_restore', 15:07:14 INFO - 'tabpaint']}, 15:07:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:07:14 INFO - 'ts_paint', 15:07:14 INFO - 'tpaint', 15:07:14 INFO - 'sessionrestore', 15:07:14 INFO - 'sessionrestore_no_auto_restore', 15:07:14 INFO - 'tabpaint']}, 15:07:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:07:14 INFO - 'tests': ['a11yr', 15:07:14 INFO - 'ts_paint', 15:07:14 INFO - 'tpaint', 15:07:14 INFO - 'sessionrestore', 15:07:14 INFO - 'sessionrestore_no_auto_restore', 15:07:14 INFO - 'tabpaint']}, 15:07:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:07:14 INFO - 'tests': ['a11yr', 15:07:14 INFO - 'ts_paint', 15:07:14 INFO - 'tpaint', 15:07:14 INFO - 'sessionrestore', 15:07:14 INFO - 'sessionrestore_no_auto_restore', 15:07:14 INFO - 'tabpaint']}, 15:07:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:07:14 INFO - 'tests': ['tsvgx', 15:07:14 INFO - 'tsvgr_opacity', 15:07:14 INFO - 'tart', 15:07:14 INFO - 'tscrollx', 15:07:14 INFO - 'cart']}, 15:07:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:07:14 INFO - 'tsvgr_opacity', 15:07:14 INFO - 'tart', 15:07:14 INFO - 'tscrollx', 15:07:14 INFO - 'cart']}, 15:07:14 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:07:14 INFO - 'talos_options': ['--disable-e10s'], 15:07:14 INFO - 'tests': ['tp5o']}, 15:07:14 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:07:14 INFO - 'tests': ['tp5o']}, 15:07:14 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:07:14 INFO - 'talos_options': ['--disable-e10s', 15:07:14 INFO - '--xperf_path', 15:07:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:07:14 INFO - 'tests': ['tp5n']}, 15:07:14 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:07:14 INFO - 'talos_options': ['--xperf_path', 15:07:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:07:14 INFO - 'tests': ['tp5n']}}, 15:07:14 INFO - 'talos.zip': {'path': '', 15:07:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:07:14 INFO - Running post-action listener: _resource_record_post_action 15:07:14 INFO - [mozharness: 2016-12-21 23:07:14.411111Z] Finished populate-webroot step (success) 15:07:14 INFO - [mozharness: 2016-12-21 23:07:14.411207Z] Running create-virtualenv step. 15:07:14 INFO - Running pre-action listener: _resource_record_pre_action 15:07:14 INFO - Running main action method: create_virtualenv 15:07:14 INFO - Creating virtualenv /builds/slave/test/build/venv 15:07:14 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:07:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:07:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:07:14 INFO - Using real prefix '/usr' 15:07:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:07:15 INFO - Installing distribute.............................................................................................................................................................................................done. 15:07:16 INFO - Installing pip.................done. 15:07:16 INFO - Return code: 0 15:07:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:07: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')]} 15:07:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:07:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07: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')]} 15:07:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:07:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaca9acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2636ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2633f60>, '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 0x2634d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26351d0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1482361461.603230-1973869559', '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 15:07:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:07:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:07:16 INFO - Using env: {'DISPLAY': ':0', 15:07:16 INFO - 'HOME': '/home/cltbld', 15:07:16 INFO - 'LANG': 'en_US.UTF-8', 15:07:16 INFO - 'LANGUAGE': 'en_US:en', 15:07:16 INFO - 'LOGNAME': 'cltbld', 15:07:16 INFO - 'MAIL': '/var/mail/cltbld', 15:07:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:07:16 INFO - 'MOZ_NO_REMOTE': '1', 15:07:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:07:16 INFO - 'NO_EM_RESTART': '1', 15:07:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:07:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:07:16 INFO - 'PWD': '/builds/slave/test', 15:07:16 INFO - 'SHELL': '/bin/bash', 15:07:16 INFO - 'SHLVL': '1', 15:07:16 INFO - 'TERM': 'linux', 15:07:16 INFO - 'TMOUT': '86400', 15:07:16 INFO - 'USER': 'cltbld', 15:07:16 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1482361461.603230-1973869559', 15:07:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:07:16 INFO - '_': '/tools/buildbot/bin/python'} 15:07:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:07:17 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 15:07:17 INFO - Cleaning up... 15:07:17 INFO - Return code: 0 15:07:17 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:07:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:07:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:07:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:07:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:07:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaca9acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2636ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2633f60>, '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 0x2634d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26351d0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1482361461.603230-1973869559', '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 15:07:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:07:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:07:17 INFO - Using env: {'DISPLAY': ':0', 15:07:17 INFO - 'HOME': '/home/cltbld', 15:07:17 INFO - 'LANG': 'en_US.UTF-8', 15:07:17 INFO - 'LANGUAGE': 'en_US:en', 15:07:17 INFO - 'LOGNAME': 'cltbld', 15:07:17 INFO - 'MAIL': '/var/mail/cltbld', 15:07:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:07:17 INFO - 'MOZ_NO_REMOTE': '1', 15:07:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:07:17 INFO - 'NO_EM_RESTART': '1', 15:07:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:07:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:07:17 INFO - 'PWD': '/builds/slave/test', 15:07:17 INFO - 'SHELL': '/bin/bash', 15:07:17 INFO - 'SHLVL': '1', 15:07:17 INFO - 'TERM': 'linux', 15:07:17 INFO - 'TMOUT': '86400', 15:07:17 INFO - 'USER': 'cltbld', 15:07:17 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1482361461.603230-1973869559', 15:07:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:07:17 INFO - '_': '/tools/buildbot/bin/python'} 15:07:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:07:17 INFO - Downloading/unpacking psutil>=3.1.1 15:07:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:07:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:07:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:07:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:07:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:07:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:07:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:07:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:07:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:07:19 INFO - Installing collected packages: psutil 15:07:19 INFO - Running setup.py install for psutil 15:07:19 INFO - building 'psutil._psutil_linux' extension 15:07:19 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 15:07:19 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 15:07:19 INFO - building 'psutil._psutil_posix' extension 15:07:19 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 15:07:19 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 15:07:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:07:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:07:20 INFO - Successfully installed psutil 15:07:20 INFO - Cleaning up... 15:07:20 INFO - Return code: 0 15:07:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:07:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:07:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:07:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:07:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:07:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaca9acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2636ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2633f60>, '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 0x2634d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26351d0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1482361461.603230-1973869559', '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 15:07:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:07:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:07:20 INFO - Using env: {'DISPLAY': ':0', 15:07:20 INFO - 'HOME': '/home/cltbld', 15:07:20 INFO - 'LANG': 'en_US.UTF-8', 15:07:20 INFO - 'LANGUAGE': 'en_US:en', 15:07:20 INFO - 'LOGNAME': 'cltbld', 15:07:20 INFO - 'MAIL': '/var/mail/cltbld', 15:07:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:07:20 INFO - 'MOZ_NO_REMOTE': '1', 15:07:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:07:20 INFO - 'NO_EM_RESTART': '1', 15:07:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:07:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:07:20 INFO - 'PWD': '/builds/slave/test', 15:07:20 INFO - 'SHELL': '/bin/bash', 15:07:20 INFO - 'SHLVL': '1', 15:07:20 INFO - 'TERM': 'linux', 15:07:20 INFO - 'TMOUT': '86400', 15:07:20 INFO - 'USER': 'cltbld', 15:07:20 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1482361461.603230-1973869559', 15:07:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:07:20 INFO - '_': '/tools/buildbot/bin/python'} 15:07:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:07:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:07:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:07:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:07:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:07:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:07:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:07:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:07:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:07:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:07:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:07:22 INFO - Installing collected packages: mozsystemmonitor 15:07:22 INFO - Running setup.py install for mozsystemmonitor 15:07:22 INFO - Successfully installed mozsystemmonitor 15:07:22 INFO - Cleaning up... 15:07:22 INFO - Return code: 0 15:07:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:07:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:07:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:07:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:07:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:07:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaca9acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2636ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2633f60>, '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 0x2634d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26351d0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1482361461.603230-1973869559', '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 15:07:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:07:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:07:22 INFO - Using env: {'DISPLAY': ':0', 15:07:22 INFO - 'HOME': '/home/cltbld', 15:07:22 INFO - 'LANG': 'en_US.UTF-8', 15:07:22 INFO - 'LANGUAGE': 'en_US:en', 15:07:22 INFO - 'LOGNAME': 'cltbld', 15:07:22 INFO - 'MAIL': '/var/mail/cltbld', 15:07:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:07:22 INFO - 'MOZ_NO_REMOTE': '1', 15:07:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:07:22 INFO - 'NO_EM_RESTART': '1', 15:07:22 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:07:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:07:22 INFO - 'PWD': '/builds/slave/test', 15:07:22 INFO - 'SHELL': '/bin/bash', 15:07:22 INFO - 'SHLVL': '1', 15:07:22 INFO - 'TERM': 'linux', 15:07:22 INFO - 'TMOUT': '86400', 15:07:22 INFO - 'USER': 'cltbld', 15:07:22 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1482361461.603230-1973869559', 15:07:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:07:22 INFO - '_': '/tools/buildbot/bin/python'} 15:07:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:07:22 INFO - Downloading/unpacking jsonschema==2.5.1 15:07:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:07:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:07:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:07:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:07:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:07:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:07:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:07:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:07:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:07:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:07:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:07:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:07:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:07:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:07:24 INFO - Downloading functools32-3.2.3-2.tar.gz 15:07:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:07:25 INFO - warning: no files found matching '*.txt' 15:07:25 INFO - no previously-included directories found matching 'build' 15:07:25 INFO - no previously-included directories found matching 'dist' 15:07:25 INFO - no previously-included directories found matching '.git*' 15:07:25 INFO - Installing collected packages: jsonschema, functools32 15:07:25 INFO - Running setup.py install for functools32 15:07:25 INFO - warning: no files found matching '*.txt' 15:07:25 INFO - no previously-included directories found matching 'build' 15:07:25 INFO - no previously-included directories found matching 'dist' 15:07:25 INFO - no previously-included directories found matching '.git*' 15:07:25 INFO - Successfully installed jsonschema functools32 15:07:25 INFO - Cleaning up... 15:07:25 INFO - Return code: 0 15:07:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:07:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:07:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:07:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:07:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:07:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaca9acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2636ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2633f60>, '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 0x2634d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26351d0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1482361461.603230-1973869559', '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 15:07:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:07:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:07:25 INFO - Using env: {'DISPLAY': ':0', 15:07:25 INFO - 'HOME': '/home/cltbld', 15:07:25 INFO - 'LANG': 'en_US.UTF-8', 15:07:25 INFO - 'LANGUAGE': 'en_US:en', 15:07:25 INFO - 'LOGNAME': 'cltbld', 15:07:25 INFO - 'MAIL': '/var/mail/cltbld', 15:07:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:07:25 INFO - 'MOZ_NO_REMOTE': '1', 15:07:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:07:25 INFO - 'NO_EM_RESTART': '1', 15:07:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:07:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:07:25 INFO - 'PWD': '/builds/slave/test', 15:07:25 INFO - 'SHELL': '/bin/bash', 15:07:25 INFO - 'SHLVL': '1', 15:07:25 INFO - 'TERM': 'linux', 15:07:25 INFO - 'TMOUT': '86400', 15:07:25 INFO - 'USER': 'cltbld', 15:07:25 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1482361461.603230-1973869559', 15:07:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:07:25 INFO - '_': '/tools/buildbot/bin/python'} 15:07:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:07:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:07:25 INFO - Cleaning up... 15:07:25 INFO - Return code: 0 15:07:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:07:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:07:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:07:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:07:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:07:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaca9acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2636ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2633f60>, '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 0x2634d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26351d0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1482361461.603230-1973869559', '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 15:07:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:07:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:07:25 INFO - Using env: {'DISPLAY': ':0', 15:07:25 INFO - 'HOME': '/home/cltbld', 15:07:25 INFO - 'LANG': 'en_US.UTF-8', 15:07:25 INFO - 'LANGUAGE': 'en_US:en', 15:07:25 INFO - 'LOGNAME': 'cltbld', 15:07:25 INFO - 'MAIL': '/var/mail/cltbld', 15:07:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:07:25 INFO - 'MOZ_NO_REMOTE': '1', 15:07:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:07:25 INFO - 'NO_EM_RESTART': '1', 15:07:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:07:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:07:25 INFO - 'PWD': '/builds/slave/test', 15:07:25 INFO - 'SHELL': '/bin/bash', 15:07:25 INFO - 'SHLVL': '1', 15:07:25 INFO - 'TERM': 'linux', 15:07:25 INFO - 'TMOUT': '86400', 15:07:25 INFO - 'USER': 'cltbld', 15:07:25 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1482361461.603230-1973869559', 15:07:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:07:25 INFO - '_': '/tools/buildbot/bin/python'} 15:07:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:07:25 INFO - Downloading/unpacking blobuploader==1.2.4 15:07:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:07:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:07:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:07:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:07:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:07:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:07:27 INFO - Downloading blobuploader-1.2.4.tar.gz 15:07:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:07:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:07:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:07:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:07:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:07:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:07:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:07:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:07:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:07:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:07:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:07:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:07:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:07:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:07:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:07:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:07:28 INFO - Downloading docopt-0.6.1.tar.gz 15:07:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:07:28 INFO - Installing collected packages: blobuploader, requests, docopt 15:07:28 INFO - Running setup.py install for blobuploader 15:07:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:07:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:07:28 INFO - Running setup.py install for requests 15:07:29 INFO - Running setup.py install for docopt 15:07:29 INFO - Successfully installed blobuploader requests docopt 15:07:29 INFO - Cleaning up... 15:07:29 INFO - Return code: 0 15:07:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:07:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:07:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:07:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:07:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:07:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaca9acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2636ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2633f60>, '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 0x2634d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26351d0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1482361461.603230-1973869559', '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 15:07:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:07:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:07:29 INFO - Using env: {'DISPLAY': ':0', 15:07:29 INFO - 'HOME': '/home/cltbld', 15:07:29 INFO - 'LANG': 'en_US.UTF-8', 15:07:29 INFO - 'LANGUAGE': 'en_US:en', 15:07:29 INFO - 'LOGNAME': 'cltbld', 15:07:29 INFO - 'MAIL': '/var/mail/cltbld', 15:07:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:07:29 INFO - 'MOZ_NO_REMOTE': '1', 15:07:29 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:07:29 INFO - 'NO_EM_RESTART': '1', 15:07:29 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:07:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:07:29 INFO - 'PWD': '/builds/slave/test', 15:07:29 INFO - 'SHELL': '/bin/bash', 15:07:29 INFO - 'SHLVL': '1', 15:07:29 INFO - 'TERM': 'linux', 15:07:29 INFO - 'TMOUT': '86400', 15:07:29 INFO - 'USER': 'cltbld', 15:07:29 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1482361461.603230-1973869559', 15:07:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:07:29 INFO - '_': '/tools/buildbot/bin/python'} 15:07:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:07:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:07:29 INFO - Running setup.py (path:/tmp/pip-qFabbj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:07:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:07:29 INFO - Running setup.py (path:/tmp/pip-UMQ90R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:07:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:07:29 INFO - Running setup.py (path:/tmp/pip-D0EdoQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:07:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:07:29 INFO - Running setup.py (path:/tmp/pip-eknstm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:07:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:07:29 INFO - Running setup.py (path:/tmp/pip-J2xLlp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:07:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:07:29 INFO - Running setup.py (path:/tmp/pip-IMi5Y4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:07:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:07:29 INFO - Running setup.py (path:/tmp/pip-VUJZF0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:07:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:07:29 INFO - Running setup.py (path:/tmp/pip-ZpE6tR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:07:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:07:30 INFO - Running setup.py (path:/tmp/pip-Hya5vf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:07:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:07:30 INFO - Running setup.py (path:/tmp/pip-TsF6mT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:07:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:07:30 INFO - Running setup.py (path:/tmp/pip-MgPK0W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:07:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:07:30 INFO - Running setup.py (path:/tmp/pip-4VGvPo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:07:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:07:30 INFO - Running setup.py (path:/tmp/pip-RSzhAL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:07:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:07:30 INFO - Running setup.py (path:/tmp/pip-018xqd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:07:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:07:30 INFO - Running setup.py (path:/tmp/pip-E7FywS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:07:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:07:30 INFO - Running setup.py (path:/tmp/pip-74PtPt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:07:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:07:30 INFO - Running setup.py (path:/tmp/pip-trE7v2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:07:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:07:30 INFO - Running setup.py install for manifestparser 15:07:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:07:30 INFO - Running setup.py install for mozcrash 15:07:30 INFO - Running setup.py install for mozdebug 15:07:30 INFO - Running setup.py install for mozdevice 15:07:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:07:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:07:31 INFO - Running setup.py install for mozfile 15:07:31 INFO - Running setup.py install for mozhttpd 15:07:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:07:31 INFO - Running setup.py install for mozinfo 15:07:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:07:31 INFO - Running setup.py install for mozInstall 15:07:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:07:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:07:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:07:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:07:31 INFO - Running setup.py install for mozleak 15:07:31 INFO - Running setup.py install for mozlog 15:07:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:07:31 INFO - Running setup.py install for moznetwork 15:07:31 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:07:31 INFO - Running setup.py install for mozprocess 15:07:31 INFO - Running setup.py install for mozprofile 15:07:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:07:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:07:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:07:32 INFO - Running setup.py install for mozrunner 15:07:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:07:32 INFO - Running setup.py install for mozscreenshot 15:07:32 INFO - Running setup.py install for moztest 15:07:32 INFO - Running setup.py install for mozversion 15:07:32 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:07:32 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:07:32 INFO - Cleaning up... 15:07:32 INFO - Return code: 0 15:07:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:07:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:07:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:07:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:07:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:07:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaca9acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2636ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2633f60>, '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 0x2634d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26351d0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1482361461.603230-1973869559', '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 15:07:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 15:07:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:07:32 INFO - Using env: {'DISPLAY': ':0', 15:07:32 INFO - 'HOME': '/home/cltbld', 15:07:32 INFO - 'LANG': 'en_US.UTF-8', 15:07:32 INFO - 'LANGUAGE': 'en_US:en', 15:07:32 INFO - 'LOGNAME': 'cltbld', 15:07:32 INFO - 'MAIL': '/var/mail/cltbld', 15:07:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:07:32 INFO - 'MOZ_NO_REMOTE': '1', 15:07:32 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:07:32 INFO - 'NO_EM_RESTART': '1', 15:07:32 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:07:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:07:32 INFO - 'PWD': '/builds/slave/test', 15:07:32 INFO - 'SHELL': '/bin/bash', 15:07:32 INFO - 'SHLVL': '1', 15:07:32 INFO - 'TERM': 'linux', 15:07:32 INFO - 'TMOUT': '86400', 15:07:32 INFO - 'USER': 'cltbld', 15:07:32 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1482361461.603230-1973869559', 15:07:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:07:32 INFO - '_': '/tools/buildbot/bin/python'} 15:07:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:07:32 INFO - Running setup.py (path:/tmp/pip-gMBFr4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:07:32 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 15:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:07:32 INFO - Running setup.py (path:/tmp/pip-SufDRi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:07:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:07:32 INFO - Running setup.py (path:/tmp/pip-H50SXZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:07:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:07:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:07:32 INFO - Running setup.py (path:/tmp/pip-CphRxn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:07:33 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)) 15:07:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:07:33 INFO - Running setup.py (path:/tmp/pip-JvWA5L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:07:33 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)) 15:07:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:07:33 INFO - Running setup.py (path:/tmp/pip-if7jei-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:07:33 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)) 15:07:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:07:33 INFO - Running setup.py (path:/tmp/pip-0BohIK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:07:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 15:07:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:07:33 INFO - Running setup.py (path:/tmp/pip-0nsQeX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:07:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 15:07:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:07:33 INFO - Running setup.py (path:/tmp/pip-_yH9Ss-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:07:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 15:07:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:07:33 INFO - Running setup.py (path:/tmp/pip-Q1ypef-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:07:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:07:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:07:33 INFO - Running setup.py (path:/tmp/pip-gTuqdy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:07:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 15:07:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:07:33 INFO - Running setup.py (path:/tmp/pip-Y8KFyf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:07:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:07:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:07:33 INFO - Running setup.py (path:/tmp/pip-THFzQE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:07:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 15:07:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:07:33 INFO - Running setup.py (path:/tmp/pip-oKrc8q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:07:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 15:07:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:07:33 INFO - Running setup.py (path:/tmp/pip-XWJFOG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:07:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 15:07:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:07:33 INFO - Running setup.py (path:/tmp/pip-AP5er0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:07:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:07:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:07:33 INFO - Running setup.py (path:/tmp/pip-TR0BPQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:07:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 15:07:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:07:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 15:07:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 15:07:33 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:07:33 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 15:07:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:07:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:07:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:07:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:07:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:07:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:07:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:07:35 INFO - Downloading blessings-1.6.tar.gz 15:07:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:07:36 INFO - Installing collected packages: blessings 15:07:36 INFO - Running setup.py install for blessings 15:07:36 INFO - Successfully installed blessings 15:07:36 INFO - Cleaning up... 15:07:36 INFO - Return code: 0 15:07:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:07:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:07:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:07:36 INFO - Reading from file tmpfile_stdout 15:07:36 INFO - Current package versions: 15:07:36 INFO - argparse == 1.2.1 15:07:36 INFO - blessings == 1.6 15:07:36 INFO - blobuploader == 1.2.4 15:07:36 INFO - docopt == 0.6.1 15:07:36 INFO - functools32 == 3.2.3-2 15:07:36 INFO - jsonschema == 2.5.1 15:07:36 INFO - manifestparser == 1.1 15:07:36 INFO - mozInstall == 1.12 15:07:36 INFO - mozcrash == 0.17 15:07:36 INFO - mozdebug == 0.1 15:07:36 INFO - mozdevice == 0.48 15:07:36 INFO - mozfile == 1.2 15:07:36 INFO - mozhttpd == 0.7 15:07:36 INFO - mozinfo == 0.9 15:07:36 INFO - mozleak == 0.1 15:07:36 INFO - mozlog == 3.4 15:07:36 INFO - moznetwork == 0.27 15:07:36 INFO - mozprocess == 0.23 15:07:36 INFO - mozprofile == 0.28 15:07:36 INFO - mozrunner == 6.12 15:07:36 INFO - mozscreenshot == 0.1 15:07:36 INFO - mozsystemmonitor == 0.3 15:07:36 INFO - moztest == 0.7 15:07:36 INFO - mozversion == 1.4 15:07:36 INFO - psutil == 3.1.1 15:07:36 INFO - requests == 1.2.3 15:07:36 INFO - wsgiref == 0.1.2 15:07:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:07: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')]} 15:07:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:07:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07: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')]} 15:07:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:07:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaca9acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2636ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2633f60>, '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 0x2634d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26351d0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1482361461.603230-1973869559', '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 15:07:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 15:07:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 15:07:36 INFO - Using env: {'DISPLAY': ':0', 15:07:36 INFO - 'HOME': '/home/cltbld', 15:07:36 INFO - 'LANG': 'en_US.UTF-8', 15:07:36 INFO - 'LANGUAGE': 'en_US:en', 15:07:36 INFO - 'LOGNAME': 'cltbld', 15:07:36 INFO - 'MAIL': '/var/mail/cltbld', 15:07:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:07:36 INFO - 'MOZ_NO_REMOTE': '1', 15:07:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:07:36 INFO - 'NO_EM_RESTART': '1', 15:07:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:07:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:07:36 INFO - 'PWD': '/builds/slave/test', 15:07:36 INFO - 'SHELL': '/bin/bash', 15:07:36 INFO - 'SHLVL': '1', 15:07:36 INFO - 'TERM': 'linux', 15:07:36 INFO - 'TMOUT': '86400', 15:07:36 INFO - 'USER': 'cltbld', 15:07:36 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1482361461.603230-1973869559', 15:07:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:07:36 INFO - '_': '/tools/buildbot/bin/python'} 15:07:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:07:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:07:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 15:07:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 15:07:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:07:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 15:07:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 15:07:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 15:07:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 15:07:36 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 15:07:36 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 15:07:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 15:07:36 INFO - Cleaning up... 15:07:36 INFO - Return code: 0 15:07:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:07: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')]} 15:07:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:07:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07: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')]} 15:07:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:07:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:07:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feaca9acf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25698a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x2636ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2633f60>, '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 0x2634d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26351d0>, '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': '7fc86f70bae03e018da0b1d8000001c4-1482361461.603230-1973869559', '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 15:07:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:07:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:07:36 INFO - Using env: {'DISPLAY': ':0', 15:07:36 INFO - 'HOME': '/home/cltbld', 15:07:36 INFO - 'LANG': 'en_US.UTF-8', 15:07:36 INFO - 'LANGUAGE': 'en_US:en', 15:07:36 INFO - 'LOGNAME': 'cltbld', 15:07:36 INFO - 'MAIL': '/var/mail/cltbld', 15:07:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:07:36 INFO - 'MOZ_NO_REMOTE': '1', 15:07:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:07:36 INFO - 'NO_EM_RESTART': '1', 15:07:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:07:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:07:36 INFO - 'PWD': '/builds/slave/test', 15:07:36 INFO - 'SHELL': '/bin/bash', 15:07:36 INFO - 'SHLVL': '1', 15:07:36 INFO - 'TERM': 'linux', 15:07:36 INFO - 'TMOUT': '86400', 15:07:36 INFO - 'USER': 'cltbld', 15:07:36 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1482361461.603230-1973869559', 15:07:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:07:36 INFO - '_': '/tools/buildbot/bin/python'} 15:07:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:07:36 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:07:36 INFO - Cleaning up... 15:07:36 INFO - Return code: 0 15:07:36 INFO - Running post-action listener: _resource_record_post_action 15:07:36 INFO - Running post-action listener: _start_resource_monitoring 15:07:36 INFO - Starting resource monitoring. 15:07:36 INFO - [mozharness: 2016-12-21 23:07:36.894313Z] Finished create-virtualenv step (success) 15:07:36 INFO - [mozharness: 2016-12-21 23:07:36.894816Z] Running install step. 15:07:36 INFO - Running pre-action listener: _resource_record_pre_action 15:07:36 INFO - Running main action method: install 15:07:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:07:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:07:37 INFO - Reading from file tmpfile_stdout 15:07:37 INFO - Detecting whether we're running mozinstall >=1.0... 15:07:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:07:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:07:37 INFO - Reading from file tmpfile_stdout 15:07:37 INFO - Output received: 15:07:37 INFO - Usage: mozinstall [options] installer 15:07:37 INFO - Options: 15:07:37 INFO - -h, --help show this help message and exit 15:07:37 INFO - -d DEST, --destination=DEST 15:07:37 INFO - Directory to install application into. [default: 15:07:37 INFO - "/builds/slave/test"] 15:07:37 INFO - --app=APP Application being installed. [default: firefox] 15:07:37 INFO - mkdir: /builds/slave/test/build/application 15:07:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 15:07:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 15:07:50 INFO - Reading from file tmpfile_stdout 15:07:50 INFO - Output received: 15:07:50 INFO - /builds/slave/test/build/application/firefox/firefox 15:07:50 INFO - Running post-action listener: _resource_record_post_action 15:07:50 INFO - [mozharness: 2016-12-21 23:07:50.698254Z] Finished install step (success) 15:07:50 INFO - [mozharness: 2016-12-21 23:07:50.698502Z] Running run-tests step. 15:07:50 INFO - Running pre-action listener: _resource_record_pre_action 15:07:50 INFO - Running main action method: run_tests 15:07:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:07:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:07:50 INFO - Python 2.7.3 15:07:50 INFO - Return code: 0 15:07:50 INFO - grabbing minidump binary from tooltool 15:07:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:07:50 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2633f60>, '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 0x2634d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26351d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:07:50 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 15:07:50 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 15:07:50 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 15:07:50 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:07:50 INFO - Return code: 0 15:07:50 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 15:07:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:07:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:07:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 15:07:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:07:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/f4cE6Ds-TdemBHYzw5yjjw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--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 15:07:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/f4cE6Ds-TdemBHYzw5yjjw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-005 --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 15:07:50 INFO - Using env: {'DISPLAY': ':0', 15:07:50 INFO - 'HOME': '/home/cltbld', 15:07:50 INFO - 'LANG': 'en_US.UTF-8', 15:07:50 INFO - 'LANGUAGE': 'en_US:en', 15:07:50 INFO - 'LOGNAME': 'cltbld', 15:07:50 INFO - 'MAIL': '/var/mail/cltbld', 15:07:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:07:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 15:07:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:07:50 INFO - 'MOZ_NO_REMOTE': '1', 15:07:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:07:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 15:07:50 INFO - 'NO_EM_RESTART': '1', 15:07:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 15:07:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:07:50 INFO - 'PWD': '/builds/slave/test', 15:07:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:07:50 INFO - 'SHELL': '/bin/bash', 15:07:50 INFO - 'SHLVL': '1', 15:07:50 INFO - 'TERM': 'linux', 15:07:50 INFO - 'TMOUT': '86400', 15:07:50 INFO - 'USER': 'cltbld', 15:07:50 INFO - 'XDG_SESSION_COOKIE': '7fc86f70bae03e018da0b1d8000001c4-1482361461.603230-1973869559', 15:07:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:07:50 INFO - '_': '/tools/buildbot/bin/python'} 15:07:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/f4cE6Ds-TdemBHYzw5yjjw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-005', '--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 15:07:50 INFO - mozversion application_buildid: 20161221132752 15:07:50 INFO - mozversion application_changeset: 839579d7d875c968a0403a0cee1a7fad67c6e228 15:07:50 INFO - mozversion application_display_name: Firefox 15:07:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:07:50 INFO - mozversion application_name: Firefox 15:07:50 INFO - mozversion application_remotingname: firefox 15:07:50 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 15:07:50 INFO - mozversion application_vendor: Mozilla 15:07:50 INFO - mozversion application_version: 51.0 15:07:50 INFO - mozversion platform_buildid: 20161221132752 15:07:50 INFO - mozversion platform_changeset: 839579d7d875c968a0403a0cee1a7fad67c6e228 15:07:50 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 15:07:50 INFO - mozversion platform_version: 51.0 15:07:50 INFO - using testdate: 1482361670 15:07:50 INFO - actual date: 1482361670 15:07:50 INFO - starting webserver on 'localhost:51499' 15:07:50 INFO - SUITE-START | Running 2 tests 15:07:50 INFO - TEST-START | dromaeo_css 15:07:50 INFO - operating with platform_type : linux_ 15:07:50 INFO - Initialising browser for dromaeo_css test... 15:07:50 INFO - TEST-INFO | started process 8817 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyBE0Vn/profile http://localhost:51499/getInfo.html) 15:07:54 INFO - PROCESS | 8817 | __metrics Screen width/height:1600/1200 15:07:54 INFO - PROCESS | 8817 | colorDepth:24 15:07:54 INFO - PROCESS | 8817 | Browser inner width/height: 1024/697 15:07:54 INFO - PROCESS | 8817 | __metrics 15:07:55 INFO - PROCESS | 8817 | 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" 15:07:55 INFO - PROCESS | 8817 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:07:55 INFO - PROCESS | 8817 | console.error: PushService: 15:07:55 INFO - PROCESS | 8817 | stateChangeProcessEnqueue: Error transitioning state 15:07:55 INFO - PROCESS | 8817 | UnknownError 15:07:56 INFO - TEST-INFO | 8817: exit 0 15:07:56 INFO - Browser initialized. 15:07:56 INFO - Running cycle 1/1 for dromaeo_css test... 15:07:56 INFO - TEST-INFO | started process 8910 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpyBE0Vn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:08:08 INFO - PROCESS | 8910 | RSS: Main: 143007744 15:08:08 INFO - PROCESS | 8910 | 15:08:59 INFO - PROCESS | 8910 | 1482361739374 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] 15:11:08 INFO - PROCESS | 8910 | Cycle 1(1): loaded http://localhost:51499/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51499/tests/dromaeo/cssquery-ext.html) 15:11:09 INFO - PROCESS | 8910 | RSS: Main: 240312320 15:11:09 INFO - PROCESS | 8910 | 15:14:11 INFO - PROCESS | 8910 | Cycle 1(1): loaded http://localhost:51499/tests/dromaeo/cssquery-ext.html (next: http://localhost:51499/tests/dromaeo/cssquery-jquery.html) 15:14:12 INFO - PROCESS | 8910 | RSS: Main: 243036160 15:14:12 INFO - PROCESS | 8910 | 15:17:14 INFO - PROCESS | 8910 | Cycle 1(1): loaded http://localhost:51499/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51499/tests/dromaeo/cssquery-mootools.html) 15:17:14 INFO - PROCESS | 8910 | RSS: Main: 175091712 15:17:14 INFO - PROCESS | 8910 | 15:20:16 INFO - PROCESS | 8910 | Cycle 1(1): loaded http://localhost:51499/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51499/tests/dromaeo/cssquery-prototype.html) 15:20:17 INFO - PROCESS | 8910 | RSS: Main: 222232576 15:20:17 INFO - PROCESS | 8910 | 15:23:19 INFO - PROCESS | 8910 | Cycle 1(1): loaded http://localhost:51499/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51499/tests/dromaeo/cssquery-yui.html) 15:23:20 INFO - PROCESS | 8910 | RSS: Main: 177262592 15:23:20 INFO - PROCESS | 8910 | 15:26:22 INFO - PROCESS | 8910 | Cycle 1(1): loaded http://localhost:51499/tests/dromaeo/cssquery-yui.html (next: http://localhost:51499/tests/dromaeo/cssquery-dojo.html) 15:26:23 INFO - PROCESS | 8910 | RSS: Main: 264486912 15:26:23 INFO - PROCESS | 8910 | 15:26:23 INFO - PROCESS | 8910 | __start_tp_report 15:26:23 INFO - PROCESS | 8910 | _x_x_mozilla_page_load 15:26:23 INFO - PROCESS | 8910 | _x_x_mozilla_page_load_details 15:26:23 INFO - PROCESS | 8910 | |i|pagename|runs| 15:26:23 INFO - PROCESS | 8910 | |0;dojo.html;3134.937301253975;3181.9681803181975;3182.968170318297;3212.53418254353;3230.20859889327;5542.972285138574;5625.634333768302;5645.520130788877;5658.91511627326;5674.744636491358;5551.611387202897;5599.160125981105;5603.299587551556;5604.943950560493;5610.719464026803;5066.36670416198;5082.08522465956;5106.4382917879;5122.462141475147;5140.357455318085;4999.125153098212;5101.362329708786;5160.354955630546;5161.045206636784;5190.662606930538;4963.45402005779;5001.224810154432;5027.396712394518;5030.723310217936;5100.821471248507;5124.333836601237;5145.30538377318;5155.896882062375;5203.063448579254;5203.817866374697;5081.339425874613;5143.768530416103;5200.869978250536;5216.374035115772;5239.711815850127;5398.541124004464;5426.511613954763;5433.266509021271;5457.372402173761;5477.780888764445;5852.941470585282;5919.1713160157615;5940.881182376372;5949.9702501487645;5957.255343082114;5998.820035398945;6002.96998515009;6008.48927841134;6015.75936962521;6018.789342372996;5435.755391007375;5468.589855760794;5517.724113794335;5559.416261292587;5565.554755619541;6123.418275263843;6158.568900176944;6159.4456498914415;6162.22972128484;6162.506999440034;5891.410858914074;5896.204012458349;5897.911531327033;5900.822975310747;5901.29184497863;5766.192733017381;5804.071348584291;5883.529317654577;5895.587308888423;5945.970270148621;5950.494207992283;5961.642301461926;5963.522918166536;5982.371850955587;5987.64074155552;5933.495652869555;5987.940120598825;5997.730102145414;5999.040153575407;6000.669963151981;4515.051839113823;4543.636509079266;4560.065186636732;4587.977060114745;4659.510751371125;4083.387491876242;4107.0553772631865;4126.463559737275;4134.152498737752;4156.729812562174;5717.685527295956;5719.799807006735;5720.1991721159075;5723.313202415737;5724.141378793214;5748.770049197985;5761.308642962871;5836.970815145897;5986.042233242661;5989.790357337472;5643.153526970905;5652.434756524397;5654.717264136859;5685.374608793029;5686.3744988051285;5201.401838788512;5211.140161873249;5224.425313215544;5224.869378265497;5225.608079394061;5903.793367232212;6021.54838387114;6140.324564298013;6177.752889884444;6181.907271390933;5834.94165058361;5839.795607153729;5867.882642347215;5918.644881307136;5959.791407300723;6071.939280607137;6094.78668246627;6097.085437184458;6100.420460056288;6101.633901965896;5662.66024038559;5806.970965145148;5819.418058194146;5821.563382746226;5825.6795876225515;5736.971315143398;5886.23478947734;5902.881942361215;5921.792737254175;6050.183225264533;5816.331121921034;5856.472917437451;5857;5859.267591551056;5864.237649105589;5837.153612726046;5863.296404431496;5866.208061911758;5866.530677545871;5870.7945221917025;5664.5751568631695;5673.971630141823;5737.540996720336;5752.626079305;5796.985527532581;5921.881562368814;5940.524758019435;5942.64344139353;5946.256717910262;5971.611845230046;5596.720163991703;5738.081906894877;5766.682832444088;5789.594728369142;5791.015527360443 15:26:23 INFO - PROCESS | 8910 | |1;ext.html;596.6927032578222;604.9032154855223;643.8229486891101;651.1730102769482;658.2232965101178;33984.320313593715;37657.81171094144;44301.113977720466;44980.3252951206;45095.77452112743;47739;48159;48227.75886120564;48244.75877620607;48266.27600585994;9286.535673216345;9437.245020398386;9450.385724927857;9450.66922657707;9455.763605909873;8567.614457349404;8774.122587741207;11303.773924521505;11608.419579021058;11613.954744073031;11220.66338009861;11279.154063445234;11371.204015718902;11376.886231137665;11379;10448.477576121202;10524.78950420995;10529.526171322308;10536.315139515908;10544.894551054545;11101.888981110167;11151.888481115166;11172.10623150146;11173.664790056311;11177.385243811586;43502.564974350484;43534.34698479525;43705;43794;43866.342004869955;3660.633936606345;3661.6521430464063;3777.981110094432;3861.4593956846065;4202.810873510669;33831.323373532636;34497.6550234497;34758;35452.64547354519;35890.820545897106;4716.858494245178;4796.664233503656;4801.255805350176;4819.373481447389;4886.53577910098;366731;366952;367297;367570.1621491902;368513;38246.617533824865;39286.60713392887;62812.68593657002;63245.68377158085;63296.683516582125;41237.793811031355;41945.16109677789;41946.58053419488;42021;42026.78986605047;15534;15619.3752249911;15653.37386504527;15667.2949717263;15668.373265069498;12167.695807604881;12170.14808963364;13766.724665506634;13927.442902283998;13975.37110830015;9229.769255768659;9595.088466595662;9604.615815367308;9678.806423871623;9788.55951482185;3639.3267245559655;3645.179834537208;3677.3380791457794;3701.1487357907295;3711.4247291669835;995.7809281957958;1064.8722153341648;1066.2323127348297;1067.0343339277965;1113.6423682596976;35064.64935350665;35419.645803541636;35609.82195089008;35713.46429803555;35806;1129.5030186717818;1156.9595064172713;1176.8940795328463;1192.4574318684977;1251.5932321995388;17959.73060404095;18161.545961350806;18206.726899096524;18338.724919126224;18575.16411761474;513294;516992;517030;517776;517798;63521;63830.36169638337;63846.042309365395;63938.36061639417;64078.35921640724;1280.6862318731785;1304.54340980656;1366.9863301366859;1426.6718654709268;1427.985720142806;27559.586606201323;31240.531392028683;31457.370852582364;31769.841150795022;31987.360252794348;54985.725071374385;55518.44481555133;55649.44350556443;55667.721661391435;55674;432503;434534;436030;436560;437078;27673.44653106967;27873;28347.858260708563;31493;32610.51084233786;45615;50239.74880125722;50793.49206507888;50795.74602126966;51068;42552;43573.96957495706;43734.56265437305;43769.343459848766;44789.77605111954;42916.570834291255;43412.13175736531;43914.78042609894;44110;45043;44380.33429498625;45190.77404612956;45316;46023.309650354604;46236;36831.8158409217;37467.81266093761;38795.050355141444;38841;39021;29715.951197918894;29925.700742993162;30164.54753178748;30225.546616800326;30392.696073038987 15:26:23 INFO - PROCESS | 8910 | |2;jquery.html;393.51794052285936;400.9498812648419;402.6617641181407;407.3197759741232;418.98114584843677;13824.792628110587;18567.81432185678;19992.90003549984;20074.6988795168;20106.89946550267;15535.456259030936;15911.363545458167;17708.46874593761;17897.642047159083;19615.215391384365;5563.944360556383;5886.999210134288;5906.202662640553;5908;5914.970425147868;5816.447437493432;5884.6174998625165;5907.143464197688;5913.024350982083;5917.822465326026;5961.463468287853;6140.907886381708;6144.324124346318;6147.907781383283;6156.353582873801;6131.9386806131815;6169.475594574466;6219.253689557237;6521.739130434777;6603.207615086172;5914.704264786744;5924.703764811785;5924.703764811785;5936.5547583931375;5948.464638182584;14158.079724817957;14464.71070578593;14566.563003109924;14629.6342591435;16477.176141193013;2445.9266222013366;2465.149523414419;2653.4560415114856;3015.3818467214387;3068.3096303331795;10558.894411055868;10815.891841081568;11051.447427628586;11798.941005295004;14959;1950.7951665075104;1986.1261045140093;2042.101475350841;2070.6686930091114;2113.9788602113936;81951.5902420484;84416.57791711001;85437.57281213616;85914.14085859124;88842.11157888403;10955.890441095487;10980.231383803057;11142.442877856074;11162.832557511643;11291.604793832177;14987.32557034936;15027.699446011236;15073.397064117313;15108.39566417331;15155.469558565397;7161.92838071623;7622.237776222444;7666.8849967250535;8030.357571394275;8408.117147699526;7477.327040566375;7590.430717696195;7600.733974310872;7794.337481314038;7810.258025487569;4621.722696638212;4659.44086709597;4659.720416775004;4699.365585645963;5129.204973229155;1957.1388589020785;1992.531755037565;2027.9391618251475;2067.7622073461444;2069.461939895638;928.4522131942186;959.5346257065318;1009.3439264478149;1016.872890888639;1042.9269951103502;19349.806501935083;19387.127579258966;19513.707294390595;19945.700814487795;20264;1008.5713571732131;1015.2284263959444;1036.4247842447364;1040.2770074798093;1085.3542142425244;10390.376577405379;11172.608958686407;11231.157663175294;11301.71745706364;11307.434628268553;478499;484581;486160;491490;494448;92994.53502732443;93229.06770932204;93328.53335733413;93430.53284733533;94090.0590994095;1036.9585216591252;1040.1314902056715;1048.09863517375;1048.2400259811575;1055.2507719519074;16635.667286653956;16933.745993809855;17152.742708859627;17362;17625.20686569082;41066.1786764269;42050;42923.424594524426;43516;43521;45056.324155138354;57882.421175787706;58315;58436.83126337364;60487.09269361051;8199.508029518249;8403.537805420761;8446.957765211135;8620.61207245688;8620.78448038804;12037.819432708342;12474.43865026058;12618.17981831178;12661.810072849099;12778.424970876518;11294.887051129384;11516.884831151581;11539.4807233673;11693;12053.51785928553;11102.944485277845;11142.442877856236;11259.605913792979;11338.263012904135;11525.250858694159;11370.488328025423;11564.710882227675;11587.536498540301;11700.35648039366;11970.521179152736;7503.249675032672;7912.84174316499;8530.402871798915;8736.03903570594;8803.647854085764;10829.945850270698;10944.288621239912;11127.443627818739;11296.49165787525;11455.942720286344 15:26:23 INFO - PROCESS | 8910 | |3;mootools.html;1482.2218335373923;1499.7150541397134;1634.6403791165947;1743.7539591095674;1779.7419374190742;8895.555222238894;9602.5678844452;9607.375520591157;9608.75978100547;9623.566939487722;7970.72102476412;8300.29447496962;8331.250187483125;8488.57557122143;9351.345405821596;2351.118330626011;2457.9262622121364;2462.2613216035206;2466.950660986779;2476.1704828882257;2405.6632071509916;2432;2439.2194497760725;2453.0555736041665;2458.7787099161073;2393.258089992108;2437.597796363598;2438.634204869266;2443.0960544598524;2449.5713250181234;2351.929442116739;2352.8941197646145;2355.623100303943;2360.327306717586;2393.772591603812;2396.125414223813;2398.1486572266767;2399.664047033417;2402.3513651314224;2403.5072810073893;9138.17756401927;9341.39280946743;9440.480773557452;9443;9443.197328227108;5568.582356323252;5676.943230567683;5706.77172913083;5712.857178570527;5722.771089156428;3336.7330613550857;3375.443051896434;3543.3976224041976;3556.448750443685;3563.198280386918;1150.488032825393;1179.2747460311932;1183.1303991566192;1187.453771265219;1192.3084610925641;13370.331483425789;13409.46362145513;13421.39603717835;13423.194608323533;13435.462581496826;4703.475491217925;4759.738214398241;4834.081524510332;4872.805087796449;4976.303317535548;10990.560377584807;11003.394813285186;11026.503807328689;11033.724156895982;11049.558017679363;5696;5704.429557044263;5707;5709.058005429058;5711;4591.44902611682;4942.629302802232;5117.488251174927;5223;5255.5532779713385;4231.978840105779;4251.553586873334;4312.072904325585;4351.564843515686;4352.695311328177;1322.768515509782;1327.097573649908;1332.6202032420713;1335.79963005548;1338.3308345827086;889.1641856654853;988.1304452082265;988.2588058955783;993.562832353762;996.3075662414698;5897.262842144732;6080.8783824324155;6206.503479721611;6265.8120256392385;6408.487321014401;460.2337108713954;483.62408946547015;487.3347880143649;491.7492079039715;495.69762444908577;4183.5398106208295;4275.144971005811;4452.443444569429;4575.130725162208;4699.530046995274;11825.704357390961;12036.699082522831;12056.87943120575;12060.216085954386;12071.517139314328;10967.890321096846;11823.231489953125;11928.821067683992;11939.403029848472;11974.820377694341;8456.830863382698;8878.28973682104;8883.64465421389;8885.111488851064;8887.511186884783;6204.7828326008375;6232.844178895382;6281.124817351379;6470.547061705635;6504.252011018604;10879.836802447811;10893.891061089287;10894.455277236266;10907.890921090688;11017.1186305097;10607.310524815863;10911.072558832271;11045.28205666629;11174.217804753915;11233.269837460539;7565.6973721050545;7784.338331241908;8032.116467188722;8370.74887753344;8583.098774628574;8924.910750892408;9215.723528294162;9234.26125909939;9234.584443699916;9242;8160.347172226327;8192.262696357357;8274.669013239403;8395;8413.284870785807;7937.761867143995;7988.440809143304;8001.759947201594;8075.5558444286125;8157.673693052211;7423.369013633902;7549.282818132268;7566.546007239584;7683.078030636136;7775.961120194363;5576.163575463713;5617.4101719320515;5638.88722225545;5687.8862422752145;5863.325717542537;5649.6327738696855;5741.569382296261;5771.249737534094;5789.623674461147;5831.854203644943 15:26:23 INFO - PROCESS | 8910 | |4;prototype.html;340.15980528095594;345.2180810464298;356.06355285598903;356.77701436602126;359.2670951259431;5668.744906479208;5706.514946229576;5730.398308177639;5759.164921086445;5787.24765780449;5256.973715131419;5389.137737961927;5413.296271484702;5499.917501237474;5562.193481945125;4387.473503179623;4393.604575588196;4404.581564751344;4426.9335959960545;4457.33140028995;4321.438213032301;4410.492793328776;4411.294192929132;4411.911761764779;4470.128324976631;4358.8474403395885;4386.517483076875;4409.228385032622;4414.955850441503;4443.333499974998;4379.693421460499;4393.51671316155;4393.9121217575785;4406.955930440687;4411.1177764447075;3929.1945151243926;3938.72428929975;3942.3889297158985;3946.8026598669953;3956.3274243378696;5201.36157110509;5452.972735136299;5458.563314934796;5477.972610136924;5489.204065410493;4989.077020751172;4991.251312303148;4991.5258050485145;4997.925031124536;5011.749412529359;5731.799387021476;5872.970635146839;5877.617954832922;5890.587658863881;5897.64614123154;4214.325707886754;4214.620684138442;4228.640565551932;4236.4704411948505;4247.320428731419;6057.4245446682935;6067.362926892701;6083.209182806251;6101.542384321149;6129.478994285491;4221.324588065895;4357.193919124972;4360.65114790816;4373.890652733707;4377.759223242752;4351.108022855307;4398.934015989763;4408.31671090975;4413.183561041217;4414.381986521889;5021.246812978009;5021.397432308073;5028.522290382408;5031.597472202215;5042.117629414838;5576.749046292926;5897.7935772247765;5914.201582786269;5922.822315330547;5945.424462517436;1410.1257220523341;1449.9130052196901;1475.527831094061;1500.579837645461;1531.0048468495345;1022.9923525327547;1024.6157690865925;1040.9635662751768;1042.3172821801732;1043.598214687351;5673.163418290855;5684.715764211773;5684.8863022739315;5690.516306113944;5736.225609542742;5950.583459157818;5959.761609535657;5962.284525856839;5986.850328741816;5991.041433370639;5491.313585801775;5705.5720820938595;5712.742926568314;5797.275340582427;5824.912796218011;3201.791883527563;3208.294175281481;3225.645179030305;3240.432924238296;3257.4136655402253;6097.390260973867;6249.531285153541;6292.119103325538;6299.590526615755;6323.588966717149;6148.44663980244;6160.983437732813;6173.59871608344;6177.752889884354;6187.566870319034;6236.220472440945;6264.277323736009;6270.184875966186;6281.466075383643;6288.622682639057;5805.622634528742;6042.577019608585;6047.274327080779;6064.575479716377;6066.484348830399;5943.732532035982;5969.791057312973;5997.460228579449;6002.6398416093425;6006.639601623917;5993.760249589967;6205.317415084247;6244.812655620339;6266.436020758154;6313.169180937477;4315.11540134278;4340.218760623118;4350.21696094706;4371.213181627338;4371.431713877176;5827.883442331215;6099.75600975956;6102.542309326907;6115.4190351916495;6124.173236613178;5599.776008959596;5805.793798356597;5917.349091600006;5919.141724449844;5926.407359264039;5875.706214689334;5899.852503687443;5903.940960590339;5914.319853216935;5925.733341999534;5609.859753506195;5833.67914764692;5860.560457965585;5876.236089308362;5879.323877754113;5900.262467191601;5934.495567876779;5944.524438045032;5966.389074949826;5970.313413957277;5651.237082993907;5733.31200255972;5759.7408116634015;5774.509166720875;5781.4507621775865 15:26:23 INFO - PROCESS | 8910 | |5;yui.html;811.2009670474581;812.614008346036;867.180514413879;904.317240483435;935.9719208423747;15210.619734506643;15852.890297679167;16000.759988600166;17735.55661108473;17764.555886102855;15179.772303415457;15224.771628425582;15247.847521524782;15252.847471525281;15264.69470610587;707.9823004424879;840.2437805974641;858.5321000054954;859.4499520307008;861.4658911474894;736.2085757810348;835.1106072033296;850.5747126436781;856.7429771068686;861.1345597674328;897.2059727141461;899.8200359928007;900.4102312984973;901.4275934781429;902.6660135749748;893.4014210478995;895.5387975192781;895.9731208063769;896.282973621107;896.3591032411824;662.4402379988902;662.6653539962336;662.6786008785735;662.9900551491731;663.1114306828824;14257.429702811874;14309.35607897647;14375.353109110116;14382.71234575313;14393.856061439355;13490.460381584724;13589.796153057614;13664.180149191081;13907.79138312926;14040.087394319438;12605.180663256951;12646.241225526406;12723.42744576487;12724.363781810873;12823.14064460648;4066.8373265069363;4089.1821635672986;4153.792310384469;4182.038131229804;4187.099773548702;81523.5923820383;81584.18415815826;81592.59203703943;81714;81818.59090704509;12192;12314.753704925852;12336.75326493465;12367.257964522158;12471.939885109687;24507.387315316904;24793.00827966861;24797.25608231756;24824.627630585557;24907.87546062258;9225.55317630263;9265.768355791159;9298.302627302979;9376.156145946898;9495.525223738923;5040.873978150576;5058.974705126451;5096.923546146811;5106.131957567222;5364.91952620711;3552.4848896909803;3690.9815450922574;3691.8338674759702;3692.113892665741;3692.4830523726164;603.3363300369646;607.9544034197453;608.1577015833103;609.3540846702509;640.7148818775694;732.4762794601885;733.2484203691259;753.773867839646;759.7037155509356;759.7302957450025;10486.234897999573;10553.60728951234;10621.150307975344;10815.78368432627;11063.944680276547;613.8496068463255;681.7034589953386;736.8968344431784;760.5170716595002;778.2489897249181;4663.976680116645;4863.440704319049;4966.106100901872;5011.305752267363;5014.749262536859;123204;131517;131857.34071329582;131922;133533.33233333772;65112.674436627516;66056.66971665111;84743.57628211942;112938.43530782293;113728.43135784268;98287;100176.49911750395;100660;101370.4931475338;103900.48049759855;9443.197328226903;9457.432554046503;9467.810643787223;9498.810023799624;9510.1440870322;55752.72123639356;56315.71842140763;57106;58810.41189588049;58912;78269;83817.58091209749;86263;86677.56661216654;88722;12724.427400767172;12740.362981850685;12857.871421285667;12925;13484;13939.09395889264;14404.42382304696;14414.639634008814;15393.538193854203;15478.993865398714;13511.121777084458;13621.182729036289;14047.508337208148;14312.212828294543;14485.348159333065;13248.561129588217;13593;13615.93192034073;13843.79234311466;14380.85619143837;10497.737556560842;12692.746145077232;12871.935640321739;13433.126846754929;13446.126001809851;12186.939065304618;12318.568850090562;12328.137030407785;12768.425420856269;12776.936115319364;11803.468843901874;12275.816195341033;12685.23888566689;13014.674633134247;13060.934695326463 15:26:23 INFO - PROCESS | 8910 | __end_tp_report 15:26:23 INFO - PROCESS | 8910 | __start_cc_report 15:26:23 INFO - PROCESS | 8910 | _x_x_mozilla_cycle_collect,857 15:26:23 INFO - PROCESS | 8910 | __end_cc_report 15:26:23 INFO - PROCESS | 8910 | __startTimestamp1482362783014__endTimestamp 15:26:23 INFO - PROCESS | 8910 | 15:26:23 INFO - PROCESS | 8910 | ------- Summary: start ------- 15:26:23 INFO - PROCESS | 8910 | Number of tests: 6 15:26:23 INFO - PROCESS | 8910 | 15:26:23 INFO - PROCESS | 8910 | [#0] dojo.html Cycles:165 Average:5538.75 Median:5748.77 stddev:615.75 (10.7%) stddev-sans-first:588.06 15:26:23 INFO - PROCESS | 8910 | Values: 3134.9 3182.0 3183.0 3212.5 3230.2 5543.0 5625.6 5645.5 5658.9 5674.7 5551.6 5599.2 5603.3 5604.9 5610.7 5066.4 5082.1 5106.4 5122.5 5140.4 4999.1 5101.4 5160.4 5161.0 5190.7 4963.5 5001.2 5027.4 5030.7 5100.8 5124.3 5145.3 5155.9 5203.1 5203.8 5081.3 5143.8 5200.9 5216.4 5239.7 5398.5 5426.5 5433.3 5457.4 5477.8 5852.9 5919.2 5940.9 5950.0 5957.3 5998.8 6003.0 6008.5 6015.8 6018.8 5435.8 5468.6 5517.7 5559.4 5565.6 6123.4 6158.6 6159.4 6162.2 6162.5 5891.4 5896.2 5897.9 5900.8 5901.3 5766.2 5804.1 5883.5 5895.6 5946.0 5950.5 5961.6 5963.5 5982.4 5987.6 5933.5 5987.9 5997.7 5999.0 6000.7 4515.1 4543.6 4560.1 4588.0 4659.5 4083.4 4107.1 4126.5 4134.2 4156.7 5717.7 5719.8 5720.2 5723.3 5724.1 5748.8 5761.3 5837.0 5986.0 5989.8 5643.2 5652.4 5654.7 5685.4 5686.4 5201.4 5211.1 5224.4 5224.9 5225.6 5903.8 6021.5 6140.3 6177.8 6181.9 5834.9 5839.8 5867.9 5918.6 5959.8 6071.9 6094.8 6097.1 6100.4 6101.6 5662.7 5807.0 5819.4 5821.6 5825.7 5737.0 5886.2 5902.9 5921.8 6050.2 5816.3 5856.5 5857.0 5859.3 5864.2 5837.2 5863.3 5866.2 5866.5 5870.8 5664.6 5674.0 5737.5 5752.6 5797.0 5921.9 5940.5 5942.6 5946.3 5971.6 5596.7 5738.1 5766.7 5789.6 5791.0 15:26:23 INFO - PROCESS | 8910 | 15:26:23 INFO - PROCESS | 8910 | [#1] ext.html Cycles:180 Average:60407.58 Median:21328.25 stddev:117511.18 (551.0%) stddev-sans-first:117755.02 15:26:23 INFO - PROCESS | 8910 | Values: 596.7 604.9 643.8 651.2 658.2 33984.3 37657.8 44301.1 44980.3 45095.8 47739.0 48159.0 48227.8 48244.8 48266.3 9286.5 9437.2 9450.4 9450.7 9455.8 8567.6 8774.1 11303.8 11608.4 11614.0 11220.7 11279.2 11371.2 11376.9 11379.0 10448.5 10524.8 10529.5 10536.3 10544.9 11101.9 11151.9 11172.1 11173.7 11177.4 43502.6 43534.3 43705.0 43794.0 43866.3 3660.6 3661.7 3778.0 3861.5 4202.8 33831.3 34497.7 34758.0 35452.6 35890.8 4716.9 4796.7 4801.3 4819.4 4886.5 366731.0 366952.0 367297.0 367570.2 368513.0 38246.6 39286.6 62812.7 63245.7 63296.7 41237.8 41945.2 41946.6 42021.0 42026.8 15534.0 15619.4 15653.4 15667.3 15668.4 12167.7 12170.1 13766.7 13927.4 13975.4 9229.8 9595.1 9604.6 9678.8 9788.6 3639.3 3645.2 3677.3 3701.1 3711.4 995.8 1064.9 1066.2 1067.0 1113.6 35064.6 35419.6 35609.8 35713.5 35806.0 1129.5 1157.0 1176.9 1192.5 1251.6 17959.7 18161.5 18206.7 18338.7 18575.2 513294.0 516992.0 517030.0 517776.0 517798.0 63521.0 63830.4 63846.0 63938.4 64078.4 1280.7 1304.5 1367.0 1426.7 1428.0 27559.6 31240.5 31457.4 31769.8 31987.4 54985.7 55518.4 55649.4 55667.7 55674.0 432503.0 434534.0 436030.0 436560.0 437078.0 27673.4 27873.0 28347.9 31493.0 32610.5 45615.0 50239.7 50793.5 50795.7 51068.0 42552.0 43574.0 43734.6 43769.3 44789.8 42916.6 43412.1 43914.8 44110.0 45043.0 44380.3 45190.8 45316.0 46023.3 46236.0 36831.8 37467.8 38795.1 38841.0 39021.0 29716.0 29925.7 30164.5 30225.5 30392.7 15:26:23 INFO - PROCESS | 8910 | 15:26:23 INFO - PROCESS | 8910 | [#2] jquery.html Cycles:180 Average:28792.34 Median:2070.07 stddev:80452.64 (3886.5%) stddev-sans-first:80650.07 15:26:23 INFO - PROCESS | 8910 | Values: 393.5 400.9 402.7 407.3 419.0 13824.8 18567.8 19992.9 20074.7 20106.9 15535.5 15911.4 17708.5 17897.6 19615.2 5563.9 5887.0 5906.2 5908.0 5915.0 5816.4 5884.6 5907.1 5913.0 5917.8 5961.5 6140.9 6144.3 6147.9 6156.4 6131.9 6169.5 6219.3 6521.7 6603.2 5914.7 5924.7 5924.7 5936.6 5948.5 14158.1 14464.7 14566.6 14629.6 16477.2 2445.9 2465.1 2653.5 3015.4 3068.3 10558.9 10815.9 11051.4 11798.9 14959.0 1950.8 1986.1 2042.1 2070.7 2114.0 81951.6 84416.6 85437.6 85914.1 88842.1 10955.9 10980.2 11142.4 11162.8 11291.6 14987.3 15027.7 15073.4 15108.4 15155.5 7161.9 7622.2 7666.9 8030.4 8408.1 7477.3 7590.4 7600.7 7794.3 7810.3 4621.7 4659.4 4659.7 4699.4 5129.2 1957.1 1992.5 2027.9 2067.8 2069.5 928.5 959.5 1009.3 1016.9 1042.9 19349.8 19387.1 19513.7 19945.7 20264.0 1008.6 1015.2 1036.4 1040.3 1085.4 10390.4 11172.6 11231.2 11301.7 11307.4 478499.0 484581.0 486160.0 491490.0 494448.0 92994.5 93229.1 93328.5 93430.5 94090.1 1037.0 1040.1 1048.1 1048.2 1055.3 16635.7 16933.7 17152.7 17362.0 17625.2 41066.2 42050.0 42923.4 43516.0 43521.0 45056.3 57882.4 58315.0 58436.8 60487.1 8199.5 8403.5 8447.0 8620.6 8620.8 12037.8 12474.4 12618.2 12661.8 12778.4 11294.9 11516.9 11539.5 11693.0 12053.5 11102.9 11142.4 11259.6 11338.3 11525.3 11370.5 11564.7 11587.5 11700.4 11970.5 7503.2 7912.8 8530.4 8736.0 8803.6 10829.9 10944.3 11127.4 11296.5 11455.9 15:26:23 INFO - PROCESS | 8910 | 15:26:23 INFO - PROCESS | 8910 | [#3] mootools.html Cycles:180 Average:6178.27 Median:2660.71 stddev:3585.49 (134.8%) stddev-sans-first:3578.18 15:26:23 INFO - PROCESS | 8910 | Values: 1482.2 1499.7 1634.6 1743.8 1779.7 8895.6 9602.6 9607.4 9608.8 9623.6 7970.7 8300.3 8331.3 8488.6 9351.3 2351.1 2457.9 2462.3 2467.0 2476.2 2405.7 2432.0 2439.2 2453.1 2458.8 2393.3 2437.6 2438.6 2443.1 2449.6 2351.9 2352.9 2355.6 2360.3 2393.8 2396.1 2398.1 2399.7 2402.4 2403.5 9138.2 9341.4 9440.5 9443.0 9443.2 5568.6 5676.9 5706.8 5712.9 5722.8 3336.7 3375.4 3543.4 3556.4 3563.2 1150.5 1179.3 1183.1 1187.5 1192.3 13370.3 13409.5 13421.4 13423.2 13435.5 4703.5 4759.7 4834.1 4872.8 4976.3 10990.6 11003.4 11026.5 11033.7 11049.6 5696.0 5704.4 5707.0 5709.1 5711.0 4591.4 4942.6 5117.5 5223.0 5255.6 4232.0 4251.6 4312.1 4351.6 4352.7 1322.8 1327.1 1332.6 1335.8 1338.3 889.2 988.1 988.3 993.6 996.3 5897.3 6080.9 6206.5 6265.8 6408.5 460.2 483.6 487.3 491.7 495.7 4183.5 4275.1 4452.4 4575.1 4699.5 11825.7 12036.7 12056.9 12060.2 12071.5 10967.9 11823.2 11928.8 11939.4 11974.8 8456.8 8878.3 8883.6 8885.1 8887.5 6204.8 6232.8 6281.1 6470.5 6504.3 10879.8 10893.9 10894.5 10907.9 11017.1 10607.3 10911.1 11045.3 11174.2 11233.3 7565.7 7784.3 8032.1 8370.7 8583.1 8924.9 9215.7 9234.3 9234.6 9242.0 8160.3 8192.3 8274.7 8395.0 8413.3 7937.8 7988.4 8001.8 8075.6 8157.7 7423.4 7549.3 7566.5 7683.1 7776.0 5576.2 5617.4 5638.9 5687.9 5863.3 5649.6 5741.6 5771.2 5789.6 5831.9 15:26:23 INFO - PROCESS | 8910 | 15:26:23 INFO - PROCESS | 8910 | [#4] prototype.html Cycles:180 Average:4969.22 Median:5670.95 stddev:1452.41 (25.6%) stddev-sans-first:1414.32 15:26:23 INFO - PROCESS | 8910 | Values: 340.2 345.2 356.1 356.8 359.3 5668.7 5706.5 5730.4 5759.2 5787.2 5257.0 5389.1 5413.3 5499.9 5562.2 4387.5 4393.6 4404.6 4426.9 4457.3 4321.4 4410.5 4411.3 4411.9 4470.1 4358.8 4386.5 4409.2 4415.0 4443.3 4379.7 4393.5 4393.9 4407.0 4411.1 3929.2 3938.7 3942.4 3946.8 3956.3 5201.4 5453.0 5458.6 5478.0 5489.2 4989.1 4991.3 4991.5 4997.9 5011.7 5731.8 5873.0 5877.6 5890.6 5897.6 4214.3 4214.6 4228.6 4236.5 4247.3 6057.4 6067.4 6083.2 6101.5 6129.5 4221.3 4357.2 4360.7 4373.9 4377.8 4351.1 4398.9 4408.3 4413.2 4414.4 5021.2 5021.4 5028.5 5031.6 5042.1 5576.7 5897.8 5914.2 5922.8 5945.4 1410.1 1449.9 1475.5 1500.6 1531.0 1023.0 1024.6 1041.0 1042.3 1043.6 5673.2 5684.7 5684.9 5690.5 5736.2 5950.6 5959.8 5962.3 5986.9 5991.0 5491.3 5705.6 5712.7 5797.3 5824.9 3201.8 3208.3 3225.6 3240.4 3257.4 6097.4 6249.5 6292.1 6299.6 6323.6 6148.4 6161.0 6173.6 6177.8 6187.6 6236.2 6264.3 6270.2 6281.5 6288.6 5805.6 6042.6 6047.3 6064.6 6066.5 5943.7 5969.8 5997.5 6002.6 6006.6 5993.8 6205.3 6244.8 6266.4 6313.2 4315.1 4340.2 4350.2 4371.2 4371.4 5827.9 6099.8 6102.5 6115.4 6124.2 5599.8 5805.8 5917.3 5919.1 5926.4 5875.7 5899.9 5903.9 5914.3 5925.7 5609.9 5833.7 5860.6 5876.2 5879.3 5900.3 5934.5 5944.5 5966.4 5970.3 5651.2 5733.3 5759.7 5774.5 5781.5 15:26:23 INFO - PROCESS | 8910 | 15:26:23 INFO - PROCESS | 8910 | [#5] yui.html Cycles:180 Average:22240.48 Median:3879.66 stddev:32704.68 (843.0%) stddev-sans-first:32756.84 15:26:23 INFO - PROCESS | 8910 | Values: 811.2 812.6 867.2 904.3 936.0 15210.6 15852.9 16000.8 17735.6 17764.6 15179.8 15224.8 15247.8 15252.8 15264.7 708.0 840.2 858.5 859.4 861.5 736.2 835.1 850.6 856.7 861.1 897.2 899.8 900.4 901.4 902.7 893.4 895.5 896.0 896.3 896.4 662.4 662.7 662.7 663.0 663.1 14257.4 14309.4 14375.4 14382.7 14393.9 13490.5 13589.8 13664.2 13907.8 14040.1 12605.2 12646.2 12723.4 12724.4 12823.1 4066.8 4089.2 4153.8 4182.0 4187.1 81523.6 81584.2 81592.6 81714.0 81818.6 12192.0 12314.8 12336.8 12367.3 12471.9 24507.4 24793.0 24797.3 24824.6 24907.9 9225.6 9265.8 9298.3 9376.2 9495.5 5040.9 5059.0 5096.9 5106.1 5364.9 3552.5 3691.0 3691.8 3692.1 3692.5 603.3 608.0 608.2 609.4 640.7 732.5 733.2 753.8 759.7 759.7 10486.2 10553.6 10621.2 10815.8 11063.9 613.8 681.7 736.9 760.5 778.2 4664.0 4863.4 4966.1 5011.3 5014.7 123204.0 131517.0 131857.3 131922.0 133533.3 65112.7 66056.7 84743.6 112938.4 113728.4 98287.0 100176.5 100660.0 101370.5 103900.5 9443.2 9457.4 9467.8 9498.8 9510.1 55752.7 56315.7 57106.0 58810.4 58912.0 78269.0 83817.6 86263.0 86677.6 88722.0 12724.4 12740.4 12857.9 12925.0 13484.0 13939.1 14404.4 14414.6 15393.5 15479.0 13511.1 13621.2 14047.5 14312.2 14485.3 13248.6 13593.0 13615.9 13843.8 14380.9 10497.7 12692.7 12871.9 13433.1 13446.1 12186.9 12318.6 12328.1 12768.4 12776.9 11803.5 12275.8 12685.2 13014.7 13060.9 15:26:23 INFO - PROCESS | 8910 | -------- Summary: end -------- 15:26:23 INFO - PROCESS | 8910 | 15:26:23 INFO - TEST-INFO | 8910: exit 0 15:26:23 INFO - TEST-OK | dromaeo_css | took 1112790ms 15:26:23 INFO - TEST-START | kraken 15:26:23 INFO - operating with platform_type : linux_ 15:26:23 INFO - Initialising browser for kraken test... 15:26:23 INFO - TEST-INFO | started process 9217 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcPQqjm/profile http://localhost:51499/getInfo.html) 15:26:27 INFO - PROCESS | 9217 | __metrics Screen width/height:1600/1200 15:26:27 INFO - PROCESS | 9217 | colorDepth:24 15:26:27 INFO - PROCESS | 9217 | Browser inner width/height: 1024/697 15:26:27 INFO - PROCESS | 9217 | __metrics 15:26:27 INFO - PROCESS | 9217 | 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" 15:26:27 INFO - PROCESS | 9217 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 15:26:28 INFO - PROCESS | 9217 | console.error: PushService: 15:26:28 INFO - PROCESS | 9217 | stateChangeProcessEnqueue: Error transitioning state 15:26:28 INFO - PROCESS | 9217 | UnknownError 15:26:28 INFO - TEST-INFO | 9217: exit 0 15:26:28 INFO - Browser initialized. 15:26:28 INFO - Running cycle 1/1 for kraken test... 15:26:28 INFO - TEST-INFO | started process 9311 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpcPQqjm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:26:40 INFO - PROCESS | 9311 | RSS: Main: 142946304 15:26:40 INFO - PROCESS | 9311 | 15:26:43 INFO - PROCESS | 9311 | Cycle 1(1): loaded http://localhost:51499/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51499/tests/kraken/driver.html?testName=audio-beat-detection) 15:26:44 INFO - PROCESS | 9311 | RSS: Main: 187056128 15:26:44 INFO - PROCESS | 9311 | 15:26:46 INFO - PROCESS | 9311 | Cycle 1(1): loaded http://localhost:51499/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51499/tests/kraken/driver.html?testName=audio-dft) 15:26:47 INFO - PROCESS | 9311 | RSS: Main: 174870528 15:26:47 INFO - PROCESS | 9311 | 15:26:49 INFO - PROCESS | 9311 | Cycle 1(1): loaded http://localhost:51499/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51499/tests/kraken/driver.html?testName=audio-fft) 15:26:50 INFO - PROCESS | 9311 | RSS: Main: 266485760 15:26:50 INFO - PROCESS | 9311 | 15:26:52 INFO - PROCESS | 9311 | Cycle 1(1): loaded http://localhost:51499/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51499/tests/kraken/driver.html?testName=audio-oscillator) 15:26:53 INFO - PROCESS | 9311 | RSS: Main: 173092864 15:26:53 INFO - PROCESS | 9311 | 15:26:55 INFO - PROCESS | 9311 | Cycle 1(1): loaded http://localhost:51499/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51499/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:26:55 INFO - PROCESS | 9311 | RSS: Main: 174288896 15:26:55 INFO - PROCESS | 9311 | 15:26:58 INFO - PROCESS | 9311 | Cycle 1(1): loaded http://localhost:51499/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51499/tests/kraken/driver.html?testName=imaging-darkroom) 15:26:59 INFO - PROCESS | 9311 | RSS: Main: 209993728 15:26:59 INFO - PROCESS | 9311 | 15:27:02 INFO - PROCESS | 9311 | Cycle 1(1): loaded http://localhost:51499/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51499/tests/kraken/driver.html?testName=imaging-desaturate) 15:27:03 INFO - PROCESS | 9311 | RSS: Main: 208953344 15:27:03 INFO - PROCESS | 9311 | 15:27:06 INFO - PROCESS | 9311 | Cycle 1(1): loaded http://localhost:51499/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51499/tests/kraken/driver.html?testName=json-parse-financial) 15:27:06 INFO - PROCESS | 9311 | RSS: Main: 209326080 15:27:06 INFO - PROCESS | 9311 | 15:27:08 INFO - PROCESS | 9311 | Cycle 1(1): loaded http://localhost:51499/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51499/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:27:08 INFO - PROCESS | 9311 | RSS: Main: 171106304 15:27:08 INFO - PROCESS | 9311 | 15:27:10 INFO - PROCESS | 9311 | Cycle 1(1): loaded http://localhost:51499/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51499/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:27:11 INFO - PROCESS | 9311 | RSS: Main: 178229248 15:27:11 INFO - PROCESS | 9311 | 15:27:13 INFO - PROCESS | 9311 | Cycle 1(1): loaded http://localhost:51499/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51499/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:27:13 INFO - PROCESS | 9311 | RSS: Main: 187555840 15:27:13 INFO - PROCESS | 9311 | 15:27:16 INFO - PROCESS | 9311 | Cycle 1(1): loaded http://localhost:51499/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51499/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:27:16 INFO - PROCESS | 9311 | RSS: Main: 195936256 15:27:16 INFO - PROCESS | 9311 | 15:27:19 INFO - PROCESS | 9311 | Cycle 1(1): loaded http://localhost:51499/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51499/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:27:20 INFO - PROCESS | 9311 | RSS: Main: 192364544 15:27:20 INFO - PROCESS | 9311 | 15:27:21 INFO - PROCESS | 9311 | Cycle 1(1): loaded http://localhost:51499/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51499/tests/kraken/driver.html?testName=ai-astar) 15:27:21 INFO - PROCESS | 9311 | RSS: Main: 244039680 15:27:21 INFO - PROCESS | 9311 | 15:27:21 INFO - PROCESS | 9311 | __start_tp_report 15:27:21 INFO - PROCESS | 9311 | _x_x_mozilla_page_load 15:27:21 INFO - PROCESS | 9311 | _x_x_mozilla_page_load_details 15:27:21 INFO - PROCESS | 9311 | |i|pagename|runs| 15:27:21 INFO - PROCESS | 9311 | |0;ai-astar;111;109;112;113;110;110;111;109;111;112 15:27:21 INFO - PROCESS | 9311 | |1;audio-beat-detection;152;116;119;155;120;118;118;115;145;117 15:27:21 INFO - PROCESS | 9311 | |2;audio-dft;162;161;159;160;161;159;159;160;159;159 15:27:21 INFO - PROCESS | 9311 | |3;audio-fft;74;76;75;110;75;76;74;74;102;75 15:27:21 INFO - PROCESS | 9311 | |4;audio-oscillator;81;81;111;81;81;81;114;80;81;121 15:27:21 INFO - PROCESS | 9311 | |5;imaging-gaussian-blur;117;117;117;117;117;117;116;117;117;117 15:27:21 INFO - PROCESS | 9311 | |6;imaging-darkroom;145;146;146;146;147;146;147;146;146;146 15:27:21 INFO - PROCESS | 9311 | |7;imaging-desaturate;94;94;95;95;94;94;95;94;95;94 15:27:21 INFO - PROCESS | 9311 | |8;json-parse-financial;49;51;50;50;57;51;50;51;50;51 15:27:21 INFO - PROCESS | 9311 | |9;json-stringify-tinderbox;58;59;58;60;58;58;59;58;59;58 15:27:21 INFO - PROCESS | 9311 | |10;stanford-crypto-aes;75;75;74;79;79;73;82;73;74;73 15:27:21 INFO - PROCESS | 9311 | |11;stanford-crypto-ccm;123;122;132;122;122;132;122;124;166;121 15:27:21 INFO - PROCESS | 9311 | |12;stanford-crypto-pbkdf2;154;161;153;153;153;155;152;156;151;151 15:27:21 INFO - PROCESS | 9311 | |13;stanford-crypto-sha256-iterative;62;64;63;61;62;72;60;62;61;60 15:27:21 INFO - PROCESS | 9311 | __end_tp_report 15:27:21 INFO - PROCESS | 9311 | __start_cc_report 15:27:21 INFO - PROCESS | 9311 | _x_x_mozilla_cycle_collect,1588 15:27:21 INFO - PROCESS | 9311 | __end_cc_report 15:27:21 INFO - PROCESS | 9311 | __startTimestamp1482362841977__endTimestamp 15:27:21 INFO - PROCESS | 9311 | 15:27:21 INFO - PROCESS | 9311 | ------- Summary: start ------- 15:27:21 INFO - PROCESS | 9311 | Number of tests: 14 15:27:21 INFO - PROCESS | 9311 | 15:27:21 INFO - PROCESS | 9311 | [#0] ai-astar Cycles:10 Average:110.80 Median:111.00 stddev:1.32 (1.2%) stddev-sans-first:1.39 15:27:21 INFO - PROCESS | 9311 | Values: 111.0 109.0 112.0 113.0 110.0 110.0 111.0 109.0 111.0 112.0 15:27:21 INFO - PROCESS | 9311 | 15:27:21 INFO - PROCESS | 9311 | [#1] audio-beat-detection Cycles:10 Average:127.50 Median:119.50 stddev:16.23 (13.6%) stddev-sans-first:14.59 15:27:21 INFO - PROCESS | 9311 | Values: 152.0 116.0 119.0 155.0 120.0 118.0 118.0 115.0 145.0 117.0 15:27:21 INFO - PROCESS | 9311 | 15:27:21 INFO - PROCESS | 9311 | [#2] audio-dft Cycles:10 Average:159.90 Median:160.00 stddev:1.10 (0.7%) stddev-sans-first:0.87 15:27:21 INFO - PROCESS | 9311 | Values: 162.0 161.0 159.0 160.0 161.0 159.0 159.0 160.0 159.0 159.0 15:27:21 INFO - PROCESS | 9311 | 15:27:21 INFO - PROCESS | 9311 | [#3] audio-fft Cycles:10 Average:81.10 Median:75.00 stddev:13.28 (17.7%) stddev-sans-first:13.83 15:27:21 INFO - PROCESS | 9311 | Values: 74.0 76.0 75.0 110.0 75.0 76.0 74.0 74.0 102.0 75.0 15:27:21 INFO - PROCESS | 9311 | 15:27:21 INFO - PROCESS | 9311 | [#4] audio-oscillator Cycles:10 Average:91.20 Median:81.00 stddev:16.83 (20.8%) stddev-sans-first:17.44 15:27:21 INFO - PROCESS | 9311 | Values: 81.0 81.0 111.0 81.0 81.0 81.0 114.0 80.0 81.0 121.0 15:27:21 INFO - PROCESS | 9311 | 15:27:21 INFO - PROCESS | 9311 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 15:27:21 INFO - PROCESS | 9311 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 15:27:21 INFO - PROCESS | 9311 | 15:27:21 INFO - PROCESS | 9311 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.57 (0.4%) stddev-sans-first:0.44 15:27:21 INFO - PROCESS | 9311 | Values: 145.0 146.0 146.0 146.0 147.0 146.0 147.0 146.0 146.0 146.0 15:27:21 INFO - PROCESS | 9311 | 15:27:21 INFO - PROCESS | 9311 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:94.50 stddev:0.52 (0.5%) stddev-sans-first:0.53 15:27:21 INFO - PROCESS | 9311 | Values: 94.0 94.0 95.0 95.0 94.0 94.0 95.0 94.0 95.0 94.0 15:27:21 INFO - PROCESS | 9311 | 15:27:21 INFO - PROCESS | 9311 | [#8] json-parse-financial Cycles:10 Average:51.00 Median:51.00 stddev:2.21 (4.3%) stddev-sans-first:2.22 15:27:21 INFO - PROCESS | 9311 | Values: 49.0 51.0 50.0 50.0 57.0 51.0 50.0 51.0 50.0 51.0 15:27:21 INFO - PROCESS | 9311 | 15:27:21 INFO - PROCESS | 9311 | [#9] json-stringify-tinderbox Cycles:10 Average:58.50 Median:58.50 stddev:0.71 (1.2%) stddev-sans-first:0.73 15:27:21 INFO - PROCESS | 9311 | Values: 58.0 59.0 58.0 60.0 58.0 58.0 59.0 58.0 59.0 58.0 15:27:21 INFO - PROCESS | 9311 | 15:27:21 INFO - PROCESS | 9311 | [#10] stanford-crypto-aes Cycles:10 Average:75.70 Median:75.00 stddev:3.16 (4.2%) stddev-sans-first:3.35 15:27:21 INFO - PROCESS | 9311 | Values: 75.0 75.0 74.0 79.0 79.0 73.0 82.0 73.0 74.0 73.0 15:27:21 INFO - PROCESS | 9311 | 15:27:21 INFO - PROCESS | 9311 | [#11] stanford-crypto-ccm Cycles:10 Average:128.60 Median:123.50 stddev:13.77 (11.1%) stddev-sans-first:14.45 15:27:21 INFO - PROCESS | 9311 | Values: 123.0 122.0 132.0 122.0 122.0 132.0 122.0 124.0 166.0 121.0 15:27:21 INFO - PROCESS | 9311 | 15:27:21 INFO - PROCESS | 9311 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:153.90 Median:153.50 stddev:2.96 (1.9%) stddev-sans-first:3.14 15:27:21 INFO - PROCESS | 9311 | Values: 154.0 161.0 153.0 153.0 153.0 155.0 152.0 156.0 151.0 151.0 15:27:21 INFO - PROCESS | 9311 | 15:27:21 INFO - PROCESS | 9311 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.70 Median:62.00 stddev:3.50 (5.6%) stddev-sans-first:3.70 15:27:21 INFO - PROCESS | 9311 | Values: 62.0 64.0 63.0 61.0 62.0 72.0 60.0 62.0 61.0 60.0 15:27:21 INFO - PROCESS | 9311 | -------- Summary: end -------- 15:27:21 INFO - PROCESS | 9311 | 15:27:22 INFO - TEST-INFO | 9311: exit 0 15:27:23 INFO - TEST-OK | kraken | took 59267ms 15:27:23 INFO - SUITE-END | took 1172s 15:27:23 INFO - Completed test suite (00:19:32) 15:27:23 INFO - javascript benchmark 15:27:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3134.937301253975, 3181.9681803181975, 3182.968170318297, 3212.53418254353, 3230.20859889327, 5542.972285138574, 5625.634333768302, 5645.520130788877, 5658.91511627326, 5674.744636491358, 5551.611387202897, 5599.160125981105, 5603.299587551556, 5604.943950560493, 5610.719464026803, 5066.36670416198, 5082.08522465956, 5106.4382917879, 5122.462141475147, 5140.357455318085, 4999.125153098212, 5101.362329708786, 5160.354955630546, 5161.045206636784, 5190.662606930538, 4963.45402005779, 5001.224810154432, 5027.396712394518, 5030.723310217936, 5100.821471248507, 5124.333836601237, 5145.30538377318, 5155.896882062375, 5203.063448579254, 5203.817866374697, 5081.339425874613, 5143.768530416103, 5200.869978250536, 5216.374035115772, 5239.711815850127, 5398.541124004464, 5426.511613954763, 5433.266509021271, 5457.372402173761, 5477.780888764445, 5852.941470585282, 5919.1713160157615, 5940.881182376372, 5949.9702501487645, 5957.255343082114, 5998.820035398945, 6002.96998515009, 6008.48927841134, 6015.75936962521, 6018.789342372996, 5435.755391007375, 5468.589855760794, 5517.724113794335, 5559.416261292587, 5565.554755619541, 6123.418275263843, 6158.568900176944, 6159.4456498914415, 6162.22972128484, 6162.506999440034, 5891.410858914074, 5896.204012458349, 5897.911531327033, 5900.822975310747, 5901.29184497863, 5766.192733017381, 5804.071348584291, 5883.529317654577, 5895.587308888423, 5945.970270148621, 5950.494207992283, 5961.642301461926, 5963.522918166536, 5982.371850955587, 5987.64074155552, 5933.495652869555, 5987.940120598825, 5997.730102145414, 5999.040153575407, 6000.669963151981, 4515.051839113823, 4543.636509079266, 4560.065186636732, 4587.977060114745, 4659.510751371125, 4083.387491876242, 4107.0553772631865, 4126.463559737275, 4134.152498737752, 4156.729812562174, 5717.685527295956, 5719.799807006735, 5720.1991721159075, 5723.313202415737, 5724.141378793214, 5748.770049197985, 5761.308642962871, 5836.970815145897, 5986.042233242661, 5989.790357337472, 5643.153526970905, 5652.434756524397, 5654.717264136859, 5685.374608793029, 5686.3744988051285, 5201.401838788512, 5211.140161873249, 5224.425313215544, 5224.869378265497, 5225.608079394061, 5903.793367232212, 6021.54838387114, 6140.324564298013, 6177.752889884444, 6181.907271390933, 5834.94165058361, 5839.795607153729, 5867.882642347215, 5918.644881307136, 5959.791407300723, 6071.939280607137, 6094.78668246627, 6097.085437184458, 6100.420460056288, 6101.633901965896, 5662.66024038559, 5806.970965145148, 5819.418058194146, 5821.563382746226, 5825.6795876225515, 5736.971315143398, 5886.23478947734, 5902.881942361215, 5921.792737254175, 6050.183225264533, 5816.331121921034, 5856.472917437451, 5857.0, 5859.267591551056, 5864.237649105589, 5837.153612726046, 5863.296404431496, 5866.208061911758, 5866.530677545871, 5870.7945221917025, 5664.5751568631695, 5673.971630141823, 5737.540996720336, 5752.626079305, 5796.985527532581, 5921.881562368814, 5940.524758019435, 5942.64344139353, 5946.256717910262, 5971.611845230046, 5596.720163991703, 5738.081906894877, 5766.682832444088, 5789.594728369142, 5791.015527360443], "value": 5497.487538439439, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [596.6927032578222, 604.9032154855223, 643.8229486891101, 651.1730102769482, 658.2232965101178, 33984.320313593715, 37657.81171094144, 44301.113977720466, 44980.3252951206, 45095.77452112743, 47739.0, 48159.0, 48227.75886120564, 48244.75877620607, 48266.27600585994, 9286.535673216345, 9437.245020398386, 9450.385724927857, 9450.66922657707, 9455.763605909873, 8567.614457349404, 8774.122587741207, 11303.773924521505, 11608.419579021058, 11613.954744073031, 11220.66338009861, 11279.154063445234, 11371.204015718902, 11376.886231137665, 11379.0, 10448.477576121202, 10524.78950420995, 10529.526171322308, 10536.315139515908, 10544.894551054545, 11101.888981110167, 11151.888481115166, 11172.10623150146, 11173.664790056311, 11177.385243811586, 43502.564974350484, 43534.34698479525, 43705.0, 43794.0, 43866.342004869955, 3660.633936606345, 3661.6521430464063, 3777.981110094432, 3861.4593956846065, 4202.810873510669, 33831.323373532636, 34497.6550234497, 34758.0, 35452.64547354519, 35890.820545897106, 4716.858494245178, 4796.664233503656, 4801.255805350176, 4819.373481447389, 4886.53577910098, 366731.0, 366952.0, 367297.0, 367570.1621491902, 368513.0, 38246.617533824865, 39286.60713392887, 62812.68593657002, 63245.68377158085, 63296.683516582125, 41237.793811031355, 41945.16109677789, 41946.58053419488, 42021.0, 42026.78986605047, 15534.0, 15619.3752249911, 15653.37386504527, 15667.2949717263, 15668.373265069498, 12167.695807604881, 12170.14808963364, 13766.724665506634, 13927.442902283998, 13975.37110830015, 9229.769255768659, 9595.088466595662, 9604.615815367308, 9678.806423871623, 9788.55951482185, 3639.3267245559655, 3645.179834537208, 3677.3380791457794, 3701.1487357907295, 3711.4247291669835, 995.7809281957958, 1064.8722153341648, 1066.2323127348297, 1067.0343339277965, 1113.6423682596976, 35064.64935350665, 35419.645803541636, 35609.82195089008, 35713.46429803555, 35806.0, 1129.5030186717818, 1156.9595064172713, 1176.8940795328463, 1192.4574318684977, 1251.5932321995388, 17959.73060404095, 18161.545961350806, 18206.726899096524, 18338.724919126224, 18575.16411761474, 513294.0, 516992.0, 517030.0, 517776.0, 517798.0, 63521.0, 63830.36169638337, 63846.042309365395, 63938.36061639417, 64078.35921640724, 1280.6862318731785, 1304.54340980656, 1366.9863301366859, 1426.6718654709268, 1427.985720142806, 27559.586606201323, 31240.531392028683, 31457.370852582364, 31769.841150795022, 31987.360252794348, 54985.725071374385, 55518.44481555133, 55649.44350556443, 55667.721661391435, 55674.0, 432503.0, 434534.0, 436030.0, 436560.0, 437078.0, 27673.44653106967, 27873.0, 28347.858260708563, 31493.0, 32610.51084233786, 45615.0, 50239.74880125722, 50793.49206507888, 50795.74602126966, 51068.0, 42552.0, 43573.96957495706, 43734.56265437305, 43769.343459848766, 44789.77605111954, 42916.570834291255, 43412.13175736531, 43914.78042609894, 44110.0, 45043.0, 44380.33429498625, 45190.77404612956, 45316.0, 46023.309650354604, 46236.0, 36831.8158409217, 37467.81266093761, 38795.050355141444, 38841.0, 39021.0, 29715.951197918894, 29925.700742993162, 30164.54753178748, 30225.546616800326, 30392.696073038987], "value": 20258.13151974754, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [393.51794052285936, 400.9498812648419, 402.6617641181407, 407.3197759741232, 418.98114584843677, 13824.792628110587, 18567.81432185678, 19992.90003549984, 20074.6988795168, 20106.89946550267, 15535.456259030936, 15911.363545458167, 17708.46874593761, 17897.642047159083, 19615.215391384365, 5563.944360556383, 5886.999210134288, 5906.202662640553, 5908.0, 5914.970425147868, 5816.447437493432, 5884.6174998625165, 5907.143464197688, 5913.024350982083, 5917.822465326026, 5961.463468287853, 6140.907886381708, 6144.324124346318, 6147.907781383283, 6156.353582873801, 6131.9386806131815, 6169.475594574466, 6219.253689557237, 6521.739130434777, 6603.207615086172, 5914.704264786744, 5924.703764811785, 5924.703764811785, 5936.5547583931375, 5948.464638182584, 14158.079724817957, 14464.71070578593, 14566.563003109924, 14629.6342591435, 16477.176141193013, 2445.9266222013366, 2465.149523414419, 2653.4560415114856, 3015.3818467214387, 3068.3096303331795, 10558.894411055868, 10815.891841081568, 11051.447427628586, 11798.941005295004, 14959.0, 1950.7951665075104, 1986.1261045140093, 2042.101475350841, 2070.6686930091114, 2113.9788602113936, 81951.5902420484, 84416.57791711001, 85437.57281213616, 85914.14085859124, 88842.11157888403, 10955.890441095487, 10980.231383803057, 11142.442877856074, 11162.832557511643, 11291.604793832177, 14987.32557034936, 15027.699446011236, 15073.397064117313, 15108.39566417331, 15155.469558565397, 7161.92838071623, 7622.237776222444, 7666.8849967250535, 8030.357571394275, 8408.117147699526, 7477.327040566375, 7590.430717696195, 7600.733974310872, 7794.337481314038, 7810.258025487569, 4621.722696638212, 4659.44086709597, 4659.720416775004, 4699.365585645963, 5129.204973229155, 1957.1388589020785, 1992.531755037565, 2027.9391618251475, 2067.7622073461444, 2069.461939895638, 928.4522131942186, 959.5346257065318, 1009.3439264478149, 1016.872890888639, 1042.9269951103502, 19349.806501935083, 19387.127579258966, 19513.707294390595, 19945.700814487795, 20264.0, 1008.5713571732131, 1015.2284263959444, 1036.4247842447364, 1040.2770074798093, 1085.3542142425244, 10390.376577405379, 11172.608958686407, 11231.157663175294, 11301.71745706364, 11307.434628268553, 478499.0, 484581.0, 486160.0, 491490.0, 494448.0, 92994.53502732443, 93229.06770932204, 93328.53335733413, 93430.53284733533, 94090.0590994095, 1036.9585216591252, 1040.1314902056715, 1048.09863517375, 1048.2400259811575, 1055.2507719519074, 16635.667286653956, 16933.745993809855, 17152.742708859627, 17362.0, 17625.20686569082, 41066.1786764269, 42050.0, 42923.424594524426, 43516.0, 43521.0, 45056.324155138354, 57882.421175787706, 58315.0, 58436.83126337364, 60487.09269361051, 8199.508029518249, 8403.537805420761, 8446.957765211135, 8620.61207245688, 8620.78448038804, 12037.819432708342, 12474.43865026058, 12618.17981831178, 12661.810072849099, 12778.424970876518, 11294.887051129384, 11516.884831151581, 11539.4807233673, 11693.0, 12053.51785928553, 11102.944485277845, 11142.442877856236, 11259.605913792979, 11338.263012904135, 11525.250858694159, 11370.488328025423, 11564.710882227675, 11587.536498540301, 11700.35648039366, 11970.521179152736, 7503.249675032672, 7912.84174316499, 8530.402871798915, 8736.03903570594, 8803.647854085764, 10829.945850270698, 10944.288621239912, 11127.443627818739, 11296.49165787525, 11455.942720286344], "value": 9218.702844293672, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1482.2218335373923, 1499.7150541397134, 1634.6403791165947, 1743.7539591095674, 1779.7419374190742, 8895.555222238894, 9602.5678844452, 9607.375520591157, 9608.75978100547, 9623.566939487722, 7970.72102476412, 8300.29447496962, 8331.250187483125, 8488.57557122143, 9351.345405821596, 2351.118330626011, 2457.9262622121364, 2462.2613216035206, 2466.950660986779, 2476.1704828882257, 2405.6632071509916, 2432.0, 2439.2194497760725, 2453.0555736041665, 2458.7787099161073, 2393.258089992108, 2437.597796363598, 2438.634204869266, 2443.0960544598524, 2449.5713250181234, 2351.929442116739, 2352.8941197646145, 2355.623100303943, 2360.327306717586, 2393.772591603812, 2396.125414223813, 2398.1486572266767, 2399.664047033417, 2402.3513651314224, 2403.5072810073893, 9138.17756401927, 9341.39280946743, 9440.480773557452, 9443.0, 9443.197328227108, 5568.582356323252, 5676.943230567683, 5706.77172913083, 5712.857178570527, 5722.771089156428, 3336.7330613550857, 3375.443051896434, 3543.3976224041976, 3556.448750443685, 3563.198280386918, 1150.488032825393, 1179.2747460311932, 1183.1303991566192, 1187.453771265219, 1192.3084610925641, 13370.331483425789, 13409.46362145513, 13421.39603717835, 13423.194608323533, 13435.462581496826, 4703.475491217925, 4759.738214398241, 4834.081524510332, 4872.805087796449, 4976.303317535548, 10990.560377584807, 11003.394813285186, 11026.503807328689, 11033.724156895982, 11049.558017679363, 5696.0, 5704.429557044263, 5707.0, 5709.058005429058, 5711.0, 4591.44902611682, 4942.629302802232, 5117.488251174927, 5223.0, 5255.5532779713385, 4231.978840105779, 4251.553586873334, 4312.072904325585, 4351.564843515686, 4352.695311328177, 1322.768515509782, 1327.097573649908, 1332.6202032420713, 1335.79963005548, 1338.3308345827086, 889.1641856654853, 988.1304452082265, 988.2588058955783, 993.562832353762, 996.3075662414698, 5897.262842144732, 6080.8783824324155, 6206.503479721611, 6265.8120256392385, 6408.487321014401, 460.2337108713954, 483.62408946547015, 487.3347880143649, 491.7492079039715, 495.69762444908577, 4183.5398106208295, 4275.144971005811, 4452.443444569429, 4575.130725162208, 4699.530046995274, 11825.704357390961, 12036.699082522831, 12056.87943120575, 12060.216085954386, 12071.517139314328, 10967.890321096846, 11823.231489953125, 11928.821067683992, 11939.403029848472, 11974.820377694341, 8456.830863382698, 8878.28973682104, 8883.64465421389, 8885.111488851064, 8887.511186884783, 6204.7828326008375, 6232.844178895382, 6281.124817351379, 6470.547061705635, 6504.252011018604, 10879.836802447811, 10893.891061089287, 10894.455277236266, 10907.890921090688, 11017.1186305097, 10607.310524815863, 10911.072558832271, 11045.28205666629, 11174.217804753915, 11233.269837460539, 7565.6973721050545, 7784.338331241908, 8032.116467188722, 8370.74887753344, 8583.098774628574, 8924.910750892408, 9215.723528294162, 9234.26125909939, 9234.584443699916, 9242.0, 8160.347172226327, 8192.262696357357, 8274.669013239403, 8395.0, 8413.284870785807, 7937.761867143995, 7988.440809143304, 8001.759947201594, 8075.5558444286125, 8157.673693052211, 7423.369013633902, 7549.282818132268, 7566.546007239584, 7683.078030636136, 7775.961120194363, 5576.163575463713, 5617.4101719320515, 5638.88722225545, 5687.8862422752145, 5863.325717542537, 5649.6327738696855, 5741.569382296261, 5771.249737534094, 5789.623674461147, 5831.854203644943], "value": 4826.512922296563, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.15980528095594, 345.2180810464298, 356.06355285598903, 356.77701436602126, 359.2670951259431, 5668.744906479208, 5706.514946229576, 5730.398308177639, 5759.164921086445, 5787.24765780449, 5256.973715131419, 5389.137737961927, 5413.296271484702, 5499.917501237474, 5562.193481945125, 4387.473503179623, 4393.604575588196, 4404.581564751344, 4426.9335959960545, 4457.33140028995, 4321.438213032301, 4410.492793328776, 4411.294192929132, 4411.911761764779, 4470.128324976631, 4358.8474403395885, 4386.517483076875, 4409.228385032622, 4414.955850441503, 4443.333499974998, 4379.693421460499, 4393.51671316155, 4393.9121217575785, 4406.955930440687, 4411.1177764447075, 3929.1945151243926, 3938.72428929975, 3942.3889297158985, 3946.8026598669953, 3956.3274243378696, 5201.36157110509, 5452.972735136299, 5458.563314934796, 5477.972610136924, 5489.204065410493, 4989.077020751172, 4991.251312303148, 4991.5258050485145, 4997.925031124536, 5011.749412529359, 5731.799387021476, 5872.970635146839, 5877.617954832922, 5890.587658863881, 5897.64614123154, 4214.325707886754, 4214.620684138442, 4228.640565551932, 4236.4704411948505, 4247.320428731419, 6057.4245446682935, 6067.362926892701, 6083.209182806251, 6101.542384321149, 6129.478994285491, 4221.324588065895, 4357.193919124972, 4360.65114790816, 4373.890652733707, 4377.759223242752, 4351.108022855307, 4398.934015989763, 4408.31671090975, 4413.183561041217, 4414.381986521889, 5021.246812978009, 5021.397432308073, 5028.522290382408, 5031.597472202215, 5042.117629414838, 5576.749046292926, 5897.7935772247765, 5914.201582786269, 5922.822315330547, 5945.424462517436, 1410.1257220523341, 1449.9130052196901, 1475.527831094061, 1500.579837645461, 1531.0048468495345, 1022.9923525327547, 1024.6157690865925, 1040.9635662751768, 1042.3172821801732, 1043.598214687351, 5673.163418290855, 5684.715764211773, 5684.8863022739315, 5690.516306113944, 5736.225609542742, 5950.583459157818, 5959.761609535657, 5962.284525856839, 5986.850328741816, 5991.041433370639, 5491.313585801775, 5705.5720820938595, 5712.742926568314, 5797.275340582427, 5824.912796218011, 3201.791883527563, 3208.294175281481, 3225.645179030305, 3240.432924238296, 3257.4136655402253, 6097.390260973867, 6249.531285153541, 6292.119103325538, 6299.590526615755, 6323.588966717149, 6148.44663980244, 6160.983437732813, 6173.59871608344, 6177.752889884354, 6187.566870319034, 6236.220472440945, 6264.277323736009, 6270.184875966186, 6281.466075383643, 6288.622682639057, 5805.622634528742, 6042.577019608585, 6047.274327080779, 6064.575479716377, 6066.484348830399, 5943.732532035982, 5969.791057312973, 5997.460228579449, 6002.6398416093425, 6006.639601623917, 5993.760249589967, 6205.317415084247, 6244.812655620339, 6266.436020758154, 6313.169180937477, 4315.11540134278, 4340.218760623118, 4350.21696094706, 4371.213181627338, 4371.431713877176, 5827.883442331215, 6099.75600975956, 6102.542309326907, 6115.4190351916495, 6124.173236613178, 5599.776008959596, 5805.793798356597, 5917.349091600006, 5919.141724449844, 5926.407359264039, 5875.706214689334, 5899.852503687443, 5903.940960590339, 5914.319853216935, 5925.733341999534, 5609.859753506195, 5833.67914764692, 5860.560457965585, 5876.236089308362, 5879.323877754113, 5900.262467191601, 5934.495567876779, 5944.524438045032, 5966.389074949826, 5970.313413957277, 5651.237082993907, 5733.31200255972, 5759.7408116634015, 5774.509166720875, 5781.4507621775865], "value": 4506.1612169237915, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [811.2009670474581, 812.614008346036, 867.180514413879, 904.317240483435, 935.9719208423747, 15210.619734506643, 15852.890297679167, 16000.759988600166, 17735.55661108473, 17764.555886102855, 15179.772303415457, 15224.771628425582, 15247.847521524782, 15252.847471525281, 15264.69470610587, 707.9823004424879, 840.2437805974641, 858.5321000054954, 859.4499520307008, 861.4658911474894, 736.2085757810348, 835.1106072033296, 850.5747126436781, 856.7429771068686, 861.1345597674328, 897.2059727141461, 899.8200359928007, 900.4102312984973, 901.4275934781429, 902.6660135749748, 893.4014210478995, 895.5387975192781, 895.9731208063769, 896.282973621107, 896.3591032411824, 662.4402379988902, 662.6653539962336, 662.6786008785735, 662.9900551491731, 663.1114306828824, 14257.429702811874, 14309.35607897647, 14375.353109110116, 14382.71234575313, 14393.856061439355, 13490.460381584724, 13589.796153057614, 13664.180149191081, 13907.79138312926, 14040.087394319438, 12605.180663256951, 12646.241225526406, 12723.42744576487, 12724.363781810873, 12823.14064460648, 4066.8373265069363, 4089.1821635672986, 4153.792310384469, 4182.038131229804, 4187.099773548702, 81523.5923820383, 81584.18415815826, 81592.59203703943, 81714.0, 81818.59090704509, 12192.0, 12314.753704925852, 12336.75326493465, 12367.257964522158, 12471.939885109687, 24507.387315316904, 24793.00827966861, 24797.25608231756, 24824.627630585557, 24907.87546062258, 9225.55317630263, 9265.768355791159, 9298.302627302979, 9376.156145946898, 9495.525223738923, 5040.873978150576, 5058.974705126451, 5096.923546146811, 5106.131957567222, 5364.91952620711, 3552.4848896909803, 3690.9815450922574, 3691.8338674759702, 3692.113892665741, 3692.4830523726164, 603.3363300369646, 607.9544034197453, 608.1577015833103, 609.3540846702509, 640.7148818775694, 732.4762794601885, 733.2484203691259, 753.773867839646, 759.7037155509356, 759.7302957450025, 10486.234897999573, 10553.60728951234, 10621.150307975344, 10815.78368432627, 11063.944680276547, 613.8496068463255, 681.7034589953386, 736.8968344431784, 760.5170716595002, 778.2489897249181, 4663.976680116645, 4863.440704319049, 4966.106100901872, 5011.305752267363, 5014.749262536859, 123204.0, 131517.0, 131857.34071329582, 131922.0, 133533.33233333772, 65112.674436627516, 66056.66971665111, 84743.57628211942, 112938.43530782293, 113728.43135784268, 98287.0, 100176.49911750395, 100660.0, 101370.4931475338, 103900.48049759855, 9443.197328226903, 9457.432554046503, 9467.810643787223, 9498.810023799624, 9510.1440870322, 55752.72123639356, 56315.71842140763, 57106.0, 58810.41189588049, 58912.0, 78269.0, 83817.58091209749, 86263.0, 86677.56661216654, 88722.0, 12724.427400767172, 12740.362981850685, 12857.871421285667, 12925.0, 13484.0, 13939.09395889264, 14404.42382304696, 14414.639634008814, 15393.538193854203, 15478.993865398714, 13511.121777084458, 13621.182729036289, 14047.508337208148, 14312.212828294543, 14485.348159333065, 13248.561129588217, 13593.0, 13615.93192034073, 13843.79234311466, 14380.85619143837, 10497.737556560842, 12692.746145077232, 12871.935640321739, 13433.126846754929, 13446.126001809851, 12186.939065304618, 12318.568850090562, 12328.137030407785, 12768.425420856269, 12776.936115319364, 11803.468843901874, 12275.816195341033, 12685.23888566689, 13014.674633134247, 13060.934695326463], "value": 7936.379009363432, "unit": "score"}], "name": "dromaeo_css", "value": 7494.732993803447, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 109.0, 112.0, 113.0, 110.0, 110.0, 111.0, 109.0, 111.0, 112.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 116.0, 119.0, 155.0, 120.0, 118.0, 118.0, 115.0, 145.0, 117.0], "value": 127.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 159.0, 160.0, 161.0, 159.0, 159.0, 160.0, 159.0, 159.0], "value": 159.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 76.0, 75.0, 110.0, 75.0, 76.0, 74.0, 74.0, 102.0, 75.0], "value": 81.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 111.0, 81.0, 81.0, 81.0, 114.0, 80.0, 81.0, 121.0], "value": 91.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 146.0, 147.0, 146.0, 147.0, 146.0, 146.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 95.0, 94.0, 94.0, 95.0, 94.0, 95.0, 94.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 51.0, 50.0, 50.0, 57.0, 51.0, 50.0, 51.0, 50.0, 51.0], "value": 51.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 59.0, 58.0, 60.0, 58.0, 58.0, 59.0, 58.0, 59.0, 58.0], "value": 58.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 74.0, 79.0, 79.0, 73.0, 82.0, 73.0, 74.0, 73.0], "value": 75.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 122.0, 132.0, 122.0, 122.0, 132.0, 122.0, 124.0, 166.0, 121.0], "value": 128.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 161.0, 153.0, 153.0, 153.0, 155.0, 152.0, 156.0, 151.0, 151.0], "value": 153.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 64.0, 63.0, 61.0, 62.0, 72.0, 60.0, 62.0, 61.0, 60.0], "value": 62.7, "unit": "score"}], "name": "kraken", "value": 1458.3000000000002, "alertThreshold": 2.0}]} 15:27:23 INFO - Return code: 0 15:27:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:27:23 INFO - # TBPL SUCCESS # 15:27:23 INFO - Running post-action listener: _resource_record_post_action 15:27:23 INFO - [mozharness: 2016-12-21 23:27:23.275553Z] Finished run-tests step (success) 15:27:23 INFO - Running post-run listener: _resource_record_post_run 15:27:23 INFO - Total resource usage - Wall time: 1186s; CPU: 13.0%; Read bytes: 4096; Write bytes: 337383424; Read time: 20; Write time: 188832 15:27:23 INFO - TinderboxPrint: CPU usage
12.9% 15:27:23 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 20 15:27:23 INFO - TinderboxPrint: I/O write bytes / time
337,383,424 / 188,832 15:27:23 INFO - TinderboxPrint: CPU idle
8,244.8 (87.1%) 15:27:23 INFO - TinderboxPrint: CPU user
1,182.3 (12.5%) 15:27:23 INFO - TinderboxPrint: Swap in / out
0 / 0 15:27:23 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 100564992; Read time: 0; Write time: 81708 15:27:23 INFO - run-tests - Wall time: 1173s; CPU: 13.0%; Read bytes: 4096; Write bytes: 219910144; Read time: 20; Write time: 99564 15:27:23 INFO - Running post-run listener: _upload_blobber_files 15:27:23 INFO - Blob upload gear active. 15:27:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:27:23 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 15:27:23 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 15:27:23 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 15:27:24 INFO - (blobuploader) - INFO - Open directory for files ... 15:27:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 15:27:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:27:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:27:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 15:27:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:27:25 INFO - (blobuploader) - INFO - Done attempting. 15:27:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 15:27:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:27:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:27:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 15:27:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:27:27 INFO - (blobuploader) - INFO - Done attempting. 15:27:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:27:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:27:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:27:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:27:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:27:31 INFO - (blobuploader) - INFO - Done attempting. 15:27:31 INFO - (blobuploader) - INFO - Iteration through files over. 15:27:31 INFO - Return code: 0 15:27:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:27:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:27:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bff34fcf7826b13092784f72ea8777bad9387cf75f588acbb3d5c84eabd724a63575d461bb15672c977f9b7d5e443fe965097604a6aff3441cbbda587fa0cb29", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ce2f992445e2024c5e038cff98a603fdcb13014045178cb7afaca607d817d3fe590c7dd93708d79dea6a98c7c6bf6c82b9e082bdcd5aab725ed9c5bf0dab8014", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/347ddcfdf6c0a69d81569a56d25676031191929f94aed31f00197357ddf274c76f4c75d07a29b198de304453da90f441a4c191e23c5ec0d47e4b1c87dc7000f7"} 15:27:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:27:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:27:31 INFO - Contents: 15:27:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bff34fcf7826b13092784f72ea8777bad9387cf75f588acbb3d5c84eabd724a63575d461bb15672c977f9b7d5e443fe965097604a6aff3441cbbda587fa0cb29", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ce2f992445e2024c5e038cff98a603fdcb13014045178cb7afaca607d817d3fe590c7dd93708d79dea6a98c7c6bf6c82b9e082bdcd5aab725ed9c5bf0dab8014", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/347ddcfdf6c0a69d81569a56d25676031191929f94aed31f00197357ddf274c76f4c75d07a29b198de304453da90f441a4c191e23c5ec0d47e4b1c87dc7000f7"} 15:27:31 INFO - Running post-run listener: copy_logs_to_upload_dir 15:27:31 INFO - Copying logs to upload dir... 15:27:31 INFO - mkdir: /builds/slave/test/build/upload/logs 15:27:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1232.705365 ========= master_lag: 0.34 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 33 secs) (at 2016-12-21 15:27:31.274901) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-21 15:27:31.281970) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1482361461.603230-1973869559 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-Beta/sha512/bff34fcf7826b13092784f72ea8777bad9387cf75f588acbb3d5c84eabd724a63575d461bb15672c977f9b7d5e443fe965097604a6aff3441cbbda587fa0cb29", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ce2f992445e2024c5e038cff98a603fdcb13014045178cb7afaca607d817d3fe590c7dd93708d79dea6a98c7c6bf6c82b9e082bdcd5aab725ed9c5bf0dab8014", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/347ddcfdf6c0a69d81569a56d25676031191929f94aed31f00197357ddf274c76f4c75d07a29b198de304453da90f441a4c191e23c5ec0d47e4b1c87dc7000f7"} build_url:https://queue.taskcluster.net/v1/task/f4cE6Ds-TdemBHYzw5yjjw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011334 build_url: 'https://queue.taskcluster.net/v1/task/f4cE6Ds-TdemBHYzw5yjjw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bff34fcf7826b13092784f72ea8777bad9387cf75f588acbb3d5c84eabd724a63575d461bb15672c977f9b7d5e443fe965097604a6aff3441cbbda587fa0cb29", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ce2f992445e2024c5e038cff98a603fdcb13014045178cb7afaca607d817d3fe590c7dd93708d79dea6a98c7c6bf6c82b9e082bdcd5aab725ed9c5bf0dab8014", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/347ddcfdf6c0a69d81569a56d25676031191929f94aed31f00197357ddf274c76f4c75d07a29b198de304453da90f441a4c191e23c5ec0d47e4b1c87dc7000f7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-21 15:27:31.310573) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 15:27:31.310945) ========= 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=7fc86f70bae03e018da0b1d8000001c4-1482361461.603230-1973869559 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004656 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-21 15:27:31.360371) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-21 15:27:31.363726) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-21 15:27:31.364028) ========= ========= Total master_lag: 0.50 =========