builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0199 starttime: 1482426405.37 results: success (0) buildid: 20161222071954 builduid: 95c3c354216a4947abb48dea7e0fd854 revision: 8460203bc93b9667cea1bc00f9d9990a4b1a9474 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-22 09:06:45.365676) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-22 09:06:45.366135) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-22 09:06:45.366454) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-22 09:06:45.394394) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 09:06:45.394753) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.q1ynxt1tYC/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.eJE0hg8kKH/Listeners TMPDIR=/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-22 09:06:45-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 40.9M=0s 2016-12-22 09:06:45 (40.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.110181 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 09:06:45.528277) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 09:06:45.528618) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.q1ynxt1tYC/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.eJE0hg8kKH/Listeners TMPDIR=/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.021618 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 09:06:45.576836) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-22 09:06:45.580197) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 8460203bc93b9667cea1bc00f9d9990a4b1a9474 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 8460203bc93b9667cea1bc00f9d9990a4b1a9474 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.q1ynxt1tYC/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.eJE0hg8kKH/Listeners TMPDIR=/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-22 09:06:45,797 truncating revision to first 12 chars 2016-12-22 09:06:45,797 Setting DEBUG logging. 2016-12-22 09:06:45,797 attempt 1/10 2016-12-22 09:06:45,797 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/8460203bc93b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-22 09:06:46,753 unpacking tar archive at: mozilla-central-8460203bc93b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.211008 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-22 09:06:46.815800) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-22 09:06:46.819252) ========= script_repo_revision: 8460203bc93b9667cea1bc00f9d9990a4b1a9474 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-22 09:06:46.819793) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-22 09:06:46.820057) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-22 09:06:46.846259) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-12-22 09:06:46.846678) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.q1ynxt1tYC/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.eJE0hg8kKH/Listeners TMPDIR=/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:06:47 INFO - MultiFileLogger online at 20161222 09:06:47 in /builds/slave/test 09:06:47 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:06:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:06:47 INFO - {'append_to_log': False, 09:06:47 INFO - 'base_work_dir': '/builds/slave/test', 09:06:47 INFO - 'blob_upload_branch': 'Firefox', 09:06:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:06:47 INFO - 'branch': 'Firefox', 09:06:47 INFO - 'buildbot_json_path': 'buildprops.json', 09:06:47 INFO - 'config_files': ('talos/mac_config.py',), 09:06:47 INFO - 'default_actions': ('clobber', 09:06:47 INFO - 'read-buildbot-config', 09:06:47 INFO - 'download-and-extract', 09:06:47 INFO - 'populate-webroot', 09:06:47 INFO - 'create-virtualenv', 09:06:47 INFO - 'install', 09:06:47 INFO - 'run-tests'), 09:06:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:06:47 INFO - 'download_minidump_stackwalk': True, 09:06:47 INFO - 'download_symbols': 'ondemand', 09:06:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:06:47 INFO - 'tooltool.py': '/tools/tooltool.py', 09:06:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:06:47 INFO - '/tools/misc-python/virtualenv.py')}, 09:06:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:06:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:06:47 INFO - 'installer_path': 'installer.exe', 09:06:47 INFO - 'log_level': 'info', 09:06:47 INFO - 'log_name': 'talos', 09:06:47 INFO - 'log_to_console': True, 09:06:47 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:06:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:06:47 INFO - 'opt_config_files': (), 09:06:47 INFO - 'pip_index': False, 09:06:47 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:06:47 INFO - 'cmd': ('bash', 09:06:47 INFO - '-c', 09:06:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:06:47 INFO - 'enabled': True, 09:06:47 INFO - 'halt_on_failure': False, 09:06:47 INFO - 'name': 'check_screen_resolution'},), 09:06:47 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:06:47 INFO - 'cmd': ('bash', 09:06:47 INFO - '-c', 09:06:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:06:47 INFO - 'enabled': True, 09:06:47 INFO - 'halt_on_failure': False, 09:06:47 INFO - 'name': 'check_screen_resolution'},), 09:06:47 INFO - 'run_cmd_checks_enabled': True, 09:06:47 INFO - 'sps_profile': False, 09:06:47 INFO - 'sps_profile_interval': 0, 09:06:47 INFO - 'suite': 'dromaeojs', 09:06:47 INFO - 'system_bits': '32', 09:06:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:06:47 INFO - 'title': 't-yosemite-r7-0199', 09:06:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:06:47 INFO - 'use_talos_json': True, 09:06:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:06:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:06:47 INFO - 'work_dir': 'build'} 09:06:47 INFO - [mozharness: 2016-12-22 17:06:47.193920Z] Running clobber step. 09:06:47 INFO - Running pre-action listener: _resource_record_pre_action 09:06:47 INFO - Running main action method: clobber 09:06:47 INFO - rmtree: /builds/slave/test/build 09:06:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:06:48 INFO - Running post-action listener: _resource_record_post_action 09:06:48 INFO - [mozharness: 2016-12-22 17:06:48.300372Z] Finished clobber step (success) 09:06:48 INFO - [mozharness: 2016-12-22 17:06:48.300607Z] Running read-buildbot-config step. 09:06:48 INFO - Running pre-action listener: _resource_record_pre_action 09:06:48 INFO - Running main action method: read_buildbot_config 09:06:48 INFO - Using buildbot properties: 09:06:48 INFO - { 09:06:48 INFO - "project": "", 09:06:48 INFO - "product": "firefox", 09:06:48 INFO - "script_repo_revision": "production", 09:06:48 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 09:06:48 INFO - "repository": "", 09:06:48 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 09:06:48 INFO - "buildid": "20161222071954", 09:06:48 INFO - "pgo_build": "False", 09:06:48 INFO - "basedir": "/builds/slave/test", 09:06:48 INFO - "buildnumber": 41, 09:06:48 INFO - "slavename": "t-yosemite-r7-0199", 09:06:48 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 09:06:48 INFO - "platform": "yosemite_r7", 09:06:48 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:06:48 INFO - "branch": "mozilla-central", 09:06:48 INFO - "repo_path": "mozilla-central", 09:06:48 INFO - "revision": "8460203bc93b9667cea1bc00f9d9990a4b1a9474", 09:06:48 INFO - "stage_platform": "macosx64", 09:06:48 INFO - "builduid": "95c3c354216a4947abb48dea7e0fd854", 09:06:48 INFO - "slavebuilddir": "test" 09:06:48 INFO - } 09:06:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/fjDzv0IJRIGptMan_rhM_A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 09:06:48 INFO - Running post-action listener: _resource_record_post_action 09:06:48 INFO - [mozharness: 2016-12-22 17:06:48.305150Z] Finished read-buildbot-config step (success) 09:06:48 INFO - [mozharness: 2016-12-22 17:06:48.305280Z] Running download-and-extract step. 09:06:48 INFO - Running pre-action listener: _resource_record_pre_action 09:06:48 INFO - Running main action method: download_and_extract 09:06:48 INFO - mkdir: /builds/slave/test/build/tests 09:06:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:48 INFO - trying https://queue.taskcluster.net/v1/task/fjDzv0IJRIGptMan_rhM_A/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 09:06:48 INFO - Downloading https://queue.taskcluster.net/v1/task/fjDzv0IJRIGptMan_rhM_A/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 09:06:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fjDzv0IJRIGptMan_rhM_A/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 09:06:49 INFO - Downloaded 1199 bytes. 09:06:49 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 09:06:49 INFO - Using the following test package requirements: 09:06:49 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 09:06:49 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:06:49 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 09:06:49 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:06:49 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 09:06:49 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:06:49 INFO - u'jsshell-mac.zip'], 09:06:49 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:06:49 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 09:06:49 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 09:06:49 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:06:49 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 09:06:49 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:06:49 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 09:06:49 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:06:49 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 09:06:49 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 09:06:49 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 09:06:49 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:06:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fjDzv0IJRIGptMan_rhM_A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 09:06:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fjDzv0IJRIGptMan_rhM_A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:06:49 INFO - Fetch https://queue.taskcluster.net/v1/task/fjDzv0IJRIGptMan_rhM_A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 09:06:52 INFO - Expected file size: 16289327 09:06:52 INFO - Obtained file size: 16289327 09:06:59 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:06:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fjDzv0IJRIGptMan_rhM_A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 09:06:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fjDzv0IJRIGptMan_rhM_A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:06:59 INFO - Fetch https://queue.taskcluster.net/v1/task/fjDzv0IJRIGptMan_rhM_A/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 09:07:00 INFO - Expected file size: 16289327 09:07:00 INFO - Obtained file size: 16289327 09:07:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fjDzv0IJRIGptMan_rhM_A/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 09:07:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fjDzv0IJRIGptMan_rhM_A/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:07:06 INFO - Fetch https://queue.taskcluster.net/v1/task/fjDzv0IJRIGptMan_rhM_A/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 09:07:08 INFO - Expected file size: 14409776 09:07:08 INFO - Obtained file size: 14409776 09:07:09 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')]} 09:07:09 INFO - trying https://queue.taskcluster.net/v1/task/fjDzv0IJRIGptMan_rhM_A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 09:07:09 INFO - Downloading https://queue.taskcluster.net/v1/task/fjDzv0IJRIGptMan_rhM_A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 09:07:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fjDzv0IJRIGptMan_rhM_A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 09:07:13 INFO - Downloaded 69682195 bytes. 09:07:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fjDzv0IJRIGptMan_rhM_A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 09:07:13 INFO - mkdir: /builds/slave/test/properties 09:07:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:07:13 INFO - Writing to file /builds/slave/test/properties/build_url 09:07:13 INFO - Contents: 09:07:13 INFO - build_url:https://queue.taskcluster.net/v1/task/fjDzv0IJRIGptMan_rhM_A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 09:07:15 INFO - Running post-action listener: _resource_record_post_action 09:07:15 INFO - Running post-action listener: set_extra_try_arguments 09:07:15 INFO - [mozharness: 2016-12-22 17:07:15.014203Z] Finished download-and-extract step (success) 09:07:15 INFO - [mozharness: 2016-12-22 17:07:15.014348Z] Running populate-webroot step. 09:07:15 INFO - Running pre-action listener: _resource_record_pre_action 09:07:15 INFO - Running main action method: populate_webroot 09:07:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:07:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:07:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:07:15 INFO - '--tppagecycles', 09:07:15 INFO - '7'], 09:07:15 INFO - 'tests': ['tsvgm']}}, 09:07:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:07:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:07:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:07:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:07:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:07:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:07:15 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 09:07:15 INFO - 'talos_options': ['--disable-e10s'], 09:07:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:07:15 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 09:07:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:07:15 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 09:07:15 INFO - 'talos_options': ['--disable-e10s'], 09:07:15 INFO - 'tests': ['damp', 'tps']}, 09:07:15 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 09:07:15 INFO - 'tests': ['damp', 'tps']}, 09:07:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:07:15 INFO - 'tests': ['dromaeo_dom']}, 09:07:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:07:15 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:07:15 INFO - 'tests': ['basic_compositor_video']}, 09:07:15 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:07:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:07:15 INFO - 'tests': ['a11yr', 09:07:15 INFO - 'ts_paint', 09:07:15 INFO - 'tpaint', 09:07:15 INFO - 'sessionrestore', 09:07:15 INFO - 'sessionrestore_no_auto_restore', 09:07:15 INFO - 'tabpaint']}, 09:07:15 INFO - 'other-e10s': {'tests': ['a11yr', 09:07:15 INFO - 'ts_paint', 09:07:15 INFO - 'tpaint', 09:07:15 INFO - 'sessionrestore', 09:07:15 INFO - 'sessionrestore_no_auto_restore', 09:07:15 INFO - 'tabpaint']}, 09:07:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:07:15 INFO - 'ts_paint', 09:07:15 INFO - 'tpaint', 09:07:15 INFO - 'sessionrestore', 09:07:15 INFO - 'sessionrestore_no_auto_restore', 09:07:15 INFO - 'tabpaint']}, 09:07:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:07:15 INFO - 'ts_paint', 09:07:15 INFO - 'tpaint', 09:07:15 INFO - 'sessionrestore', 09:07:15 INFO - 'sessionrestore_no_auto_restore', 09:07:15 INFO - 'tabpaint']}, 09:07:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:07:15 INFO - 'tests': ['a11yr', 09:07:15 INFO - 'ts_paint', 09:07:15 INFO - 'tpaint', 09:07:15 INFO - 'sessionrestore', 09:07:15 INFO - 'sessionrestore_no_auto_restore', 09:07:15 INFO - 'tabpaint']}, 09:07:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:07:15 INFO - 'tests': ['a11yr', 09:07:15 INFO - 'ts_paint', 09:07:15 INFO - 'tpaint', 09:07:15 INFO - 'sessionrestore', 09:07:15 INFO - 'sessionrestore_no_auto_restore', 09:07:15 INFO - 'tabpaint']}, 09:07:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:07:15 INFO - 'tests': ['tsvgx', 09:07:15 INFO - 'tsvgr_opacity', 09:07:15 INFO - 'tart', 09:07:15 INFO - 'tscrollx', 09:07:15 INFO - 'cart', 09:07:15 INFO - 'tsvg_static']}, 09:07:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:07:15 INFO - 'tsvgr_opacity', 09:07:15 INFO - 'tart', 09:07:15 INFO - 'tscrollx', 09:07:15 INFO - 'cart', 09:07:15 INFO - 'tsvg_static']}, 09:07:15 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 09:07:15 INFO - 'talos_options': ['--disable-e10s'], 09:07:15 INFO - 'tests': ['tp5o']}, 09:07:15 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 09:07:15 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 09:07:15 INFO - 'talos_options': ['--disable-e10s', 09:07:15 INFO - '--xperf_path', 09:07:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:07:15 INFO - 'tests': ['tp5n']}, 09:07:15 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 09:07:15 INFO - 'talos_options': ['--xperf_path', 09:07:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:07:15 INFO - 'tests': ['tp5n']}}, 09:07:15 INFO - 'talos.zip': {'path': '', 09:07:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:07:15 INFO - Running post-action listener: _resource_record_post_action 09:07:15 INFO - [mozharness: 2016-12-22 17:07:15.032815Z] Finished populate-webroot step (success) 09:07:15 INFO - [mozharness: 2016-12-22 17:07:15.032948Z] Running create-virtualenv step. 09:07:15 INFO - Running pre-action listener: _resource_record_pre_action 09:07:15 INFO - Running main action method: create_virtualenv 09:07:15 INFO - Creating virtualenv /builds/slave/test/build/venv 09:07:15 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 09:07:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:07:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:07:15 INFO - Using real prefix '/tools/python27' 09:07:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:07:15 INFO - Installing distribute.............................................................................................................................................................................................done. 09:07:18 INFO - Installing pip.................done. 09:07:18 INFO - Return code: 0 09:07:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:07:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:07:19 INFO - Reading from file tmpfile_stdout 09:07:19 INFO - Output received: 09:07:19 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:07:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:07: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')]} 09:07:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07: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')]} 09:07:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107546b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107549768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa372dc40c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107324d30>, '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 0x10724bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107319be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q1ynxt1tYC/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eJE0hg8kKH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:07: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', 'pip>=1.5'] in /builds/slave/test/build 09:07: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 pip>=1.5 09:07:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q1ynxt1tYC/Render', 09:07:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:19 INFO - 'HOME': '/Users/cltbld', 09:07:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:19 INFO - 'LOGNAME': 'cltbld', 09:07:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:19 INFO - 'MOZ_NO_REMOTE': '1', 09:07:19 INFO - 'NO_EM_RESTART': '1', 09:07:19 INFO - 'PAGER': '/bin/cat', 09:07:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:19 INFO - 'PWD': '/builds/slave/test', 09:07:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:19 INFO - 'SHELL': '/bin/bash', 09:07:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eJE0hg8kKH/Listeners', 09:07:19 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 09:07:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:19 INFO - 'USER': 'cltbld', 09:07:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:19 INFO - 'XPC_FLAGS': '0x0', 09:07:19 INFO - 'XPC_SERVICE_NAME': '0', 09:07:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:19 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 09:07:19 INFO - Cleaning up... 09:07:19 INFO - Return code: 0 09:07:19 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:07: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')]} 09:07:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07: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')]} 09:07:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07: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', '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 0x107546b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107549768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa372dc40c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107324d30>, '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 0x10724bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107319be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q1ynxt1tYC/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eJE0hg8kKH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:07: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', 'psutil>=3.1.1'] in /builds/slave/test/build 09:07: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 psutil>=3.1.1 09:07:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q1ynxt1tYC/Render', 09:07:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:19 INFO - 'HOME': '/Users/cltbld', 09:07:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:19 INFO - 'LOGNAME': 'cltbld', 09:07:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:19 INFO - 'MOZ_NO_REMOTE': '1', 09:07:19 INFO - 'NO_EM_RESTART': '1', 09:07:19 INFO - 'PAGER': '/bin/cat', 09:07:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:19 INFO - 'PWD': '/builds/slave/test', 09:07:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:19 INFO - 'SHELL': '/bin/bash', 09:07:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eJE0hg8kKH/Listeners', 09:07:19 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 09:07:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:19 INFO - 'USER': 'cltbld', 09:07:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:19 INFO - 'XPC_FLAGS': '0x0', 09:07:19 INFO - 'XPC_SERVICE_NAME': '0', 09:07:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:20 INFO - Downloading/unpacking psutil>=3.1.1 09:07:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:07:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:07:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:07:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:07:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:07:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:07:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:07:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:07:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:07:23 INFO - Installing collected packages: psutil 09:07:23 INFO - Running setup.py install for psutil 09:07:23 INFO - building 'psutil._psutil_osx' extension 09:07:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:07:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:07:23 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:07:24 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:07:24 INFO - building 'psutil._psutil_posix' extension 09:07:24 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:07:24 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:07:24 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:07:24 INFO - ^ 09:07:24 INFO - 1 warning generated. 09:07:24 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:07:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:07:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:07:24 INFO - Successfully installed psutil 09:07:24 INFO - Cleaning up... 09:07:24 INFO - Return code: 0 09:07:24 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:07: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')]} 09:07:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07: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')]} 09:07:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107546b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107549768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa372dc40c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107324d30>, '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 0x10724bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107319be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q1ynxt1tYC/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eJE0hg8kKH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:07: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:07: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 mozsystemmonitor==0.3 09:07:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q1ynxt1tYC/Render', 09:07:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:24 INFO - 'HOME': '/Users/cltbld', 09:07:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:24 INFO - 'LOGNAME': 'cltbld', 09:07:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:24 INFO - 'MOZ_NO_REMOTE': '1', 09:07:24 INFO - 'NO_EM_RESTART': '1', 09:07:24 INFO - 'PAGER': '/bin/cat', 09:07:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:24 INFO - 'PWD': '/builds/slave/test', 09:07:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:24 INFO - 'SHELL': '/bin/bash', 09:07:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eJE0hg8kKH/Listeners', 09:07:24 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 09:07:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:24 INFO - 'USER': 'cltbld', 09:07:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:24 INFO - 'XPC_FLAGS': '0x0', 09:07:24 INFO - 'XPC_SERVICE_NAME': '0', 09:07:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:24 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:07:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:07:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:07:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:07:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:07:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:07:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:07:27 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:07:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:07:27 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:07:27 INFO - Installing collected packages: mozsystemmonitor 09:07:27 INFO - Running setup.py install for mozsystemmonitor 09:07:27 INFO - Successfully installed mozsystemmonitor 09:07:27 INFO - Cleaning up... 09:07:27 INFO - Return code: 0 09:07:27 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:07:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:27 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 0x107546b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107549768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa372dc40c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107324d30>, '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 0x10724bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107319be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q1ynxt1tYC/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eJE0hg8kKH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:07:27 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 09:07:27 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 09:07:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q1ynxt1tYC/Render', 09:07:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:27 INFO - 'HOME': '/Users/cltbld', 09:07:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:27 INFO - 'LOGNAME': 'cltbld', 09:07:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:27 INFO - 'MOZ_NO_REMOTE': '1', 09:07:27 INFO - 'NO_EM_RESTART': '1', 09:07:27 INFO - 'PAGER': '/bin/cat', 09:07:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:27 INFO - 'PWD': '/builds/slave/test', 09:07:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:27 INFO - 'SHELL': '/bin/bash', 09:07:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eJE0hg8kKH/Listeners', 09:07:27 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 09:07:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:27 INFO - 'USER': 'cltbld', 09:07:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:27 INFO - 'XPC_FLAGS': '0x0', 09:07:27 INFO - 'XPC_SERVICE_NAME': '0', 09:07:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:27 INFO - Downloading/unpacking jsonschema==2.5.1 09:07:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:07:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:07:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:07:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:07:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:07:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:07:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:07:30 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:07:30 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 09:07:30 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 09:07:30 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 09:07:30 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 09:07:30 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 09:07:30 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 09:07:30 INFO - Downloading functools32-3.2.3-2.tar.gz 09:07:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:07:31 INFO - warning: no files found matching '*.txt' 09:07:31 INFO - no previously-included directories found matching 'build' 09:07:31 INFO - no previously-included directories found matching 'dist' 09:07:31 INFO - no previously-included directories found matching '.git*' 09:07:31 INFO - Installing collected packages: jsonschema, functools32 09:07:31 INFO - Running setup.py install for functools32 09:07:31 INFO - warning: no files found matching '*.txt' 09:07:31 INFO - no previously-included directories found matching 'build' 09:07:31 INFO - no previously-included directories found matching 'dist' 09:07:31 INFO - no previously-included directories found matching '.git*' 09:07:31 INFO - Successfully installed jsonschema functools32 09:07:31 INFO - Cleaning up... 09:07:31 INFO - Return code: 0 09:07:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:07: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')]} 09:07:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07: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')]} 09:07:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:31 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 0x107546b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107549768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa372dc40c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107324d30>, '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 0x10724bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107319be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q1ynxt1tYC/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eJE0hg8kKH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:07:31 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 09:07:31 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 09:07:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q1ynxt1tYC/Render', 09:07:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:31 INFO - 'HOME': '/Users/cltbld', 09:07:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:31 INFO - 'LOGNAME': 'cltbld', 09:07:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:31 INFO - 'MOZ_NO_REMOTE': '1', 09:07:31 INFO - 'NO_EM_RESTART': '1', 09:07:31 INFO - 'PAGER': '/bin/cat', 09:07:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:31 INFO - 'PWD': '/builds/slave/test', 09:07:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:31 INFO - 'SHELL': '/bin/bash', 09:07:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eJE0hg8kKH/Listeners', 09:07:31 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 09:07:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:31 INFO - 'USER': 'cltbld', 09:07:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:31 INFO - 'XPC_FLAGS': '0x0', 09:07:31 INFO - 'XPC_SERVICE_NAME': '0', 09:07:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:31 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:07:31 INFO - Cleaning up... 09:07:31 INFO - Return code: 0 09:07:31 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:07: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')]} 09:07:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07: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')]} 09:07:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:31 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 0x107546b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107549768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa372dc40c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107324d30>, '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 0x10724bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107319be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q1ynxt1tYC/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eJE0hg8kKH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:07:31 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 09:07:31 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 09:07:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q1ynxt1tYC/Render', 09:07:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:31 INFO - 'HOME': '/Users/cltbld', 09:07:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:31 INFO - 'LOGNAME': 'cltbld', 09:07:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:31 INFO - 'MOZ_NO_REMOTE': '1', 09:07:31 INFO - 'NO_EM_RESTART': '1', 09:07:31 INFO - 'PAGER': '/bin/cat', 09:07:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:31 INFO - 'PWD': '/builds/slave/test', 09:07:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:31 INFO - 'SHELL': '/bin/bash', 09:07:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eJE0hg8kKH/Listeners', 09:07:31 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 09:07:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:31 INFO - 'USER': 'cltbld', 09:07:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:31 INFO - 'XPC_FLAGS': '0x0', 09:07:31 INFO - 'XPC_SERVICE_NAME': '0', 09:07:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:31 INFO - Downloading/unpacking blobuploader==1.2.4 09:07:31 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 09:07:31 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 09:07:31 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 09:07:31 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 09:07:31 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 09:07:31 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 09:07:34 INFO - Downloading blobuploader-1.2.4.tar.gz 09:07:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:07:34 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:07:34 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 09:07:34 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 09:07:34 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 09:07:34 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 09:07:34 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 09:07:34 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 09:07:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:07:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:07:35 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 09:07:35 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 09:07:35 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 09:07:35 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 09:07:35 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 09:07:35 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 09:07:35 INFO - Downloading docopt-0.6.1.tar.gz 09:07:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:07:35 INFO - Installing collected packages: blobuploader, requests, docopt 09:07:35 INFO - Running setup.py install for blobuploader 09:07:35 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:07:35 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:07:35 INFO - Running setup.py install for requests 09:07:36 INFO - Running setup.py install for docopt 09:07:36 INFO - Successfully installed blobuploader requests docopt 09:07:36 INFO - Cleaning up... 09:07:36 INFO - Return code: 0 09:07:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:07:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:36 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 0x107546b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107549768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa372dc40c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107324d30>, '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 0x10724bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107319be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q1ynxt1tYC/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eJE0hg8kKH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:07:36 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 09:07:36 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 09:07:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q1ynxt1tYC/Render', 09:07:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:36 INFO - 'HOME': '/Users/cltbld', 09:07:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:36 INFO - 'LOGNAME': 'cltbld', 09:07:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:36 INFO - 'MOZ_NO_REMOTE': '1', 09:07:36 INFO - 'NO_EM_RESTART': '1', 09:07:36 INFO - 'PAGER': '/bin/cat', 09:07:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:36 INFO - 'PWD': '/builds/slave/test', 09:07:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:36 INFO - 'SHELL': '/bin/bash', 09:07:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eJE0hg8kKH/Listeners', 09:07:36 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 09:07:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:36 INFO - 'USER': 'cltbld', 09:07:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:36 INFO - 'XPC_FLAGS': '0x0', 09:07:36 INFO - 'XPC_SERVICE_NAME': '0', 09:07:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:07:36 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-bnXXev-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:07:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:07:37 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-9t75pC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:07:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:07:37 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-BQyROT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:07:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:07:37 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-2YsKlR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:07:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:07:37 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-NuWkZF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:07:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:07:37 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-cLoHv9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:07:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:07:37 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-ha7W0e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:07:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:07:37 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-88lROu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:07:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:07:37 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-cKfqZH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:07:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:07:37 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-2KvU4S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:07:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:07:38 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-vR7ehR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:07:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:07:38 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-fZDlgF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:07:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:07:38 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-JaFmTa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:07:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:07:38 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-OSG3Mx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:07:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:07:38 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-vCh6fh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:07:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:07:38 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-YfW6Be-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:07:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:07:38 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-VtEFHQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:07:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:07:38 INFO - Running setup.py install for manifestparser 09:07:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:07:38 INFO - Running setup.py install for mozcrash 09:07:39 INFO - Running setup.py install for mozdebug 09:07:39 INFO - Running setup.py install for mozdevice 09:07:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:07:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:07:39 INFO - Running setup.py install for mozfile 09:07:39 INFO - Running setup.py install for mozhttpd 09:07:39 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:07:39 INFO - Running setup.py install for mozinfo 09:07:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:07:39 INFO - Running setup.py install for mozInstall 09:07:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:07:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:07:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:07:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:07:39 INFO - Running setup.py install for mozleak 09:07:40 INFO - Running setup.py install for mozlog 09:07:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:07:40 INFO - Running setup.py install for moznetwork 09:07:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:07:40 INFO - Running setup.py install for mozprocess 09:07:40 INFO - Running setup.py install for mozprofile 09:07:40 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:07:40 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:07:40 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:07:40 INFO - Running setup.py install for mozrunner 09:07:40 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:07:40 INFO - Running setup.py install for mozscreenshot 09:07:41 INFO - Running setup.py install for moztest 09:07:41 INFO - Running setup.py install for mozversion 09:07:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:07:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:07:41 INFO - Cleaning up... 09:07:41 INFO - Return code: 0 09:07:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:07:41 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')]} 09:07:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:41 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')]} 09:07:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:42 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 0x107546b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107549768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa372dc40c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107324d30>, '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 0x10724bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107319be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q1ynxt1tYC/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eJE0hg8kKH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:07:42 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 09:07:42 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 09:07:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q1ynxt1tYC/Render', 09:07:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:42 INFO - 'HOME': '/Users/cltbld', 09:07:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:42 INFO - 'LOGNAME': 'cltbld', 09:07:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:42 INFO - 'MOZ_NO_REMOTE': '1', 09:07:42 INFO - 'NO_EM_RESTART': '1', 09:07:42 INFO - 'PAGER': '/bin/cat', 09:07:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:42 INFO - 'PWD': '/builds/slave/test', 09:07:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:42 INFO - 'SHELL': '/bin/bash', 09:07:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eJE0hg8kKH/Listeners', 09:07:42 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 09:07:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:42 INFO - 'USER': 'cltbld', 09:07:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:42 INFO - 'XPC_FLAGS': '0x0', 09:07:42 INFO - 'XPC_SERVICE_NAME': '0', 09:07:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:07:42 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-b0aawQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:07:42 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)) 09:07:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:07:42 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-QJHI0o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:07:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:07:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:07:42 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-kyG5tB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:07:42 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)) 09:07:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:07:42 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-c40bz2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:07:43 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:07:43 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-KOlHuG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:07:43 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)) 09:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:07:43 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-bpZAVj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:07:43 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)) 09:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:07:43 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-MDm1RC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:07:43 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)) 09:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:07:43 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-XV1Le1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:07:43 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)) 09:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:07:43 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-inrIo0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:07:43 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)) 09:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:07:43 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-8hDSGR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:07:43 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)) 09:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:07:43 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-leuK86-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:07:43 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)) 09:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:07:43 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-wV2LVC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:07:43 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)) 09:07:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:07:43 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-Ly8U4y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:07:44 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)) 09:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:07:44 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-E9_Haf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:07:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:07:44 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-GDMAaR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:07:44 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)) 09:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:07:44 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-fGpICR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:07:44 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)) 09:07:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:07:44 INFO - Running setup.py (path:/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/pip-hgv66s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:07:44 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)) 09:07:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:07:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:07:44 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)) 09:07:44 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:07:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:07:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:07:44 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 09:07:44 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 09:07:44 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 09:07:44 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 09:07:44 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 09:07:44 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 09:07:47 INFO - Downloading blessings-1.6.tar.gz 09:07:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:07:47 INFO - Installing collected packages: blessings 09:07:47 INFO - Running setup.py install for blessings 09:07:47 INFO - Successfully installed blessings 09:07:47 INFO - Cleaning up... 09:07:47 INFO - Return code: 0 09:07:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:07:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:07:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:07:48 INFO - Reading from file tmpfile_stdout 09:07:48 INFO - Current package versions: 09:07:48 INFO - blessings == 1.6 09:07:48 INFO - blobuploader == 1.2.4 09:07:48 INFO - docopt == 0.6.1 09:07:48 INFO - functools32 == 3.2.3-2 09:07:48 INFO - jsonschema == 2.5.1 09:07:48 INFO - manifestparser == 1.1 09:07:48 INFO - mozInstall == 1.12 09:07:48 INFO - mozcrash == 1.0 09:07:48 INFO - mozdebug == 0.1 09:07:48 INFO - mozdevice == 0.49 09:07:48 INFO - mozfile == 1.2 09:07:48 INFO - mozhttpd == 0.7 09:07:48 INFO - mozinfo == 0.9 09:07:48 INFO - mozleak == 0.1 09:07:48 INFO - mozlog == 3.4 09:07:48 INFO - moznetwork == 0.27 09:07:48 INFO - mozprocess == 0.23 09:07:48 INFO - mozprofile == 0.28 09:07:48 INFO - mozrunner == 6.13 09:07:48 INFO - mozscreenshot == 0.1 09:07:48 INFO - mozsystemmonitor == 0.3 09:07:48 INFO - moztest == 0.7 09:07:48 INFO - mozversion == 1.4 09:07:48 INFO - psutil == 3.1.1 09:07:48 INFO - requests == 1.2.3 09:07:48 INFO - wsgiref == 0.1.2 09:07:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:07:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:48 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 0x107546b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107549768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa372dc40c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107324d30>, '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 0x10724bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107319be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q1ynxt1tYC/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eJE0hg8kKH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:07:48 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 09:07:48 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 09:07:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q1ynxt1tYC/Render', 09:07:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:48 INFO - 'HOME': '/Users/cltbld', 09:07:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:48 INFO - 'LOGNAME': 'cltbld', 09:07:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:48 INFO - 'MOZ_NO_REMOTE': '1', 09:07:48 INFO - 'NO_EM_RESTART': '1', 09:07:48 INFO - 'PAGER': '/bin/cat', 09:07:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:48 INFO - 'PWD': '/builds/slave/test', 09:07:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:48 INFO - 'SHELL': '/bin/bash', 09:07:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eJE0hg8kKH/Listeners', 09:07:48 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 09:07:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:48 INFO - 'USER': 'cltbld', 09:07:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:48 INFO - 'XPC_FLAGS': '0x0', 09:07:48 INFO - 'XPC_SERVICE_NAME': '0', 09:07:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:48 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)) 09:07:48 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)) 09:07:48 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)) 09:07:48 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)) 09:07:48 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)) 09:07:48 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)) 09:07:48 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)) 09:07:48 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)) 09:07:48 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)) 09:07:48 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)) 09:07:48 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)) 09:07:48 INFO - Cleaning up... 09:07:48 INFO - Return code: 0 09:07:48 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:07:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:07:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:07:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:07:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:07:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107546b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107549768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa372dc40c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107324d30>, '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 0x10724bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107319be8>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q1ynxt1tYC/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eJE0hg8kKH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:07:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:07:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:07:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q1ynxt1tYC/Render', 09:07:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:48 INFO - 'HOME': '/Users/cltbld', 09:07:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:48 INFO - 'LOGNAME': 'cltbld', 09:07:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:48 INFO - 'MOZ_NO_REMOTE': '1', 09:07:48 INFO - 'NO_EM_RESTART': '1', 09:07:48 INFO - 'PAGER': '/bin/cat', 09:07:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:48 INFO - 'PWD': '/builds/slave/test', 09:07:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:48 INFO - 'SHELL': '/bin/bash', 09:07:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eJE0hg8kKH/Listeners', 09:07:48 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 09:07:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:48 INFO - 'USER': 'cltbld', 09:07:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:48 INFO - 'XPC_FLAGS': '0x0', 09:07:48 INFO - 'XPC_SERVICE_NAME': '0', 09:07:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:07:48 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:07:48 INFO - Cleaning up... 09:07:48 INFO - Return code: 0 09:07:48 INFO - Running post-action listener: _resource_record_post_action 09:07:48 INFO - Running post-action listener: _start_resource_monitoring 09:07:48 INFO - Starting resource monitoring. 09:07:48 INFO - [mozharness: 2016-12-22 17:07:48.646878Z] Finished create-virtualenv step (success) 09:07:48 INFO - [mozharness: 2016-12-22 17:07:48.647996Z] Running install step. 09:07:48 INFO - Running pre-action listener: _resource_record_pre_action 09:07:48 INFO - Running main action method: install 09:07:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:07:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:07:48 INFO - Reading from file tmpfile_stdout 09:07:48 INFO - Detecting whether we're running mozinstall >=1.0... 09:07:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:07:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:07:49 INFO - Reading from file tmpfile_stdout 09:07:49 INFO - Output received: 09:07:49 INFO - Usage: mozinstall [options] installer 09:07:49 INFO - Options: 09:07:49 INFO - -h, --help show this help message and exit 09:07:49 INFO - -d DEST, --destination=DEST 09:07:49 INFO - Directory to install application into. [default: 09:07:49 INFO - "/builds/slave/test"] 09:07:49 INFO - --app=APP Application being installed. [default: firefox] 09:07:49 INFO - mkdir: /builds/slave/test/build/application 09:07:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:07:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:08:10 INFO - Reading from file tmpfile_stdout 09:08:10 INFO - Output received: 09:08:10 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:08:10 INFO - Running post-action listener: _resource_record_post_action 09:08:10 INFO - [mozharness: 2016-12-22 17:08:10.769674Z] Finished install step (success) 09:08:10 INFO - [mozharness: 2016-12-22 17:08:10.769827Z] Running run-tests step. 09:08:10 INFO - Running pre-action listener: _resource_record_pre_action 09:08:10 INFO - Running main action method: run_tests 09:08:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:08:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:08:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:08:10 INFO - 2016-12-22 09:08:10.785 screenresolution[6871:121642] starting screenresolution argv=screenresolution get 09:08:10 INFO - 2016-12-22 09:08:10.787 screenresolution[6871:121642] Display 0: 1600x1200x32@60 09:08:10 INFO - 2016-12-22 09:08:10.793 screenresolution[6872:121644] starting screenresolution argv=screenresolution list 09:08:10 INFO - Available Modes on Display 0 09:08:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:08:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:08:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:08:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:08:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:08:11 INFO - Intel Iris: 09:08:11 INFO - Chipset Model: Intel Iris 09:08:11 INFO - Type: GPU 09:08:11 INFO - Bus: Built-In 09:08:11 INFO - VRAM (Dynamic, Max): 1536 MB 09:08:11 INFO - Vendor: Intel (0x8086) 09:08:11 INFO - Device ID: 0x0a2e 09:08:11 INFO - Revision ID: 0x0009 09:08:11 INFO - Displays: 09:08:11 INFO - Display: 09:08:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:08:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:08:11 INFO - Display Serial Number: 1600x1200 60 09:08:11 INFO - Main Display: Yes 09:08:11 INFO - Mirror: Off 09:08:11 INFO - Online: Yes 09:08:11 INFO - Rotation: Supported 09:08:11 INFO - Return code: 0 09:08:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:08:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:08:11 INFO - Python 2.7.3 09:08:11 INFO - Return code: 0 09:08:11 INFO - grabbing minidump binary from tooltool 09:08: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')]} 09:08:11 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/macosx64/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 0x107324d30>, '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 0x10724bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107319be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:08:11 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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:08:11 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/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:08:11 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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:08:11 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:08:11 INFO - Return code: 0 09:08:11 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:08:11 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:08:11 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:08:11 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:08:11 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:08:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fjDzv0IJRIGptMan_rhM_A/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0199', '--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 09:08:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fjDzv0IJRIGptMan_rhM_A/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0199 --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 09:08:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.q1ynxt1tYC/Render', 09:08:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:08:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:08:11 INFO - 'HOME': '/Users/cltbld', 09:08:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:08:11 INFO - 'LOGNAME': 'cltbld', 09:08:11 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:08:11 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:08:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:08:11 INFO - 'MOZ_NO_REMOTE': '1', 09:08:11 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:08:11 INFO - 'NO_EM_RESTART': '1', 09:08:11 INFO - 'PAGER': '/bin/cat', 09:08:11 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:08:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:08:11 INFO - 'PWD': '/builds/slave/test', 09:08:11 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:08:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:08:11 INFO - 'SHELL': '/bin/bash', 09:08:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.eJE0hg8kKH/Listeners', 09:08:11 INFO - 'TMPDIR': '/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/', 09:08:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:08:11 INFO - 'USER': 'cltbld', 09:08:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:08:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:08:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:08:11 INFO - 'XPC_FLAGS': '0x0', 09:08:11 INFO - 'XPC_SERVICE_NAME': '0', 09:08:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:08:11 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fjDzv0IJRIGptMan_rhM_A/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0199', '--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 09:08:11 INFO - mozversion application_buildid: 20161222071954 09:08:11 INFO - mozversion application_changeset: 8460203bc93b9667cea1bc00f9d9990a4b1a9474 09:08:11 INFO - mozversion application_display_name: Nightly 09:08:11 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:08:11 INFO - mozversion application_name: Firefox 09:08:11 INFO - mozversion application_remotingname: firefox 09:08:11 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:08:11 INFO - mozversion application_vendor: Mozilla 09:08:11 INFO - mozversion application_version: 53.0a1 09:08:11 INFO - mozversion platform_buildid: 20161222071954 09:08:11 INFO - mozversion platform_changeset: 8460203bc93b9667cea1bc00f9d9990a4b1a9474 09:08:11 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:08:11 INFO - mozversion platform_version: 53.0a1 09:08:11 INFO - using testdate: 1482426491 09:08:11 INFO - actual date: 1482426491 09:08:11 INFO - starting webserver on 'localhost:61549' 09:08:11 INFO - SUITE-START | Running 2 tests 09:08:11 INFO - TEST-START | dromaeo_css 09:08:11 INFO - operating with platform_type : mac_ 09:08:11 INFO - Initialising browser for dromaeo_css test... 09:08:11 INFO - TEST-INFO | started process 6882 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/tmp8Jt88t/profile http://localhost:61549/getInfo.html) 09:08:11 INFO - PROCESS | 6882 | 1482426491925 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 09:08:13 INFO - PROCESS | 6882 | __metrics Screen width/height:1600/1200 09:08:13 INFO - PROCESS | 6882 | colorDepth:24 09:08:13 INFO - PROCESS | 6882 | Browser inner width/height: 1024/689 09:08:13 INFO - PROCESS | 6882 | __metrics 09:08:13 INFO - PROCESS | 6882 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:08:13 INFO - PROCESS | 6882 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:08:14 INFO - TEST-INFO | 6882: exit 0 09:08:14 INFO - Browser initialized. 09:08:14 INFO - Running cycle 1/1 for dromaeo_css test... 09:08:14 INFO - TEST-INFO | started process 6884 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/tmp8Jt88t/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:08:26 INFO - PROCESS | 6884 | RSS: Main: 234946560 09:08:26 INFO - PROCESS | 6884 | 09:09:17 INFO - PROCESS | 6884 | 1482426557616 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] 09:11:31 INFO - PROCESS | 6884 | Cycle 1(1): loaded http://localhost:61549/tests/dromaeo/cssquery-dojo.html (next: http://localhost:61549/tests/dromaeo/cssquery-ext.html) 09:11:33 INFO - PROCESS | 6884 | RSS: Main: 345178112 09:11:33 INFO - PROCESS | 6884 | 09:13:15 INFO - PROCESS | 6884 | ************************* 09:13:15 INFO - PROCESS | 6884 | A coding exception was thrown and uncaught in a Task. 09:13:15 INFO - PROCESS | 6884 | 09:13:15 INFO - PROCESS | 6884 | Full message: ReferenceError: fetch is not defined 09:13:15 INFO - PROCESS | 6884 | Full stack: apiCall@resource://shield-recipe-client/lib/NormandyApi.jsm:37:5 09:13:15 INFO - PROCESS | 6884 | get@resource://shield-recipe-client/lib/NormandyApi.jsm:44:12 09:13:15 INFO - PROCESS | 6884 | this.NormandyApi.fetchRecipes<@resource://shield-recipe-client/lib/NormandyApi.jsm:52:34 09:13:15 INFO - PROCESS | 6884 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 09:13:15 INFO - PROCESS | 6884 | TaskImpl@resource://gre/modules/Task.jsm:277:3 09:13:15 INFO - PROCESS | 6884 | asyncFunction@resource://gre/modules/Task.jsm:252:14 09:13:15 INFO - PROCESS | 6884 | this.RecipeRunner.start<@resource://shield-recipe-client/lib/RecipeRunner.jsm:64:23 09:13:15 INFO - PROCESS | 6884 | TaskImpl_run@resource://gre/modules/Task.jsm:319:42 09:13:15 INFO - PROCESS | 6884 | TaskImpl@resource://gre/modules/Task.jsm:277:3 09:13:15 INFO - PROCESS | 6884 | asyncFunction@resource://gre/modules/Task.jsm:252:14 09:13:15 INFO - PROCESS | 6884 | setTimeout_timer@resource://gre/modules/Timer.jsm:30:5 09:13:15 INFO - PROCESS | 6884 | 09:13:15 INFO - PROCESS | 6884 | ************************* 09:14:36 INFO - PROCESS | 6884 | Cycle 1(1): loaded http://localhost:61549/tests/dromaeo/cssquery-ext.html (next: http://localhost:61549/tests/dromaeo/cssquery-jquery.html) 09:14:36 INFO - PROCESS | 6884 | RSS: Main: 369131520 09:14:36 INFO - PROCESS | 6884 | 09:17:39 INFO - PROCESS | 6884 | Cycle 1(1): loaded http://localhost:61549/tests/dromaeo/cssquery-jquery.html (next: http://localhost:61549/tests/dromaeo/cssquery-mootools.html) 09:17:39 INFO - PROCESS | 6884 | RSS: Main: 365494272 09:17:39 INFO - PROCESS | 6884 | 09:20:42 INFO - PROCESS | 6884 | Cycle 1(1): loaded http://localhost:61549/tests/dromaeo/cssquery-mootools.html (next: http://localhost:61549/tests/dromaeo/cssquery-prototype.html) 09:20:42 INFO - PROCESS | 6884 | RSS: Main: 353894400 09:20:42 INFO - PROCESS | 6884 | 09:23:46 INFO - PROCESS | 6884 | Cycle 1(1): loaded http://localhost:61549/tests/dromaeo/cssquery-prototype.html (next: http://localhost:61549/tests/dromaeo/cssquery-yui.html) 09:23:46 INFO - PROCESS | 6884 | RSS: Main: 338952192 09:23:46 INFO - PROCESS | 6884 | 09:26:50 INFO - PROCESS | 6884 | Cycle 1(1): loaded http://localhost:61549/tests/dromaeo/cssquery-yui.html (next: http://localhost:61549/tests/dromaeo/cssquery-dojo.html) 09:26:50 INFO - PROCESS | 6884 | RSS: Main: 467464192 09:26:50 INFO - PROCESS | 6884 | 09:26:50 INFO - PROCESS | 6884 | __start_tp_report 09:26:50 INFO - PROCESS | 6884 | _x_x_mozilla_page_load 09:26:50 INFO - PROCESS | 6884 | _x_x_mozilla_page_load_details 09:26:50 INFO - PROCESS | 6884 | |i|pagename|runs| 09:26:50 INFO - PROCESS | 6884 | |0;dojo.html;3263.7108342204824;3271.086414447549;3289.0461766087838;3321.5515905352786;3332.466805311151;5540.168974653804;5592.440755924411;5654.943450565494;5673.26247587814;5673.716314184289;5642.125470552071;5660.462256085676;5690.943090569103;5705;5775.047117225663;5110.259012443194;5182.818601348954;5191.428942816287;5211.661242019266;5219.791208351661;5104.872378190538;5224.555912747402;5246.265522826808;5247.212918062302;5251.107311756991;5240.816571420001;5264.39459462161;5265.631405801595;5280.9207861882105;5303.787848486056;5100.668456550312;5121.897562048775;5125.769340379692;5179.404368497596;5209.583233341362;5105.15764898795;5115.769790359443;5129.974350128263;5141.5886729061585;5191.6625419348;5564.86087847803;5608.6634801911605;5616.775328986836;5647.237622920894;5669.744861481243;6052.425019623129;6167.444929956326;6181.103739957726;6185.597936134137;6187.041008643622;6118.171816584309;6122.938770612282;6174.938250617482;6197.380261973812;6203.906941395882;5694.487496125327;5707.828765137053;5714.200011998323;5725.198472213893;5757.740901659436;6167.414095660905;6240.5319601030105;6248.250209974832;6248.968920128219;6261.499080073583;5963.850903727354;5970.283565972111;5990.790322338785;6007.939920600738;6051.576389652682;6162.969185154046;6221.906671399933;6222.533310001768;6229.003359462465;6234;5896.705164741746;5939.138824870456;5952.285725712942;6135.141080248769;6170.691465426711;6035.275766907911;6127.724252408652;6129.522954539822;6214.844628884314;6229.4393504584805;4888.828890988798;4890.828820991248;4906.779194936236;4908.730019848943;4935.87660308488;4276.294411422081;4302.139572085595;4322.502912165079;4329.480462344539;4373.781310934441;5664.093745000862;5674.97162514193;5678.77284908599;5683.403242659544;5726.341470730836;6202.131701561785;6208.534359923024;6229.875402491924;6241.594296370721;6254.906176407358;5561.221428999943;5627.887442251132;5638.295213098363;5670.092785154438;5688.203651488795;5311.707856067954;5355.598330125178;5367.731613419313;5385.165299378603;5400.405955344909;6274.090256912813;6283.214598175228;6286.874262514724;6289.056641503628;6301.558890877594;6114.175715401237;6143.877122457437;6259.530535209969;6267.655278959701;6280.905786413299;5229.843104706865;6192.442680158807;6218.81343559694;6258.812235632939;6300.149479820166;6132.263729246367;6133.059244992972;6135.5695317960535;6136.427173609184;6144.678894037804;6058.394160583906;6167.568270221221;6181.041938499539;6218.129461875432;6226.688665566613;5817.243758311392;5822.243108395882;5886.411358864079;5888.440598143169;6010.391658722271;5470.74172940218;5698.2592263005545;5968.582199246185;6033.969830150997;6071.453569178795;5748.971255143698;5790.247267855152;5805.738741756547;5851.1223316502865;5857.092150716646;5944.256967879015;6015.48868346178;6053.182820319201;6061.545384096298;6076.54425918049;5676.4323567642905;5885.529157667462;5901.468867801918;5907.822765317047;5907.91138132937 09:26:50 INFO - PROCESS | 6884 | |1;ext.html;426.28384314351894;429.2059689574288;484.23490884402645;504.7249249159208;513.0713408730196;31398.84300578497;32114.518282225756;38493;40429;40470.79764601173;41803;42204.155916881646;42291.94270143248;42400.36399454011;42423.78788106063;8367.577511823005;9295.442273463597;9503.904960950405;9517.953025167226;9520.857187142165;8365.790855228606;9626.614935402575;11200;11394.487248073816;11580.305181689126;11280.54877804891;11398.088152947745;11408.429578521082;11420.314781113118;11432.142589305835;11303.943480282629;11400.486978085964;11400.657980260605;11404.144689148347;11405.973462388341;11199.328040317607;11258.54965801367;11280.943595282053;11285.04077153443;11287.717807054807;38375;38722;38739.806300968594;38773;38882.61117388818;3324.1855745342305;3398.1844357354303;3483.3381657484997;3762.7177961652988;3958.247932892741;29380.118596442142;30266.84866575653;30514;30531;31172.37655246905;3118.610173728284;3178.284885900677;3179.2210908327606;3255.3489302139665;3283.868645254187;486375;486792;487504;487529;489331;56340.71829640825;56467.43532564704;56528.717356412955;56614;56967.71516142393;59677.80644387088;59712;59786;59836.70081649651;59902.10146847801;15163.545093647208;15415.152166630944;15457.304421301069;15621.375144994301;15636.374545018072;10942.17933654963;11218.775624487465;12098.576549820715;12188.14682972201;12196;13582.388792504362;15019.098854068572;15155.318010689545;15436.691266174614;15534;5288.65623734456;6151.692415379213;6157.815265542041;6191.752329906843;6242.438180563771;856.7858035491128;920.3787443475761;974.1524873360222;1010.8079464901646;1057.281048886749;23389.883050584638;28782.424351513273;29621;30305.393892122032;30371.24071898176;990.3513198854762;997.6757553795046;1040.7346126737634;1052.4053909541085;1106.286445242814;14651.12093274407;15294.464693735888;15300.387984480496;15642.45251416195;15981.44064957721;602213;606557;606792;607086;607979;56920;57027.42972570304;57085.42914570884;57192.71403642955;57419.425805741404;1342.804903635765;1382.1845111384332;1393.8257717785277;1397.7134687389062;1406.071992484955;25791.96832126694;28716.856415717786;28725.71274287314;28742.99399521007;29574;40110;48390;48796;48805;48871.778205545146;532326;534190;536192;536215;536595;23447.06211751579;24076.39809004789;38002;38403.2319353617;38521.6147838518;40887.795561022;41182.48813141785;41417;41651;41801.79099104485;30086.73044099548;33109.503357449175;34248.82875585606;34874.47688284729;35043.82478087593;30226.093217203517;33877.322453551285;34037;34741.305173896886;35228;27190.592141117504;33560.32879342448;34243.82878085594;34459.96620101401;34584.40616953789;28715.8564207186;30673.53989690112;30821.22946926345;30891.536626951063;30951.91668291599;23294.65058024165;23356.437514295172;23582;23798;23847.761522385248 09:26:50 INFO - PROCESS | 6884 | |2;jquery.html;349.34323471872875;360.2866324677139;365.93230252403305;369.3224125093074;396.39549686727736;13241.735165296688;15019.699606007887;15985.120818355;19967.901765402898;20431.18275268991;19661;20161.798382016175;20735.896320518375;20835;21102.894485527588;5144.202648589456;5715.5427565794635;5964.7315870785915;5973.133895585138;5985.88028239435;4359.193549193409;5528.640638358494;6145.846353841145;6497.545171837974;6608.570442921195;5972.820815375546;6290;6490.513211509132;6521.608703477807;6539.34606539347;6422.807315780534;6466.6766661666725;6487.416132548094;6491.156149700556;6492.805215843532;5335.1197052486295;5584.4136365681425;5614.691191984439;5653.349864765563;5667.234923285345;24517;25546.36134096644;25584.744152558607;25635;26004.86997565019;2009.397180845755;2179.4333473297056;2189.79196976287;2389.7132344118645;3049.786514943955;10061.54723037465;10450.007249311304;10793;11200.327980321124;15835.920820395824;1817.6273863857878;1827.8354948054605;1831.2308830291176;1865.2259312385345;1886.0946745562208;115602;116190;116923.4153829234;119113.40443297815;120513.39743301315;10834.07910327631;10856.78286434267;11168.776624467628;11313.886861131283;11317.320960742381;14481.78277325841;14493.275336233146;14501.839852811961;14508.274586270856;14536.563903082924;8125.8781118282095;8292.58537073144;8296.460730052528;8438.493690378597;8464.830703385898;8101.756947291591;8114.229148230908;8136.837263254702;8280.337572994145;8289.709860154866;4436.068425630654;4463.843765468193;4510.932336014962;4659.068186362741;4714.010057887814;1843.9858078057016;1896.8577356698304;1904.495308743184;1905.3045638341905;1915.3104882242383;806.2703253555542;822.481836443049;886.8714036464677;914.1270087066836;947.1712251779693;20511.692324615142;21018.78981210199;21065.368038958855;21346.893265533883;21456.785432145793;899.1952202778476;925.7222833150163;974.3033730882312;974.9610015599296;1007.596961215505;10209.846852297222;11074.390908499949;11142.387168705798;11146.777064458665;11254.774904501865;370241;441068;443140;561495;562591;124680.37659811824;134112;137225;137777.79999900027;140197;951.3435729346867;1020.731755658618;1026.086782763356;1031.0823367203195;1034.984475232872;15442.2278886053;18886.150123244213;19437;19528.414147575597;19549.902250488656;32436.83781581077;41560.1687966245;50486.74756626193;53854;54813.725931370085;66864;75563;77194;80017.59947201594;80901.19098808937;7141.392981596622;7267.563946163247;7364.815879602838;7793.727219547289;8429.747107586782;12881.098323117292;12967.027472939755;13045.673858153623;13390.665233368854;13642.317884105954;9303.953480232556;11163.274387164809;11286.887131128911;11391.54433822671;11703.180777345504;10478.37129772216;11301.943490282496;11589;11651.941740291244;11740.941295293811;10767.89232107669;10916.7270818227;11013.073036426182;11108;11778.58774942907;8031.1567285434185;8297.25324720778;8336.458130221516;8372.7906802328;8477.618507167315;10711.625093121704;11397.979535422954;11702.414879256174;11703.76592468163;11736.589219377282 09:26:50 INFO - PROCESS | 6884 | |3;mootools.html;1359.694068834513;1464.8242210934686;1504.2027725305588;1708.1886104100554;1872.31660443938;7458.216887226838;7838.216178382159;7842.8039299017555;7846.960765196173;7853;7643.961780191091;7940.364770818321;8181.509109453422;8241.052278987918;8287.378446616513;1959.2163134746072;2262.7963483286503;2289.8053665438456;2301.3786277705008;2308.1805958884606;2062.102985201442;2142.539354038889;2269.4893648929024;2309.3649246457276;2341.66045923341;2088.8955552222405;2218.800307972282;2302.4474126209675;2303.815694744425;2308.73449553301;1983.1274239334646;2195.9231426899983;2273.3858960138323;2278.464560828204;2278.9658155127686;2284.366677825363;2290.2556669082446;2326.325365643961;2328.5459335429596;2331.25399872041;8268.627911743986;8331.541765202912;8338.791530211733;8351.665933362658;8351.95824020876;4551.453825540923;4966.255061740731;4971.627127965381;5143.974280128576;5147.7683504242395;3230.709236168756;3313.5361049453095;3522.806245656488;3531.1172206948263;3542.752007359486;1031.257494603884;1031.6956497833157;1064.1433645915035;1071.4374953149581;1083.182197440935;10596.576136954589;10753.03222709952;10812.189085818594;10826.837597436044;10854.348739075602;4416.558344165558;4487.842925497592;4547.3179023146795;4571.9314210286875;4573.542645735466;9275.628974841065;9281.21109705669;9297.488638124833;9301.302402319852;9356.157945784911;5155.200943853709;5171.534561889448;5178.119719647669;5181.974090129525;5181.974090129601;4653.674242803039;4689.343491911135;4747.216709242936;4755.286706993978;4761.880952976203;3695.2978934002454;3752.7185461090526;3763.698904087721;3814.3706288462095;3833.674137698271;1248.6753444104597;1249.925004499733;1251.4806355362423;1258.0816004316716;1265.9746805063846;635.9109724638554;785.3167744062702;890.7327801659476;926.2080920812608;968.4867020479157;5374.193870919394;5599.440055994368;5619.747111379916;5719.685417302005;5732.541396688255;807.697466134823;878.7890906182598;892.5358813416987;911.8495448251097;951.191487235844;3513.631068737746;3841.654251117413;3918.647321741057;3956.5054368203973;3973.980130099331;9431.481268530297;9971.202303815677;9989.750256243651;9993.80012399748;9997;9286.582103805345;9803.803923921481;9805.852912206323;9918.05778451046;9952.601895924083;7554.244575542401;7759.92240077592;7768.883466748003;7780.105287891852;7784;5498.890022199614;5617.382087970238;5685.800996965087;5769.249997500298;5903.321118071477;9105.362624616213;9173.678921237992;9252.121048500381;9267.814643707223;9311.860322095039;8872.911270887209;9354.766130846783;9434.575444105169;9450.102240287162;9454.480003599592;7087.539309944837;7232.89150662751;7239.927600723925;7251.746188883364;7251.8187045324285;7768.7669369918995;7779.416543759127;7782.377409807315;7827.608619569112;7860.0960889496155;6877.140357455318;7021.262767409553;7057.117860267466;7068;7127.10911136108;6802.183737951477;6887.276835932155;6902.102726645504;6945.409640180641;6981.371676549135;6512.544121911421;6659.500537459613;6663.300353462817;6667.533272670867;6675.165604299463;4737.336772851872;4869.9026019480125;4937.530934561211;4993.224122125246;4994.400671919393;4169.687273454564;4782.7847746850775;4954.281629163679;4973.651844370859;5115.053715062725 09:26:50 INFO - PROCESS | 6884 | |4;prototype.html;341.6685814759683;353.4203905594825;356.86872613814205;369.99815000925025;370.6478845097158;4448.911021779562;5063.58208209459;5265.262463387083;5293.444188360215;5297.973510132453;4845.709257444556;4906.950930490694;5011.118606801086;5109.850283686169;5135.948640513594;3570.464430335451;4067.3898915162667;4137.400076988834;4143.19207754488;4166.354215096665;3728.8999745075134;3815.9427608585893;4089.8159582818844;4124.340105583107;4126.917461650765;3447.3277710846396;3735.9439608405896;3957.357696555926;4028;4092.0383709828166;3327.7038843198116;3908.7263891527605;3955.2485027844905;3959.742616729903;4001.7198796084285;2848.897587474031;3404.6254911959913;3411.522386865841;3433.3819912415756;3437.2781715839706;4696.577308042259;4927.088488629579;5054.646174767768;5061.569766569846;5101.948980510185;4017.8593749218635;4325.978370108161;4421.602055814992;4427.1406009476095;4511.8646440606835;5458.754356053987;5468.28912241406;5475.397706252309;5508.889822203573;5517.779288828442;3722.274156539476;3728.962710372916;3749.250149970017;3754.2491501699496;3762.849486020556;4838.854834354975;5245.737713114329;5663.915041274426;5720.542356611502;5732.226149469851;3378.8817391391185;3626.9455958160647;3776.6412190841825;3804.2581696569455;3806.4290356446754;3121.939519916844;3833.099221682902;3844.36567966288;3849.6920246380782;3907.5897030811925;4080.9795951020055;4098.405731168964;4100.954256664531;4256.808443620044;4367.781610919441;5517.2896402115475;5542.61201715876;5547.639403438763;5558.555315574744;5574.846739179337;1062.4793851013103;1203.6870413692327;1211.19455562052;1246.5637027040464;1269.51123817331;751.8045308219902;758.3846407152729;771.067008919214;808.5310519898445;809.9311558517475;4619.815207391734;4782.880427989258;4814.831480898151;4815.903681926342;4853.029394121119;4662.733598757167;4809.78355973982;5307.548858347083;5358;5360.490753378423;4372.766551481749;4375.068703968349;4660.764897302218;4793.41817200323;4828.823068046115;2440.9861863961946;2627.618995245717;2785.428987057689;2814.7607453366695;2817.2110709699487;5025.824096156617;5224.60815438836;5552.250446189793;5575.275214222125;5575.665460072328;4526.117407105583;4579.5878370946775;4887.560119589254;5451.591130665216;5490.972545137329;4911.673848061075;5483.464629903633;5493.615446918678;5508.9724551376985;5545.861353466195;4820.866436816098;4894.877628059327;5081.44104148536;5295.962602632924;5339.225812257278;4379.518252992105;4478;4957.380327459067;5399.191270924284;5408.161225183457;4744.6751091964;4896.499003212578;5437.782488700408;5540.058190107772;5582.218489411404;3002.5946497222594;3116.579261799628;3221.4523530999318;3421.956303327509;3590.533230679995;4414.955850441583;5041.294218809443;5325.360956685223;5394.354721809886;5450.193150527402;4470.949326908239;5149.871253218699;5159.948400516097;5195.623159862715;5223.477652234898;4523.258545460088;5230.686158830483;5246.947530524646;5258.425170102036;5273.126146250226;4180.679722753901;4606.930896036495;4892.3884514435695;5083.389993200692;5182.24857395683;4620.752396852764;5412.43169467216;5487.314085739283;5501.367342755474;5535.557155427637;4585.81656733727;5329.920051199313;5375.344765559457;5411.2965314508865;5424.64383904024 09:26:50 INFO - PROCESS | 6884 | |5;yui.html;731.279689505837;742.5915746339514;769.299937057278;771.2326235395782;827.7516744976506;14616.12303261805;15663.60840978976;15676.137812420327;15709.978851374666;15775.974561653513;14152.50466233682;14311.212883291415;14316.642083947907;14335.426582936696;14365;680.9216940051883;700.4816435837469;700.5446459801119;849.8300339932007;875.9124087591254;838.1995194589152;850.1838235294125;859.3683642522741;881.6341218394359;882.5278476015333;923.9882328849899;926.4024704065873;931.4737173496986;933.1182032978837;933.5752232734123;716.6237725194262;923.8152369526088;925.5187302602676;927.3786563002805;928.5264515097282;547.1191381875178;548.4241546376329;550.3780727777586;550.9917351239735;666.8199586111723;14748.70502589953;15204.619884502976;15215.315310810929;15232.543023709306;15251.847481525265;13590.59228223155;13728.45086196551;13755.31223438834;13760.793588096187;13789.724205515933;12695.55565555201;12832.807507887295;12833.294168820712;12875.935620321932;12990.480380784851;4339.110482351142;4388.407564978751;4402.119576084764;4451.955480445186;4577;108705.91294087037;110169;110643;112367;116992.41503792512;10711;10967.012968832843;11017.724556885982;11168.274062185932;11241.550337986553;21554.56890862174;21870.23454179128;21903.561928761337;21991.010404531833;22259.332220033426;7651.196624354474;7656.464047516621;7673.232676732282;7691.846163076707;7718.729844455417;4242.978785106055;4269.146170765859;4335.3063509838275;4348.934765978513;4447.2439685253585;3393.6776006279365;3472.982635086859;3508.5965114012215;3518.0325410512314;3521;628.827072555051;634.8635043465584;640.6412409050853;648.9221293444817;657.5397221944658;503.37661043135597;590.3977942498627;629.5750368501243;649.0394216559519;663.4659099424913;8672.78318042054;9084.636614535344;9190.218831399272;9433.339666223299;9452.621895124117;596.4840413042687;628.3998781163978;636.5766765101156;668.345021878552;669.5828060700866;4454.420925279693;4521.412216411911;4565.360849481075;4613.354130421677;4661.137689527448;100620;103926;106281;106691.46654266679;110848.44575777231;85259.14740852636;94023.52988235015;95134;95578;96311.0368896316;77650.83523747035;78261.21738782653;81255;83775;85272.4418267453;7313.853722925618;8236.958815205886;8847.867281990904;8848.513331766815;8855.645774168961;50867.491325086274;52260.693482661714;52280;52406.44919385077;53140.73429632827;67590;67933.3206667927;70509;74188;75829;11713.648590542298;11722.94138529302;11754.471048802654;11853.288802671867;12128.818067728815;12731.299778512272;13121.671958200739;13158.276294803494;13272.601821945356;13278.80081798793;10138.746531336776;12087;12127.45426455794;12194.51221951112;12202.81695774582;11705.765884682089;11888.167828251939;11892.227005244631;11964.222325548812;12123.575674851338;9930.25523085786;12003.219790713576;12029;12136.878631213574;12144.574939877062;9805.754856128653;9981.768687095784;10302.33034852732;10616.734581635521;10666.253362264877;9989.405625909669;10281.485925703835;10289.91140211874;10528.684139475828;10572.577096916037 09:26:50 INFO - PROCESS | 6884 | __end_tp_report 09:26:50 INFO - PROCESS | 6884 | __start_cc_report 09:26:50 INFO - PROCESS | 6884 | _x_x_mozilla_cycle_collect,1245 09:26:50 INFO - PROCESS | 6884 | __end_cc_report 09:26:50 INFO - PROCESS | 6884 | __startTimestamp1482427610327__endTimestamp 09:26:50 INFO - PROCESS | 6884 | 09:26:50 INFO - PROCESS | 6884 | ------- Summary: start ------- 09:26:50 INFO - PROCESS | 6884 | Number of tests: 6 09:26:50 INFO - PROCESS | 6884 | 09:26:50 INFO - PROCESS | 6884 | [#0] dojo.html Cycles:165 Average:5674.52 Median:5817.24 stddev:629.39 (10.8%) stddev-sans-first:602.23 09:26:50 INFO - PROCESS | 6884 | Values: 3263.7 3271.1 3289.0 3321.6 3332.5 5540.2 5592.4 5654.9 5673.3 5673.7 5642.1 5660.5 5690.9 5705.0 5775.0 5110.3 5182.8 5191.4 5211.7 5219.8 5104.9 5224.6 5246.3 5247.2 5251.1 5240.8 5264.4 5265.6 5280.9 5303.8 5100.7 5121.9 5125.8 5179.4 5209.6 5105.2 5115.8 5130.0 5141.6 5191.7 5564.9 5608.7 5616.8 5647.2 5669.7 6052.4 6167.4 6181.1 6185.6 6187.0 6118.2 6122.9 6174.9 6197.4 6203.9 5694.5 5707.8 5714.2 5725.2 5757.7 6167.4 6240.5 6248.3 6249.0 6261.5 5963.9 5970.3 5990.8 6007.9 6051.6 6163.0 6221.9 6222.5 6229.0 6234.0 5896.7 5939.1 5952.3 6135.1 6170.7 6035.3 6127.7 6129.5 6214.8 6229.4 4888.8 4890.8 4906.8 4908.7 4935.9 4276.3 4302.1 4322.5 4329.5 4373.8 5664.1 5675.0 5678.8 5683.4 5726.3 6202.1 6208.5 6229.9 6241.6 6254.9 5561.2 5627.9 5638.3 5670.1 5688.2 5311.7 5355.6 5367.7 5385.2 5400.4 6274.1 6283.2 6286.9 6289.1 6301.6 6114.2 6143.9 6259.5 6267.7 6280.9 5229.8 6192.4 6218.8 6258.8 6300.1 6132.3 6133.1 6135.6 6136.4 6144.7 6058.4 6167.6 6181.0 6218.1 6226.7 5817.2 5822.2 5886.4 5888.4 6010.4 5470.7 5698.3 5968.6 6034.0 6071.5 5749.0 5790.2 5805.7 5851.1 5857.1 5944.3 6015.5 6053.2 6061.5 6076.5 5676.4 5885.5 5901.5 5907.8 5907.9 09:26:50 INFO - PROCESS | 6884 | 09:26:50 INFO - PROCESS | 6884 | [#1] ext.html Cycles:180 Average:66931.70 Median:34140.41 stddev:145575.74 (426.4%) stddev-sans-first:145898.48 09:26:50 INFO - PROCESS | 6884 | Values: 426.3 429.2 484.2 504.7 513.1 31398.8 32114.5 38493.0 40429.0 40470.8 41803.0 42204.2 42291.9 42400.4 42423.8 8367.6 9295.4 9503.9 9518.0 9520.9 8365.8 9626.6 11200.0 11394.5 11580.3 11280.5 11398.1 11408.4 11420.3 11432.1 11303.9 11400.5 11400.7 11404.1 11406.0 11199.3 11258.5 11280.9 11285.0 11287.7 38375.0 38722.0 38739.8 38773.0 38882.6 3324.2 3398.2 3483.3 3762.7 3958.2 29380.1 30266.8 30514.0 30531.0 31172.4 3118.6 3178.3 3179.2 3255.3 3283.9 486375.0 486792.0 487504.0 487529.0 489331.0 56340.7 56467.4 56528.7 56614.0 56967.7 59677.8 59712.0 59786.0 59836.7 59902.1 15163.5 15415.2 15457.3 15621.4 15636.4 10942.2 11218.8 12098.6 12188.1 12196.0 13582.4 15019.1 15155.3 15436.7 15534.0 5288.7 6151.7 6157.8 6191.8 6242.4 856.8 920.4 974.2 1010.8 1057.3 23389.9 28782.4 29621.0 30305.4 30371.2 990.4 997.7 1040.7 1052.4 1106.3 14651.1 15294.5 15300.4 15642.5 15981.4 602213.0 606557.0 606792.0 607086.0 607979.0 56920.0 57027.4 57085.4 57192.7 57419.4 1342.8 1382.2 1393.8 1397.7 1406.1 25792.0 28716.9 28725.7 28743.0 29574.0 40110.0 48390.0 48796.0 48805.0 48871.8 532326.0 534190.0 536192.0 536215.0 536595.0 23447.1 24076.4 38002.0 38403.2 38521.6 40887.8 41182.5 41417.0 41651.0 41801.8 30086.7 33109.5 34248.8 34874.5 35043.8 30226.1 33877.3 34037.0 34741.3 35228.0 27190.6 33560.3 34243.8 34460.0 34584.4 28715.9 30673.5 30821.2 30891.5 30951.9 23294.7 23356.4 23582.0 23798.0 23847.8 09:26:50 INFO - PROCESS | 6884 | 09:26:50 INFO - PROCESS | 6884 | [#2] jquery.html Cycles:180 Average:31556.43 Median:2184.61 stddev:81899.50 (3748.9%) stddev-sans-first:82095.73 09:26:50 INFO - PROCESS | 6884 | Values: 349.3 360.3 365.9 369.3 396.4 13241.7 15019.7 15985.1 19967.9 20431.2 19661.0 20161.8 20735.9 20835.0 21102.9 5144.2 5715.5 5964.7 5973.1 5985.9 4359.2 5528.6 6145.8 6497.5 6608.6 5972.8 6290.0 6490.5 6521.6 6539.3 6422.8 6466.7 6487.4 6491.2 6492.8 5335.1 5584.4 5614.7 5653.3 5667.2 24517.0 25546.4 25584.7 25635.0 26004.9 2009.4 2179.4 2189.8 2389.7 3049.8 10061.5 10450.0 10793.0 11200.3 15835.9 1817.6 1827.8 1831.2 1865.2 1886.1 115602.0 116190.0 116923.4 119113.4 120513.4 10834.1 10856.8 11168.8 11313.9 11317.3 14481.8 14493.3 14501.8 14508.3 14536.6 8125.9 8292.6 8296.5 8438.5 8464.8 8101.8 8114.2 8136.8 8280.3 8289.7 4436.1 4463.8 4510.9 4659.1 4714.0 1844.0 1896.9 1904.5 1905.3 1915.3 806.3 822.5 886.9 914.1 947.2 20511.7 21018.8 21065.4 21346.9 21456.8 899.2 925.7 974.3 975.0 1007.6 10209.8 11074.4 11142.4 11146.8 11254.8 370241.0 441068.0 443140.0 561495.0 562591.0 124680.4 134112.0 137225.0 137777.8 140197.0 951.3 1020.7 1026.1 1031.1 1035.0 15442.2 18886.2 19437.0 19528.4 19549.9 32436.8 41560.2 50486.7 53854.0 54813.7 66864.0 75563.0 77194.0 80017.6 80901.2 7141.4 7267.6 7364.8 7793.7 8429.7 12881.1 12967.0 13045.7 13390.7 13642.3 9304.0 11163.3 11286.9 11391.5 11703.2 10478.4 11301.9 11589.0 11651.9 11740.9 10767.9 10916.7 11013.1 11108.0 11778.6 8031.2 8297.3 8336.5 8372.8 8477.6 10711.6 11398.0 11702.4 11703.8 11736.6 09:26:50 INFO - PROCESS | 6884 | 09:26:50 INFO - PROCESS | 6884 | [#3] mootools.html Cycles:180 Average:5387.76 Median:5436.54 stddev:2936.11 (54.0%) stddev-sans-first:2928.74 09:26:50 INFO - PROCESS | 6884 | Values: 1359.7 1464.8 1504.2 1708.2 1872.3 7458.2 7838.2 7842.8 7847.0 7853.0 7644.0 7940.4 8181.5 8241.1 8287.4 1959.2 2262.8 2289.8 2301.4 2308.2 2062.1 2142.5 2269.5 2309.4 2341.7 2088.9 2218.8 2302.4 2303.8 2308.7 1983.1 2195.9 2273.4 2278.5 2279.0 2284.4 2290.3 2326.3 2328.5 2331.3 8268.6 8331.5 8338.8 8351.7 8352.0 4551.5 4966.3 4971.6 5144.0 5147.8 3230.7 3313.5 3522.8 3531.1 3542.8 1031.3 1031.7 1064.1 1071.4 1083.2 10596.6 10753.0 10812.2 10826.8 10854.3 4416.6 4487.8 4547.3 4571.9 4573.5 9275.6 9281.2 9297.5 9301.3 9356.2 5155.2 5171.5 5178.1 5182.0 5182.0 4653.7 4689.3 4747.2 4755.3 4761.9 3695.3 3752.7 3763.7 3814.4 3833.7 1248.7 1249.9 1251.5 1258.1 1266.0 635.9 785.3 890.7 926.2 968.5 5374.2 5599.4 5619.7 5719.7 5732.5 807.7 878.8 892.5 911.8 951.2 3513.6 3841.7 3918.6 3956.5 3974.0 9431.5 9971.2 9989.8 9993.8 9997.0 9286.6 9803.8 9805.9 9918.1 9952.6 7554.2 7759.9 7768.9 7780.1 7784.0 5498.9 5617.4 5685.8 5769.2 5903.3 9105.4 9173.7 9252.1 9267.8 9311.9 8872.9 9354.8 9434.6 9450.1 9454.5 7087.5 7232.9 7239.9 7251.7 7251.8 7768.8 7779.4 7782.4 7827.6 7860.1 6877.1 7021.3 7057.1 7068.0 7127.1 6802.2 6887.3 6902.1 6945.4 6981.4 6512.5 6659.5 6663.3 6667.5 6675.2 4737.3 4869.9 4937.5 4993.2 4994.4 4169.7 4782.8 4954.3 4973.7 5115.1 09:26:50 INFO - PROCESS | 6884 | 09:26:50 INFO - PROCESS | 6884 | [#4] prototype.html Cycles:180 Average:4293.37 Median:4801.60 stddev:1310.74 (27.3%) stddev-sans-first:1280.42 09:26:50 INFO - PROCESS | 6884 | Values: 341.7 353.4 356.9 370.0 370.6 4448.9 5063.6 5265.3 5293.4 5298.0 4845.7 4907.0 5011.1 5109.9 5135.9 3570.5 4067.4 4137.4 4143.2 4166.4 3728.9 3815.9 4089.8 4124.3 4126.9 3447.3 3735.9 3957.4 4028.0 4092.0 3327.7 3908.7 3955.2 3959.7 4001.7 2848.9 3404.6 3411.5 3433.4 3437.3 4696.6 4927.1 5054.6 5061.6 5101.9 4017.9 4326.0 4421.6 4427.1 4511.9 5458.8 5468.3 5475.4 5508.9 5517.8 3722.3 3729.0 3749.3 3754.2 3762.8 4838.9 5245.7 5663.9 5720.5 5732.2 3378.9 3626.9 3776.6 3804.3 3806.4 3121.9 3833.1 3844.4 3849.7 3907.6 4081.0 4098.4 4101.0 4256.8 4367.8 5517.3 5542.6 5547.6 5558.6 5574.8 1062.5 1203.7 1211.2 1246.6 1269.5 751.8 758.4 771.1 808.5 809.9 4619.8 4782.9 4814.8 4815.9 4853.0 4662.7 4809.8 5307.5 5358.0 5360.5 4372.8 4375.1 4660.8 4793.4 4828.8 2441.0 2627.6 2785.4 2814.8 2817.2 5025.8 5224.6 5552.3 5575.3 5575.7 4526.1 4579.6 4887.6 5451.6 5491.0 4911.7 5483.5 5493.6 5509.0 5545.9 4820.9 4894.9 5081.4 5296.0 5339.2 4379.5 4478.0 4957.4 5399.2 5408.2 4744.7 4896.5 5437.8 5540.1 5582.2 3002.6 3116.6 3221.5 3422.0 3590.5 4415.0 5041.3 5325.4 5394.4 5450.2 4470.9 5149.9 5159.9 5195.6 5223.5 4523.3 5230.7 5246.9 5258.4 5273.1 4180.7 4606.9 4892.4 5083.4 5182.2 4620.8 5412.4 5487.3 5501.4 5535.6 4585.8 5329.9 5375.3 5411.3 5424.6 09:26:50 INFO - PROCESS | 6884 | 09:26:50 INFO - PROCESS | 6884 | [#5] yui.html Cycles:180 Average:20816.24 Median:4487.92 stddev:30849.98 (687.4%) stddev-sans-first:30899.66 09:26:50 INFO - PROCESS | 6884 | Values: 731.3 742.6 769.3 771.2 827.8 14616.1 15663.6 15676.1 15710.0 15776.0 14152.5 14311.2 14316.6 14335.4 14365.0 680.9 700.5 700.5 849.8 875.9 838.2 850.2 859.4 881.6 882.5 924.0 926.4 931.5 933.1 933.6 716.6 923.8 925.5 927.4 928.5 547.1 548.4 550.4 551.0 666.8 14748.7 15204.6 15215.3 15232.5 15251.8 13590.6 13728.5 13755.3 13760.8 13789.7 12695.6 12832.8 12833.3 12875.9 12990.5 4339.1 4388.4 4402.1 4452.0 4577.0 108705.9 110169.0 110643.0 112367.0 116992.4 10711.0 10967.0 11017.7 11168.3 11241.6 21554.6 21870.2 21903.6 21991.0 22259.3 7651.2 7656.5 7673.2 7691.8 7718.7 4243.0 4269.1 4335.3 4348.9 4447.2 3393.7 3473.0 3508.6 3518.0 3521.0 628.8 634.9 640.6 648.9 657.5 503.4 590.4 629.6 649.0 663.5 8672.8 9084.6 9190.2 9433.3 9452.6 596.5 628.4 636.6 668.3 669.6 4454.4 4521.4 4565.4 4613.4 4661.1 100620.0 103926.0 106281.0 106691.5 110848.4 85259.1 94023.5 95134.0 95578.0 96311.0 77650.8 78261.2 81255.0 83775.0 85272.4 7313.9 8237.0 8847.9 8848.5 8855.6 50867.5 52260.7 52280.0 52406.4 53140.7 67590.0 67933.3 70509.0 74188.0 75829.0 11713.6 11722.9 11754.5 11853.3 12128.8 12731.3 13121.7 13158.3 13272.6 13278.8 10138.7 12087.0 12127.5 12194.5 12202.8 11705.8 11888.2 11892.2 11964.2 12123.6 9930.3 12003.2 12029.0 12136.9 12144.6 9805.8 9981.8 10302.3 10616.7 10666.3 9989.4 10281.5 10289.9 10528.7 10572.6 09:26:50 INFO - PROCESS | 6884 | -------- Summary: end -------- 09:26:50 INFO - PROCESS | 6884 | 09:26:50 INFO - TEST-INFO | 6884: exit 0 09:26:50 INFO - TEST-OK | dromaeo_css | took 1119590ms 09:26:50 INFO - TEST-START | kraken 09:26:50 INFO - operating with platform_type : mac_ 09:26:50 INFO - Initialising browser for kraken test... 09:26:50 INFO - TEST-INFO | started process 6886 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/tmp_A3w34/profile http://localhost:61549/getInfo.html) 09:26:51 INFO - PROCESS | 6886 | 1482427611484 addons.xpi WARN Exception running bootstrap method install on shield-recipe-client@mozilla.org: [Exception... "Component returned failure code: 0x8000ffff (NS_ERROR_UNEXPECTED) [nsIPrefBranch.getBoolPref]" nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js :: this.install :: line 38" data: no] Stack trace: this.install()@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/Nightly.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:38 < callBootstrapMethod()@resource://gre/modules/addons/XPIProvider.jsm:4979 < processFileChanges()@resource://gre/modules/addons/XPIProvider.jsm -> resource://gre/modules/addons/XPIProviderUtils.js:2097 < checkForChanges()@resource://gre/modules/addons/XPIProvider.jsm:3825 < startup()@resource://gre/modules/addons/XPIProvider.jsm:2829 < callProvider()@resource://gre/modules/AddonManager.jsm:264 < _startProvider()@resource://gre/modules/AddonManager.jsm:771 < startup()@resource://gre/modules/AddonManager.jsm:957 < startup()@resource://gre/modules/AddonManager.jsm:2923 < observe()@resource://gre/components/addonManager.js:65 09:26:53 INFO - PROCESS | 6886 | __metrics Screen width/height:1600/1200 09:26:53 INFO - PROCESS | 6886 | colorDepth:24 09:26:53 INFO - PROCESS | 6886 | Browser inner width/height: 1024/689 09:26:53 INFO - PROCESS | 6886 | __metrics 09:26:53 INFO - PROCESS | 6886 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:26:53 INFO - PROCESS | 6886 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:26:54 INFO - TEST-INFO | 6886: exit 0 09:26:54 INFO - Browser initialized. 09:26:54 INFO - Running cycle 1/1 for kraken test... 09:26:54 INFO - TEST-INFO | started process 6887 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/tmp_A3w34/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:27:06 INFO - PROCESS | 6887 | RSS: Main: 235548672 09:27:06 INFO - PROCESS | 6887 | 09:27:09 INFO - PROCESS | 6887 | Cycle 1(1): loaded http://localhost:61549/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:61549/tests/kraken/driver.html?testName=audio-beat-detection) 09:27:10 INFO - PROCESS | 6887 | RSS: Main: 287563776 09:27:10 INFO - PROCESS | 6887 | 09:27:12 INFO - PROCESS | 6887 | Cycle 1(1): loaded http://localhost:61549/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:61549/tests/kraken/driver.html?testName=audio-dft) 09:27:13 INFO - PROCESS | 6887 | RSS: Main: 288944128 09:27:13 INFO - PROCESS | 6887 | 09:27:16 INFO - PROCESS | 6887 | Cycle 1(1): loaded http://localhost:61549/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:61549/tests/kraken/driver.html?testName=audio-fft) 09:27:17 INFO - PROCESS | 6887 | RSS: Main: 380153856 09:27:17 INFO - PROCESS | 6887 | 09:27:19 INFO - PROCESS | 6887 | Cycle 1(1): loaded http://localhost:61549/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:61549/tests/kraken/driver.html?testName=audio-oscillator) 09:27:20 INFO - PROCESS | 6887 | RSS: Main: 283385856 09:27:20 INFO - PROCESS | 6887 | 09:27:22 INFO - PROCESS | 6887 | Cycle 1(1): loaded http://localhost:61549/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:61549/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:27:22 INFO - PROCESS | 6887 | RSS: Main: 287621120 09:27:22 INFO - PROCESS | 6887 | 09:27:25 INFO - PROCESS | 6887 | Cycle 1(1): loaded http://localhost:61549/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:61549/tests/kraken/driver.html?testName=imaging-darkroom) 09:27:26 INFO - PROCESS | 6887 | RSS: Main: 330272768 09:27:26 INFO - PROCESS | 6887 | 09:27:29 INFO - PROCESS | 6887 | Cycle 1(1): loaded http://localhost:61549/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:61549/tests/kraken/driver.html?testName=imaging-desaturate) 09:27:29 INFO - PROCESS | 6887 | RSS: Main: 328486912 09:27:29 INFO - PROCESS | 6887 | 09:27:32 INFO - PROCESS | 6887 | Cycle 1(1): loaded http://localhost:61549/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:61549/tests/kraken/driver.html?testName=json-parse-financial) 09:27:33 INFO - PROCESS | 6887 | RSS: Main: 326434816 09:27:33 INFO - PROCESS | 6887 | 09:27:34 INFO - PROCESS | 6887 | Cycle 1(1): loaded http://localhost:61549/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:61549/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:27:35 INFO - PROCESS | 6887 | RSS: Main: 303497216 09:27:35 INFO - PROCESS | 6887 | 09:27:37 INFO - PROCESS | 6887 | Cycle 1(1): loaded http://localhost:61549/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:61549/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:27:38 INFO - PROCESS | 6887 | RSS: Main: 305758208 09:27:38 INFO - PROCESS | 6887 | 09:27:40 INFO - PROCESS | 6887 | Cycle 1(1): loaded http://localhost:61549/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:61549/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:27:41 INFO - PROCESS | 6887 | RSS: Main: 307281920 09:27:41 INFO - PROCESS | 6887 | 09:27:43 INFO - PROCESS | 6887 | Cycle 1(1): loaded http://localhost:61549/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:61549/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:27:44 INFO - PROCESS | 6887 | RSS: Main: 324231168 09:27:44 INFO - PROCESS | 6887 | 09:27:47 INFO - PROCESS | 6887 | Cycle 1(1): loaded http://localhost:61549/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:61549/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:27:48 INFO - PROCESS | 6887 | RSS: Main: 318275584 09:27:48 INFO - PROCESS | 6887 | 09:27:50 INFO - PROCESS | 6887 | Cycle 1(1): loaded http://localhost:61549/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:61549/tests/kraken/driver.html?testName=ai-astar) 09:27:50 INFO - PROCESS | 6887 | RSS: Main: 362246144 09:27:50 INFO - PROCESS | 6887 | 09:27:50 INFO - PROCESS | 6887 | __start_tp_report 09:27:50 INFO - PROCESS | 6887 | _x_x_mozilla_page_load 09:27:50 INFO - PROCESS | 6887 | _x_x_mozilla_page_load_details 09:27:50 INFO - PROCESS | 6887 | |i|pagename|runs| 09:27:50 INFO - PROCESS | 6887 | |0;ai-astar;114;112;114;112;112;122;166;150;112;112 09:27:50 INFO - PROCESS | 6887 | |1;audio-beat-detection;149;111;111;150;112;112;120;143;165;110 09:27:50 INFO - PROCESS | 6887 | |2;audio-dft;187;184;185;188;185;182;182;185;190;260 09:27:50 INFO - PROCESS | 6887 | |3;audio-fft;80;58;61;112;55;53;74;80;97;59 09:27:50 INFO - PROCESS | 6887 | |4;audio-oscillator;93;71;86;68;69;67;84;68;68;86 09:27:50 INFO - PROCESS | 6887 | |5;imaging-gaussian-blur;88;85;88;86;88;85;89;85;88;84 09:27:50 INFO - PROCESS | 6887 | |6;imaging-darkroom;95;95;102;148;128;94;95;95;94;94 09:27:50 INFO - PROCESS | 6887 | |7;imaging-desaturate;88;87;84;85;85;86;86;87;88;85 09:27:50 INFO - PROCESS | 6887 | |8;json-parse-financial;54;54;54;53;55;56;54;54;55;55 09:27:50 INFO - PROCESS | 6887 | |9;json-stringify-tinderbox;64;69;79;78;74;86;101;102;84;73 09:27:50 INFO - PROCESS | 6887 | |10;stanford-crypto-aes;89;90;86;86;86;87;84;86;88;91 09:27:50 INFO - PROCESS | 6887 | |11;stanford-crypto-ccm;142;147;144;137;139;141;140;139;188;151 09:27:50 INFO - PROCESS | 6887 | |12;stanford-crypto-pbkdf2;172;174;170;215;172;174;172;171;174;170 09:27:50 INFO - PROCESS | 6887 | |13;stanford-crypto-sha256-iterative;68;66;66;66;63;66;65;66;66;66 09:27:50 INFO - PROCESS | 6887 | __end_tp_report 09:27:50 INFO - PROCESS | 6887 | __start_cc_report 09:27:50 INFO - PROCESS | 6887 | _x_x_mozilla_cycle_collect,1984 09:27:50 INFO - PROCESS | 6887 | __end_cc_report 09:27:50 INFO - PROCESS | 6887 | __startTimestamp1482427670283__endTimestamp 09:27:50 INFO - PROCESS | 6887 | 09:27:50 INFO - PROCESS | 6887 | ------- Summary: start ------- 09:27:50 INFO - PROCESS | 6887 | Number of tests: 14 09:27:50 INFO - PROCESS | 6887 | 09:27:50 INFO - PROCESS | 6887 | [#0] ai-astar Cycles:10 Average:122.60 Median:114.00 stddev:19.28 (16.9%) stddev-sans-first:20.19 09:27:50 INFO - PROCESS | 6887 | Values: 114.0 112.0 114.0 112.0 112.0 122.0 166.0 150.0 112.0 112.0 09:27:50 INFO - PROCESS | 6887 | 09:27:50 INFO - PROCESS | 6887 | [#1] audio-beat-detection Cycles:10 Average:128.30 Median:131.50 stddev:21.07 (16.0%) stddev-sans-first:20.98 09:27:50 INFO - PROCESS | 6887 | Values: 149.0 111.0 111.0 150.0 112.0 112.0 120.0 143.0 165.0 110.0 09:27:50 INFO - PROCESS | 6887 | 09:27:50 INFO - PROCESS | 6887 | [#2] audio-dft Cycles:10 Average:192.80 Median:186.00 stddev:23.74 (12.8%) stddev-sans-first:25.09 09:27:50 INFO - PROCESS | 6887 | Values: 187.0 184.0 185.0 188.0 185.0 182.0 182.0 185.0 190.0 260.0 09:27:50 INFO - PROCESS | 6887 | 09:27:50 INFO - PROCESS | 6887 | [#3] audio-fft Cycles:10 Average:72.90 Median:67.50 stddev:19.68 (29.2%) stddev-sans-first:20.70 09:27:50 INFO - PROCESS | 6887 | Values: 80.0 58.0 61.0 112.0 55.0 53.0 74.0 80.0 97.0 59.0 09:27:50 INFO - PROCESS | 6887 | 09:27:50 INFO - PROCESS | 6887 | [#4] audio-oscillator Cycles:10 Average:76.00 Median:77.50 stddev:10.00 (12.9%) stddev-sans-first:8.51 09:27:50 INFO - PROCESS | 6887 | Values: 93.0 71.0 86.0 68.0 69.0 67.0 84.0 68.0 68.0 86.0 09:27:50 INFO - PROCESS | 6887 | 09:27:50 INFO - PROCESS | 6887 | [#5] imaging-gaussian-blur Cycles:10 Average:86.60 Median:88.00 stddev:1.78 (2.0%) stddev-sans-first:1.81 09:27:50 INFO - PROCESS | 6887 | Values: 88.0 85.0 88.0 86.0 88.0 85.0 89.0 85.0 88.0 84.0 09:27:50 INFO - PROCESS | 6887 | 09:27:50 INFO - PROCESS | 6887 | [#6] imaging-darkroom Cycles:10 Average:104.00 Median:94.50 stddev:18.68 (19.8%) stddev-sans-first:19.53 09:27:50 INFO - PROCESS | 6887 | Values: 95.0 95.0 102.0 148.0 128.0 94.0 95.0 95.0 94.0 94.0 09:27:50 INFO - PROCESS | 6887 | 09:27:50 INFO - PROCESS | 6887 | [#7] imaging-desaturate Cycles:10 Average:86.10 Median:86.50 stddev:1.37 (1.6%) stddev-sans-first:1.27 09:27:50 INFO - PROCESS | 6887 | Values: 88.0 87.0 84.0 85.0 85.0 86.0 86.0 87.0 88.0 85.0 09:27:50 INFO - PROCESS | 6887 | 09:27:50 INFO - PROCESS | 6887 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:54.50 stddev:0.84 (1.5%) stddev-sans-first:0.88 09:27:50 INFO - PROCESS | 6887 | Values: 54.0 54.0 54.0 53.0 55.0 56.0 54.0 54.0 55.0 55.0 09:27:50 INFO - PROCESS | 6887 | 09:27:50 INFO - PROCESS | 6887 | [#9] json-stringify-tinderbox Cycles:10 Average:81.00 Median:76.00 stddev:12.62 (16.6%) stddev-sans-first:11.79 09:27:50 INFO - PROCESS | 6887 | Values: 64.0 69.0 79.0 78.0 74.0 86.0 101.0 102.0 84.0 73.0 09:27:50 INFO - PROCESS | 6887 | 09:27:50 INFO - PROCESS | 6887 | [#10] stanford-crypto-aes Cycles:10 Average:87.30 Median:87.50 stddev:2.16 (2.5%) stddev-sans-first:2.20 09:27:50 INFO - PROCESS | 6887 | Values: 89.0 90.0 86.0 86.0 86.0 87.0 84.0 86.0 88.0 91.0 09:27:50 INFO - PROCESS | 6887 | 09:27:50 INFO - PROCESS | 6887 | [#11] stanford-crypto-ccm Cycles:10 Average:146.80 Median:143.00 stddev:15.07 (10.5%) stddev-sans-first:15.88 09:27:50 INFO - PROCESS | 6887 | Values: 142.0 147.0 144.0 137.0 139.0 141.0 140.0 139.0 188.0 151.0 09:27:50 INFO - PROCESS | 6887 | 09:27:50 INFO - PROCESS | 6887 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.40 Median:173.00 stddev:13.65 (7.9%) stddev-sans-first:14.38 09:27:50 INFO - PROCESS | 6887 | Values: 172.0 174.0 170.0 215.0 172.0 174.0 172.0 171.0 174.0 170.0 09:27:50 INFO - PROCESS | 6887 | 09:27:50 INFO - PROCESS | 6887 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.80 Median:66.00 stddev:1.23 (1.9%) stddev-sans-first:1.01 09:27:50 INFO - PROCESS | 6887 | Values: 68.0 66.0 66.0 66.0 63.0 66.0 65.0 66.0 66.0 66.0 09:27:50 INFO - PROCESS | 6887 | -------- Summary: end -------- 09:27:50 INFO - PROCESS | 6887 | 09:27:51 INFO - TEST-INFO | 6887: exit 0 09:27:51 INFO - TEST-OK | kraken | took 60216ms 09:27:51 INFO - SUITE-END | took 1179s 09:27:51 INFO - Completed test suite (00:19:40) 09:27:51 INFO - javascript benchmark 09:27:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3263.7108342204824, 3271.086414447549, 3289.0461766087838, 3321.5515905352786, 3332.466805311151, 5540.168974653804, 5592.440755924411, 5654.943450565494, 5673.26247587814, 5673.716314184289, 5642.125470552071, 5660.462256085676, 5690.943090569103, 5705.0, 5775.047117225663, 5110.259012443194, 5182.818601348954, 5191.428942816287, 5211.661242019266, 5219.791208351661, 5104.872378190538, 5224.555912747402, 5246.265522826808, 5247.212918062302, 5251.107311756991, 5240.816571420001, 5264.39459462161, 5265.631405801595, 5280.9207861882105, 5303.787848486056, 5100.668456550312, 5121.897562048775, 5125.769340379692, 5179.404368497596, 5209.583233341362, 5105.15764898795, 5115.769790359443, 5129.974350128263, 5141.5886729061585, 5191.6625419348, 5564.86087847803, 5608.6634801911605, 5616.775328986836, 5647.237622920894, 5669.744861481243, 6052.425019623129, 6167.444929956326, 6181.103739957726, 6185.597936134137, 6187.041008643622, 6118.171816584309, 6122.938770612282, 6174.938250617482, 6197.380261973812, 6203.906941395882, 5694.487496125327, 5707.828765137053, 5714.200011998323, 5725.198472213893, 5757.740901659436, 6167.414095660905, 6240.5319601030105, 6248.250209974832, 6248.968920128219, 6261.499080073583, 5963.850903727354, 5970.283565972111, 5990.790322338785, 6007.939920600738, 6051.576389652682, 6162.969185154046, 6221.906671399933, 6222.533310001768, 6229.003359462465, 6234.0, 5896.705164741746, 5939.138824870456, 5952.285725712942, 6135.141080248769, 6170.691465426711, 6035.275766907911, 6127.724252408652, 6129.522954539822, 6214.844628884314, 6229.4393504584805, 4888.828890988798, 4890.828820991248, 4906.779194936236, 4908.730019848943, 4935.87660308488, 4276.294411422081, 4302.139572085595, 4322.502912165079, 4329.480462344539, 4373.781310934441, 5664.093745000862, 5674.97162514193, 5678.77284908599, 5683.403242659544, 5726.341470730836, 6202.131701561785, 6208.534359923024, 6229.875402491924, 6241.594296370721, 6254.906176407358, 5561.221428999943, 5627.887442251132, 5638.295213098363, 5670.092785154438, 5688.203651488795, 5311.707856067954, 5355.598330125178, 5367.731613419313, 5385.165299378603, 5400.405955344909, 6274.090256912813, 6283.214598175228, 6286.874262514724, 6289.056641503628, 6301.558890877594, 6114.175715401237, 6143.877122457437, 6259.530535209969, 6267.655278959701, 6280.905786413299, 5229.843104706865, 6192.442680158807, 6218.81343559694, 6258.812235632939, 6300.149479820166, 6132.263729246367, 6133.059244992972, 6135.5695317960535, 6136.427173609184, 6144.678894037804, 6058.394160583906, 6167.568270221221, 6181.041938499539, 6218.129461875432, 6226.688665566613, 5817.243758311392, 5822.243108395882, 5886.411358864079, 5888.440598143169, 6010.391658722271, 5470.74172940218, 5698.2592263005545, 5968.582199246185, 6033.969830150997, 6071.453569178795, 5748.971255143698, 5790.247267855152, 5805.738741756547, 5851.1223316502865, 5857.092150716646, 5944.256967879015, 6015.48868346178, 6053.182820319201, 6061.545384096298, 6076.54425918049, 5676.4323567642905, 5885.529157667462, 5901.468867801918, 5907.822765317047, 5907.91138132937], "value": 5633.671734370525, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [426.28384314351894, 429.2059689574288, 484.23490884402645, 504.7249249159208, 513.0713408730196, 31398.84300578497, 32114.518282225756, 38493.0, 40429.0, 40470.79764601173, 41803.0, 42204.155916881646, 42291.94270143248, 42400.36399454011, 42423.78788106063, 8367.577511823005, 9295.442273463597, 9503.904960950405, 9517.953025167226, 9520.857187142165, 8365.790855228606, 9626.614935402575, 11200.0, 11394.487248073816, 11580.305181689126, 11280.54877804891, 11398.088152947745, 11408.429578521082, 11420.314781113118, 11432.142589305835, 11303.943480282629, 11400.486978085964, 11400.657980260605, 11404.144689148347, 11405.973462388341, 11199.328040317607, 11258.54965801367, 11280.943595282053, 11285.04077153443, 11287.717807054807, 38375.0, 38722.0, 38739.806300968594, 38773.0, 38882.61117388818, 3324.1855745342305, 3398.1844357354303, 3483.3381657484997, 3762.7177961652988, 3958.247932892741, 29380.118596442142, 30266.84866575653, 30514.0, 30531.0, 31172.37655246905, 3118.610173728284, 3178.284885900677, 3179.2210908327606, 3255.3489302139665, 3283.868645254187, 486375.0, 486792.0, 487504.0, 487529.0, 489331.0, 56340.71829640825, 56467.43532564704, 56528.717356412955, 56614.0, 56967.71516142393, 59677.80644387088, 59712.0, 59786.0, 59836.70081649651, 59902.10146847801, 15163.545093647208, 15415.152166630944, 15457.304421301069, 15621.375144994301, 15636.374545018072, 10942.17933654963, 11218.775624487465, 12098.576549820715, 12188.14682972201, 12196.0, 13582.388792504362, 15019.098854068572, 15155.318010689545, 15436.691266174614, 15534.0, 5288.65623734456, 6151.692415379213, 6157.815265542041, 6191.752329906843, 6242.438180563771, 856.7858035491128, 920.3787443475761, 974.1524873360222, 1010.8079464901646, 1057.281048886749, 23389.883050584638, 28782.424351513273, 29621.0, 30305.393892122032, 30371.24071898176, 990.3513198854762, 997.6757553795046, 1040.7346126737634, 1052.4053909541085, 1106.286445242814, 14651.12093274407, 15294.464693735888, 15300.387984480496, 15642.45251416195, 15981.44064957721, 602213.0, 606557.0, 606792.0, 607086.0, 607979.0, 56920.0, 57027.42972570304, 57085.42914570884, 57192.71403642955, 57419.425805741404, 1342.804903635765, 1382.1845111384332, 1393.8257717785277, 1397.7134687389062, 1406.071992484955, 25791.96832126694, 28716.856415717786, 28725.71274287314, 28742.99399521007, 29574.0, 40110.0, 48390.0, 48796.0, 48805.0, 48871.778205545146, 532326.0, 534190.0, 536192.0, 536215.0, 536595.0, 23447.06211751579, 24076.39809004789, 38002.0, 38403.2319353617, 38521.6147838518, 40887.795561022, 41182.48813141785, 41417.0, 41651.0, 41801.79099104485, 30086.73044099548, 33109.503357449175, 34248.82875585606, 34874.47688284729, 35043.82478087593, 30226.093217203517, 33877.322453551285, 34037.0, 34741.305173896886, 35228.0, 27190.592141117504, 33560.32879342448, 34243.82878085594, 34459.96620101401, 34584.40616953789, 28715.8564207186, 30673.53989690112, 30821.22946926345, 30891.536626951063, 30951.91668291599, 23294.65058024165, 23356.437514295172, 23582.0, 23798.0, 23847.761522385248], "value": 19322.3206761551, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.34323471872875, 360.2866324677139, 365.93230252403305, 369.3224125093074, 396.39549686727736, 13241.735165296688, 15019.699606007887, 15985.120818355, 19967.901765402898, 20431.18275268991, 19661.0, 20161.798382016175, 20735.896320518375, 20835.0, 21102.894485527588, 5144.202648589456, 5715.5427565794635, 5964.7315870785915, 5973.133895585138, 5985.88028239435, 4359.193549193409, 5528.640638358494, 6145.846353841145, 6497.545171837974, 6608.570442921195, 5972.820815375546, 6290.0, 6490.513211509132, 6521.608703477807, 6539.34606539347, 6422.807315780534, 6466.6766661666725, 6487.416132548094, 6491.156149700556, 6492.805215843532, 5335.1197052486295, 5584.4136365681425, 5614.691191984439, 5653.349864765563, 5667.234923285345, 24517.0, 25546.36134096644, 25584.744152558607, 25635.0, 26004.86997565019, 2009.397180845755, 2179.4333473297056, 2189.79196976287, 2389.7132344118645, 3049.786514943955, 10061.54723037465, 10450.007249311304, 10793.0, 11200.327980321124, 15835.920820395824, 1817.6273863857878, 1827.8354948054605, 1831.2308830291176, 1865.2259312385345, 1886.0946745562208, 115602.0, 116190.0, 116923.4153829234, 119113.40443297815, 120513.39743301315, 10834.07910327631, 10856.78286434267, 11168.776624467628, 11313.886861131283, 11317.320960742381, 14481.78277325841, 14493.275336233146, 14501.839852811961, 14508.274586270856, 14536.563903082924, 8125.8781118282095, 8292.58537073144, 8296.460730052528, 8438.493690378597, 8464.830703385898, 8101.756947291591, 8114.229148230908, 8136.837263254702, 8280.337572994145, 8289.709860154866, 4436.068425630654, 4463.843765468193, 4510.932336014962, 4659.068186362741, 4714.010057887814, 1843.9858078057016, 1896.8577356698304, 1904.495308743184, 1905.3045638341905, 1915.3104882242383, 806.2703253555542, 822.481836443049, 886.8714036464677, 914.1270087066836, 947.1712251779693, 20511.692324615142, 21018.78981210199, 21065.368038958855, 21346.893265533883, 21456.785432145793, 899.1952202778476, 925.7222833150163, 974.3033730882312, 974.9610015599296, 1007.596961215505, 10209.846852297222, 11074.390908499949, 11142.387168705798, 11146.777064458665, 11254.774904501865, 370241.0, 441068.0, 443140.0, 561495.0, 562591.0, 124680.37659811824, 134112.0, 137225.0, 137777.79999900027, 140197.0, 951.3435729346867, 1020.731755658618, 1026.086782763356, 1031.0823367203195, 1034.984475232872, 15442.2278886053, 18886.150123244213, 19437.0, 19528.414147575597, 19549.902250488656, 32436.83781581077, 41560.1687966245, 50486.74756626193, 53854.0, 54813.725931370085, 66864.0, 75563.0, 77194.0, 80017.59947201594, 80901.19098808937, 7141.392981596622, 7267.563946163247, 7364.815879602838, 7793.727219547289, 8429.747107586782, 12881.098323117292, 12967.027472939755, 13045.673858153623, 13390.665233368854, 13642.317884105954, 9303.953480232556, 11163.274387164809, 11286.887131128911, 11391.54433822671, 11703.180777345504, 10478.37129772216, 11301.943490282496, 11589.0, 11651.941740291244, 11740.941295293811, 10767.89232107669, 10916.7270818227, 11013.073036426182, 11108.0, 11778.58774942907, 8031.1567285434185, 8297.25324720778, 8336.458130221516, 8372.7906802328, 8477.618507167315, 10711.625093121704, 11397.979535422954, 11702.414879256174, 11703.76592468163, 11736.589219377282], "value": 9496.946686593019, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1359.694068834513, 1464.8242210934686, 1504.2027725305588, 1708.1886104100554, 1872.31660443938, 7458.216887226838, 7838.216178382159, 7842.8039299017555, 7846.960765196173, 7853.0, 7643.961780191091, 7940.364770818321, 8181.509109453422, 8241.052278987918, 8287.378446616513, 1959.2163134746072, 2262.7963483286503, 2289.8053665438456, 2301.3786277705008, 2308.1805958884606, 2062.102985201442, 2142.539354038889, 2269.4893648929024, 2309.3649246457276, 2341.66045923341, 2088.8955552222405, 2218.800307972282, 2302.4474126209675, 2303.815694744425, 2308.73449553301, 1983.1274239334646, 2195.9231426899983, 2273.3858960138323, 2278.464560828204, 2278.9658155127686, 2284.366677825363, 2290.2556669082446, 2326.325365643961, 2328.5459335429596, 2331.25399872041, 8268.627911743986, 8331.541765202912, 8338.791530211733, 8351.665933362658, 8351.95824020876, 4551.453825540923, 4966.255061740731, 4971.627127965381, 5143.974280128576, 5147.7683504242395, 3230.709236168756, 3313.5361049453095, 3522.806245656488, 3531.1172206948263, 3542.752007359486, 1031.257494603884, 1031.6956497833157, 1064.1433645915035, 1071.4374953149581, 1083.182197440935, 10596.576136954589, 10753.03222709952, 10812.189085818594, 10826.837597436044, 10854.348739075602, 4416.558344165558, 4487.842925497592, 4547.3179023146795, 4571.9314210286875, 4573.542645735466, 9275.628974841065, 9281.21109705669, 9297.488638124833, 9301.302402319852, 9356.157945784911, 5155.200943853709, 5171.534561889448, 5178.119719647669, 5181.974090129525, 5181.974090129601, 4653.674242803039, 4689.343491911135, 4747.216709242936, 4755.286706993978, 4761.880952976203, 3695.2978934002454, 3752.7185461090526, 3763.698904087721, 3814.3706288462095, 3833.674137698271, 1248.6753444104597, 1249.925004499733, 1251.4806355362423, 1258.0816004316716, 1265.9746805063846, 635.9109724638554, 785.3167744062702, 890.7327801659476, 926.2080920812608, 968.4867020479157, 5374.193870919394, 5599.440055994368, 5619.747111379916, 5719.685417302005, 5732.541396688255, 807.697466134823, 878.7890906182598, 892.5358813416987, 911.8495448251097, 951.191487235844, 3513.631068737746, 3841.654251117413, 3918.647321741057, 3956.5054368203973, 3973.980130099331, 9431.481268530297, 9971.202303815677, 9989.750256243651, 9993.80012399748, 9997.0, 9286.582103805345, 9803.803923921481, 9805.852912206323, 9918.05778451046, 9952.601895924083, 7554.244575542401, 7759.92240077592, 7768.883466748003, 7780.105287891852, 7784.0, 5498.890022199614, 5617.382087970238, 5685.800996965087, 5769.249997500298, 5903.321118071477, 9105.362624616213, 9173.678921237992, 9252.121048500381, 9267.814643707223, 9311.860322095039, 8872.911270887209, 9354.766130846783, 9434.575444105169, 9450.102240287162, 9454.480003599592, 7087.539309944837, 7232.89150662751, 7239.927600723925, 7251.746188883364, 7251.8187045324285, 7768.7669369918995, 7779.416543759127, 7782.377409807315, 7827.608619569112, 7860.0960889496155, 6877.140357455318, 7021.262767409553, 7057.117860267466, 7068.0, 7127.10911136108, 6802.183737951477, 6887.276835932155, 6902.102726645504, 6945.409640180641, 6981.371676549135, 6512.544121911421, 6659.500537459613, 6663.300353462817, 6667.533272670867, 6675.165604299463, 4737.336772851872, 4869.9026019480125, 4937.530934561211, 4993.224122125246, 4994.400671919393, 4169.687273454564, 4782.7847746850775, 4954.281629163679, 4973.651844370859, 5115.053715062725], "value": 4365.560798708068, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.6685814759683, 353.4203905594825, 356.86872613814205, 369.99815000925025, 370.6478845097158, 4448.911021779562, 5063.58208209459, 5265.262463387083, 5293.444188360215, 5297.973510132453, 4845.709257444556, 4906.950930490694, 5011.118606801086, 5109.850283686169, 5135.948640513594, 3570.464430335451, 4067.3898915162667, 4137.400076988834, 4143.19207754488, 4166.354215096665, 3728.8999745075134, 3815.9427608585893, 4089.8159582818844, 4124.340105583107, 4126.917461650765, 3447.3277710846396, 3735.9439608405896, 3957.357696555926, 4028.0, 4092.0383709828166, 3327.7038843198116, 3908.7263891527605, 3955.2485027844905, 3959.742616729903, 4001.7198796084285, 2848.897587474031, 3404.6254911959913, 3411.522386865841, 3433.3819912415756, 3437.2781715839706, 4696.577308042259, 4927.088488629579, 5054.646174767768, 5061.569766569846, 5101.948980510185, 4017.8593749218635, 4325.978370108161, 4421.602055814992, 4427.1406009476095, 4511.8646440606835, 5458.754356053987, 5468.28912241406, 5475.397706252309, 5508.889822203573, 5517.779288828442, 3722.274156539476, 3728.962710372916, 3749.250149970017, 3754.2491501699496, 3762.849486020556, 4838.854834354975, 5245.737713114329, 5663.915041274426, 5720.542356611502, 5732.226149469851, 3378.8817391391185, 3626.9455958160647, 3776.6412190841825, 3804.2581696569455, 3806.4290356446754, 3121.939519916844, 3833.099221682902, 3844.36567966288, 3849.6920246380782, 3907.5897030811925, 4080.9795951020055, 4098.405731168964, 4100.954256664531, 4256.808443620044, 4367.781610919441, 5517.2896402115475, 5542.61201715876, 5547.639403438763, 5558.555315574744, 5574.846739179337, 1062.4793851013103, 1203.6870413692327, 1211.19455562052, 1246.5637027040464, 1269.51123817331, 751.8045308219902, 758.3846407152729, 771.067008919214, 808.5310519898445, 809.9311558517475, 4619.815207391734, 4782.880427989258, 4814.831480898151, 4815.903681926342, 4853.029394121119, 4662.733598757167, 4809.78355973982, 5307.548858347083, 5358.0, 5360.490753378423, 4372.766551481749, 4375.068703968349, 4660.764897302218, 4793.41817200323, 4828.823068046115, 2440.9861863961946, 2627.618995245717, 2785.428987057689, 2814.7607453366695, 2817.2110709699487, 5025.824096156617, 5224.60815438836, 5552.250446189793, 5575.275214222125, 5575.665460072328, 4526.117407105583, 4579.5878370946775, 4887.560119589254, 5451.591130665216, 5490.972545137329, 4911.673848061075, 5483.464629903633, 5493.615446918678, 5508.9724551376985, 5545.861353466195, 4820.866436816098, 4894.877628059327, 5081.44104148536, 5295.962602632924, 5339.225812257278, 4379.518252992105, 4478.0, 4957.380327459067, 5399.191270924284, 5408.161225183457, 4744.6751091964, 4896.499003212578, 5437.782488700408, 5540.058190107772, 5582.218489411404, 3002.5946497222594, 3116.579261799628, 3221.4523530999318, 3421.956303327509, 3590.533230679995, 4414.955850441583, 5041.294218809443, 5325.360956685223, 5394.354721809886, 5450.193150527402, 4470.949326908239, 5149.871253218699, 5159.948400516097, 5195.623159862715, 5223.477652234898, 4523.258545460088, 5230.686158830483, 5246.947530524646, 5258.425170102036, 5273.126146250226, 4180.679722753901, 4606.930896036495, 4892.3884514435695, 5083.389993200692, 5182.24857395683, 4620.752396852764, 5412.43169467216, 5487.314085739283, 5501.367342755474, 5535.557155427637, 4585.81656733727, 5329.920051199313, 5375.344765559457, 5411.2965314508865, 5424.64383904024], "value": 3886.5034308362888, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [731.279689505837, 742.5915746339514, 769.299937057278, 771.2326235395782, 827.7516744976506, 14616.12303261805, 15663.60840978976, 15676.137812420327, 15709.978851374666, 15775.974561653513, 14152.50466233682, 14311.212883291415, 14316.642083947907, 14335.426582936696, 14365.0, 680.9216940051883, 700.4816435837469, 700.5446459801119, 849.8300339932007, 875.9124087591254, 838.1995194589152, 850.1838235294125, 859.3683642522741, 881.6341218394359, 882.5278476015333, 923.9882328849899, 926.4024704065873, 931.4737173496986, 933.1182032978837, 933.5752232734123, 716.6237725194262, 923.8152369526088, 925.5187302602676, 927.3786563002805, 928.5264515097282, 547.1191381875178, 548.4241546376329, 550.3780727777586, 550.9917351239735, 666.8199586111723, 14748.70502589953, 15204.619884502976, 15215.315310810929, 15232.543023709306, 15251.847481525265, 13590.59228223155, 13728.45086196551, 13755.31223438834, 13760.793588096187, 13789.724205515933, 12695.55565555201, 12832.807507887295, 12833.294168820712, 12875.935620321932, 12990.480380784851, 4339.110482351142, 4388.407564978751, 4402.119576084764, 4451.955480445186, 4577.0, 108705.91294087037, 110169.0, 110643.0, 112367.0, 116992.41503792512, 10711.0, 10967.012968832843, 11017.724556885982, 11168.274062185932, 11241.550337986553, 21554.56890862174, 21870.23454179128, 21903.561928761337, 21991.010404531833, 22259.332220033426, 7651.196624354474, 7656.464047516621, 7673.232676732282, 7691.846163076707, 7718.729844455417, 4242.978785106055, 4269.146170765859, 4335.3063509838275, 4348.934765978513, 4447.2439685253585, 3393.6776006279365, 3472.982635086859, 3508.5965114012215, 3518.0325410512314, 3521.0, 628.827072555051, 634.8635043465584, 640.6412409050853, 648.9221293444817, 657.5397221944658, 503.37661043135597, 590.3977942498627, 629.5750368501243, 649.0394216559519, 663.4659099424913, 8672.78318042054, 9084.636614535344, 9190.218831399272, 9433.339666223299, 9452.621895124117, 596.4840413042687, 628.3998781163978, 636.5766765101156, 668.345021878552, 669.5828060700866, 4454.420925279693, 4521.412216411911, 4565.360849481075, 4613.354130421677, 4661.137689527448, 100620.0, 103926.0, 106281.0, 106691.46654266679, 110848.44575777231, 85259.14740852636, 94023.52988235015, 95134.0, 95578.0, 96311.0368896316, 77650.83523747035, 78261.21738782653, 81255.0, 83775.0, 85272.4418267453, 7313.853722925618, 8236.958815205886, 8847.867281990904, 8848.513331766815, 8855.645774168961, 50867.491325086274, 52260.693482661714, 52280.0, 52406.44919385077, 53140.73429632827, 67590.0, 67933.3206667927, 70509.0, 74188.0, 75829.0, 11713.648590542298, 11722.94138529302, 11754.471048802654, 11853.288802671867, 12128.818067728815, 12731.299778512272, 13121.671958200739, 13158.276294803494, 13272.601821945356, 13278.80081798793, 10138.746531336776, 12087.0, 12127.45426455794, 12194.51221951112, 12202.81695774582, 11705.765884682089, 11888.167828251939, 11892.227005244631, 11964.222325548812, 12123.575674851338, 9930.25523085786, 12003.219790713576, 12029.0, 12136.878631213574, 12144.574939877062, 9805.754856128653, 9981.768687095784, 10302.33034852732, 10616.734581635521, 10666.253362264877, 9989.405625909669, 10281.485925703835, 10289.91140211874, 10528.684139475828, 10572.577096916037], "value": 7328.1491921459365, "unit": "score"}], "name": "dromaeo_css", "value": 7104.162166659135, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 112.0, 114.0, 112.0, 112.0, 122.0, 166.0, 150.0, 112.0, 112.0], "value": 122.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 111.0, 111.0, 150.0, 112.0, 112.0, 120.0, 143.0, 165.0, 110.0], "value": 128.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 184.0, 185.0, 188.0, 185.0, 182.0, 182.0, 185.0, 190.0, 260.0], "value": 192.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 58.0, 61.0, 112.0, 55.0, 53.0, 74.0, 80.0, 97.0, 59.0], "value": 72.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 71.0, 86.0, 68.0, 69.0, 67.0, 84.0, 68.0, 68.0, 86.0], "value": 76.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 86.0, 88.0, 85.0, 89.0, 85.0, 88.0, 84.0], "value": 86.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 102.0, 148.0, 128.0, 94.0, 95.0, 95.0, 94.0, 94.0], "value": 104.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 87.0, 84.0, 85.0, 85.0, 86.0, 86.0, 87.0, 88.0, 85.0], "value": 86.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 54.0, 53.0, 55.0, 56.0, 54.0, 54.0, 55.0, 55.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 69.0, 79.0, 78.0, 74.0, 86.0, 101.0, 102.0, 84.0, 73.0], "value": 81.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 86.0, 86.0, 86.0, 87.0, 84.0, 86.0, 88.0, 91.0], "value": 87.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 147.0, 144.0, 137.0, 139.0, 141.0, 140.0, 139.0, 188.0, 151.0], "value": 146.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 174.0, 170.0, 215.0, 172.0, 174.0, 172.0, 171.0, 174.0, 170.0], "value": 176.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 66.0, 66.0, 63.0, 66.0, 65.0, 66.0, 66.0, 66.0], "value": 65.8, "unit": "score"}], "name": "kraken", "value": 1481.0, "alertThreshold": 2.0}]} 09:27:51 INFO - Return code: 0 09:27:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:27:51 INFO - # TBPL SUCCESS # 09:27:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:27:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:27:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:27:51 INFO - 2016-12-22 09:27:51.606 screenresolution[6891:123557] starting screenresolution argv=screenresolution get 09:27:51 INFO - 2016-12-22 09:27:51.609 screenresolution[6891:123557] Display 0: 1600x1200x32@60 09:27:51 INFO - 2016-12-22 09:27:51.615 screenresolution[6892:123560] starting screenresolution argv=screenresolution list 09:27:51 INFO - Available Modes on Display 0 09:27:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:27:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:27:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:27:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:27:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:27:51 INFO - Intel Iris: 09:27:51 INFO - Chipset Model: Intel Iris 09:27:51 INFO - Type: GPU 09:27:51 INFO - Bus: Built-In 09:27:51 INFO - VRAM (Dynamic, Max): 1536 MB 09:27:51 INFO - Vendor: Intel (0x8086) 09:27:51 INFO - Device ID: 0x0a2e 09:27:51 INFO - Revision ID: 0x0009 09:27:51 INFO - Displays: 09:27:51 INFO - Display: 09:27:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:27:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:27:51 INFO - Display Serial Number: 1600x1200 60 09:27:51 INFO - Main Display: Yes 09:27:51 INFO - Mirror: Off 09:27:51 INFO - Online: Yes 09:27:51 INFO - Rotation: Supported 09:27:51 INFO - Return code: 0 09:27:51 INFO - Running post-action listener: _resource_record_post_action 09:27:51 INFO - [mozharness: 2016-12-22 17:27:51.858181Z] Finished run-tests step (success) 09:27:51 INFO - Running post-run listener: _resource_record_post_run 09:27:51 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 696832; Write bytes: 548081152; Read time: 6; Write time: 1713 09:27:51 INFO - TinderboxPrint: CPU usage
53.9% 09:27:51 INFO - TinderboxPrint: I/O read bytes / time
696,832 / 6 09:27:51 INFO - TinderboxPrint: I/O write bytes / time
548,081,152 / 1,713 09:27:51 INFO - TinderboxPrint: CPU idle
2,217.7 (46.1%) 09:27:51 INFO - TinderboxPrint: CPU system
966.3 (20.1%) 09:27:51 INFO - TinderboxPrint: CPU user
1,625.7 (33.8%) 09:27:51 INFO - TinderboxPrint: Swap in / out
249,622,528 / 0 09:27:51 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 250581504; Write bytes: 250552320; Read time: 18221; Write time: 451 09:27:51 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 565248; Write bytes: 296226304; Read time: 4; Write time: 1256 09:27:52 INFO - Running post-run listener: _upload_blobber_files 09:27:52 INFO - Blob upload gear active. 09:27:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:27:52 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 09:27:52 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:27:52 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:27:52 INFO - (blobuploader) - INFO - Open directory for files ... 09:27:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:27:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:27:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:27:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:27:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:27:53 INFO - (blobuploader) - INFO - Done attempting. 09:27:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:27:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:27:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:27:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:27:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:27:54 INFO - (blobuploader) - INFO - Done attempting. 09:27:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:27:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:27:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:27:55 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:27:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:27:55 INFO - (blobuploader) - INFO - Done attempting. 09:27:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:27:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:27:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:27:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:27:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:27:58 INFO - (blobuploader) - INFO - Done attempting. 09:27:58 INFO - (blobuploader) - INFO - Iteration through files over. 09:27:58 INFO - Return code: 0 09:27:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:27:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:27:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ba8467ddb4845f80c3767d371ce62bf24f5e311e5f11ee579cf1efba57cc2e83ef40a1332202587fadf19e07d65db07eeda2bea896f2aa2e22753282e64cf47", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b6c1a2a8185ff066f16241343ff9e3a2977036c8861d8b4eb6ef82fb9b4b0862bb85f96a7cb43ad31a031570e513489cb80cfb6ffd16af3e0dc74159d17295d5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d35701a5d3a8b06b25d4a3920a5dd4926c904209b75f6832afe658b07e72a7bd521bd244983d9ec50872f20089678055be756b3c5dec555b39d105eb72493db0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1728c5abf9cddd832f0594714a0679bb0d9e042abfda40ba5f351572340d4a53a9a8a3ab5b12451b02498a499f130ae2ff782942e9abd82fcaf8bb72080f80d1"} 09:27:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:27:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:27:58 INFO - Contents: 09:27:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ba8467ddb4845f80c3767d371ce62bf24f5e311e5f11ee579cf1efba57cc2e83ef40a1332202587fadf19e07d65db07eeda2bea896f2aa2e22753282e64cf47", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b6c1a2a8185ff066f16241343ff9e3a2977036c8861d8b4eb6ef82fb9b4b0862bb85f96a7cb43ad31a031570e513489cb80cfb6ffd16af3e0dc74159d17295d5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d35701a5d3a8b06b25d4a3920a5dd4926c904209b75f6832afe658b07e72a7bd521bd244983d9ec50872f20089678055be756b3c5dec555b39d105eb72493db0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1728c5abf9cddd832f0594714a0679bb0d9e042abfda40ba5f351572340d4a53a9a8a3ab5b12451b02498a499f130ae2ff782942e9abd82fcaf8bb72080f80d1"} 09:27:58 INFO - Running post-run listener: copy_logs_to_upload_dir 09:27:58 INFO - Copying logs to upload dir... 09:27:58 INFO - mkdir: /builds/slave/test/build/upload/logs 09:27:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.074437 ========= master_lag: 0.54 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-12-22 09:27:58.460285) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-22 09:27:58.464525) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.q1ynxt1tYC/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test/properties RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.eJE0hg8kKH/Listeners TMPDIR=/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ba8467ddb4845f80c3767d371ce62bf24f5e311e5f11ee579cf1efba57cc2e83ef40a1332202587fadf19e07d65db07eeda2bea896f2aa2e22753282e64cf47", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b6c1a2a8185ff066f16241343ff9e3a2977036c8861d8b4eb6ef82fb9b4b0862bb85f96a7cb43ad31a031570e513489cb80cfb6ffd16af3e0dc74159d17295d5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d35701a5d3a8b06b25d4a3920a5dd4926c904209b75f6832afe658b07e72a7bd521bd244983d9ec50872f20089678055be756b3c5dec555b39d105eb72493db0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1728c5abf9cddd832f0594714a0679bb0d9e042abfda40ba5f351572340d4a53a9a8a3ab5b12451b02498a499f130ae2ff782942e9abd82fcaf8bb72080f80d1"} build_url:https://queue.taskcluster.net/v1/task/fjDzv0IJRIGptMan_rhM_A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011420 build_url: 'https://queue.taskcluster.net/v1/task/fjDzv0IJRIGptMan_rhM_A/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ba8467ddb4845f80c3767d371ce62bf24f5e311e5f11ee579cf1efba57cc2e83ef40a1332202587fadf19e07d65db07eeda2bea896f2aa2e22753282e64cf47", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b6c1a2a8185ff066f16241343ff9e3a2977036c8861d8b4eb6ef82fb9b4b0862bb85f96a7cb43ad31a031570e513489cb80cfb6ffd16af3e0dc74159d17295d5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d35701a5d3a8b06b25d4a3920a5dd4926c904209b75f6832afe658b07e72a7bd521bd244983d9ec50872f20089678055be756b3c5dec555b39d105eb72493db0", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/1728c5abf9cddd832f0594714a0679bb0d9e042abfda40ba5f351572340d4a53a9a8a3ab5b12451b02498a499f130ae2ff782942e9abd82fcaf8bb72080f80d1"}' ========= master_lag: 0.18 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-22 09:27:58.659214) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 09:27:58.659536) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.q1ynxt1tYC/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.eJE0hg8kKH/Listeners TMPDIR=/var/folders/ll/nfr6cy655632107dm4rhqyxr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005425 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 09:27:58.686498) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-22 09:27:58.686795) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-22 09:27:58.687120) ========= ========= Total master_lag: 0.82 =========