builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-013 starttime: 1484439476.63 results: success (0) buildid: 20170114142523 builduid: 300f00f497644d51b7ce296e63d0f814 revision: 34030b2a1cd430e080d5b2a0df5e8f954ccb727e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-14 16:17:56.631261) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-14 16:17:56.631929) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-14 16:17:56.632422) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-14 16:17:56.647321) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-14 16:17:56.647615) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1484438666.387947-1951917740 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-01-14 16:17: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% 46.1M=0s 2017-01-14 16:17:57 (46.1 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.393753 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-14 16:17:57.060443) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-14 16:17:57.060766) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1484438666.387947-1951917740 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017349 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-14 16:17:57.113052) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-14 16:17:57.113446) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 34030b2a1cd430e080d5b2a0df5e8f954ccb727e --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 34030b2a1cd430e080d5b2a0df5e8f954ccb727e --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=9c3a94c35cc88655b05d2c08000001b2-1484438666.387947-1951917740 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-01-14 16:17:57,611 truncating revision to first 12 chars 2017-01-14 16:17:57,611 Setting DEBUG logging. 2017-01-14 16:17:57,611 attempt 1/10 2017-01-14 16:17:57,611 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/34030b2a1cd4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-14 16:17:58,406 unpacking tar archive at: mozilla-beta-34030b2a1cd4/testing/mozharness/ program finished with exit code 0 elapsedTime=1.144435 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-14 16:17:58.275717) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-14 16:17:58.276059) ========= script_repo_revision: 34030b2a1cd430e080d5b2a0df5e8f954ccb727e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-14 16:17:58.277063) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-14 16:17:58.277410) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-14 16:17:58.291728) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 31 secs) (at 2017-01-14 16:17:58.292210) ========= /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=9c3a94c35cc88655b05d2c08000001b2-1484438666.387947-1951917740 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:17:58 INFO - MultiFileLogger online at 20170114 16:17:58 in /builds/slave/test 16:17:58 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 16:17:58 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:17:58 INFO - {'append_to_log': False, 16:17:58 INFO - 'base_work_dir': '/builds/slave/test', 16:17:58 INFO - 'blob_upload_branch': 'Mozilla-Beta', 16:17:58 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:17:58 INFO - 'branch': 'Mozilla-Beta', 16:17:58 INFO - 'buildbot_json_path': 'buildprops.json', 16:17:58 INFO - 'config_files': ('talos/linux_config.py',), 16:17:58 INFO - 'default_actions': ('clobber', 16:17:58 INFO - 'read-buildbot-config', 16:17:58 INFO - 'download-and-extract', 16:17:58 INFO - 'populate-webroot', 16:17:58 INFO - 'create-virtualenv', 16:17:58 INFO - 'install', 16:17:58 INFO - 'run-tests'), 16:17:58 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:17:58 INFO - 'download_minidump_stackwalk': True, 16:17:58 INFO - 'download_symbols': 'ondemand', 16:17:58 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:17:58 INFO - 'tooltool.py': '/tools/tooltool.py', 16:17:58 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:17:58 INFO - '/tools/misc-python/virtualenv.py')}, 16:17:58 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:17:58 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:17:58 INFO - 'installer_path': 'installer.exe', 16:17:58 INFO - 'log_level': 'info', 16:17:58 INFO - 'log_name': 'talos', 16:17:58 INFO - 'log_to_console': True, 16:17:58 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:17:58 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:17:58 INFO - 'opt_config_files': (), 16:17:58 INFO - 'pip_index': False, 16:17:58 INFO - 'sps_profile': False, 16:17:58 INFO - 'sps_profile_interval': 0, 16:17:58 INFO - 'suite': 'dromaeojs', 16:17:58 INFO - 'system_bits': '32', 16:17:58 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:17:58 INFO - 'title': 'talos-linux64-ix-013', 16:17:58 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:17:58 INFO - 'use_talos_json': True, 16:17:58 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:17:58 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:17:58 INFO - 'work_dir': 'build'} 16:17:58 INFO - [mozharness: 2017-01-15 00:17:58.910000Z] Running clobber step. 16:17:58 INFO - Running pre-action listener: _resource_record_pre_action 16:17:58 INFO - Running main action method: clobber 16:17:58 INFO - rmtree: /builds/slave/test/build 16:17:58 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:17:59 INFO - Running post-action listener: _resource_record_post_action 16:17:59 INFO - [mozharness: 2017-01-15 00:17:59.367622Z] Finished clobber step (success) 16:17:59 INFO - [mozharness: 2017-01-15 00:17:59.367722Z] Running read-buildbot-config step. 16:17:59 INFO - Running pre-action listener: _resource_record_pre_action 16:17:59 INFO - Running main action method: read_buildbot_config 16:17:59 INFO - Using buildbot properties: 16:17:59 INFO - { 16:17:59 INFO - "project": "", 16:17:59 INFO - "product": "firefox", 16:17:59 INFO - "script_repo_revision": "production", 16:17:59 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs", 16:17:59 INFO - "repository": "", 16:17:59 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs", 16:17:59 INFO - "buildid": "20170114142523", 16:17:59 INFO - "pgo_build": "True", 16:17:59 INFO - "basedir": "/builds/slave/test", 16:17:59 INFO - "buildnumber": 97, 16:17:59 INFO - "slavename": "talos-linux64-ix-013", 16:17:59 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 16:17:59 INFO - "platform": "ubuntu64_hw", 16:17:59 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 16:17:59 INFO - "branch": "mozilla-beta", 16:17:59 INFO - "repo_path": "releases/mozilla-beta", 16:17:59 INFO - "revision": "34030b2a1cd430e080d5b2a0df5e8f954ccb727e", 16:17:59 INFO - "stage_platform": "linux64-pgo", 16:17:59 INFO - "builduid": "300f00f497644d51b7ce296e63d0f814", 16:17:59 INFO - "slavebuilddir": "test" 16:17:59 INFO - } 16:17:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/bYWqahrFTeOq8tw86MlhGw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2. 16:17:59 INFO - Running post-action listener: _resource_record_post_action 16:17:59 INFO - [mozharness: 2017-01-15 00:17:59.370180Z] Finished read-buildbot-config step (success) 16:17:59 INFO - [mozharness: 2017-01-15 00:17:59.370271Z] Running download-and-extract step. 16:17:59 INFO - Running pre-action listener: _resource_record_pre_action 16:17:59 INFO - Running main action method: download_and_extract 16:17:59 INFO - mkdir: /builds/slave/test/build/tests 16:17:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:17:59 INFO - trying https://queue.taskcluster.net/v1/task/bYWqahrFTeOq8tw86MlhGw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 16:17:59 INFO - Downloading https://queue.taskcluster.net/v1/task/bYWqahrFTeOq8tw86MlhGw/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 16:17:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bYWqahrFTeOq8tw86MlhGw/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 16:17:59 INFO - Downloaded 1327 bytes. 16:17:59 INFO - Reading from file /builds/slave/test/build/firefox-51.0.en-US.linux-x86_64.test_packages.json 16:17:59 INFO - Using the following test package requirements: 16:17:59 INFO - {u'common': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'], 16:17:59 INFO - u'cppunittest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 16:17:59 INFO - u'firefox-51.0.en-US.linux-x86_64.cppunittest.tests.zip'], 16:17:59 INFO - u'gtest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 16:17:59 INFO - u'firefox-51.0.en-US.linux-x86_64.gtest.tests.zip'], 16:17:59 INFO - u'jittest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 16:17:59 INFO - u'jsshell-linux-x86_64.zip'], 16:17:59 INFO - u'mochitest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 16:17:59 INFO - u'firefox-51.0.en-US.linux-x86_64.mochitest.tests.zip'], 16:17:59 INFO - u'mozbase': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'], 16:17:59 INFO - u'reftest': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 16:17:59 INFO - u'firefox-51.0.en-US.linux-x86_64.reftest.tests.zip'], 16:17:59 INFO - u'talos': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 16:17:59 INFO - u'firefox-51.0.en-US.linux-x86_64.talos.tests.zip'], 16:17:59 INFO - u'web-platform': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 16:17:59 INFO - u'firefox-51.0.en-US.linux-x86_64.web-platform.tests.zip'], 16:17:59 INFO - u'xpcshell': [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip', 16:17:59 INFO - u'firefox-51.0.en-US.linux-x86_64.xpcshell.tests.zip']} 16:17:59 INFO - Downloading packages: [u'firefox-51.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:17:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bYWqahrFTeOq8tw86MlhGw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip 16:17:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bYWqahrFTeOq8tw86MlhGw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:17:59 INFO - Fetch https://queue.taskcluster.net/v1/task/bYWqahrFTeOq8tw86MlhGw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip into memory 16:18:00 INFO - Expected file size: 21973037 16:18:04 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 16:18:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bYWqahrFTeOq8tw86MlhGw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip 16:18:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bYWqahrFTeOq8tw86MlhGw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:18:04 INFO - Fetch https://queue.taskcluster.net/v1/task/bYWqahrFTeOq8tw86MlhGw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.common.tests.zip into memory 16:18:05 INFO - Expected file size: 21973037 16:18:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/bYWqahrFTeOq8tw86MlhGw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip 16:18:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/bYWqahrFTeOq8tw86MlhGw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:18:10 INFO - Fetch https://queue.taskcluster.net/v1/task/bYWqahrFTeOq8tw86MlhGw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.talos.tests.zip into memory 16:18:10 INFO - Expected file size: 14414089 16:18:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:11 INFO - trying https://queue.taskcluster.net/v1/task/bYWqahrFTeOq8tw86MlhGw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 16:18:11 INFO - Downloading https://queue.taskcluster.net/v1/task/bYWqahrFTeOq8tw86MlhGw/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 16:18:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/bYWqahrFTeOq8tw86MlhGw/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 16:18:12 INFO - Downloaded 57045484 bytes. 16:18:12 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/bYWqahrFTeOq8tw86MlhGw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 16:18:12 INFO - mkdir: /builds/slave/test/properties 16:18:12 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:18:12 INFO - Writing to file /builds/slave/test/properties/build_url 16:18:12 INFO - Contents: 16:18:12 INFO - build_url:https://queue.taskcluster.net/v1/task/bYWqahrFTeOq8tw86MlhGw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 16:18:13 INFO - Running post-action listener: _resource_record_post_action 16:18:13 INFO - Running post-action listener: set_extra_try_arguments 16:18:13 INFO - [mozharness: 2017-01-15 00:18:13.547387Z] Finished download-and-extract step (success) 16:18:13 INFO - [mozharness: 2017-01-15 00:18:13.547616Z] Running populate-webroot step. 16:18:13 INFO - Running pre-action listener: _resource_record_pre_action 16:18:13 INFO - Running main action method: populate_webroot 16:18:13 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:18:13 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:18:13 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:18:13 INFO - '--tppagecycles', 16:18:13 INFO - '7'], 16:18:13 INFO - 'tests': ['tsvgm']}}, 16:18:13 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:18:13 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:18:13 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:18:13 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:18:13 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:18:13 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:18:13 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:18:13 INFO - 'talos_options': ['--disable-e10s'], 16:18:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:18:13 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:18:13 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:18:13 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:18:13 INFO - 'talos_options': ['--disable-e10s'], 16:18:13 INFO - 'tests': ['damp', 'tps']}, 16:18:13 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:18:13 INFO - 'tests': ['damp', 'tps']}, 16:18:13 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:18:13 INFO - 'tests': ['dromaeo_dom']}, 16:18:13 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:18:13 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:18:13 INFO - 'tests': ['basic_compositor_video']}, 16:18:13 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:18:13 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:18:13 INFO - 'tests': ['a11yr', 16:18:13 INFO - 'ts_paint', 16:18:13 INFO - 'tpaint', 16:18:13 INFO - 'sessionrestore', 16:18:13 INFO - 'sessionrestore_no_auto_restore', 16:18:13 INFO - 'tabpaint']}, 16:18:13 INFO - 'other-e10s': {'tests': ['a11yr', 16:18:13 INFO - 'ts_paint', 16:18:13 INFO - 'tpaint', 16:18:13 INFO - 'sessionrestore', 16:18:13 INFO - 'sessionrestore_no_auto_restore', 16:18:13 INFO - 'tabpaint']}, 16:18:13 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:18:13 INFO - 'ts_paint', 16:18:13 INFO - 'tpaint', 16:18:13 INFO - 'sessionrestore', 16:18:13 INFO - 'sessionrestore_no_auto_restore', 16:18:13 INFO - 'tabpaint']}, 16:18:13 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:18:13 INFO - 'ts_paint', 16:18:13 INFO - 'tpaint', 16:18:13 INFO - 'sessionrestore', 16:18:13 INFO - 'sessionrestore_no_auto_restore', 16:18:13 INFO - 'tabpaint']}, 16:18:13 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:18:13 INFO - 'tests': ['a11yr', 16:18:13 INFO - 'ts_paint', 16:18:13 INFO - 'tpaint', 16:18:13 INFO - 'sessionrestore', 16:18:13 INFO - 'sessionrestore_no_auto_restore', 16:18:13 INFO - 'tabpaint']}, 16:18:13 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:18:13 INFO - 'tests': ['a11yr', 16:18:13 INFO - 'ts_paint', 16:18:13 INFO - 'tpaint', 16:18:13 INFO - 'sessionrestore', 16:18:13 INFO - 'sessionrestore_no_auto_restore', 16:18:13 INFO - 'tabpaint']}, 16:18:13 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:18:13 INFO - 'tests': ['tsvgx', 16:18:13 INFO - 'tsvgr_opacity', 16:18:13 INFO - 'tart', 16:18:13 INFO - 'tscrollx', 16:18:13 INFO - 'cart']}, 16:18:13 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:18:13 INFO - 'tsvgr_opacity', 16:18:13 INFO - 'tart', 16:18:13 INFO - 'tscrollx', 16:18:13 INFO - 'cart']}, 16:18:13 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:18:13 INFO - 'talos_options': ['--disable-e10s'], 16:18:13 INFO - 'tests': ['tp5o']}, 16:18:13 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:18:13 INFO - 'tests': ['tp5o']}, 16:18:13 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:18:13 INFO - 'talos_options': ['--disable-e10s', 16:18:13 INFO - '--xperf_path', 16:18:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:18:13 INFO - 'tests': ['tp5n']}, 16:18:13 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:18:13 INFO - 'talos_options': ['--xperf_path', 16:18:13 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:18:13 INFO - 'tests': ['tp5n']}}, 16:18:13 INFO - 'talos.zip': {'path': '', 16:18:13 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:18:13 INFO - Running post-action listener: _resource_record_post_action 16:18:13 INFO - [mozharness: 2017-01-15 00:18:13.567657Z] Finished populate-webroot step (success) 16:18:13 INFO - [mozharness: 2017-01-15 00:18:13.567751Z] Running create-virtualenv step. 16:18:13 INFO - Running pre-action listener: _resource_record_pre_action 16:18:13 INFO - Running main action method: create_virtualenv 16:18:13 INFO - Creating virtualenv /builds/slave/test/build/venv 16:18:13 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 16:18:13 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:18:13 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:18:13 INFO - Using real prefix '/usr' 16:18:13 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:18:14 INFO - Installing distribute.............................................................................................................................................................................................done. 16:18:16 INFO - Installing pip.................done. 16:18:16 INFO - Return code: 0 16:18:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:18:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18: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 0x7fe5275f2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b58ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b55f60>, '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 0x1b56d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b571d0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1484438666.387947-1951917740', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:18:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:18:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:18:16 INFO - Using env: {'DISPLAY': ':0', 16:18:16 INFO - 'HOME': '/home/cltbld', 16:18:16 INFO - 'LANG': 'en_US.UTF-8', 16:18:16 INFO - 'LANGUAGE': 'en_US:en', 16:18:16 INFO - 'LOGNAME': 'cltbld', 16:18:16 INFO - 'MAIL': '/var/mail/cltbld', 16:18:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:16 INFO - 'MOZ_NO_REMOTE': '1', 16:18:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:16 INFO - 'NO_EM_RESTART': '1', 16:18:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:16 INFO - 'PWD': '/builds/slave/test', 16:18:16 INFO - 'SHELL': '/bin/bash', 16:18:16 INFO - 'SHLVL': '1', 16:18:16 INFO - 'TERM': 'linux', 16:18:16 INFO - 'TMOUT': '86400', 16:18:16 INFO - 'USER': 'cltbld', 16:18:16 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1484438666.387947-1951917740', 16:18:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:16 INFO - '_': '/tools/buildbot/bin/python'} 16:18:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:16 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 16:18:16 INFO - Cleaning up... 16:18:16 INFO - Return code: 0 16:18:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:18:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5275f2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b58ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b55f60>, '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 0x1b56d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b571d0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1484438666.387947-1951917740', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:18:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 16:18:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 16:18:16 INFO - Using env: {'DISPLAY': ':0', 16:18:16 INFO - 'HOME': '/home/cltbld', 16:18:16 INFO - 'LANG': 'en_US.UTF-8', 16:18:16 INFO - 'LANGUAGE': 'en_US:en', 16:18:16 INFO - 'LOGNAME': 'cltbld', 16:18:16 INFO - 'MAIL': '/var/mail/cltbld', 16:18:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:16 INFO - 'MOZ_NO_REMOTE': '1', 16:18:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:16 INFO - 'NO_EM_RESTART': '1', 16:18:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:16 INFO - 'PWD': '/builds/slave/test', 16:18:16 INFO - 'SHELL': '/bin/bash', 16:18:16 INFO - 'SHLVL': '1', 16:18:16 INFO - 'TERM': 'linux', 16:18:16 INFO - 'TMOUT': '86400', 16:18:16 INFO - 'USER': 'cltbld', 16:18:16 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1484438666.387947-1951917740', 16:18:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:16 INFO - '_': '/tools/buildbot/bin/python'} 16:18:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:16 INFO - Downloading/unpacking psutil>=3.1.1 16:18:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:18:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:18:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:18:18 INFO - Installing collected packages: psutil 16:18:18 INFO - Running setup.py install for psutil 16:18:18 INFO - building 'psutil._psutil_linux' extension 16:18:18 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 16:18:18 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 16:18:18 INFO - building 'psutil._psutil_posix' extension 16:18:18 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 16:18: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 16:18:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:18:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:18:19 INFO - Successfully installed psutil 16:18:19 INFO - Cleaning up... 16:18:19 INFO - Return code: 0 16:18:19 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:18:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5275f2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b58ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b55f60>, '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 0x1b56d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b571d0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1484438666.387947-1951917740', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:18:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:18:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:18:19 INFO - Using env: {'DISPLAY': ':0', 16:18:19 INFO - 'HOME': '/home/cltbld', 16:18:19 INFO - 'LANG': 'en_US.UTF-8', 16:18:19 INFO - 'LANGUAGE': 'en_US:en', 16:18:19 INFO - 'LOGNAME': 'cltbld', 16:18:19 INFO - 'MAIL': '/var/mail/cltbld', 16:18:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:19 INFO - 'MOZ_NO_REMOTE': '1', 16:18:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:19 INFO - 'NO_EM_RESTART': '1', 16:18:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:19 INFO - 'PWD': '/builds/slave/test', 16:18:19 INFO - 'SHELL': '/bin/bash', 16:18:19 INFO - 'SHLVL': '1', 16:18:19 INFO - 'TERM': 'linux', 16:18:19 INFO - 'TMOUT': '86400', 16:18:19 INFO - 'USER': 'cltbld', 16:18:19 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1484438666.387947-1951917740', 16:18:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:19 INFO - '_': '/tools/buildbot/bin/python'} 16:18:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:18:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:18:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:18:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:18:21 INFO - Installing collected packages: mozsystemmonitor 16:18:21 INFO - Running setup.py install for mozsystemmonitor 16:18:21 INFO - Successfully installed mozsystemmonitor 16:18:21 INFO - Cleaning up... 16:18:21 INFO - Return code: 0 16:18:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:18:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5275f2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b58ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b55f60>, '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 0x1b56d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b571d0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1484438666.387947-1951917740', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:18:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:18:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:18:21 INFO - Using env: {'DISPLAY': ':0', 16:18:21 INFO - 'HOME': '/home/cltbld', 16:18:21 INFO - 'LANG': 'en_US.UTF-8', 16:18:21 INFO - 'LANGUAGE': 'en_US:en', 16:18:21 INFO - 'LOGNAME': 'cltbld', 16:18:21 INFO - 'MAIL': '/var/mail/cltbld', 16:18:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:21 INFO - 'MOZ_NO_REMOTE': '1', 16:18:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:21 INFO - 'NO_EM_RESTART': '1', 16:18:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:21 INFO - 'PWD': '/builds/slave/test', 16:18:21 INFO - 'SHELL': '/bin/bash', 16:18:21 INFO - 'SHLVL': '1', 16:18:21 INFO - 'TERM': 'linux', 16:18:21 INFO - 'TMOUT': '86400', 16:18:21 INFO - 'USER': 'cltbld', 16:18:21 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1484438666.387947-1951917740', 16:18:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:21 INFO - '_': '/tools/buildbot/bin/python'} 16:18:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:21 INFO - Downloading/unpacking jsonschema==2.5.1 16:18:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:18:23 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:18:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:24 INFO - Downloading functools32-3.2.3-2.tar.gz 16:18:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:18:24 INFO - warning: no files found matching '*.txt' 16:18:24 INFO - no previously-included directories found matching 'build' 16:18:24 INFO - no previously-included directories found matching 'dist' 16:18:24 INFO - no previously-included directories found matching '.git*' 16:18:24 INFO - Installing collected packages: jsonschema, functools32 16:18:24 INFO - Running setup.py install for functools32 16:18:24 INFO - warning: no files found matching '*.txt' 16:18:24 INFO - no previously-included directories found matching 'build' 16:18:24 INFO - no previously-included directories found matching 'dist' 16:18:24 INFO - no previously-included directories found matching '.git*' 16:18:24 INFO - Successfully installed jsonschema functools32 16:18:24 INFO - Cleaning up... 16:18:24 INFO - Return code: 0 16:18:24 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:18:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5275f2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b58ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b55f60>, '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 0x1b56d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b571d0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1484438666.387947-1951917740', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:18:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:18:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:18:24 INFO - Using env: {'DISPLAY': ':0', 16:18:24 INFO - 'HOME': '/home/cltbld', 16:18:24 INFO - 'LANG': 'en_US.UTF-8', 16:18:24 INFO - 'LANGUAGE': 'en_US:en', 16:18:24 INFO - 'LOGNAME': 'cltbld', 16:18:24 INFO - 'MAIL': '/var/mail/cltbld', 16:18:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:24 INFO - 'MOZ_NO_REMOTE': '1', 16:18:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:24 INFO - 'NO_EM_RESTART': '1', 16:18:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:24 INFO - 'PWD': '/builds/slave/test', 16:18:24 INFO - 'SHELL': '/bin/bash', 16:18:24 INFO - 'SHLVL': '1', 16:18:24 INFO - 'TERM': 'linux', 16:18:24 INFO - 'TMOUT': '86400', 16:18:24 INFO - 'USER': 'cltbld', 16:18:24 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1484438666.387947-1951917740', 16:18:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:24 INFO - '_': '/tools/buildbot/bin/python'} 16:18:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:24 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:18:24 INFO - Cleaning up... 16:18:24 INFO - Return code: 0 16:18:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:18:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5275f2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b58ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b55f60>, '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 0x1b56d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b571d0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1484438666.387947-1951917740', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:18:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:18:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:18:24 INFO - Using env: {'DISPLAY': ':0', 16:18:24 INFO - 'HOME': '/home/cltbld', 16:18:24 INFO - 'LANG': 'en_US.UTF-8', 16:18:24 INFO - 'LANGUAGE': 'en_US:en', 16:18:24 INFO - 'LOGNAME': 'cltbld', 16:18:24 INFO - 'MAIL': '/var/mail/cltbld', 16:18:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:24 INFO - 'MOZ_NO_REMOTE': '1', 16:18:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:24 INFO - 'NO_EM_RESTART': '1', 16:18:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:24 INFO - 'PWD': '/builds/slave/test', 16:18:24 INFO - 'SHELL': '/bin/bash', 16:18:24 INFO - 'SHLVL': '1', 16:18:24 INFO - 'TERM': 'linux', 16:18:24 INFO - 'TMOUT': '86400', 16:18:24 INFO - 'USER': 'cltbld', 16:18:24 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1484438666.387947-1951917740', 16:18:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:24 INFO - '_': '/tools/buildbot/bin/python'} 16:18:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:24 INFO - Downloading/unpacking blobuploader==1.2.4 16:18:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:26 INFO - Downloading blobuploader-1.2.4.tar.gz 16:18:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:18:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:18:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:18:27 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:18:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:27 INFO - Downloading docopt-0.6.1.tar.gz 16:18:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:18:27 INFO - Installing collected packages: blobuploader, requests, docopt 16:18:27 INFO - Running setup.py install for blobuploader 16:18:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:18:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:18:27 INFO - Running setup.py install for requests 16:18:28 INFO - Running setup.py install for docopt 16:18:28 INFO - Successfully installed blobuploader requests docopt 16:18:28 INFO - Cleaning up... 16:18:28 INFO - Return code: 0 16:18:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:18:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:28 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 0x7fe5275f2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b58ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b55f60>, '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 0x1b56d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b571d0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1484438666.387947-1951917740', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:18:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:18:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:18:28 INFO - Using env: {'DISPLAY': ':0', 16:18:28 INFO - 'HOME': '/home/cltbld', 16:18:28 INFO - 'LANG': 'en_US.UTF-8', 16:18:28 INFO - 'LANGUAGE': 'en_US:en', 16:18:28 INFO - 'LOGNAME': 'cltbld', 16:18:28 INFO - 'MAIL': '/var/mail/cltbld', 16:18:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:28 INFO - 'MOZ_NO_REMOTE': '1', 16:18:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:28 INFO - 'NO_EM_RESTART': '1', 16:18:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:28 INFO - 'PWD': '/builds/slave/test', 16:18:28 INFO - 'SHELL': '/bin/bash', 16:18:28 INFO - 'SHLVL': '1', 16:18:28 INFO - 'TERM': 'linux', 16:18:28 INFO - 'TMOUT': '86400', 16:18:28 INFO - 'USER': 'cltbld', 16:18:28 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1484438666.387947-1951917740', 16:18:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:28 INFO - '_': '/tools/buildbot/bin/python'} 16:18:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:18:28 INFO - Running setup.py (path:/tmp/pip-MQquMO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:18:28 INFO - Running setup.py (path:/tmp/pip-5TF782-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:18:28 INFO - Running setup.py (path:/tmp/pip-eo26Qo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:18:28 INFO - Running setup.py (path:/tmp/pip-gge1kF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:18:28 INFO - Running setup.py (path:/tmp/pip-IBYOUu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:18:28 INFO - Running setup.py (path:/tmp/pip-Ydrh6h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:18:28 INFO - Running setup.py (path:/tmp/pip-mydvFk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:18:28 INFO - Running setup.py (path:/tmp/pip-DXjb1k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:18:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:18:28 INFO - Running setup.py (path:/tmp/pip-rFRyEU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:18:29 INFO - Running setup.py (path:/tmp/pip-3Xc4B9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:18:29 INFO - Running setup.py (path:/tmp/pip-eLTrzq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:18:29 INFO - Running setup.py (path:/tmp/pip-ZcIc9a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:18:29 INFO - Running setup.py (path:/tmp/pip-2P53P3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:18:29 INFO - Running setup.py (path:/tmp/pip-KbWZFD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:18:29 INFO - Running setup.py (path:/tmp/pip-BObLKs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:18:29 INFO - Running setup.py (path:/tmp/pip-jrV7Tq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:18:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:18:29 INFO - Running setup.py (path:/tmp/pip-gPSjBc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:18:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:18:29 INFO - Running setup.py install for manifestparser 16:18:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:18:29 INFO - Running setup.py install for mozcrash 16:18:29 INFO - Running setup.py install for mozdebug 16:18:29 INFO - Running setup.py install for mozdevice 16:18:30 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:18:30 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:18:30 INFO - Running setup.py install for mozfile 16:18:30 INFO - Running setup.py install for mozhttpd 16:18:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:18:30 INFO - Running setup.py install for mozinfo 16:18:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:18:30 INFO - Running setup.py install for mozInstall 16:18:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:18:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:18:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:18:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:18:30 INFO - Running setup.py install for mozleak 16:18:30 INFO - Running setup.py install for mozlog 16:18:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:18:30 INFO - Running setup.py install for moznetwork 16:18:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:18:30 INFO - Running setup.py install for mozprocess 16:18:30 INFO - Running setup.py install for mozprofile 16:18:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:18:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:18:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:18:31 INFO - Running setup.py install for mozrunner 16:18:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:18:31 INFO - Running setup.py install for mozscreenshot 16:18:31 INFO - Running setup.py install for moztest 16:18:31 INFO - Running setup.py install for mozversion 16:18:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:18:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:18:31 INFO - Cleaning up... 16:18:31 INFO - Return code: 0 16:18:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:18:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5275f2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b58ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b55f60>, '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 0x1b56d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b571d0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1484438666.387947-1951917740', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:18:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:18:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:18:31 INFO - Using env: {'DISPLAY': ':0', 16:18:31 INFO - 'HOME': '/home/cltbld', 16:18:31 INFO - 'LANG': 'en_US.UTF-8', 16:18:31 INFO - 'LANGUAGE': 'en_US:en', 16:18:31 INFO - 'LOGNAME': 'cltbld', 16:18:31 INFO - 'MAIL': '/var/mail/cltbld', 16:18:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:31 INFO - 'MOZ_NO_REMOTE': '1', 16:18:31 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:31 INFO - 'NO_EM_RESTART': '1', 16:18:31 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:31 INFO - 'PWD': '/builds/slave/test', 16:18:31 INFO - 'SHELL': '/bin/bash', 16:18:31 INFO - 'SHLVL': '1', 16:18:31 INFO - 'TERM': 'linux', 16:18:31 INFO - 'TMOUT': '86400', 16:18:31 INFO - 'USER': 'cltbld', 16:18:31 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1484438666.387947-1951917740', 16:18:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:31 INFO - '_': '/tools/buildbot/bin/python'} 16:18:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:18:31 INFO - Running setup.py (path:/tmp/pip-mZszc_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:18:31 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 16:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:18:31 INFO - Running setup.py (path:/tmp/pip-xXErUD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:18:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:18:31 INFO - Running setup.py (path:/tmp/pip-YmLdmV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:18:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:18:31 INFO - Running setup.py (path:/tmp/pip-gyKKcW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:18:31 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:18:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:18:31 INFO - Running setup.py (path:/tmp/pip-VsIMGw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:18:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 16:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:18:32 INFO - Running setup.py (path:/tmp/pip-oy5OcN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:18:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:18:32 INFO - Running setup.py (path:/tmp/pip-9CYgxI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:18:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:18:32 INFO - Running setup.py (path:/tmp/pip-QVjeik-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:18:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:18:32 INFO - Running setup.py (path:/tmp/pip-biaFPn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:18:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:18:32 INFO - Running setup.py (path:/tmp/pip-ENWbmI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:18:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:18:32 INFO - Running setup.py (path:/tmp/pip-uUVKJR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:18:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 16:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:18:32 INFO - Running setup.py (path:/tmp/pip-OTPiB8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:18:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 16:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:18:32 INFO - Running setup.py (path:/tmp/pip-DjLTjj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:18:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:18:32 INFO - Running setup.py (path:/tmp/pip-mTireS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:18:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 16:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:18:32 INFO - Running setup.py (path:/tmp/pip-uKi8Pq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:18:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 16:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:18:32 INFO - Running setup.py (path:/tmp/pip-DuNrdn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:18:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:18:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:18:32 INFO - Running setup.py (path:/tmp/pip-KWnCZq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:18:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 16:18:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:18:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 16:18:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:18:32 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:18:32 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:18:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:18:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:18:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:18:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:18:34 INFO - Downloading blessings-1.6.tar.gz 16:18:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:18:35 INFO - Installing collected packages: blessings 16:18:35 INFO - Running setup.py install for blessings 16:18:35 INFO - Successfully installed blessings 16:18:35 INFO - Cleaning up... 16:18:35 INFO - Return code: 0 16:18:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:18:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:18:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:18:35 INFO - Reading from file tmpfile_stdout 16:18:35 INFO - Current package versions: 16:18:35 INFO - argparse == 1.2.1 16:18:35 INFO - blessings == 1.6 16:18:35 INFO - blobuploader == 1.2.4 16:18:35 INFO - docopt == 0.6.1 16:18:35 INFO - functools32 == 3.2.3-2 16:18:35 INFO - jsonschema == 2.5.1 16:18:35 INFO - manifestparser == 1.1 16:18:35 INFO - mozInstall == 1.12 16:18:35 INFO - mozcrash == 0.17 16:18:35 INFO - mozdebug == 0.1 16:18:35 INFO - mozdevice == 0.48 16:18:35 INFO - mozfile == 1.2 16:18:35 INFO - mozhttpd == 0.7 16:18:35 INFO - mozinfo == 0.9 16:18:35 INFO - mozleak == 0.1 16:18:35 INFO - mozlog == 3.4 16:18:35 INFO - moznetwork == 0.27 16:18:35 INFO - mozprocess == 0.23 16:18:35 INFO - mozprofile == 0.28 16:18:35 INFO - mozrunner == 6.12 16:18:35 INFO - mozscreenshot == 0.1 16:18:35 INFO - mozsystemmonitor == 0.3 16:18:35 INFO - moztest == 0.7 16:18:35 INFO - mozversion == 1.4 16:18:35 INFO - psutil == 3.1.1 16:18:35 INFO - requests == 1.2.3 16:18:35 INFO - wsgiref == 0.1.2 16:18:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:18:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5275f2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b58ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b55f60>, '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 0x1b56d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b571d0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1484438666.387947-1951917740', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:18:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 16:18:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:18:35 INFO - Using env: {'DISPLAY': ':0', 16:18:35 INFO - 'HOME': '/home/cltbld', 16:18:35 INFO - 'LANG': 'en_US.UTF-8', 16:18:35 INFO - 'LANGUAGE': 'en_US:en', 16:18:35 INFO - 'LOGNAME': 'cltbld', 16:18:35 INFO - 'MAIL': '/var/mail/cltbld', 16:18:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:35 INFO - 'MOZ_NO_REMOTE': '1', 16:18:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:35 INFO - 'NO_EM_RESTART': '1', 16:18:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:35 INFO - 'PWD': '/builds/slave/test', 16:18:35 INFO - 'SHELL': '/bin/bash', 16:18:35 INFO - 'SHLVL': '1', 16:18:35 INFO - 'TERM': 'linux', 16:18:35 INFO - 'TMOUT': '86400', 16:18:35 INFO - 'USER': 'cltbld', 16:18:35 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1484438666.387947-1951917740', 16:18:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:35 INFO - '_': '/tools/buildbot/bin/python'} 16:18:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:18:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 16:18:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 16:18:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:18:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 16:18:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 16:18:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 16:18:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 16:18:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 16:18:35 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 16:18:35 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 16:18:35 INFO - Cleaning up... 16:18:35 INFO - Return code: 0 16:18:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:18:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:18:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:18:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:18:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5275f2f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8b8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1b58ca0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1b55f60>, '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 0x1b56d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b571d0>, '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': '9c3a94c35cc88655b05d2c08000001b2-1484438666.387947-1951917740', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 16:18:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 16:18:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 16:18:35 INFO - Using env: {'DISPLAY': ':0', 16:18:35 INFO - 'HOME': '/home/cltbld', 16:18:35 INFO - 'LANG': 'en_US.UTF-8', 16:18:35 INFO - 'LANGUAGE': 'en_US:en', 16:18:35 INFO - 'LOGNAME': 'cltbld', 16:18:35 INFO - 'MAIL': '/var/mail/cltbld', 16:18:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:35 INFO - 'MOZ_NO_REMOTE': '1', 16:18:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:35 INFO - 'NO_EM_RESTART': '1', 16:18:35 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:35 INFO - 'PWD': '/builds/slave/test', 16:18:35 INFO - 'SHELL': '/bin/bash', 16:18:35 INFO - 'SHLVL': '1', 16:18:35 INFO - 'TERM': 'linux', 16:18:35 INFO - 'TMOUT': '86400', 16:18:35 INFO - 'USER': 'cltbld', 16:18:35 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1484438666.387947-1951917740', 16:18:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:35 INFO - '_': '/tools/buildbot/bin/python'} 16:18:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:18:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:18:35 INFO - Cleaning up... 16:18:35 INFO - Return code: 0 16:18:35 INFO - Running post-action listener: _resource_record_post_action 16:18:35 INFO - Running post-action listener: _start_resource_monitoring 16:18:35 INFO - Starting resource monitoring. 16:18:35 INFO - [mozharness: 2017-01-15 00:18:35.874666Z] Finished create-virtualenv step (success) 16:18:35 INFO - [mozharness: 2017-01-15 00:18:35.875108Z] Running install step. 16:18:35 INFO - Running pre-action listener: _resource_record_pre_action 16:18:35 INFO - Running main action method: install 16:18:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:18:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:18:36 INFO - Reading from file tmpfile_stdout 16:18:36 INFO - Detecting whether we're running mozinstall >=1.0... 16:18:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:18:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:18:36 INFO - Reading from file tmpfile_stdout 16:18:36 INFO - Output received: 16:18:36 INFO - Usage: mozinstall [options] installer 16:18:36 INFO - Options: 16:18:36 INFO - -h, --help show this help message and exit 16:18:36 INFO - -d DEST, --destination=DEST 16:18:36 INFO - Directory to install application into. [default: 16:18:36 INFO - "/builds/slave/test"] 16:18:36 INFO - --app=APP Application being installed. [default: firefox] 16:18:36 INFO - mkdir: /builds/slave/test/build/application 16:18:36 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'] 16:18:36 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 16:18:49 INFO - Reading from file tmpfile_stdout 16:18:49 INFO - Output received: 16:18:49 INFO - /builds/slave/test/build/application/firefox/firefox 16:18:49 INFO - Running post-action listener: _resource_record_post_action 16:18:49 INFO - [mozharness: 2017-01-15 00:18:49.668272Z] Finished install step (success) 16:18:49 INFO - [mozharness: 2017-01-15 00:18:49.668516Z] Running run-tests step. 16:18:49 INFO - Running pre-action listener: _resource_record_pre_action 16:18:49 INFO - Running main action method: run_tests 16:18:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:18:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:18:49 INFO - Python 2.7.3 16:18:49 INFO - Return code: 0 16:18:49 INFO - grabbing minidump binary from tooltool 16:18:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:18:49 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 0x1b55f60>, '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 0x1b56d40>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1b571d0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:18:49 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 16:18:49 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 16:18:49 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 16:18:49 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:18:49 INFO - Return code: 0 16:18:49 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:18:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:18:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:18:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:18:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:18:49 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/bYWqahrFTeOq8tw86MlhGw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 16:18:49 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/bYWqahrFTeOq8tw86MlhGw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-013 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 16:18:49 INFO - Using env: {'DISPLAY': ':0', 16:18:49 INFO - 'HOME': '/home/cltbld', 16:18:49 INFO - 'LANG': 'en_US.UTF-8', 16:18:49 INFO - 'LANGUAGE': 'en_US:en', 16:18:49 INFO - 'LOGNAME': 'cltbld', 16:18:49 INFO - 'MAIL': '/var/mail/cltbld', 16:18:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:18:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:18:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:49 INFO - 'MOZ_NO_REMOTE': '1', 16:18:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:18:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:18:49 INFO - 'NO_EM_RESTART': '1', 16:18:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:18:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:49 INFO - 'PWD': '/builds/slave/test', 16:18:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:18:49 INFO - 'SHELL': '/bin/bash', 16:18:49 INFO - 'SHLVL': '1', 16:18:49 INFO - 'TERM': 'linux', 16:18:49 INFO - 'TMOUT': '86400', 16:18:49 INFO - 'USER': 'cltbld', 16:18:49 INFO - 'XDG_SESSION_COOKIE': '9c3a94c35cc88655b05d2c08000001b2-1484438666.387947-1951917740', 16:18:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:49 INFO - '_': '/tools/buildbot/bin/python'} 16:18:49 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/bYWqahrFTeOq8tw86MlhGw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-013', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:18:49 INFO - mozversion application_buildid: 20170114142523 16:18:49 INFO - mozversion application_changeset: 34030b2a1cd430e080d5b2a0df5e8f954ccb727e 16:18:49 INFO - mozversion application_display_name: Firefox 16:18:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:18:49 INFO - mozversion application_name: Firefox 16:18:49 INFO - mozversion application_remotingname: firefox 16:18:49 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 16:18:49 INFO - mozversion application_vendor: Mozilla 16:18:49 INFO - mozversion application_version: 51.0 16:18:49 INFO - mozversion platform_buildid: 20170114142523 16:18:49 INFO - mozversion platform_changeset: 34030b2a1cd430e080d5b2a0df5e8f954ccb727e 16:18:49 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 16:18:49 INFO - mozversion platform_version: 51.0 16:18:49 INFO - using testdate: 1484439529 16:18:49 INFO - actual date: 1484439529 16:18:49 INFO - starting webserver on 'localhost:54619' 16:18:49 INFO - SUITE-START | Running 2 tests 16:18:49 INFO - TEST-START | dromaeo_css 16:18:49 INFO - operating with platform_type : linux_ 16:18:49 INFO - Initialising browser for dromaeo_css test... 16:18:50 INFO - TEST-INFO | started process 18424 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzAd6hW/profile http://localhost:54619/getInfo.html) 16:18:53 INFO - PROCESS | 18424 | __metrics Screen width/height:1600/1200 16:18:53 INFO - PROCESS | 18424 | colorDepth:24 16:18:53 INFO - PROCESS | 18424 | Browser inner width/height: 1024/697 16:18:53 INFO - PROCESS | 18424 | __metrics 16:18:54 INFO - PROCESS | 18424 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:18:54 INFO - PROCESS | 18424 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:18:55 INFO - PROCESS | 18424 | console.error: PushService: 16:18:55 INFO - PROCESS | 18424 | stateChangeProcessEnqueue: Error transitioning state 16:18:55 INFO - PROCESS | 18424 | UnknownError 16:18:55 INFO - TEST-INFO | 18424: exit 0 16:18:55 INFO - Browser initialized. 16:18:55 INFO - Running cycle 1/1 for dromaeo_css test... 16:18:55 INFO - TEST-INFO | started process 18514 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpzAd6hW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:19:07 INFO - PROCESS | 18514 | RSS: Main: 143572992 16:19:07 INFO - PROCESS | 18514 | 16:19:58 INFO - PROCESS | 18514 | 1484439598551 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 16:22:07 INFO - PROCESS | 18514 | Cycle 1(1): loaded http://localhost:54619/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54619/tests/dromaeo/cssquery-ext.html) 16:22:09 INFO - PROCESS | 18514 | RSS: Main: 283082752 16:22:09 INFO - PROCESS | 18514 | 16:25:11 INFO - PROCESS | 18514 | Cycle 1(1): loaded http://localhost:54619/tests/dromaeo/cssquery-ext.html (next: http://localhost:54619/tests/dromaeo/cssquery-jquery.html) 16:25:11 INFO - PROCESS | 18514 | RSS: Main: 235634688 16:25:11 INFO - PROCESS | 18514 | 16:28:13 INFO - PROCESS | 18514 | Cycle 1(1): loaded http://localhost:54619/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54619/tests/dromaeo/cssquery-mootools.html) 16:28:14 INFO - PROCESS | 18514 | RSS: Main: 174190592 16:28:14 INFO - PROCESS | 18514 | 16:31:16 INFO - PROCESS | 18514 | Cycle 1(1): loaded http://localhost:54619/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54619/tests/dromaeo/cssquery-prototype.html) 16:31:16 INFO - PROCESS | 18514 | RSS: Main: 205438976 16:31:16 INFO - PROCESS | 18514 | 16:34:19 INFO - PROCESS | 18514 | Cycle 1(1): loaded http://localhost:54619/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54619/tests/dromaeo/cssquery-yui.html) 16:34:20 INFO - PROCESS | 18514 | RSS: Main: 177201152 16:34:20 INFO - PROCESS | 18514 | 16:37:22 INFO - PROCESS | 18514 | Cycle 1(1): loaded http://localhost:54619/tests/dromaeo/cssquery-yui.html (next: http://localhost:54619/tests/dromaeo/cssquery-dojo.html) 16:37:22 INFO - PROCESS | 18514 | RSS: Main: 261726208 16:37:22 INFO - PROCESS | 18514 | 16:37:22 INFO - PROCESS | 18514 | __start_tp_report 16:37:22 INFO - PROCESS | 18514 | _x_x_mozilla_page_load 16:37:22 INFO - PROCESS | 18514 | _x_x_mozilla_page_load_details 16:37:22 INFO - PROCESS | 18514 | |i|pagename|runs| 16:37:22 INFO - PROCESS | 18514 | |0;dojo.html;3143.60704911886;3180.8568614412347;3181.681831816817;3204.1188673114875;3215.9196020099494;5577.749001294941;5648.068068768654;5650.26546548949;5657.519110875575;5671.8582035449135;5757.395473475277;5816.098504731774;5819.098039803837;5821.3305469870975;5821.912671309923;5196.662216955895;5228.346456692913;5243.711595862226;5306.628536002481;5312.601554883399;5037.949620503803;5301.655392399501;5321.042212401766;5322.0952438085615;5322.733863306838;5003.674761140533;5127.282180494733;5138.049460849736;5144.717040562768;5154.639175257734;5191.610629202806;5200.973995130038;5214.426413094557;5289.550388216968;5305.071612467803;5156.535911767959;5186.170212765977;5255.973720131375;5278.996990571779;5296.285001524783;5454.10007348787;5472.151816468454;5497.945020549783;5559.027170245191;5585.525230355424;5986.221791167164;5991.131285963555;5997.370276121032;6009.369016253274;6012.609180403259;6043.123747056696;6044.788432404889;6046.697665116727;6047.425494578008;6059.212302400704;5628.240187574707;5651.576131790132;5688.886222275572;5743.569232307594;5746.396628353963;6265.373462653698;6282.434580887651;6286.49708023357;6297.118403423525;6302.991521356561;6067.998780201306;6070.6357618543025;6074.817755467343;6091.42131497507;6096.177016102858;5927.407259274038;6049.18336024631;6054.939450605526;6109.283207920342;6184.690765461709;5886.264216972879;5933.495652869555;6080.908786368208;6106.664133472702;6129.172561704202;5996.610220335664;6009.338972712998;6010.669413182317;6037.758489660452;6055.818325450244;4596.4254468191475;4601.079784043205;4604.838830640979;4608.262677971508;4621.491635920046;4082;4092.8567500137947;4092.9181416371507;4097.037196258877;4132.111596006873;5752.252207213036;5832.912506312408;5834.154047663065;5834.299884013945;5867.677277749679;6234.314225435199;6241.063840423973;6249.468795152372;6251.3123556408755;6253.906191407133;5612.102063669793;5666;5689.573282003867;5764.279465066867;5816.1566572846705;5159.458256883048;5205.167173252337;5209.088409528317;5238.633295669267;5248.9737551312;6353.237611486651;6400.135981642418;6401;6402.871942561216;6409.134766806515;6044.123602077586;6086.3000754913755;6157.32269450369;6160.322364539987;6163.445289923929;6241.126242325987;6265.373462653698;6280.089387038763;6385;6416.326285740117;6033.303997175823;6082.543809214415;6087.634741915499;6094.695265236632;6095.2685677718955;6173.506119510518;6230.7507699691505;6234.2830574484515;6242.313345531897;6244.843878903063;5852.473277405054;5912.438318359749;5917.615355001911;5942.6731529766275;5990.7304171311525;5997;5997.850053748692;6000.279966404059;6031.969840150771;6072.787452439144;5898.557608179318;5935.258092738408;5936.525077993664;5938.198343223782;5939.346671866005;5888.499477544457;5948.940510594839;5967.104934259895;5996.040633498619;6083.330833608388;5883.323417806836;5992.0712289595185;5993.67034813072;6010.3088144863905;6034.185384973148 16:37:22 INFO - PROCESS | 18514 | |1;ext.html;584.9444302791236;632.2065807411698;660.6663634864394;668.7525615522252;672.3478226120661;34805;46644.53355466444;47531;47612.0477590448;47712.28431573529;48412.51587484133;48843.51156488434;48932.75533622327;48997.51002489974;49026.754866225616;8573.699920502784;9354.953225233881;9497.335186536946;9498.335116541844;9518.80962380752;8383.538905360203;8687.826243475127;8692.261157801595;10865.945670271678;11156.274842135275;11490.425478726072;11537.711557211052;11557.133215008866;11561.190716649839;11563.595274165405;11242.831357529603;11299.378534180616;11315.264507806965;11318.60384886525;11328.09375249982;11178.832317515244;11264.042556382718;11287.604933827359;11289.661310160625;11295.887041129567;44974.32538511925;45257.773711131566;45283.773581131885;45376.3193552097;45415.77292113551;3658.2317713280245;3691.409374500094;3722.088088418355;3740.657083165024;4204.579542045771;34547.30905381904;34590.82704586486;34648.480272795925;34749.13127171816;35032.299354013034;4580.496145424001;4595.264757638762;4692.882677933045;4721.480637129913;4746.762661866893;395910;397284;397456;397736;397963;62413.375866241666;62784.37215627877;62856.37143628505;62867;62870.68564657147;40973.1805363891;41835.163296733896;42068.94827629272;43987.34018989718;44017.11965760731;17459.38892138769;17562.912185439247;17567.912160439115;17580.208890599955;17583.12084395775;13922.164670119826;14019.50931717385;14078.366473508717;14112.294385280695;14125.293735313193;18546.350877719215;19001.809981900005;19589.608207835765;20835.79164208369;20934.47663808417;7292.380147687506;7357.889631655529;7506.699732010658;7552.735654252075;7605;1043.3531210649446;1054.1619412567027;1085.1264731890903;1090.6509916826699;1125.2290289173843;34053.48919766206;34124.82937585296;34404.31191376159;34822.89806788828;34868.302633947176;1122.635143578324;1184.1651635596884;1196.6230637349206;1199.8860108289698;1265.2661456355293;17822.37621683235;18115.456536303933;18223.81545199558;18239.63520729578;18240.27038918455;589699;591006;591907.3237070469;591941;592226;60549.39450605526;61029.38970610326;61070.694646526485;61077.38922610806;61089.694551526954;1306.6537367597593;1384.3908680180689;1399.0836002418432;1416.525463969561;1418.6098822823806;31424.214394640316;34173.316533669684;35111.47332789959;35261.118472038404;35482.822585886905;51628.74185629048;52173.478265216865;52332.21501677402;52339;52392;456187;456290;458582;458865;460204;27170;27936.44127117487;27971;29776.255593610334;30111.397772044875;49601.75199123981;50018;50086.749566251936;50222;50353.49646503635;42070.57929420789;42127.36808947929;42791.572084278756;43577.56422435735;43712;41851;42390.57609423866;42498.15003699847;43415;43958.340624890014;42847.14305713931;42956;43745;44118.55881441273;44338.3349249768;34172.82913585416;37231.627683722814;37994.620053800216;38233.23533529258;38613.80693096516;29060.418791623626;29306.51872399462;29320.85339573374;29680.109596712133;29711.85144074266 16:37:22 INFO - PROCESS | 18514 | |2;jquery.html;422.2357532865512;428.7813215260218;438.666453979901;441.36443521329284;448.17312059250673;19227.32704355346;19456.99999999998;20810.47973800652;20987.160513579438;21126.57746845062;19345.806541934577;19568.11943462544;19988.30040948567;20096.196152153934;20269;5690.089585666295;5954.4640982311585;5956.493698035672;5962.25471816023;5967.85080372992;5708.172315014321;5892.587518873681;5902.2622172228475;5902.350741418462;5945;5878;5880.117982302668;5902.793402230923;5905.2323197984415;5906.05503119501;5681.801136960207;5853.063509838448;5869.09029100488;5890.263717035371;5891.909996650634;5325.520703136736;5334.199870019489;5336.759845806948;5341.1187154119525;5343.893122137535;23934.64098038531;24213.273601791974;24264.1507547235;24359;24611.876940615362;2663.5605125154325;2683.8121331506804;2723.8774255158564;2887.2493151780477;3034.317278612317;14161.22113283769;14953.327100280405;14969.476068337555;15126.470573529874;15300.005499642488;2041.7141600175987;2094.5706130243416;2134.1783413385956;2138.9572208555896;2242.394553470554;62284.903845200395;71230;72230.93599208811;79101;79101;11733.823992640117;11812.822807657893;11889.643310700692;12025.4588543514;12131.454084566216;17075.40236091731;17080.829191707922;17085.145742712815;17088.743668844978;17089.743653845202;8219.712310069239;8396.874046889301;8434.536100514408;8505.31957443403;8571.614277357534;7744.883826742604;7900.9604951976025;7926.8810967835525;7939.523628582304;8158.918410815934;5101.540861322499;5113.15632920564;5177.275181474596;5223.634345595772;5273.367195936436;2014.8488863335308;2119.1417475922276;2169.7995456701237;2198.1097655449566;2218.7892150245702;802.3460879383284;958.5782255807422;981.1415011864618;1048.9580416783397;1084.967450976472;20440.28459003928;20538;20546.28088016912;20565.38303850887;20632.17471301131;1022.2792930983638;1039.7556574205055;1077.5097330714507;1080.6487891435315;1086.125668836594;10894.18293627981;11452.3701196435;11540.884591153981;11549.826752598718;11637.709057273636;507204;527228;528881;546551;553993;103085;105125;105321;105332.47333763282;108441.45779271211;994.7562847102362;1005.8340373838381;1031.7008067660445;1044.237706474278;1044.8014877173314;17805.46583602494;18246.178921948278;18565.72151417703;18609.627807444045;18791.436256912315;37725.81137094297;47680.28479572879;47965.280520791515;48136.27795583139;49294.26058609196;65599;66174.66912665406;66225;66337.33662663311;66753.99869002066;8254.587270636564;8670.349723770616;8745.344099192458;8837.734867953972;9014.639414423349;13127.93436032852;13335.333233338488;13474.595762127152;13512.121712088683;13540.255285959367;11755.706107347385;11924.22492538017;12028.27830330183;12322.630321090382;12538.749225015266;11348.319100853974;11641.301521908712;11687.532498699957;11889.643310700692;12014.879851201376;12000.219985701251;12013.63959081229;12097.395130243276;12151.175565809697;12403.93798031004;7997.880031799644;8178.672853086048;8434.65954393226;8748.60631271607;8839;9147.542622868963;11586.188966772244;11912.940435297767;12012.33932133706;12082.879171208528 16:37:22 INFO - PROCESS | 18514 | |3;mootools.html;1540.6610545679946;1564.4915402494191;1715.5796829776707;1853.5644123630952;1867.69183084791;9208.171264586186;9972;9972.40165590065;9974.650887218957;9986.50067496625;8547.829043419128;8783.429077109984;9299.30255230857;9635.80728385432;9770.853437198448;2570.935726606831;2581.2643396631966;2598.4803039392104;2603.2190342897056;2608.334874606973;2532.050481069599;2584.8578328191943;2585.61215817627;2586.831855929368;2590.5596048671773;2563.6539067225967;2566.153169454076;2589.3008887600336;2591.6501272328182;2596.2730435478;2497.1759319424546;2505.5114252720728;2516.3457501049675;2538.4288535079645;2549.4008907906627;2467.9431317381564;2483.4412257242157;2491.676082109332;2510.1716433576876;2515.019142534413;9452.054794520562;9624.95187524065;9625.951870240675;9628.47043412612;9630.807383852283;5623.128415095667;5749.195112684228;5782.710864456761;5784.1902133701315;5793.449622285876;3547.8935631931085;3609.2240168363664;3682.171511409938;3701.9259614807825;3725.6833169180654;1114.749181434179;1183.727492945079;1186.1163433242205;1192.165484161082;1195.0260537661866;13812.447502099903;13829.654258643614;13829.861701382957;13842.446302147902;13842.792358114637;5104.464031276737;5112.795488180505;5197.58419326453;5264.0261551613075;5268.947310526845;11590.246633968765;11598.420078996016;11600.129990250765;11601.651950441501;11611.825822612498;5888.852778680567;5890.4698577128265;5893.911591326134;5894.32215295238;5895.469407753323;4766.880827979259;5081.695098294114;5276.736163191825;5324.547413469821;5419.241306217133;4371.191329604034;4412.294032954712;4461.375407442961;4500.369948207188;4513.458384993822;1416.0795482936173;1443.1557538839895;1451.535508637226;1453.7019910918236;1464.9707005860037;1005.416858222244;1020.7805321855836;1023.2376879225025;1025.2925481417947;1033.7777377863795;6402.583832050902;6454.354564543508;6588.8023359299295;6592;6791.151106111736;477.25548144894077;480.5026797264814;484.12373603777274;491.02286449965027;508.13109582614146;4315.374270730789;4486.214912390318;4486.484054333793;4496.797644106023;4538.1377538267625;11643.301401915913;12145.939270303592;12147.5140994361;12153.149279550524;12178.57374991871;10734.355938643706;11094.778104438028;12023.759524809455;12110.39448027595;12111.152219344738;8854;8997.055309192572;9032.503212323385;9050.728478145535;9050.86423703645;6173.228346456693;6372.458341040878;6493.253275873335;6574.474042076718;6603.339666033358;10725.141988640735;10971.396573188551;11010.669679909615;11182.608608698656;11272.605458808903;11411.486483108447;11485.885141148481;11499.712507187387;11528.884711153116;11537.653870383901;7787.182345853604;8042.557659328793;8286.958565207377;8507.659693612186;8665.956670216608;8889.111088891059;9215.677451289173;9305.395149315273;9306.953465232631;9314.394564353566;8456.915430845613;8550.444221125606;8561.44350617208;8591.871121933302;8709.608067636846;7151.2848715128075;7572.091349038151;7818.882716759367;7821.882671760042;7931.246531579491;7681.308682218403;7703.460757746904;7729.613519323899;7794.649240784065;7816.218378162138;5526;5564.109742441352;5572.080606699849;5599.916001259903;5706.8573285666525;5565.944340556543;5648.011597970421;5661.207430959586;5688.943110568841;5844.970775146267 16:37:22 INFO - PROCESS | 18514 | |4;prototype.html;337.7973216070358;349.2804822066543;354.50723494342867;361.5661206552138;363.7090327737809;5508.732991411977;5595.972020139904;5599.160125981105;5630.521405680517;5640.717964101788;5201.087391061009;5345.545628621562;5349.331333583302;5370.194470829368;5516.531094856942;4202.222588821062;4203.201391735576;4349.825087456272;4362.956370436303;4402.028630674282;4335.263005289108;4358.171947330013;4368.410264614268;4372.868813935587;4373.431453911003;4158.022864626811;4354.782260886943;4371.060222052258;4382.0797632497215;4389.758563279019;4334.869953901388;4350.521442641307;4363.934540981872;4372.147431250907;4376.759278239696;3903.8048097595292;3933;3935.1932853765197;3939.231849789292;3946.782926939017;5243.475652434764;5402.055879545618;5435.673859568438;5440.265564148829;5461.290032295816;5045.949540504585;5053.9494605054215;5055.721935293557;5057.49425057495;5063.240513922904;5794.159846822187;5798.88402231953;5811.970940145272;5832.154337621021;5835.153902684087;4082.6733861291027;4257.510386305553;4289.155663064881;4305.913881722379;4328.91342173158;6010.9699451502465;6015.909761353583;6022.69886505673;6063.727132279058;6072.726727297282;4372.715773474714;4402.0095478517715;4408.955910440919;4415.911681766347;4438.844640437634;4278.379634952915;4457.621102206349;4502.617277531446;4525.705829121097;4549.249373853343;5063.443021267658;5072.543471087546;5074.314967479417;5075.644704870698;5078.873028174326;5868.589198756132;5885.852853678607;5886.8822623547285;5905;5908.320543137508;1482.9406823726931;1484.502691598305;1507.5376884422094;1558.9142597157038;1559.5165498695442;1055.335138862527;1065.8561094252177;1086.1365214654368;1097.1113398147527;1108.1245815805423;5508.862278442991;5542.4734650208975;5548.91676624851;5572.749226284827;5573.777048918079;5929.37741537141;5938.465538101592;5940.257467816523;5949.494292985059;5955.970220148872;5369.48989845964;5539.559714474185;5541.141123125922;5553.389127196006;5568.693721845338;3137.8587963541695;3141.513065474855;3154.9842250788597;3158.8894388696285;3165.572647692578;5882.470577648032;6055.878882422327;6098.054801505774;6098.268207815002;6110.725017374229;6042.848928776816;6057.939420605826;6060.969695151584;6061.151438798572;6062.939370606237;6100.69496525172;6106.5114790817515;6107.755689772448;6108.51131909455;6109.938900611026;5771.97114014444;5893.616914900518;5899.439553242435;5931.466168044896;5937.910931335947;5860.853478663067;5860.882782344244;5861.853453663693;5863.120531920246;5865.94134058654;5916.290045194604;6011.519078473624;6097.780443911324;6098.390160983866;6104.26748790148;4373.322135069069;4388.210122178039;4399.516053234078;4411.139827733561;4421.8282155229535;5927.318358388668;5930.644161350333;5932.851678707894;5936.28764548257;5940.376260492586;5610.49505544503;5708.686022268815;5783.161441590862;5870.26621672291;5892.438503796583;5837.6497410155525;5859.179714840011;5881.9705901470215;5888.352281248974;5893;5653.491185793298;5822.621529600562;5826.82519524415;5831.271091113611;5832.941670583409;5906.202662640489;5926.525877929669;5951.28584569837;5955.970220148872;5973.522118230444;5680.886382272414;5838.386969368155;5839.766409343579;5843.970780146242;5849.3858144894175 16:37:22 INFO - PROCESS | 18514 | |5;yui.html;753.8416932444186;792.6393490961613;824.562981619741;857.7641148684115;865.4374656473292;16051.197440127991;16706.83293167068;16795.07627080513;16858.662826743457;16912.83087169128;15090.849091509082;15140.621484462894;15231.390744370212;15268.694626107472;15287.847121528781;721.1454426504582;733.6881825224285;845.8731190321439;852.3053711225347;873.6723728601775;855.5764896376303;859.7850537365658;859.8882145321099;874.6588830356167;875.7460336502406;917.3578495053489;917.4632839788732;919.0487845080344;919.0533750237266;919.7102912582515;907.6324088744069;913.4336711239041;913.5569248914271;913.9223166030895;914.8216097860919;663.4791688524533;666.3303380103014;667.2560095493538;667.3160010988727;667.7495939022866;16512.66974660512;16524;16541;16580.50258492235;16586.6682666346;15995.720342372699;16537.421190258283;16644.167791610493;16742.497725068268;17298.56753581158;13585.52450664232;13709.862901370958;13758.449662013507;13919.930400347934;13927.930360348235;4058.391241313797;4131.757144496718;4171.937420938689;4191.476065491814;4301.3117901135665;75368.62315688387;89838.5508072462;90084.54957725234;90776.54611726965;91232;11766.117541184274;11944.462499187557;12028.819567706492;12342.691432714075;12395.070369722127;24764.876175619007;24783.752162478504;24798.752012480007;24811.5037699245;24827.255182344557;9555.092266234697;9666.635206223275;9694.6122155113;9831.31180817336;10136;4720.834770783006;4746.359241502422;4824.879378015507;4899.559039686445;4971.477994810492;3847.9038024049623;3967.6429121379215;4002.1595464952684;4005.5794141615293;4007.178528401729;580.3616022375353;658.9604623722591;663.575311800448;663.6781161136845;665.9200895892524;641.5445034025801;672.6132473827569;762.7330434347933;776.8679324514846;803.3292201012104;10366.740831479123;10587.311824731521;10918.836217456745;10966.396848173268;10976.670699879016;583.988320233593;627.3945642455053;690.3476214976899;720.8666396716624;736.9535259930911;4944.456109827916;4996.7501624918605;5064.366954130734;5110.182370820651;5174.689518628894;138184;139871;139962;142134;144427;118563;119075;120092.3995380035;121167.39416302861;122402;64066;65143.674281628286;102527.48736256271;107957.46021269844;110761;9319.767005824908;9599.904000960181;9681.757956051155;9828.361156524803;9855.260855436014;62816.685916570124;63021.36978630338;65034;65550.58564500429;65651.67174164098;53146.73426632842;68879;70637.64681176562;72775.27224727685;96125;13728.65678358009;14102.858971410566;14278.857211427752;14303.284835758379;14430.350634221695;13708.725825483634;15200.619984500476;15443.536693899201;15569.532914012598;17034.659306814043;13249.867501324863;13400.530981415604;13442.596722097962;14085.718285634024;14218;12415.317157556421;13351.799723004357;13789;14205.147691138565;14507.854921450651;12882;14075.929620351833;14271.143731375736;14649.340779664728;15117.773233401287;11719.589814356457;11950.820737688768;12410.131290809557;12528.74942501163;12545;12090.214136081127;12166.817497737364;12204.572839950559;12303.75392492163;12337.444814983168 16:37:22 INFO - PROCESS | 18514 | __end_tp_report 16:37:22 INFO - PROCESS | 18514 | __start_cc_report 16:37:22 INFO - PROCESS | 18514 | _x_x_mozilla_cycle_collect,1117 16:37:22 INFO - PROCESS | 18514 | __end_cc_report 16:37:22 INFO - PROCESS | 18514 | __startTimestamp1484440642893__endTimestamp 16:37:22 INFO - PROCESS | 18514 | 16:37:22 INFO - PROCESS | 18514 | ------- Summary: start ------- 16:37:22 INFO - PROCESS | 18514 | Number of tests: 6 16:37:22 INFO - PROCESS | 18514 | 16:37:22 INFO - PROCESS | 18514 | [#0] dojo.html Cycles:165 Average:5670.99 Median:5927.41 stddev:670.70 (11.3%) stddev-sans-first:642.78 16:37:22 INFO - PROCESS | 18514 | Values: 3143.6 3180.9 3181.7 3204.1 3215.9 5577.7 5648.1 5650.3 5657.5 5671.9 5757.4 5816.1 5819.1 5821.3 5821.9 5196.7 5228.3 5243.7 5306.6 5312.6 5037.9 5301.7 5321.0 5322.1 5322.7 5003.7 5127.3 5138.0 5144.7 5154.6 5191.6 5201.0 5214.4 5289.6 5305.1 5156.5 5186.2 5256.0 5279.0 5296.3 5454.1 5472.2 5497.9 5559.0 5585.5 5986.2 5991.1 5997.4 6009.4 6012.6 6043.1 6044.8 6046.7 6047.4 6059.2 5628.2 5651.6 5688.9 5743.6 5746.4 6265.4 6282.4 6286.5 6297.1 6303.0 6068.0 6070.6 6074.8 6091.4 6096.2 5927.4 6049.2 6054.9 6109.3 6184.7 5886.3 5933.5 6080.9 6106.7 6129.2 5996.6 6009.3 6010.7 6037.8 6055.8 4596.4 4601.1 4604.8 4608.3 4621.5 4082.0 4092.9 4092.9 4097.0 4132.1 5752.3 5832.9 5834.2 5834.3 5867.7 6234.3 6241.1 6249.5 6251.3 6253.9 5612.1 5666.0 5689.6 5764.3 5816.2 5159.5 5205.2 5209.1 5238.6 5249.0 6353.2 6400.1 6401.0 6402.9 6409.1 6044.1 6086.3 6157.3 6160.3 6163.4 6241.1 6265.4 6280.1 6385.0 6416.3 6033.3 6082.5 6087.6 6094.7 6095.3 6173.5 6230.8 6234.3 6242.3 6244.8 5852.5 5912.4 5917.6 5942.7 5990.7 5997.0 5997.9 6000.3 6032.0 6072.8 5898.6 5935.3 5936.5 5938.2 5939.3 5888.5 5948.9 5967.1 5996.0 6083.3 5883.3 5992.1 5993.7 6010.3 6034.2 16:37:22 INFO - PROCESS | 18514 | 16:37:22 INFO - PROCESS | 18514 | [#1] ext.html Cycles:180 Average:64578.90 Median:397849.50 stddev:129724.46 (32.6%) stddev-sans-first:129999.39 16:37:22 INFO - PROCESS | 18514 | Values: 584.9 632.2 660.7 668.8 672.3 34805.0 46644.5 47531.0 47612.0 47712.3 48412.5 48843.5 48932.8 48997.5 49026.8 8573.7 9355.0 9497.3 9498.3 9518.8 8383.5 8687.8 8692.3 10865.9 11156.3 11490.4 11537.7 11557.1 11561.2 11563.6 11242.8 11299.4 11315.3 11318.6 11328.1 11178.8 11264.0 11287.6 11289.7 11295.9 44974.3 45257.8 45283.8 45376.3 45415.8 3658.2 3691.4 3722.1 3740.7 4204.6 34547.3 34590.8 34648.5 34749.1 35032.3 4580.5 4595.3 4692.9 4721.5 4746.8 395910.0 397284.0 397456.0 397736.0 397963.0 62413.4 62784.4 62856.4 62867.0 62870.7 40973.2 41835.2 42068.9 43987.3 44017.1 17459.4 17562.9 17567.9 17580.2 17583.1 13922.2 14019.5 14078.4 14112.3 14125.3 18546.4 19001.8 19589.6 20835.8 20934.5 7292.4 7357.9 7506.7 7552.7 7605.0 1043.4 1054.2 1085.1 1090.7 1125.2 34053.5 34124.8 34404.3 34822.9 34868.3 1122.6 1184.2 1196.6 1199.9 1265.3 17822.4 18115.5 18223.8 18239.6 18240.3 589699.0 591006.0 591907.3 591941.0 592226.0 60549.4 61029.4 61070.7 61077.4 61089.7 1306.7 1384.4 1399.1 1416.5 1418.6 31424.2 34173.3 35111.5 35261.1 35482.8 51628.7 52173.5 52332.2 52339.0 52392.0 456187.0 456290.0 458582.0 458865.0 460204.0 27170.0 27936.4 27971.0 29776.3 30111.4 49601.8 50018.0 50086.7 50222.0 50353.5 42070.6 42127.4 42791.6 43577.6 43712.0 41851.0 42390.6 42498.2 43415.0 43958.3 42847.1 42956.0 43745.0 44118.6 44338.3 34172.8 37231.6 37994.6 38233.2 38613.8 29060.4 29306.5 29320.9 29680.1 29711.9 16:37:22 INFO - PROCESS | 18514 | 16:37:22 INFO - PROCESS | 18514 | [#2] jquery.html Cycles:180 Average:31136.83 Median:24073.96 stddev:87865.36 (365.0%) stddev-sans-first:88081.58 16:37:22 INFO - PROCESS | 18514 | Values: 422.2 428.8 438.7 441.4 448.2 19227.3 19457.0 20810.5 20987.2 21126.6 19345.8 19568.1 19988.3 20096.2 20269.0 5690.1 5954.5 5956.5 5962.3 5967.9 5708.2 5892.6 5902.3 5902.4 5945.0 5878.0 5880.1 5902.8 5905.2 5906.1 5681.8 5853.1 5869.1 5890.3 5891.9 5325.5 5334.2 5336.8 5341.1 5343.9 23934.6 24213.3 24264.2 24359.0 24611.9 2663.6 2683.8 2723.9 2887.2 3034.3 14161.2 14953.3 14969.5 15126.5 15300.0 2041.7 2094.6 2134.2 2139.0 2242.4 62284.9 71230.0 72230.9 79101.0 79101.0 11733.8 11812.8 11889.6 12025.5 12131.5 17075.4 17080.8 17085.1 17088.7 17089.7 8219.7 8396.9 8434.5 8505.3 8571.6 7744.9 7901.0 7926.9 7939.5 8158.9 5101.5 5113.2 5177.3 5223.6 5273.4 2014.8 2119.1 2169.8 2198.1 2218.8 802.3 958.6 981.1 1049.0 1085.0 20440.3 20538.0 20546.3 20565.4 20632.2 1022.3 1039.8 1077.5 1080.6 1086.1 10894.2 11452.4 11540.9 11549.8 11637.7 507204.0 527228.0 528881.0 546551.0 553993.0 103085.0 105125.0 105321.0 105332.5 108441.5 994.8 1005.8 1031.7 1044.2 1044.8 17805.5 18246.2 18565.7 18609.6 18791.4 37725.8 47680.3 47965.3 48136.3 49294.3 65599.0 66174.7 66225.0 66337.3 66754.0 8254.6 8670.3 8745.3 8837.7 9014.6 13127.9 13335.3 13474.6 13512.1 13540.3 11755.7 11924.2 12028.3 12322.6 12538.7 11348.3 11641.3 11687.5 11889.6 12014.9 12000.2 12013.6 12097.4 12151.2 12403.9 7997.9 8178.7 8434.7 8748.6 8839.0 9147.5 11586.2 11912.9 12012.3 12082.9 16:37:22 INFO - PROCESS | 18514 | 16:37:22 INFO - PROCESS | 18514 | [#3] mootools.html Cycles:180 Average:6337.45 Median:2622.07 stddev:3651.97 (139.3%) stddev-sans-first:3644.42 16:37:22 INFO - PROCESS | 18514 | Values: 1540.7 1564.5 1715.6 1853.6 1867.7 9208.2 9972.0 9972.4 9974.7 9986.5 8547.8 8783.4 9299.3 9635.8 9770.9 2570.9 2581.3 2598.5 2603.2 2608.3 2532.1 2584.9 2585.6 2586.8 2590.6 2563.7 2566.2 2589.3 2591.7 2596.3 2497.2 2505.5 2516.3 2538.4 2549.4 2467.9 2483.4 2491.7 2510.2 2515.0 9452.1 9625.0 9626.0 9628.5 9630.8 5623.1 5749.2 5782.7 5784.2 5793.4 3547.9 3609.2 3682.2 3701.9 3725.7 1114.7 1183.7 1186.1 1192.2 1195.0 13812.4 13829.7 13829.9 13842.4 13842.8 5104.5 5112.8 5197.6 5264.0 5268.9 11590.2 11598.4 11600.1 11601.7 11611.8 5888.9 5890.5 5893.9 5894.3 5895.5 4766.9 5081.7 5276.7 5324.5 5419.2 4371.2 4412.3 4461.4 4500.4 4513.5 1416.1 1443.2 1451.5 1453.7 1465.0 1005.4 1020.8 1023.2 1025.3 1033.8 6402.6 6454.4 6588.8 6592.0 6791.2 477.3 480.5 484.1 491.0 508.1 4315.4 4486.2 4486.5 4496.8 4538.1 11643.3 12145.9 12147.5 12153.1 12178.6 10734.4 11094.8 12023.8 12110.4 12111.2 8854.0 8997.1 9032.5 9050.7 9050.9 6173.2 6372.5 6493.3 6574.5 6603.3 10725.1 10971.4 11010.7 11182.6 11272.6 11411.5 11485.9 11499.7 11528.9 11537.7 7787.2 8042.6 8287.0 8507.7 8666.0 8889.1 9215.7 9305.4 9307.0 9314.4 8456.9 8550.4 8561.4 8591.9 8709.6 7151.3 7572.1 7818.9 7821.9 7931.2 7681.3 7703.5 7729.6 7794.6 7816.2 5526.0 5564.1 5572.1 5599.9 5706.9 5565.9 5648.0 5661.2 5688.9 5845.0 16:37:22 INFO - PROCESS | 18514 | 16:37:22 INFO - PROCESS | 18514 | [#4] prototype.html Cycles:180 Average:4924.31 Median:5545.70 stddev:1417.38 (25.6%) stddev-sans-first:1378.92 16:37:22 INFO - PROCESS | 18514 | Values: 337.8 349.3 354.5 361.6 363.7 5508.7 5596.0 5599.2 5630.5 5640.7 5201.1 5345.5 5349.3 5370.2 5516.5 4202.2 4203.2 4349.8 4363.0 4402.0 4335.3 4358.2 4368.4 4372.9 4373.4 4158.0 4354.8 4371.1 4382.1 4389.8 4334.9 4350.5 4363.9 4372.1 4376.8 3903.8 3933.0 3935.2 3939.2 3946.8 5243.5 5402.1 5435.7 5440.3 5461.3 5045.9 5053.9 5055.7 5057.5 5063.2 5794.2 5798.9 5812.0 5832.2 5835.2 4082.7 4257.5 4289.2 4305.9 4328.9 6011.0 6015.9 6022.7 6063.7 6072.7 4372.7 4402.0 4409.0 4415.9 4438.8 4278.4 4457.6 4502.6 4525.7 4549.2 5063.4 5072.5 5074.3 5075.6 5078.9 5868.6 5885.9 5886.9 5905.0 5908.3 1482.9 1484.5 1507.5 1558.9 1559.5 1055.3 1065.9 1086.1 1097.1 1108.1 5508.9 5542.5 5548.9 5572.7 5573.8 5929.4 5938.5 5940.3 5949.5 5956.0 5369.5 5539.6 5541.1 5553.4 5568.7 3137.9 3141.5 3155.0 3158.9 3165.6 5882.5 6055.9 6098.1 6098.3 6110.7 6042.8 6057.9 6061.0 6061.2 6062.9 6100.7 6106.5 6107.8 6108.5 6109.9 5772.0 5893.6 5899.4 5931.5 5937.9 5860.9 5860.9 5861.9 5863.1 5865.9 5916.3 6011.5 6097.8 6098.4 6104.3 4373.3 4388.2 4399.5 4411.1 4421.8 5927.3 5930.6 5932.9 5936.3 5940.4 5610.5 5708.7 5783.2 5870.3 5892.4 5837.6 5859.2 5882.0 5888.4 5893.0 5653.5 5822.6 5826.8 5831.3 5832.9 5906.2 5926.5 5951.3 5956.0 5973.5 5680.9 5838.4 5839.8 5844.0 5849.4 16:37:22 INFO - PROCESS | 18514 | 16:37:22 INFO - PROCESS | 18514 | [#5] yui.html Cycles:180 Average:23388.73 Median:3984.90 stddev:35122.90 (881.4%) stddev-sans-first:35180.31 16:37:22 INFO - PROCESS | 18514 | Values: 753.8 792.6 824.6 857.8 865.4 16051.2 16706.8 16795.1 16858.7 16912.8 15090.8 15140.6 15231.4 15268.7 15287.8 721.1 733.7 845.9 852.3 873.7 855.6 859.8 859.9 874.7 875.7 917.4 917.5 919.0 919.1 919.7 907.6 913.4 913.6 913.9 914.8 663.5 666.3 667.3 667.3 667.7 16512.7 16524.0 16541.0 16580.5 16586.7 15995.7 16537.4 16644.2 16742.5 17298.6 13585.5 13709.9 13758.4 13919.9 13927.9 4058.4 4131.8 4171.9 4191.5 4301.3 75368.6 89838.6 90084.5 90776.5 91232.0 11766.1 11944.5 12028.8 12342.7 12395.1 24764.9 24783.8 24798.8 24811.5 24827.3 9555.1 9666.6 9694.6 9831.3 10136.0 4720.8 4746.4 4824.9 4899.6 4971.5 3847.9 3967.6 4002.2 4005.6 4007.2 580.4 659.0 663.6 663.7 665.9 641.5 672.6 762.7 776.9 803.3 10366.7 10587.3 10918.8 10966.4 10976.7 584.0 627.4 690.3 720.9 737.0 4944.5 4996.8 5064.4 5110.2 5174.7 138184.0 139871.0 139962.0 142134.0 144427.0 118563.0 119075.0 120092.4 121167.4 122402.0 64066.0 65143.7 102527.5 107957.5 110761.0 9319.8 9599.9 9681.8 9828.4 9855.3 62816.7 63021.4 65034.0 65550.6 65651.7 53146.7 68879.0 70637.6 72775.3 96125.0 13728.7 14102.9 14278.9 14303.3 14430.4 13708.7 15200.6 15443.5 15569.5 17034.7 13249.9 13400.5 13442.6 14085.7 14218.0 12415.3 13351.8 13789.0 14205.1 14507.9 12882.0 14075.9 14271.1 14649.3 15117.8 11719.6 11950.8 12410.1 12528.7 12545.0 12090.2 12166.8 12204.6 12303.8 12337.4 16:37:22 INFO - PROCESS | 18514 | -------- Summary: end -------- 16:37:22 INFO - PROCESS | 18514 | 16:37:23 INFO - TEST-INFO | 18514: exit 0 16:37:23 INFO - TEST-OK | dromaeo_css | took 1113672ms 16:37:23 INFO - TEST-START | kraken 16:37:23 INFO - operating with platform_type : linux_ 16:37:23 INFO - Initialising browser for kraken test... 16:37:23 INFO - TEST-INFO | started process 18816 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmV9GZ2/profile http://localhost:54619/getInfo.html) 16:37:27 INFO - PROCESS | 18816 | __metrics Screen width/height:1600/1200 16:37:27 INFO - PROCESS | 18816 | colorDepth:24 16:37:27 INFO - PROCESS | 18816 | Browser inner width/height: 1024/697 16:37:27 INFO - PROCESS | 18816 | __metrics 16:37:28 INFO - PROCESS | 18816 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:37:28 INFO - PROCESS | 18816 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:37:28 INFO - PROCESS | 18816 | console.error: PushService: 16:37:28 INFO - PROCESS | 18816 | stateChangeProcessEnqueue: Error transitioning state 16:37:28 INFO - PROCESS | 18816 | UnknownError 16:37:29 INFO - TEST-INFO | 18816: exit 0 16:37:29 INFO - Browser initialized. 16:37:29 INFO - Running cycle 1/1 for kraken test... 16:37:29 INFO - TEST-INFO | started process 18904 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpmV9GZ2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:37:41 INFO - PROCESS | 18904 | RSS: Main: 143962112 16:37:41 INFO - PROCESS | 18904 | 16:37:43 INFO - PROCESS | 18904 | Cycle 1(1): loaded http://localhost:54619/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54619/tests/kraken/driver.html?testName=audio-beat-detection) 16:37:44 INFO - PROCESS | 18904 | RSS: Main: 187355136 16:37:44 INFO - PROCESS | 18904 | 16:37:46 INFO - PROCESS | 18904 | Cycle 1(1): loaded http://localhost:54619/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54619/tests/kraken/driver.html?testName=audio-dft) 16:37:47 INFO - PROCESS | 18904 | RSS: Main: 174682112 16:37:47 INFO - PROCESS | 18904 | 16:37:50 INFO - PROCESS | 18904 | Cycle 1(1): loaded http://localhost:54619/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54619/tests/kraken/driver.html?testName=audio-fft) 16:37:50 INFO - PROCESS | 18904 | RSS: Main: 265981952 16:37:50 INFO - PROCESS | 18904 | 16:37:52 INFO - PROCESS | 18904 | Cycle 1(1): loaded http://localhost:54619/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54619/tests/kraken/driver.html?testName=audio-oscillator) 16:37:53 INFO - PROCESS | 18904 | RSS: Main: 172703744 16:37:53 INFO - PROCESS | 18904 | 16:37:55 INFO - PROCESS | 18904 | Cycle 1(1): loaded http://localhost:54619/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54619/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:37:56 INFO - PROCESS | 18904 | RSS: Main: 173875200 16:37:56 INFO - PROCESS | 18904 | 16:37:59 INFO - PROCESS | 18904 | Cycle 1(1): loaded http://localhost:54619/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54619/tests/kraken/driver.html?testName=imaging-darkroom) 16:37:59 INFO - PROCESS | 18904 | RSS: Main: 209686528 16:37:59 INFO - PROCESS | 18904 | 16:38:03 INFO - PROCESS | 18904 | Cycle 1(1): loaded http://localhost:54619/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54619/tests/kraken/driver.html?testName=imaging-desaturate) 16:38:03 INFO - PROCESS | 18904 | RSS: Main: 208519168 16:38:03 INFO - PROCESS | 18904 | 16:38:06 INFO - PROCESS | 18904 | Cycle 1(1): loaded http://localhost:54619/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54619/tests/kraken/driver.html?testName=json-parse-financial) 16:38:06 INFO - PROCESS | 18904 | RSS: Main: 208453632 16:38:06 INFO - PROCESS | 18904 | 16:38:08 INFO - PROCESS | 18904 | Cycle 1(1): loaded http://localhost:54619/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54619/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:38:09 INFO - PROCESS | 18904 | RSS: Main: 170246144 16:38:09 INFO - PROCESS | 18904 | 16:38:10 INFO - PROCESS | 18904 | Cycle 1(1): loaded http://localhost:54619/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54619/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:38:11 INFO - PROCESS | 18904 | RSS: Main: 177971200 16:38:11 INFO - PROCESS | 18904 | 16:38:13 INFO - PROCESS | 18904 | Cycle 1(1): loaded http://localhost:54619/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54619/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:38:13 INFO - PROCESS | 18904 | RSS: Main: 187445248 16:38:13 INFO - PROCESS | 18904 | 16:38:16 INFO - PROCESS | 18904 | Cycle 1(1): loaded http://localhost:54619/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54619/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:38:17 INFO - PROCESS | 18904 | RSS: Main: 197218304 16:38:17 INFO - PROCESS | 18904 | 16:38:19 INFO - PROCESS | 18904 | Cycle 1(1): loaded http://localhost:54619/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54619/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:38:20 INFO - PROCESS | 18904 | RSS: Main: 192593920 16:38:20 INFO - PROCESS | 18904 | 16:38:21 INFO - PROCESS | 18904 | Cycle 1(1): loaded http://localhost:54619/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54619/tests/kraken/driver.html?testName=ai-astar) 16:38:22 INFO - PROCESS | 18904 | RSS: Main: 243793920 16:38:22 INFO - PROCESS | 18904 | 16:38:22 INFO - PROCESS | 18904 | __start_tp_report 16:38:22 INFO - PROCESS | 18904 | _x_x_mozilla_page_load 16:38:22 INFO - PROCESS | 18904 | _x_x_mozilla_page_load_details 16:38:22 INFO - PROCESS | 18904 | |i|pagename|runs| 16:38:22 INFO - PROCESS | 18904 | |0;ai-astar;112;112;112;112;112;113;110;111;111;111 16:38:22 INFO - PROCESS | 18904 | |1;audio-beat-detection;143;116;120;147;116;118;117;123;149;117 16:38:22 INFO - PROCESS | 18904 | |2;audio-dft;161;160;161;162;161;159;160;161;162;162 16:38:22 INFO - PROCESS | 18904 | |3;audio-fft;75;75;76;112;76;78;76;76;102;76 16:38:22 INFO - PROCESS | 18904 | |4;audio-oscillator;82;82;112;80;81;81;110;82;81;101 16:38:22 INFO - PROCESS | 18904 | |5;imaging-gaussian-blur;116;116;117;117;117;116;117;117;117;116 16:38:22 INFO - PROCESS | 18904 | |6;imaging-darkroom;146;145;146;145;146;146;146;147;148;146 16:38:22 INFO - PROCESS | 18904 | |7;imaging-desaturate;95;95;95;95;95;95;95;95;95;95 16:38:22 INFO - PROCESS | 18904 | |8;json-parse-financial;51;51;50;50;50;49;50;50;50;50 16:38:22 INFO - PROCESS | 18904 | |9;json-stringify-tinderbox;59;58;57;58;60;57;58;57;59;59 16:38:22 INFO - PROCESS | 18904 | |10;stanford-crypto-aes;77;79;73;73;74;74;73;73;74;80 16:38:22 INFO - PROCESS | 18904 | |11;stanford-crypto-ccm;133;129;121;122;120;123;122;123;160;134 16:38:22 INFO - PROCESS | 18904 | |12;stanford-crypto-pbkdf2;154;153;155;156;157;155;155;155;158;160 16:38:22 INFO - PROCESS | 18904 | |13;stanford-crypto-sha256-iterative;62;61;66;63;65;62;62;62;60;62 16:38:22 INFO - PROCESS | 18904 | __end_tp_report 16:38:22 INFO - PROCESS | 18904 | __start_cc_report 16:38:22 INFO - PROCESS | 18904 | _x_x_mozilla_cycle_collect,1595 16:38:22 INFO - PROCESS | 18904 | __end_cc_report 16:38:22 INFO - PROCESS | 18904 | __startTimestamp1484440702228__endTimestamp 16:38:22 INFO - PROCESS | 18904 | 16:38:22 INFO - PROCESS | 18904 | ------- Summary: start ------- 16:38:22 INFO - PROCESS | 18904 | Number of tests: 14 16:38:22 INFO - PROCESS | 18904 | 16:38:22 INFO - PROCESS | 18904 | [#0] ai-astar Cycles:10 Average:111.60 Median:112.00 stddev:0.84 (0.8%) stddev-sans-first:0.88 16:38:22 INFO - PROCESS | 18904 | Values: 112.0 112.0 112.0 112.0 112.0 113.0 110.0 111.0 111.0 111.0 16:38:22 INFO - PROCESS | 18904 | 16:38:22 INFO - PROCESS | 18904 | [#1] audio-beat-detection Cycles:10 Average:126.60 Median:121.50 stddev:13.85 (11.4%) stddev-sans-first:13.36 16:38:22 INFO - PROCESS | 18904 | Values: 143.0 116.0 120.0 147.0 116.0 118.0 117.0 123.0 149.0 117.0 16:38:22 INFO - PROCESS | 18904 | 16:38:22 INFO - PROCESS | 18904 | [#2] audio-dft Cycles:10 Average:160.90 Median:161.00 stddev:0.99 (0.6%) stddev-sans-first:1.05 16:38:22 INFO - PROCESS | 18904 | Values: 161.0 160.0 161.0 162.0 161.0 159.0 160.0 161.0 162.0 162.0 16:38:22 INFO - PROCESS | 18904 | 16:38:22 INFO - PROCESS | 18904 | [#3] audio-fft Cycles:10 Average:82.20 Median:76.00 stddev:13.31 (17.5%) stddev-sans-first:13.86 16:38:22 INFO - PROCESS | 18904 | Values: 75.0 75.0 76.0 112.0 76.0 78.0 76.0 76.0 102.0 76.0 16:38:22 INFO - PROCESS | 18904 | 16:38:22 INFO - PROCESS | 18904 | [#4] audio-oscillator Cycles:10 Average:89.20 Median:81.00 stddev:13.05 (16.1%) stddev-sans-first:13.58 16:38:22 INFO - PROCESS | 18904 | Values: 82.0 82.0 112.0 80.0 81.0 81.0 110.0 82.0 81.0 101.0 16:38:22 INFO - PROCESS | 18904 | 16:38:22 INFO - PROCESS | 18904 | [#5] imaging-gaussian-blur Cycles:10 Average:116.60 Median:117.00 stddev:0.52 (0.4%) stddev-sans-first:0.50 16:38:22 INFO - PROCESS | 18904 | Values: 116.0 116.0 117.0 117.0 117.0 116.0 117.0 117.0 117.0 116.0 16:38:22 INFO - PROCESS | 18904 | 16:38:22 INFO - PROCESS | 18904 | [#6] imaging-darkroom Cycles:10 Average:146.10 Median:146.00 stddev:0.88 (0.6%) stddev-sans-first:0.93 16:38:22 INFO - PROCESS | 18904 | Values: 146.0 145.0 146.0 145.0 146.0 146.0 146.0 147.0 148.0 146.0 16:38:22 INFO - PROCESS | 18904 | 16:38:22 INFO - PROCESS | 18904 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.00 (0.0%) stddev-sans-first:0.00 16:38:22 INFO - PROCESS | 18904 | Values: 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 16:38:22 INFO - PROCESS | 18904 | 16:38:22 INFO - PROCESS | 18904 | [#8] json-parse-financial Cycles:10 Average:50.10 Median:50.00 stddev:0.57 (1.1%) stddev-sans-first:0.50 16:38:22 INFO - PROCESS | 18904 | Values: 51.0 51.0 50.0 50.0 50.0 49.0 50.0 50.0 50.0 50.0 16:38:22 INFO - PROCESS | 18904 | 16:38:22 INFO - PROCESS | 18904 | [#9] json-stringify-tinderbox Cycles:10 Average:58.20 Median:58.50 stddev:1.03 (1.8%) stddev-sans-first:1.05 16:38:22 INFO - PROCESS | 18904 | Values: 59.0 58.0 57.0 58.0 60.0 57.0 58.0 57.0 59.0 59.0 16:38:22 INFO - PROCESS | 18904 | 16:38:22 INFO - PROCESS | 18904 | [#10] stanford-crypto-aes Cycles:10 Average:75.00 Median:74.00 stddev:2.67 (3.6%) stddev-sans-first:2.73 16:38:22 INFO - PROCESS | 18904 | Values: 77.0 79.0 73.0 73.0 74.0 74.0 73.0 73.0 74.0 80.0 16:38:22 INFO - PROCESS | 18904 | 16:38:22 INFO - PROCESS | 18904 | [#11] stanford-crypto-ccm Cycles:10 Average:128.70 Median:126.00 stddev:12.09 (9.6%) stddev-sans-first:12.73 16:38:22 INFO - PROCESS | 18904 | Values: 133.0 129.0 121.0 122.0 120.0 123.0 122.0 123.0 160.0 134.0 16:38:22 INFO - PROCESS | 18904 | 16:38:22 INFO - PROCESS | 18904 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.80 Median:155.50 stddev:2.04 (1.3%) stddev-sans-first:2.06 16:38:22 INFO - PROCESS | 18904 | Values: 154.0 153.0 155.0 156.0 157.0 155.0 155.0 155.0 158.0 160.0 16:38:22 INFO - PROCESS | 18904 | 16:38:22 INFO - PROCESS | 18904 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.50 Median:62.00 stddev:1.78 (2.9%) stddev-sans-first:1.88 16:38:22 INFO - PROCESS | 18904 | Values: 62.0 61.0 66.0 63.0 65.0 62.0 62.0 62.0 60.0 62.0 16:38:22 INFO - PROCESS | 18904 | -------- Summary: end -------- 16:38:22 INFO - PROCESS | 18904 | 16:38:23 INFO - TEST-INFO | 18904: exit 0 16:38:23 INFO - TEST-OK | kraken | took 59528ms 16:38:23 INFO - SUITE-END | took 1173s 16:38:23 INFO - Completed test suite (00:19:33) 16:38:23 INFO - javascript benchmark 16:38:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3143.60704911886, 3180.8568614412347, 3181.681831816817, 3204.1188673114875, 3215.9196020099494, 5577.749001294941, 5648.068068768654, 5650.26546548949, 5657.519110875575, 5671.8582035449135, 5757.395473475277, 5816.098504731774, 5819.098039803837, 5821.3305469870975, 5821.912671309923, 5196.662216955895, 5228.346456692913, 5243.711595862226, 5306.628536002481, 5312.601554883399, 5037.949620503803, 5301.655392399501, 5321.042212401766, 5322.0952438085615, 5322.733863306838, 5003.674761140533, 5127.282180494733, 5138.049460849736, 5144.717040562768, 5154.639175257734, 5191.610629202806, 5200.973995130038, 5214.426413094557, 5289.550388216968, 5305.071612467803, 5156.535911767959, 5186.170212765977, 5255.973720131375, 5278.996990571779, 5296.285001524783, 5454.10007348787, 5472.151816468454, 5497.945020549783, 5559.027170245191, 5585.525230355424, 5986.221791167164, 5991.131285963555, 5997.370276121032, 6009.369016253274, 6012.609180403259, 6043.123747056696, 6044.788432404889, 6046.697665116727, 6047.425494578008, 6059.212302400704, 5628.240187574707, 5651.576131790132, 5688.886222275572, 5743.569232307594, 5746.396628353963, 6265.373462653698, 6282.434580887651, 6286.49708023357, 6297.118403423525, 6302.991521356561, 6067.998780201306, 6070.6357618543025, 6074.817755467343, 6091.42131497507, 6096.177016102858, 5927.407259274038, 6049.18336024631, 6054.939450605526, 6109.283207920342, 6184.690765461709, 5886.264216972879, 5933.495652869555, 6080.908786368208, 6106.664133472702, 6129.172561704202, 5996.610220335664, 6009.338972712998, 6010.669413182317, 6037.758489660452, 6055.818325450244, 4596.4254468191475, 4601.079784043205, 4604.838830640979, 4608.262677971508, 4621.491635920046, 4082.0, 4092.8567500137947, 4092.9181416371507, 4097.037196258877, 4132.111596006873, 5752.252207213036, 5832.912506312408, 5834.154047663065, 5834.299884013945, 5867.677277749679, 6234.314225435199, 6241.063840423973, 6249.468795152372, 6251.3123556408755, 6253.906191407133, 5612.102063669793, 5666.0, 5689.573282003867, 5764.279465066867, 5816.1566572846705, 5159.458256883048, 5205.167173252337, 5209.088409528317, 5238.633295669267, 5248.9737551312, 6353.237611486651, 6400.135981642418, 6401.0, 6402.871942561216, 6409.134766806515, 6044.123602077586, 6086.3000754913755, 6157.32269450369, 6160.322364539987, 6163.445289923929, 6241.126242325987, 6265.373462653698, 6280.089387038763, 6385.0, 6416.326285740117, 6033.303997175823, 6082.543809214415, 6087.634741915499, 6094.695265236632, 6095.2685677718955, 6173.506119510518, 6230.7507699691505, 6234.2830574484515, 6242.313345531897, 6244.843878903063, 5852.473277405054, 5912.438318359749, 5917.615355001911, 5942.6731529766275, 5990.7304171311525, 5997.0, 5997.850053748692, 6000.279966404059, 6031.969840150771, 6072.787452439144, 5898.557608179318, 5935.258092738408, 5936.525077993664, 5938.198343223782, 5939.346671866005, 5888.499477544457, 5948.940510594839, 5967.104934259895, 5996.040633498619, 6083.330833608388, 5883.323417806836, 5992.0712289595185, 5993.67034813072, 6010.3088144863905, 6034.185384973148], "value": 5623.052598544904, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [584.9444302791236, 632.2065807411698, 660.6663634864394, 668.7525615522252, 672.3478226120661, 34805.0, 46644.53355466444, 47531.0, 47612.0477590448, 47712.28431573529, 48412.51587484133, 48843.51156488434, 48932.75533622327, 48997.51002489974, 49026.754866225616, 8573.699920502784, 9354.953225233881, 9497.335186536946, 9498.335116541844, 9518.80962380752, 8383.538905360203, 8687.826243475127, 8692.261157801595, 10865.945670271678, 11156.274842135275, 11490.425478726072, 11537.711557211052, 11557.133215008866, 11561.190716649839, 11563.595274165405, 11242.831357529603, 11299.378534180616, 11315.264507806965, 11318.60384886525, 11328.09375249982, 11178.832317515244, 11264.042556382718, 11287.604933827359, 11289.661310160625, 11295.887041129567, 44974.32538511925, 45257.773711131566, 45283.773581131885, 45376.3193552097, 45415.77292113551, 3658.2317713280245, 3691.409374500094, 3722.088088418355, 3740.657083165024, 4204.579542045771, 34547.30905381904, 34590.82704586486, 34648.480272795925, 34749.13127171816, 35032.299354013034, 4580.496145424001, 4595.264757638762, 4692.882677933045, 4721.480637129913, 4746.762661866893, 395910.0, 397284.0, 397456.0, 397736.0, 397963.0, 62413.375866241666, 62784.37215627877, 62856.37143628505, 62867.0, 62870.68564657147, 40973.1805363891, 41835.163296733896, 42068.94827629272, 43987.34018989718, 44017.11965760731, 17459.38892138769, 17562.912185439247, 17567.912160439115, 17580.208890599955, 17583.12084395775, 13922.164670119826, 14019.50931717385, 14078.366473508717, 14112.294385280695, 14125.293735313193, 18546.350877719215, 19001.809981900005, 19589.608207835765, 20835.79164208369, 20934.47663808417, 7292.380147687506, 7357.889631655529, 7506.699732010658, 7552.735654252075, 7605.0, 1043.3531210649446, 1054.1619412567027, 1085.1264731890903, 1090.6509916826699, 1125.2290289173843, 34053.48919766206, 34124.82937585296, 34404.31191376159, 34822.89806788828, 34868.302633947176, 1122.635143578324, 1184.1651635596884, 1196.6230637349206, 1199.8860108289698, 1265.2661456355293, 17822.37621683235, 18115.456536303933, 18223.81545199558, 18239.63520729578, 18240.27038918455, 589699.0, 591006.0, 591907.3237070469, 591941.0, 592226.0, 60549.39450605526, 61029.38970610326, 61070.694646526485, 61077.38922610806, 61089.694551526954, 1306.6537367597593, 1384.3908680180689, 1399.0836002418432, 1416.525463969561, 1418.6098822823806, 31424.214394640316, 34173.316533669684, 35111.47332789959, 35261.118472038404, 35482.822585886905, 51628.74185629048, 52173.478265216865, 52332.21501677402, 52339.0, 52392.0, 456187.0, 456290.0, 458582.0, 458865.0, 460204.0, 27170.0, 27936.44127117487, 27971.0, 29776.255593610334, 30111.397772044875, 49601.75199123981, 50018.0, 50086.749566251936, 50222.0, 50353.49646503635, 42070.57929420789, 42127.36808947929, 42791.572084278756, 43577.56422435735, 43712.0, 41851.0, 42390.57609423866, 42498.15003699847, 43415.0, 43958.340624890014, 42847.14305713931, 42956.0, 43745.0, 44118.55881441273, 44338.3349249768, 34172.82913585416, 37231.627683722814, 37994.620053800216, 38233.23533529258, 38613.80693096516, 29060.418791623626, 29306.51872399462, 29320.85339573374, 29680.109596712133, 29711.85144074266], "value": 21428.106753265176, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [422.2357532865512, 428.7813215260218, 438.666453979901, 441.36443521329284, 448.17312059250673, 19227.32704355346, 19456.99999999998, 20810.47973800652, 20987.160513579438, 21126.57746845062, 19345.806541934577, 19568.11943462544, 19988.30040948567, 20096.196152153934, 20269.0, 5690.089585666295, 5954.4640982311585, 5956.493698035672, 5962.25471816023, 5967.85080372992, 5708.172315014321, 5892.587518873681, 5902.2622172228475, 5902.350741418462, 5945.0, 5878.0, 5880.117982302668, 5902.793402230923, 5905.2323197984415, 5906.05503119501, 5681.801136960207, 5853.063509838448, 5869.09029100488, 5890.263717035371, 5891.909996650634, 5325.520703136736, 5334.199870019489, 5336.759845806948, 5341.1187154119525, 5343.893122137535, 23934.64098038531, 24213.273601791974, 24264.1507547235, 24359.0, 24611.876940615362, 2663.5605125154325, 2683.8121331506804, 2723.8774255158564, 2887.2493151780477, 3034.317278612317, 14161.22113283769, 14953.327100280405, 14969.476068337555, 15126.470573529874, 15300.005499642488, 2041.7141600175987, 2094.5706130243416, 2134.1783413385956, 2138.9572208555896, 2242.394553470554, 62284.903845200395, 71230.0, 72230.93599208811, 79101.0, 79101.0, 11733.823992640117, 11812.822807657893, 11889.643310700692, 12025.4588543514, 12131.454084566216, 17075.40236091731, 17080.829191707922, 17085.145742712815, 17088.743668844978, 17089.743653845202, 8219.712310069239, 8396.874046889301, 8434.536100514408, 8505.31957443403, 8571.614277357534, 7744.883826742604, 7900.9604951976025, 7926.8810967835525, 7939.523628582304, 8158.918410815934, 5101.540861322499, 5113.15632920564, 5177.275181474596, 5223.634345595772, 5273.367195936436, 2014.8488863335308, 2119.1417475922276, 2169.7995456701237, 2198.1097655449566, 2218.7892150245702, 802.3460879383284, 958.5782255807422, 981.1415011864618, 1048.9580416783397, 1084.967450976472, 20440.28459003928, 20538.0, 20546.28088016912, 20565.38303850887, 20632.17471301131, 1022.2792930983638, 1039.7556574205055, 1077.5097330714507, 1080.6487891435315, 1086.125668836594, 10894.18293627981, 11452.3701196435, 11540.884591153981, 11549.826752598718, 11637.709057273636, 507204.0, 527228.0, 528881.0, 546551.0, 553993.0, 103085.0, 105125.0, 105321.0, 105332.47333763282, 108441.45779271211, 994.7562847102362, 1005.8340373838381, 1031.7008067660445, 1044.237706474278, 1044.8014877173314, 17805.46583602494, 18246.178921948278, 18565.72151417703, 18609.627807444045, 18791.436256912315, 37725.81137094297, 47680.28479572879, 47965.280520791515, 48136.27795583139, 49294.26058609196, 65599.0, 66174.66912665406, 66225.0, 66337.33662663311, 66753.99869002066, 8254.587270636564, 8670.349723770616, 8745.344099192458, 8837.734867953972, 9014.639414423349, 13127.93436032852, 13335.333233338488, 13474.595762127152, 13512.121712088683, 13540.255285959367, 11755.706107347385, 11924.22492538017, 12028.27830330183, 12322.630321090382, 12538.749225015266, 11348.319100853974, 11641.301521908712, 11687.532498699957, 11889.643310700692, 12014.879851201376, 12000.219985701251, 12013.63959081229, 12097.395130243276, 12151.175565809697, 12403.93798031004, 7997.880031799644, 8178.672853086048, 8434.65954393226, 8748.60631271607, 8839.0, 9147.542622868963, 11586.188966772244, 11912.940435297767, 12012.33932133706, 12082.879171208528], "value": 9751.088895358344, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1540.6610545679946, 1564.4915402494191, 1715.5796829776707, 1853.5644123630952, 1867.69183084791, 9208.171264586186, 9972.0, 9972.40165590065, 9974.650887218957, 9986.50067496625, 8547.829043419128, 8783.429077109984, 9299.30255230857, 9635.80728385432, 9770.853437198448, 2570.935726606831, 2581.2643396631966, 2598.4803039392104, 2603.2190342897056, 2608.334874606973, 2532.050481069599, 2584.8578328191943, 2585.61215817627, 2586.831855929368, 2590.5596048671773, 2563.6539067225967, 2566.153169454076, 2589.3008887600336, 2591.6501272328182, 2596.2730435478, 2497.1759319424546, 2505.5114252720728, 2516.3457501049675, 2538.4288535079645, 2549.4008907906627, 2467.9431317381564, 2483.4412257242157, 2491.676082109332, 2510.1716433576876, 2515.019142534413, 9452.054794520562, 9624.95187524065, 9625.951870240675, 9628.47043412612, 9630.807383852283, 5623.128415095667, 5749.195112684228, 5782.710864456761, 5784.1902133701315, 5793.449622285876, 3547.8935631931085, 3609.2240168363664, 3682.171511409938, 3701.9259614807825, 3725.6833169180654, 1114.749181434179, 1183.727492945079, 1186.1163433242205, 1192.165484161082, 1195.0260537661866, 13812.447502099903, 13829.654258643614, 13829.861701382957, 13842.446302147902, 13842.792358114637, 5104.464031276737, 5112.795488180505, 5197.58419326453, 5264.0261551613075, 5268.947310526845, 11590.246633968765, 11598.420078996016, 11600.129990250765, 11601.651950441501, 11611.825822612498, 5888.852778680567, 5890.4698577128265, 5893.911591326134, 5894.32215295238, 5895.469407753323, 4766.880827979259, 5081.695098294114, 5276.736163191825, 5324.547413469821, 5419.241306217133, 4371.191329604034, 4412.294032954712, 4461.375407442961, 4500.369948207188, 4513.458384993822, 1416.0795482936173, 1443.1557538839895, 1451.535508637226, 1453.7019910918236, 1464.9707005860037, 1005.416858222244, 1020.7805321855836, 1023.2376879225025, 1025.2925481417947, 1033.7777377863795, 6402.583832050902, 6454.354564543508, 6588.8023359299295, 6592.0, 6791.151106111736, 477.25548144894077, 480.5026797264814, 484.12373603777274, 491.02286449965027, 508.13109582614146, 4315.374270730789, 4486.214912390318, 4486.484054333793, 4496.797644106023, 4538.1377538267625, 11643.301401915913, 12145.939270303592, 12147.5140994361, 12153.149279550524, 12178.57374991871, 10734.355938643706, 11094.778104438028, 12023.759524809455, 12110.39448027595, 12111.152219344738, 8854.0, 8997.055309192572, 9032.503212323385, 9050.728478145535, 9050.86423703645, 6173.228346456693, 6372.458341040878, 6493.253275873335, 6574.474042076718, 6603.339666033358, 10725.141988640735, 10971.396573188551, 11010.669679909615, 11182.608608698656, 11272.605458808903, 11411.486483108447, 11485.885141148481, 11499.712507187387, 11528.884711153116, 11537.653870383901, 7787.182345853604, 8042.557659328793, 8286.958565207377, 8507.659693612186, 8665.956670216608, 8889.111088891059, 9215.677451289173, 9305.395149315273, 9306.953465232631, 9314.394564353566, 8456.915430845613, 8550.444221125606, 8561.44350617208, 8591.871121933302, 8709.608067636846, 7151.2848715128075, 7572.091349038151, 7818.882716759367, 7821.882671760042, 7931.246531579491, 7681.308682218403, 7703.460757746904, 7729.613519323899, 7794.649240784065, 7816.218378162138, 5526.0, 5564.109742441352, 5572.080606699849, 5599.916001259903, 5706.8573285666525, 5565.944340556543, 5648.011597970421, 5661.207430959586, 5688.943110568841, 5844.970775146267], "value": 4973.406815187988, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.7973216070358, 349.2804822066543, 354.50723494342867, 361.5661206552138, 363.7090327737809, 5508.732991411977, 5595.972020139904, 5599.160125981105, 5630.521405680517, 5640.717964101788, 5201.087391061009, 5345.545628621562, 5349.331333583302, 5370.194470829368, 5516.531094856942, 4202.222588821062, 4203.201391735576, 4349.825087456272, 4362.956370436303, 4402.028630674282, 4335.263005289108, 4358.171947330013, 4368.410264614268, 4372.868813935587, 4373.431453911003, 4158.022864626811, 4354.782260886943, 4371.060222052258, 4382.0797632497215, 4389.758563279019, 4334.869953901388, 4350.521442641307, 4363.934540981872, 4372.147431250907, 4376.759278239696, 3903.8048097595292, 3933.0, 3935.1932853765197, 3939.231849789292, 3946.782926939017, 5243.475652434764, 5402.055879545618, 5435.673859568438, 5440.265564148829, 5461.290032295816, 5045.949540504585, 5053.9494605054215, 5055.721935293557, 5057.49425057495, 5063.240513922904, 5794.159846822187, 5798.88402231953, 5811.970940145272, 5832.154337621021, 5835.153902684087, 4082.6733861291027, 4257.510386305553, 4289.155663064881, 4305.913881722379, 4328.91342173158, 6010.9699451502465, 6015.909761353583, 6022.69886505673, 6063.727132279058, 6072.726727297282, 4372.715773474714, 4402.0095478517715, 4408.955910440919, 4415.911681766347, 4438.844640437634, 4278.379634952915, 4457.621102206349, 4502.617277531446, 4525.705829121097, 4549.249373853343, 5063.443021267658, 5072.543471087546, 5074.314967479417, 5075.644704870698, 5078.873028174326, 5868.589198756132, 5885.852853678607, 5886.8822623547285, 5905.0, 5908.320543137508, 1482.9406823726931, 1484.502691598305, 1507.5376884422094, 1558.9142597157038, 1559.5165498695442, 1055.335138862527, 1065.8561094252177, 1086.1365214654368, 1097.1113398147527, 1108.1245815805423, 5508.862278442991, 5542.4734650208975, 5548.91676624851, 5572.749226284827, 5573.777048918079, 5929.37741537141, 5938.465538101592, 5940.257467816523, 5949.494292985059, 5955.970220148872, 5369.48989845964, 5539.559714474185, 5541.141123125922, 5553.389127196006, 5568.693721845338, 3137.8587963541695, 3141.513065474855, 3154.9842250788597, 3158.8894388696285, 3165.572647692578, 5882.470577648032, 6055.878882422327, 6098.054801505774, 6098.268207815002, 6110.725017374229, 6042.848928776816, 6057.939420605826, 6060.969695151584, 6061.151438798572, 6062.939370606237, 6100.69496525172, 6106.5114790817515, 6107.755689772448, 6108.51131909455, 6109.938900611026, 5771.97114014444, 5893.616914900518, 5899.439553242435, 5931.466168044896, 5937.910931335947, 5860.853478663067, 5860.882782344244, 5861.853453663693, 5863.120531920246, 5865.94134058654, 5916.290045194604, 6011.519078473624, 6097.780443911324, 6098.390160983866, 6104.26748790148, 4373.322135069069, 4388.210122178039, 4399.516053234078, 4411.139827733561, 4421.8282155229535, 5927.318358388668, 5930.644161350333, 5932.851678707894, 5936.28764548257, 5940.376260492586, 5610.49505544503, 5708.686022268815, 5783.161441590862, 5870.26621672291, 5892.438503796583, 5837.6497410155525, 5859.179714840011, 5881.9705901470215, 5888.352281248974, 5893.0, 5653.491185793298, 5822.621529600562, 5826.82519524415, 5831.271091113611, 5832.941670583409, 5906.202662640489, 5926.525877929669, 5951.28584569837, 5955.970220148872, 5973.522118230444, 5680.886382272414, 5838.386969368155, 5839.766409343579, 5843.970780146242, 5849.3858144894175], "value": 4478.492065367781, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [753.8416932444186, 792.6393490961613, 824.562981619741, 857.7641148684115, 865.4374656473292, 16051.197440127991, 16706.83293167068, 16795.07627080513, 16858.662826743457, 16912.83087169128, 15090.849091509082, 15140.621484462894, 15231.390744370212, 15268.694626107472, 15287.847121528781, 721.1454426504582, 733.6881825224285, 845.8731190321439, 852.3053711225347, 873.6723728601775, 855.5764896376303, 859.7850537365658, 859.8882145321099, 874.6588830356167, 875.7460336502406, 917.3578495053489, 917.4632839788732, 919.0487845080344, 919.0533750237266, 919.7102912582515, 907.6324088744069, 913.4336711239041, 913.5569248914271, 913.9223166030895, 914.8216097860919, 663.4791688524533, 666.3303380103014, 667.2560095493538, 667.3160010988727, 667.7495939022866, 16512.66974660512, 16524.0, 16541.0, 16580.50258492235, 16586.6682666346, 15995.720342372699, 16537.421190258283, 16644.167791610493, 16742.497725068268, 17298.56753581158, 13585.52450664232, 13709.862901370958, 13758.449662013507, 13919.930400347934, 13927.930360348235, 4058.391241313797, 4131.757144496718, 4171.937420938689, 4191.476065491814, 4301.3117901135665, 75368.62315688387, 89838.5508072462, 90084.54957725234, 90776.54611726965, 91232.0, 11766.117541184274, 11944.462499187557, 12028.819567706492, 12342.691432714075, 12395.070369722127, 24764.876175619007, 24783.752162478504, 24798.752012480007, 24811.5037699245, 24827.255182344557, 9555.092266234697, 9666.635206223275, 9694.6122155113, 9831.31180817336, 10136.0, 4720.834770783006, 4746.359241502422, 4824.879378015507, 4899.559039686445, 4971.477994810492, 3847.9038024049623, 3967.6429121379215, 4002.1595464952684, 4005.5794141615293, 4007.178528401729, 580.3616022375353, 658.9604623722591, 663.575311800448, 663.6781161136845, 665.9200895892524, 641.5445034025801, 672.6132473827569, 762.7330434347933, 776.8679324514846, 803.3292201012104, 10366.740831479123, 10587.311824731521, 10918.836217456745, 10966.396848173268, 10976.670699879016, 583.988320233593, 627.3945642455053, 690.3476214976899, 720.8666396716624, 736.9535259930911, 4944.456109827916, 4996.7501624918605, 5064.366954130734, 5110.182370820651, 5174.689518628894, 138184.0, 139871.0, 139962.0, 142134.0, 144427.0, 118563.0, 119075.0, 120092.3995380035, 121167.39416302861, 122402.0, 64066.0, 65143.674281628286, 102527.48736256271, 107957.46021269844, 110761.0, 9319.767005824908, 9599.904000960181, 9681.757956051155, 9828.361156524803, 9855.260855436014, 62816.685916570124, 63021.36978630338, 65034.0, 65550.58564500429, 65651.67174164098, 53146.73426632842, 68879.0, 70637.64681176562, 72775.27224727685, 96125.0, 13728.65678358009, 14102.858971410566, 14278.857211427752, 14303.284835758379, 14430.350634221695, 13708.725825483634, 15200.619984500476, 15443.536693899201, 15569.532914012598, 17034.659306814043, 13249.867501324863, 13400.530981415604, 13442.596722097962, 14085.718285634024, 14218.0, 12415.317157556421, 13351.799723004357, 13789.0, 14205.147691138565, 14507.854921450651, 12882.0, 14075.929620351833, 14271.143731375736, 14649.340779664728, 15117.773233401287, 11719.589814356457, 11950.820737688768, 12410.131290809557, 12528.74942501163, 12545.0, 12090.214136081127, 12166.817497737364, 12204.572839950559, 12303.75392492163, 12337.444814983168], "value": 8156.386918333105, "unit": "score"}], "name": "dromaeo_css", "value": 7730.766049189685, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 112.0, 112.0, 112.0, 112.0, 113.0, 110.0, 111.0, 111.0, 111.0], "value": 111.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 116.0, 120.0, 147.0, 116.0, 118.0, 117.0, 123.0, 149.0, 117.0], "value": 126.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 160.0, 161.0, 162.0, 161.0, 159.0, 160.0, 161.0, 162.0, 162.0], "value": 160.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 76.0, 112.0, 76.0, 78.0, 76.0, 76.0, 102.0, 76.0], "value": 82.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 112.0, 80.0, 81.0, 81.0, 110.0, 82.0, 81.0, 101.0], "value": 89.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 117.0, 117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0], "value": 116.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 145.0, 146.0, 145.0, 146.0, 146.0, 146.0, 147.0, 148.0, 146.0], "value": 146.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 51.0, 50.0, 50.0, 50.0, 49.0, 50.0, 50.0, 50.0, 50.0], "value": 50.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 58.0, 57.0, 58.0, 60.0, 57.0, 58.0, 57.0, 59.0, 59.0], "value": 58.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 79.0, 73.0, 73.0, 74.0, 74.0, 73.0, 73.0, 74.0, 80.0], "value": 75.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 129.0, 121.0, 122.0, 120.0, 123.0, 122.0, 123.0, 160.0, 134.0], "value": 128.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 153.0, 155.0, 156.0, 157.0, 155.0, 155.0, 155.0, 158.0, 160.0], "value": 155.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 61.0, 66.0, 63.0, 65.0, 62.0, 62.0, 62.0, 60.0, 62.0], "value": 62.5, "unit": "score"}], "name": "kraken", "value": 1458.5, "alertThreshold": 2.0}]} 16:38:23 INFO - Return code: 0 16:38:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:38:23 INFO - # TBPL SUCCESS # 16:38:23 INFO - Running post-action listener: _resource_record_post_action 16:38:23 INFO - [mozharness: 2017-01-15 00:38:23.558272Z] Finished run-tests step (success) 16:38:23 INFO - Running post-run listener: _resource_record_post_run 16:38:23 INFO - Total resource usage - Wall time: 1187s; CPU: 13.0%; Read bytes: 1871872; Write bytes: 331489280; Read time: 572; Write time: 222340 16:38:23 INFO - TinderboxPrint: CPU usage
12.9% 16:38:23 INFO - TinderboxPrint: I/O read bytes / time
1,871,872 / 572 16:38:23 INFO - TinderboxPrint: I/O write bytes / time
331,489,280 / 222,340 16:38:23 INFO - TinderboxPrint: CPU idle
8,251.8 (87.1%) 16:38:23 INFO - TinderboxPrint: CPU user
1,181.4 (12.5%) 16:38:23 INFO - TinderboxPrint: Swap in / out
0 / 0 16:38:23 INFO - install - Wall time: 14s; CPU: 13.0%; Read bytes: 0; Write bytes: 111960064; Read time: 0; Write time: 81036 16:38:23 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4096; Write bytes: 219529216; Read time: 8; Write time: 141304 16:38:24 INFO - Running post-run listener: _upload_blobber_files 16:38:24 INFO - Blob upload gear active. 16:38:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:38:24 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:38:24 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'] 16:38:24 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 16:38:24 INFO - (blobuploader) - INFO - Open directory for files ... 16:38:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:38:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:38:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:38:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:38:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:38:25 INFO - (blobuploader) - INFO - Done attempting. 16:38:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:38:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:38:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:38:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:38:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:38:29 INFO - (blobuploader) - INFO - Done attempting. 16:38:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:38:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:38:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:38:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:38:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:38:29 INFO - (blobuploader) - INFO - Done attempting. 16:38:29 INFO - (blobuploader) - INFO - Iteration through files over. 16:38:29 INFO - Return code: 0 16:38:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:38:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:38:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ea0251e761f39b353724b69966c3e9824faea6f829ddce5c18748b695ad7ce944cfc9eda17e560ba30999275b59710bf4090634399f8df829700870ebabc6da0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a2914666e4f1cbd3b38851d06718851563feb2547bb9dc29c515b1a0e9e5d21331c226444baf14573e6816c810907d4de2fcfe5c9b87b7805678496ec6350b52", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bc576d7aa97341ca780aa90c7c7c8ade99a6ce260937fa2c81b5a93118d4286c71a21e01ffabc65ca553fc98b4427a8c41646adce17d3388c58a059c24d9100d"} 16:38:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:38:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:38:29 INFO - Contents: 16:38:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ea0251e761f39b353724b69966c3e9824faea6f829ddce5c18748b695ad7ce944cfc9eda17e560ba30999275b59710bf4090634399f8df829700870ebabc6da0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a2914666e4f1cbd3b38851d06718851563feb2547bb9dc29c515b1a0e9e5d21331c226444baf14573e6816c810907d4de2fcfe5c9b87b7805678496ec6350b52", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bc576d7aa97341ca780aa90c7c7c8ade99a6ce260937fa2c81b5a93118d4286c71a21e01ffabc65ca553fc98b4427a8c41646adce17d3388c58a059c24d9100d"} 16:38:29 INFO - Running post-run listener: copy_logs_to_upload_dir 16:38:29 INFO - Copying logs to upload dir... 16:38:29 INFO - mkdir: /builds/slave/test/build/upload/logs 16:38:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1231.110616 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 31 secs) (at 2017-01-14 16:38:29.440375) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-14 16:38:29.443569) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1484438666.387947-1951917740 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/ea0251e761f39b353724b69966c3e9824faea6f829ddce5c18748b695ad7ce944cfc9eda17e560ba30999275b59710bf4090634399f8df829700870ebabc6da0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a2914666e4f1cbd3b38851d06718851563feb2547bb9dc29c515b1a0e9e5d21331c226444baf14573e6816c810907d4de2fcfe5c9b87b7805678496ec6350b52", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bc576d7aa97341ca780aa90c7c7c8ade99a6ce260937fa2c81b5a93118d4286c71a21e01ffabc65ca553fc98b4427a8c41646adce17d3388c58a059c24d9100d"} build_url:https://queue.taskcluster.net/v1/task/bYWqahrFTeOq8tw86MlhGw/artifacts/public/build/firefox-51.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011332 build_url: 'https://queue.taskcluster.net/v1/task/bYWqahrFTeOq8tw86MlhGw/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/ea0251e761f39b353724b69966c3e9824faea6f829ddce5c18748b695ad7ce944cfc9eda17e560ba30999275b59710bf4090634399f8df829700870ebabc6da0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a2914666e4f1cbd3b38851d06718851563feb2547bb9dc29c515b1a0e9e5d21331c226444baf14573e6816c810907d4de2fcfe5c9b87b7805678496ec6350b52", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/bc576d7aa97341ca780aa90c7c7c8ade99a6ce260937fa2c81b5a93118d4286c71a21e01ffabc65ca553fc98b4427a8c41646adce17d3388c58a059c24d9100d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-14 16:38:29.471535) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-14 16:38:29.472214) ========= 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=9c3a94c35cc88655b05d2c08000001b2-1484438666.387947-1951917740 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004432 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-14 16:38:29.557081) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-14 16:38:29.557813) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-14 16:38:29.558483) ========= ========= Total master_lag: 0.21 =========