builder: graphics_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0372 starttime: 1484891741.94 results: success (0) buildid: 20170119193525 builduid: 07e19ef70bda4382b53c7ce3438d1e80 revision: 19949cfb8d7e297ad74ed4ed9186d3313e3d22ae ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-19 21:55:41.941685) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-19 21:55:41.942138) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-19 21:55:41.942447) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-19 21:55:41.968848) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-19 21:55:41.969212) ========= 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.aWuXcfye6E/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.HrLB51YzXP/Listeners TMPDIR=/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 --2017-01-19 21:55:41-- 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% 166M=0s 2017-01-19 21:55:42 (166 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.133011 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-19 21:55:42.126011) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-19 21:55:42.126319) ========= 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.aWuXcfye6E/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.HrLB51YzXP/Listeners TMPDIR=/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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.025399 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-19 21:55:42.176200) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2017-01-19 21:55:42.176812) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev 19949cfb8d7e297ad74ed4ed9186d3313e3d22ae --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev 19949cfb8d7e297ad74ed4ed9186d3313e3d22ae --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.aWuXcfye6E/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.HrLB51YzXP/Listeners TMPDIR=/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 2017-01-19 21:55:42,278 truncating revision to first 12 chars 2017-01-19 21:55:42,278 Setting DEBUG logging. 2017-01-19 21:55:42,278 attempt 1/10 2017-01-19 21:55:42,278 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/19949cfb8d7e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-19 21:55:43,808 unpacking tar archive at: graphics-19949cfb8d7e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.807902 ========= master_lag: 2.92 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 4 secs) (at 2017-01-19 21:55:46.905257) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-19 21:55:46.905593) ========= script_repo_revision: 19949cfb8d7e297ad74ed4ed9186d3313e3d22ae ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-19 21:55:46.905990) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-19 21:55:46.906279) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-19 21:55:46.926865) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-01-19 21:55:46.927353) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Graphics', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.aWuXcfye6E/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.HrLB51YzXP/Listeners TMPDIR=/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 21:55:47 INFO - MultiFileLogger online at 20170119 21:55:47 in /builds/slave/test 21:55:47 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 21:55:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:55:47 INFO - {'append_to_log': False, 21:55:47 INFO - 'base_work_dir': '/builds/slave/test', 21:55:47 INFO - 'blob_upload_branch': 'Graphics', 21:55:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:55:47 INFO - 'branch': 'Graphics', 21:55:47 INFO - 'buildbot_json_path': 'buildprops.json', 21:55:47 INFO - 'config_files': ('talos/mac_config.py',), 21:55:47 INFO - 'default_actions': ('clobber', 21:55:47 INFO - 'read-buildbot-config', 21:55:47 INFO - 'download-and-extract', 21:55:47 INFO - 'populate-webroot', 21:55:47 INFO - 'create-virtualenv', 21:55:47 INFO - 'install', 21:55:47 INFO - 'run-tests'), 21:55:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:55:47 INFO - 'download_minidump_stackwalk': True, 21:55:47 INFO - 'download_symbols': 'ondemand', 21:55:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:55:47 INFO - 'tooltool.py': '/tools/tooltool.py', 21:55:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:55:47 INFO - '/tools/misc-python/virtualenv.py')}, 21:55:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:55:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:55:47 INFO - 'installer_path': 'installer.exe', 21:55:47 INFO - 'log_level': 'info', 21:55:47 INFO - 'log_name': 'talos', 21:55:47 INFO - 'log_to_console': True, 21:55:47 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 21:55:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 21:55:47 INFO - 'opt_config_files': (), 21:55:47 INFO - 'pip_index': False, 21:55:47 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:55:47 INFO - 'cmd': ('bash', 21:55:47 INFO - '-c', 21:55:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:55:47 INFO - 'enabled': True, 21:55:47 INFO - 'halt_on_failure': False, 21:55:47 INFO - 'name': 'check_screen_resolution'},), 21:55:47 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 21:55:47 INFO - 'cmd': ('bash', 21:55:47 INFO - '-c', 21:55:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 21:55:47 INFO - 'enabled': True, 21:55:47 INFO - 'halt_on_failure': False, 21:55:47 INFO - 'name': 'check_screen_resolution'},), 21:55:47 INFO - 'run_cmd_checks_enabled': True, 21:55:47 INFO - 'sps_profile': False, 21:55:47 INFO - 'sps_profile_interval': 0, 21:55:47 INFO - 'suite': 'dromaeojs-e10s', 21:55:47 INFO - 'system_bits': '32', 21:55:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:55:47 INFO - 'title': 't-yosemite-r7-0372', 21:55:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:55:47 INFO - 'use_talos_json': True, 21:55:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:55:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:55:47 INFO - 'work_dir': 'build'} 21:55:47 INFO - [mozharness: 2017-01-20 05:55:47.133143Z] Running clobber step. 21:55:47 INFO - Running pre-action listener: _resource_record_pre_action 21:55:47 INFO - Running main action method: clobber 21:55:47 INFO - rmtree: /builds/slave/test/build 21:55:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:55:49 INFO - Running post-action listener: _resource_record_post_action 21:55:49 INFO - [mozharness: 2017-01-20 05:55:49.553703Z] Finished clobber step (success) 21:55:49 INFO - [mozharness: 2017-01-20 05:55:49.553828Z] Running read-buildbot-config step. 21:55:49 INFO - Running pre-action listener: _resource_record_pre_action 21:55:49 INFO - Running main action method: read_buildbot_config 21:55:49 INFO - Using buildbot properties: 21:55:49 INFO - { 21:55:49 INFO - "project": "", 21:55:49 INFO - "product": "firefox", 21:55:49 INFO - "script_repo_revision": "default", 21:55:49 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs-e10s", 21:55:49 INFO - "repository": "", 21:55:49 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs-e10s", 21:55:49 INFO - "buildid": "20170119193525", 21:55:49 INFO - "pgo_build": "False", 21:55:49 INFO - "basedir": "/builds/slave/test", 21:55:49 INFO - "buildnumber": 26, 21:55:49 INFO - "slavename": "t-yosemite-r7-0372", 21:55:49 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 21:55:49 INFO - "platform": "yosemite_r7", 21:55:49 INFO - "scheduler": "tests-graphics-macosx64-talos-8-3600", 21:55:49 INFO - "branch": "graphics", 21:55:49 INFO - "repo_path": "projects/graphics", 21:55:49 INFO - "revision": "19949cfb8d7e297ad74ed4ed9186d3313e3d22ae", 21:55:49 INFO - "stage_platform": "macosx64", 21:55:49 INFO - "builduid": "07e19ef70bda4382b53c7ce3438d1e80", 21:55:49 INFO - "slavebuilddir": "test" 21:55:49 INFO - } 21:55:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/JV6kSIXdSnqzhHQCwyNtng/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 21:55:49 INFO - Running post-action listener: _resource_record_post_action 21:55:49 INFO - [mozharness: 2017-01-20 05:55:49.557241Z] Finished read-buildbot-config step (success) 21:55:49 INFO - [mozharness: 2017-01-20 05:55:49.557369Z] Running download-and-extract step. 21:55:49 INFO - Running pre-action listener: _resource_record_pre_action 21:55:49 INFO - Running main action method: download_and_extract 21:55:49 INFO - mkdir: /builds/slave/test/build/tests 21:55:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:55:49 INFO - trying https://queue.taskcluster.net/v1/task/JV6kSIXdSnqzhHQCwyNtng/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 21:55:49 INFO - Downloading https://queue.taskcluster.net/v1/task/JV6kSIXdSnqzhHQCwyNtng/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 21:55:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JV6kSIXdSnqzhHQCwyNtng/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 21:55:50 INFO - Downloaded 1199 bytes. 21:55:50 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 21:55:50 INFO - Using the following test package requirements: 21:55:50 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 21:55:50 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:55:50 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 21:55:50 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:55:50 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 21:55:50 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:55:50 INFO - u'jsshell-mac.zip'], 21:55:50 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:55:50 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 21:55:50 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 21:55:50 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:55:50 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 21:55:50 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:55:50 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 21:55:50 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:55:50 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 21:55:50 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 21:55:50 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 21:55:50 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 21:55:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JV6kSIXdSnqzhHQCwyNtng/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 21:55:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JV6kSIXdSnqzhHQCwyNtng/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:55:50 INFO - Fetch https://queue.taskcluster.net/v1/task/JV6kSIXdSnqzhHQCwyNtng/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 21:55:51 INFO - Expected file size: 16340635 21:55:51 INFO - Obtained file size: 16340635 21:55:58 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 21:55:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JV6kSIXdSnqzhHQCwyNtng/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 21:55:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JV6kSIXdSnqzhHQCwyNtng/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 21:55:58 INFO - Fetch https://queue.taskcluster.net/v1/task/JV6kSIXdSnqzhHQCwyNtng/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 21:55:59 INFO - Expected file size: 16340635 21:55:59 INFO - Obtained file size: 16340635 21:56:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JV6kSIXdSnqzhHQCwyNtng/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 21:56:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JV6kSIXdSnqzhHQCwyNtng/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 21:56:05 INFO - Fetch https://queue.taskcluster.net/v1/task/JV6kSIXdSnqzhHQCwyNtng/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 21:56:06 INFO - Expected file size: 14403463 21:56:06 INFO - Obtained file size: 14403463 21:56:06 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')]} 21:56:06 INFO - trying https://queue.taskcluster.net/v1/task/JV6kSIXdSnqzhHQCwyNtng/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 21:56:06 INFO - Downloading https://queue.taskcluster.net/v1/task/JV6kSIXdSnqzhHQCwyNtng/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 21:56:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JV6kSIXdSnqzhHQCwyNtng/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 21:56:08 INFO - Downloaded 69934388 bytes. 21:56:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JV6kSIXdSnqzhHQCwyNtng/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 21:56:08 INFO - mkdir: /builds/slave/test/properties 21:56:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:56:08 INFO - Writing to file /builds/slave/test/properties/build_url 21:56:08 INFO - Contents: 21:56:08 INFO - build_url:https://queue.taskcluster.net/v1/task/JV6kSIXdSnqzhHQCwyNtng/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 21:56:09 INFO - Running post-action listener: _resource_record_post_action 21:56:09 INFO - Running post-action listener: set_extra_try_arguments 21:56:09 INFO - [mozharness: 2017-01-20 05:56:09.480893Z] Finished download-and-extract step (success) 21:56:09 INFO - [mozharness: 2017-01-20 05:56:09.481038Z] Running populate-webroot step. 21:56:09 INFO - Running pre-action listener: _resource_record_pre_action 21:56:09 INFO - Running main action method: populate_webroot 21:56:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:56:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:56:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:56:09 INFO - '--tppagecycles', 21:56:09 INFO - '7'], 21:56:09 INFO - 'tests': ['tsvgm']}}, 21:56:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:56:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:56:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:56:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:56:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:56:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:56:09 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 21:56:09 INFO - 'talos_options': ['--disable-e10s'], 21:56:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:56:09 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 21:56:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:56:09 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 21:56:09 INFO - 'talos_options': ['--disable-e10s'], 21:56:09 INFO - 'tests': ['damp', 'tps']}, 21:56:09 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 21:56:09 INFO - 'tests': ['damp', 'tps']}, 21:56:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:56:09 INFO - 'tests': ['dromaeo_dom']}, 21:56:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:56:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 21:56:09 INFO - 'tests': ['basic_compositor_video']}, 21:56:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 21:56:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:56:09 INFO - 'tests': ['a11yr', 21:56:09 INFO - 'ts_paint', 21:56:09 INFO - 'tpaint', 21:56:09 INFO - 'sessionrestore', 21:56:09 INFO - 'sessionrestore_no_auto_restore', 21:56:09 INFO - 'tabpaint']}, 21:56:09 INFO - 'other-e10s': {'tests': ['a11yr', 21:56:09 INFO - 'ts_paint', 21:56:09 INFO - 'tpaint', 21:56:09 INFO - 'sessionrestore', 21:56:09 INFO - 'sessionrestore_no_auto_restore', 21:56:09 INFO - 'tabpaint']}, 21:56:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:56:09 INFO - 'ts_paint', 21:56:09 INFO - 'tpaint', 21:56:09 INFO - 'sessionrestore', 21:56:09 INFO - 'sessionrestore_no_auto_restore', 21:56:09 INFO - 'tabpaint']}, 21:56:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:56:09 INFO - 'ts_paint', 21:56:09 INFO - 'tpaint', 21:56:09 INFO - 'sessionrestore', 21:56:09 INFO - 'sessionrestore_no_auto_restore', 21:56:09 INFO - 'tabpaint']}, 21:56:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:56:09 INFO - 'tests': ['a11yr', 21:56:09 INFO - 'ts_paint', 21:56:09 INFO - 'tpaint', 21:56:09 INFO - 'sessionrestore', 21:56:09 INFO - 'sessionrestore_no_auto_restore', 21:56:09 INFO - 'tabpaint']}, 21:56:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:56:09 INFO - 'tests': ['a11yr', 21:56:09 INFO - 'ts_paint', 21:56:09 INFO - 'tpaint', 21:56:09 INFO - 'sessionrestore', 21:56:09 INFO - 'sessionrestore_no_auto_restore', 21:56:09 INFO - 'tabpaint']}, 21:56:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:56:09 INFO - 'tests': ['tsvgx', 21:56:09 INFO - 'tsvgr_opacity', 21:56:09 INFO - 'tart', 21:56:09 INFO - 'tscrollx', 21:56:09 INFO - 'cart', 21:56:09 INFO - 'tsvg_static']}, 21:56:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:56:09 INFO - 'tsvgr_opacity', 21:56:09 INFO - 'tart', 21:56:09 INFO - 'tscrollx', 21:56:09 INFO - 'cart', 21:56:09 INFO - 'tsvg_static']}, 21:56:09 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 21:56:09 INFO - 'talos_options': ['--disable-e10s'], 21:56:09 INFO - 'tests': ['tp5o']}, 21:56:09 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 21:56:09 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 21:56:09 INFO - 'talos_options': ['--disable-e10s', 21:56:09 INFO - '--xperf_path', 21:56:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:56:09 INFO - 'tests': ['tp5n']}, 21:56:09 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 21:56:09 INFO - 'talos_options': ['--xperf_path', 21:56:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:56:09 INFO - 'tests': ['tp5n']}}, 21:56:09 INFO - 'talos.zip': {'path': '', 21:56:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:56:09 INFO - Running post-action listener: _resource_record_post_action 21:56:09 INFO - [mozharness: 2017-01-20 05:56:09.499216Z] Finished populate-webroot step (success) 21:56:09 INFO - [mozharness: 2017-01-20 05:56:09.499356Z] Running create-virtualenv step. 21:56:09 INFO - Running pre-action listener: _resource_record_pre_action 21:56:09 INFO - Running main action method: create_virtualenv 21:56:09 INFO - Creating virtualenv /builds/slave/test/build/venv 21:56:09 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 21:56:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:56:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:56:09 INFO - Using real prefix '/tools/python27' 21:56:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:56:10 INFO - Installing distribute.............................................................................................................................................................................................done. 21:56:13 INFO - Installing pip.................done. 21:56:13 INFO - Return code: 0 21:56:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 21:56:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 21:56:13 INFO - Reading from file tmpfile_stdout 21:56:13 INFO - Output received: 21:56:13 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) 21:56:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:56:13 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')]} 21:56:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:56:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:13 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')]} 21:56:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:56:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:13 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 0x10074eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100751768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce7a588e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10052cd30>, '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 0x100453db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100521be8>, '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.aWuXcfye6E/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.HrLB51YzXP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 21:56:13 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 21:56:13 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 21:56:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aWuXcfye6E/Render', 21:56:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:56:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:56:13 INFO - 'HOME': '/Users/cltbld', 21:56:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:56:13 INFO - 'LOGNAME': 'cltbld', 21:56:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:56:13 INFO - 'MOZ_NO_REMOTE': '1', 21:56:13 INFO - 'NO_EM_RESTART': '1', 21:56:13 INFO - 'PAGER': '/bin/cat', 21:56:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:56:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:56:13 INFO - 'PWD': '/builds/slave/test', 21:56:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:56:13 INFO - 'SHELL': '/bin/bash', 21:56:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HrLB51YzXP/Listeners', 21:56:13 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 21:56:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:56:13 INFO - 'USER': 'cltbld', 21:56:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:56:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:56:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:56:13 INFO - 'XPC_FLAGS': '0x0', 21:56:13 INFO - 'XPC_SERVICE_NAME': '0', 21:56:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:56:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:56:13 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 21:56:13 INFO - Cleaning up... 21:56:13 INFO - Return code: 0 21:56:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:56:13 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')]} 21:56:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:56:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:13 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')]} 21:56:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:56:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:13 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 0x10074eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100751768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce7a588e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10052cd30>, '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 0x100453db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100521be8>, '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.aWuXcfye6E/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.HrLB51YzXP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 21:56:13 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 21:56:13 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 21:56:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aWuXcfye6E/Render', 21:56:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:56:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:56:13 INFO - 'HOME': '/Users/cltbld', 21:56:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:56:13 INFO - 'LOGNAME': 'cltbld', 21:56:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:56:13 INFO - 'MOZ_NO_REMOTE': '1', 21:56:13 INFO - 'NO_EM_RESTART': '1', 21:56:13 INFO - 'PAGER': '/bin/cat', 21:56:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:56:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:56:13 INFO - 'PWD': '/builds/slave/test', 21:56:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:56:13 INFO - 'SHELL': '/bin/bash', 21:56:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HrLB51YzXP/Listeners', 21:56:13 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 21:56:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:56:13 INFO - 'USER': 'cltbld', 21:56:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:56:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:56:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:56:13 INFO - 'XPC_FLAGS': '0x0', 21:56:13 INFO - 'XPC_SERVICE_NAME': '0', 21:56:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:56:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:56:13 INFO - Downloading/unpacking psutil>=3.1.1 21:56:13 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 21:56:13 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 21:56:13 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 21:56:13 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 21:56:13 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 21:56:13 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 21:56:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:56:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:56:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:56:17 INFO - Installing collected packages: psutil 21:56:17 INFO - Running setup.py install for psutil 21:56:17 INFO - building 'psutil._psutil_osx' extension 21:56:17 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 21:56:18 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 21:56:18 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 21:56:18 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 21:56:18 INFO - building 'psutil._psutil_posix' extension 21:56:18 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 21:56:18 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 21:56:18 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 21:56:18 INFO - ^ 21:56:18 INFO - 1 warning generated. 21:56:18 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 21:56:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:56:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:56:18 INFO - Successfully installed psutil 21:56:18 INFO - Cleaning up... 21:56:18 INFO - Return code: 0 21:56:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:56:18 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')]} 21:56:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:56:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:18 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')]} 21:56:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:56:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:18 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 0x10074eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100751768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce7a588e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10052cd30>, '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 0x100453db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100521be8>, '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.aWuXcfye6E/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.HrLB51YzXP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 21:56:18 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 21:56:18 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 21:56:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aWuXcfye6E/Render', 21:56:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:56:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:56:18 INFO - 'HOME': '/Users/cltbld', 21:56:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:56:18 INFO - 'LOGNAME': 'cltbld', 21:56:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:56:18 INFO - 'MOZ_NO_REMOTE': '1', 21:56:18 INFO - 'NO_EM_RESTART': '1', 21:56:18 INFO - 'PAGER': '/bin/cat', 21:56:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:56:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:56:18 INFO - 'PWD': '/builds/slave/test', 21:56:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:56:18 INFO - 'SHELL': '/bin/bash', 21:56:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HrLB51YzXP/Listeners', 21:56:18 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 21:56:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:56:18 INFO - 'USER': 'cltbld', 21:56:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:56:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:56:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:56:18 INFO - 'XPC_FLAGS': '0x0', 21:56:18 INFO - 'XPC_SERVICE_NAME': '0', 21:56:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:56:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:56:18 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:56:18 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 21:56:18 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 21:56:18 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 21:56:18 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 21:56:18 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 21:56:18 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 21:56:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:56:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:56:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:56:21 INFO - Installing collected packages: mozsystemmonitor 21:56:21 INFO - Running setup.py install for mozsystemmonitor 21:56:21 INFO - Successfully installed mozsystemmonitor 21:56:21 INFO - Cleaning up... 21:56:21 INFO - Return code: 0 21:56:21 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:56:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:56:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:56:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:56:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:56:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10074eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100751768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce7a588e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10052cd30>, '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 0x100453db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100521be8>, '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.aWuXcfye6E/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.HrLB51YzXP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 21:56:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 21:56:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 21:56:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aWuXcfye6E/Render', 21:56:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:56:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:56:21 INFO - 'HOME': '/Users/cltbld', 21:56:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:56:21 INFO - 'LOGNAME': 'cltbld', 21:56:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:56:21 INFO - 'MOZ_NO_REMOTE': '1', 21:56:21 INFO - 'NO_EM_RESTART': '1', 21:56:21 INFO - 'PAGER': '/bin/cat', 21:56:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:56:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:56:21 INFO - 'PWD': '/builds/slave/test', 21:56:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:56:21 INFO - 'SHELL': '/bin/bash', 21:56:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HrLB51YzXP/Listeners', 21:56:21 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 21:56:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:56:21 INFO - 'USER': 'cltbld', 21:56:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:56:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:56:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:56:21 INFO - 'XPC_FLAGS': '0x0', 21:56:21 INFO - 'XPC_SERVICE_NAME': '0', 21:56:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:56:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:56:22 INFO - Downloading/unpacking jsonschema==2.5.1 21:56:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:56:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:56:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:56:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:56:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:56:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:56:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:56:24 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:56: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 21:56: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 21:56: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 21:56: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 21:56: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 21:56: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 21:56:24 INFO - Downloading functools32-3.2.3-2.tar.gz 21:56:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:56:25 INFO - warning: no files found matching '*.txt' 21:56:25 INFO - no previously-included directories found matching 'build' 21:56:25 INFO - no previously-included directories found matching 'dist' 21:56:25 INFO - no previously-included directories found matching '.git*' 21:56:25 INFO - Installing collected packages: jsonschema, functools32 21:56:25 INFO - Running setup.py install for functools32 21:56:25 INFO - warning: no files found matching '*.txt' 21:56:25 INFO - no previously-included directories found matching 'build' 21:56:25 INFO - no previously-included directories found matching 'dist' 21:56:25 INFO - no previously-included directories found matching '.git*' 21:56:25 INFO - Successfully installed jsonschema functools32 21:56:25 INFO - Cleaning up... 21:56:25 INFO - Return code: 0 21:56:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 21:56:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:56:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:56:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:56:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:56:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10074eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100751768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce7a588e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10052cd30>, '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 0x100453db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100521be8>, '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.aWuXcfye6E/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.HrLB51YzXP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 21:56:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 21:56:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 21:56:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aWuXcfye6E/Render', 21:56:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:56:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:56:25 INFO - 'HOME': '/Users/cltbld', 21:56:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:56:25 INFO - 'LOGNAME': 'cltbld', 21:56:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:56:25 INFO - 'MOZ_NO_REMOTE': '1', 21:56:25 INFO - 'NO_EM_RESTART': '1', 21:56:25 INFO - 'PAGER': '/bin/cat', 21:56:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:56:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:56:25 INFO - 'PWD': '/builds/slave/test', 21:56:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:56:25 INFO - 'SHELL': '/bin/bash', 21:56:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HrLB51YzXP/Listeners', 21:56:25 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 21:56:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:56:25 INFO - 'USER': 'cltbld', 21:56:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:56:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:56:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:56:25 INFO - 'XPC_FLAGS': '0x0', 21:56:25 INFO - 'XPC_SERVICE_NAME': '0', 21:56:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:56:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:56:25 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 21:56:25 INFO - Cleaning up... 21:56:25 INFO - Return code: 0 21:56:25 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:56:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:56:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:56:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:56:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:56:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10074eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100751768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce7a588e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10052cd30>, '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 0x100453db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100521be8>, '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.aWuXcfye6E/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.HrLB51YzXP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 21:56:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 21:56:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 21:56:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aWuXcfye6E/Render', 21:56:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:56:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:56:25 INFO - 'HOME': '/Users/cltbld', 21:56:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:56:25 INFO - 'LOGNAME': 'cltbld', 21:56:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:56:25 INFO - 'MOZ_NO_REMOTE': '1', 21:56:25 INFO - 'NO_EM_RESTART': '1', 21:56:25 INFO - 'PAGER': '/bin/cat', 21:56:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:56:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:56:25 INFO - 'PWD': '/builds/slave/test', 21:56:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:56:25 INFO - 'SHELL': '/bin/bash', 21:56:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HrLB51YzXP/Listeners', 21:56:25 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 21:56:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:56:25 INFO - 'USER': 'cltbld', 21:56:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:56:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:56:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:56:25 INFO - 'XPC_FLAGS': '0x0', 21:56:25 INFO - 'XPC_SERVICE_NAME': '0', 21:56:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:56:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:56:25 INFO - Downloading/unpacking blobuploader==1.2.4 21:56:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:56:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:56:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:56:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:56:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:56:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:56:28 INFO - Downloading blobuploader-1.2.4.tar.gz 21:56:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:56:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:56:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:56:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:56:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:56:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:56:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:56:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:56:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:56:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:56:29 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 21:56:29 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 21:56:29 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 21:56:29 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 21:56:29 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 21:56:29 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 21:56:29 INFO - Downloading docopt-0.6.1.tar.gz 21:56:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:56:29 INFO - Installing collected packages: blobuploader, requests, docopt 21:56:29 INFO - Running setup.py install for blobuploader 21:56:29 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:56:29 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:56:29 INFO - Running setup.py install for requests 21:56:30 INFO - Running setup.py install for docopt 21:56:30 INFO - Successfully installed blobuploader requests docopt 21:56:30 INFO - Cleaning up... 21:56:30 INFO - Return code: 0 21:56:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:56:30 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')]} 21:56:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:56:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:30 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')]} 21:56:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:56:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:30 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 0x10074eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100751768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce7a588e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10052cd30>, '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 0x100453db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100521be8>, '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.aWuXcfye6E/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.HrLB51YzXP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 21:56:30 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 21:56:30 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 21:56:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aWuXcfye6E/Render', 21:56:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:56:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:56:30 INFO - 'HOME': '/Users/cltbld', 21:56:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:56:30 INFO - 'LOGNAME': 'cltbld', 21:56:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:56:30 INFO - 'MOZ_NO_REMOTE': '1', 21:56:30 INFO - 'NO_EM_RESTART': '1', 21:56:30 INFO - 'PAGER': '/bin/cat', 21:56:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:56:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:56:30 INFO - 'PWD': '/builds/slave/test', 21:56:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:56:30 INFO - 'SHELL': '/bin/bash', 21:56:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HrLB51YzXP/Listeners', 21:56:30 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 21:56:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:56:30 INFO - 'USER': 'cltbld', 21:56:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:56:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:56:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:56:30 INFO - 'XPC_FLAGS': '0x0', 21:56:30 INFO - 'XPC_SERVICE_NAME': '0', 21:56:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:56:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:56:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:56:30 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-RPbMVI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:56:31 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-xoWe3E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:56:31 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-p6VkWc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:56:31 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-wYtW9F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:56:31 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-b0i3W6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:56:31 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-16VoTG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:56:31 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-Iv9WFo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:56:31 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-YVpg62-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:56:31 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-urTSCP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:56:31 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-S7euNi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:56:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:56:31 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-l7g2Cd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:56:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:56:32 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-a8PKr8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:56:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:56:32 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-jqIiOw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:56:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:56:32 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-VX8CwN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:56:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:56:32 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-sW4E5_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:56:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:56:32 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-fuPzPO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:56:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:56:32 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-5iWDov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:56:32 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:56:32 INFO - Running setup.py install for manifestparser 21:56:32 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:56:32 INFO - Running setup.py install for mozcrash 21:56:32 INFO - Running setup.py install for mozdebug 21:56:33 INFO - Running setup.py install for mozdevice 21:56:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:56:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:56:33 INFO - Running setup.py install for mozfile 21:56:33 INFO - Running setup.py install for mozhttpd 21:56:33 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:56:33 INFO - Running setup.py install for mozinfo 21:56:33 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:56:33 INFO - Running setup.py install for mozInstall 21:56:33 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:56:33 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:56:33 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:56:33 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:56:33 INFO - Running setup.py install for mozleak 21:56:34 INFO - Running setup.py install for mozlog 21:56:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:56:34 INFO - Running setup.py install for moznetwork 21:56:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:56:34 INFO - Running setup.py install for mozprocess 21:56:34 INFO - Running setup.py install for mozprofile 21:56:34 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:56:34 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:56:34 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:56:34 INFO - Running setup.py install for mozrunner 21:56:34 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:56:34 INFO - Running setup.py install for mozscreenshot 21:56:34 INFO - Running setup.py install for moztest 21:56:35 INFO - Running setup.py install for mozversion 21:56:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:56:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:56:35 INFO - Cleaning up... 21:56:35 INFO - Return code: 0 21:56:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:56:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:56:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:56:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:56:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:56:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:35 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 0x10074eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100751768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce7a588e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10052cd30>, '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 0x100453db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100521be8>, '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.aWuXcfye6E/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.HrLB51YzXP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 21:56:35 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 21:56:35 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 21:56:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aWuXcfye6E/Render', 21:56:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:56:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:56:35 INFO - 'HOME': '/Users/cltbld', 21:56:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:56:35 INFO - 'LOGNAME': 'cltbld', 21:56:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:56:35 INFO - 'MOZ_NO_REMOTE': '1', 21:56:35 INFO - 'NO_EM_RESTART': '1', 21:56:35 INFO - 'PAGER': '/bin/cat', 21:56:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:56:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:56:35 INFO - 'PWD': '/builds/slave/test', 21:56:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:56:35 INFO - 'SHELL': '/bin/bash', 21:56:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HrLB51YzXP/Listeners', 21:56:35 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 21:56:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:56:35 INFO - 'USER': 'cltbld', 21:56:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:56:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:56:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:56:35 INFO - 'XPC_FLAGS': '0x0', 21:56:35 INFO - 'XPC_SERVICE_NAME': '0', 21:56:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:56:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:56:35 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-RNRi1q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:56:35 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)) 21:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:56:35 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-PsIE7p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:56:35 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)) 21:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:56:35 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-G23FYx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:56:35 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)) 21:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:56:35 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-uiK9E4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:56:35 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)) 21:56:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:56:35 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-d5HFTq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:56:36 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)) 21:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:56:36 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-50BlbS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:56:36 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)) 21:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:56:36 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-Bsj5aK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:56:36 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)) 21:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:56:36 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-Bsz2_5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:56:36 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)) 21:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:56:36 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-fwVXGJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:56:36 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)) 21:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:56:36 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-HCkt74-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:56:36 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)) 21:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:56:36 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-r005ge-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:56:36 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)) 21:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:56:36 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-WKgcbh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:56:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 21:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:56:36 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-dlkKz4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:56:36 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)) 21:56:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:56:36 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-pnbYMb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:56:37 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)) 21:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:56:37 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-Lpq0vw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:56:37 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)) 21:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:56:37 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-LUm0s9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:56:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 21:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:56:37 INFO - Running setup.py (path:/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/pip-ahdcm1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:56:37 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)) 21:56:37 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)) 21:56:37 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)) 21:56:37 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)) 21:56:37 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)) 21:56:37 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)) 21:56:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:56:37 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 21:56:37 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 21:56:37 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 21:56:37 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 21:56:37 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 21:56:37 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 21:56:39 INFO - Downloading blessings-1.6.tar.gz 21:56:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:56:40 INFO - Installing collected packages: blessings 21:56:40 INFO - Running setup.py install for blessings 21:56:40 INFO - Successfully installed blessings 21:56:40 INFO - Cleaning up... 21:56:40 INFO - Return code: 0 21:56:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:56:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:56:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:56:40 INFO - Reading from file tmpfile_stdout 21:56:40 INFO - Current package versions: 21:56:40 INFO - blessings == 1.6 21:56:40 INFO - blobuploader == 1.2.4 21:56:40 INFO - docopt == 0.6.1 21:56:40 INFO - functools32 == 3.2.3-2 21:56:40 INFO - jsonschema == 2.5.1 21:56:40 INFO - manifestparser == 1.1 21:56:40 INFO - mozInstall == 1.12 21:56:40 INFO - mozcrash == 1.0 21:56:40 INFO - mozdebug == 0.1 21:56:40 INFO - mozdevice == 0.49 21:56:40 INFO - mozfile == 1.2 21:56:40 INFO - mozhttpd == 0.7 21:56:40 INFO - mozinfo == 0.9 21:56:40 INFO - mozleak == 0.1 21:56:40 INFO - mozlog == 3.4 21:56:40 INFO - moznetwork == 0.27 21:56:40 INFO - mozprocess == 0.24 21:56:40 INFO - mozprofile == 0.28 21:56:40 INFO - mozrunner == 6.13 21:56:40 INFO - mozscreenshot == 0.1 21:56:40 INFO - mozsystemmonitor == 0.3 21:56:40 INFO - moztest == 0.8 21:56:40 INFO - mozversion == 1.4 21:56:40 INFO - psutil == 3.1.1 21:56:40 INFO - requests == 1.2.3 21:56:40 INFO - wsgiref == 0.1.2 21:56:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:56:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 21:56:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:56:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56: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')]} 21:56:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:56:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:41 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 0x10074eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100751768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce7a588e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10052cd30>, '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 0x100453db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100521be8>, '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.aWuXcfye6E/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.HrLB51YzXP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 21:56:41 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 21:56:41 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 21:56:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aWuXcfye6E/Render', 21:56:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:56:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:56:41 INFO - 'HOME': '/Users/cltbld', 21:56:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:56:41 INFO - 'LOGNAME': 'cltbld', 21:56:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:56:41 INFO - 'MOZ_NO_REMOTE': '1', 21:56:41 INFO - 'NO_EM_RESTART': '1', 21:56:41 INFO - 'PAGER': '/bin/cat', 21:56:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:56:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:56:41 INFO - 'PWD': '/builds/slave/test', 21:56:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:56:41 INFO - 'SHELL': '/bin/bash', 21:56:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HrLB51YzXP/Listeners', 21:56:41 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 21:56:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:56:41 INFO - 'USER': 'cltbld', 21:56:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:56:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:56:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:56:41 INFO - 'XPC_FLAGS': '0x0', 21:56:41 INFO - 'XPC_SERVICE_NAME': '0', 21:56:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:56:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:56:41 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)) 21:56:41 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)) 21:56:41 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)) 21:56:41 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)) 21:56:41 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)) 21:56:41 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)) 21:56:41 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)) 21:56:41 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)) 21:56:41 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)) 21:56:41 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)) 21:56:41 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)) 21:56:41 INFO - Cleaning up... 21:56:41 INFO - Return code: 0 21:56:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:56: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')]} 21:56:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:56:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56: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')]} 21:56:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:56:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:56:42 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 0x10074eb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100751768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fce7a588e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10052cd30>, '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 0x100453db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100521be8>, '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.aWuXcfye6E/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.HrLB51YzXP/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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 21:56:42 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 21:56:42 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 21:56:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aWuXcfye6E/Render', 21:56:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:56:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:56:42 INFO - 'HOME': '/Users/cltbld', 21:56:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:56:42 INFO - 'LOGNAME': 'cltbld', 21:56:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:56:42 INFO - 'MOZ_NO_REMOTE': '1', 21:56:42 INFO - 'NO_EM_RESTART': '1', 21:56:42 INFO - 'PAGER': '/bin/cat', 21:56:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:56:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:56:42 INFO - 'PWD': '/builds/slave/test', 21:56:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:56:42 INFO - 'SHELL': '/bin/bash', 21:56:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HrLB51YzXP/Listeners', 21:56:42 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 21:56:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:56:42 INFO - 'USER': 'cltbld', 21:56:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:56:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:56:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:56:42 INFO - 'XPC_FLAGS': '0x0', 21:56:42 INFO - 'XPC_SERVICE_NAME': '0', 21:56:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:56:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:56:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:56:42 INFO - Cleaning up... 21:56:42 INFO - Return code: 0 21:56:42 INFO - Running post-action listener: _resource_record_post_action 21:56:42 INFO - Running post-action listener: _start_resource_monitoring 21:56:42 INFO - Starting resource monitoring. 21:56:42 INFO - [mozharness: 2017-01-20 05:56:42.340814Z] Finished create-virtualenv step (success) 21:56:42 INFO - [mozharness: 2017-01-20 05:56:42.341754Z] Running install step. 21:56:42 INFO - Running pre-action listener: _resource_record_pre_action 21:56:42 INFO - Running main action method: install 21:56:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:56:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:56:42 INFO - Reading from file tmpfile_stdout 21:56:42 INFO - Detecting whether we're running mozinstall >=1.0... 21:56:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:56:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:56:42 INFO - Reading from file tmpfile_stdout 21:56:42 INFO - Output received: 21:56:42 INFO - Usage: mozinstall [options] installer 21:56:42 INFO - Options: 21:56:42 INFO - -h, --help show this help message and exit 21:56:42 INFO - -d DEST, --destination=DEST 21:56:42 INFO - Directory to install application into. [default: 21:56:42 INFO - "/builds/slave/test"] 21:56:42 INFO - --app=APP Application being installed. [default: firefox] 21:56:42 INFO - mkdir: /builds/slave/test/build/application 21:56:42 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'] 21:56:42 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 21:57:04 INFO - Reading from file tmpfile_stdout 21:57:04 INFO - Output received: 21:57:04 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 21:57:04 INFO - Running post-action listener: _resource_record_post_action 21:57:04 INFO - [mozharness: 2017-01-20 05:57:04.578507Z] Finished install step (success) 21:57:04 INFO - [mozharness: 2017-01-20 05:57:04.578654Z] Running run-tests step. 21:57:04 INFO - Running pre-action listener: _resource_record_pre_action 21:57:04 INFO - Running main action method: run_tests 21:57:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:57:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:57:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:57:04 INFO - 2017-01-19 21:57:04.593 screenresolution[1623:7314] starting screenresolution argv=screenresolution get 21:57:04 INFO - 2017-01-19 21:57:04.595 screenresolution[1623:7314] Display 0: 1600x1200x32@60 21:57:04 INFO - 2017-01-19 21:57:04.601 screenresolution[1624:7316] starting screenresolution argv=screenresolution list 21:57:04 INFO - Available Modes on Display 0 21:57:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:57:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:57:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:57:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:57:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:57:04 INFO - Intel Iris: 21:57:04 INFO - Chipset Model: Intel Iris 21:57:04 INFO - Type: GPU 21:57:04 INFO - Bus: Built-In 21:57:04 INFO - VRAM (Dynamic, Max): 1536 MB 21:57:04 INFO - Vendor: Intel (0x8086) 21:57:04 INFO - Device ID: 0x0a2e 21:57:04 INFO - Revision ID: 0x0009 21:57:04 INFO - Displays: 21:57:04 INFO - Display: 21:57:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:57:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:57:04 INFO - Display Serial Number: 1600x1200 60 21:57:04 INFO - Main Display: Yes 21:57:04 INFO - Mirror: Off 21:57:04 INFO - Online: Yes 21:57:04 INFO - Rotation: Supported 21:57:04 INFO - Return code: 0 21:57:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:57:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:57:04 INFO - Python 2.7.3 21:57:04 INFO - Return code: 0 21:57:04 INFO - grabbing minidump binary from tooltool 21:57:04 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')]} 21:57:04 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 0x10052cd30>, '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 0x100453db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100521be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:57:04 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 21:57:04 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 21:57:04 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 21:57:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:57:04 INFO - Return code: 0 21:57:04 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 21:57:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:57:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:57:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 21:57:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:57:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JV6kSIXdSnqzhHQCwyNtng/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0372', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 21:57:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JV6kSIXdSnqzhHQCwyNtng/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0372 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 21:57:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aWuXcfye6E/Render', 21:57:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 21:57:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 21:57:04 INFO - 'HOME': '/Users/cltbld', 21:57:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 21:57:04 INFO - 'LOGNAME': 'cltbld', 21:57:04 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:57:04 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 21:57:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:57:04 INFO - 'MOZ_NO_REMOTE': '1', 21:57:04 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:57:04 INFO - 'NO_EM_RESTART': '1', 21:57:04 INFO - 'PAGER': '/bin/cat', 21:57:04 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 21:57:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:57:04 INFO - 'PWD': '/builds/slave/test', 21:57:04 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:57:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 21:57:04 INFO - 'SHELL': '/bin/bash', 21:57:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.HrLB51YzXP/Listeners', 21:57:04 INFO - 'TMPDIR': '/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/', 21:57:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 21:57:04 INFO - 'USER': 'cltbld', 21:57:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 21:57:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 21:57:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:57:04 INFO - 'XPC_FLAGS': '0x0', 21:57:04 INFO - 'XPC_SERVICE_NAME': '0', 21:57:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 21:57:04 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JV6kSIXdSnqzhHQCwyNtng/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0372', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 21:57:05 INFO - mozversion application_buildid: 20170119193525 21:57:05 INFO - mozversion application_changeset: 19949cfb8d7e297ad74ed4ed9186d3313e3d22ae 21:57:05 INFO - mozversion application_display_name: Nightly 21:57:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:57:05 INFO - mozversion application_name: Firefox 21:57:05 INFO - mozversion application_remotingname: firefox 21:57:05 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 21:57:05 INFO - mozversion application_vendor: Mozilla 21:57:05 INFO - mozversion application_version: 53.0a1 21:57:05 INFO - mozversion platform_buildid: 20170119193525 21:57:05 INFO - mozversion platform_changeset: 19949cfb8d7e297ad74ed4ed9186d3313e3d22ae 21:57:05 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 21:57:05 INFO - mozversion platform_version: 53.0a1 21:57:05 INFO - using testdate: 1484891825 21:57:05 INFO - actual date: 1484891825 21:57:05 INFO - starting webserver on 'localhost:49236' 21:57:05 INFO - SUITE-START | Running 2 tests 21:57:05 INFO - TEST-START | dromaeo_css 21:57:05 INFO - operating with platform_type : mac_ 21:57:05 INFO - Initialising browser for dromaeo_css test... 21:57:05 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/tmphai0B4/profile http://localhost:49236/getInfo.html) 21:57:07 INFO - PROCESS | 1634 | 2017-01-19 21:57:07.996 plugin-container[1636:7487] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x903f, name = 'com.apple.tsm.portname' 21:57:07 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:57:08 INFO - PROCESS | 1634 | 2017-01-19 21:57:08.000 plugin-container[1636:7487] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950f, name = 'com.apple.CFPasteboardClient' 21:57:08 INFO - PROCESS | 1634 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:57:08 INFO - PROCESS | 1634 | 2017-01-19 21:57:08.000 plugin-container[1636:7487] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:57:08 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 21:57:08 INFO - PROCESS | 1634 | colorDepth:24 21:57:08 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 21:57:08 INFO - PROCESS | 1634 | __metrics 21:57:08 ERROR - PROCESS | 1634 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3269: TypeError: this.tabs is undefined 21:57:08 INFO - TEST-INFO | 1634: exit 0 21:57:08 INFO - Browser initialized. 21:57:08 INFO - Running cycle 1/1 for dromaeo_css test... 21:57:08 INFO - TEST-INFO | started process 1637 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/tmphai0B4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:57:11 INFO - PROCESS | 1637 | 2017-01-19 21:57:11.325 plugin-container[1638:7612] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8943, name = 'com.apple.tsm.portname' 21:57:11 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:57:11 INFO - PROCESS | 1637 | 2017-01-19 21:57:11.326 plugin-container[1638:7612] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4423, name = 'com.apple.CFPasteboardClient' 21:57:11 INFO - PROCESS | 1637 | See /usr/include/servers/bootstrap_defs.h for the error codes. 21:57:11 INFO - PROCESS | 1637 | 2017-01-19 21:57:11.326 plugin-container[1638:7612] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 21:57:21 INFO - PROCESS | 1637 | RSS: Main: 232390656 21:57:21 INFO - PROCESS | 1637 | 22:00:24 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49236/tests/dromaeo/cssquery-ext.html) 22:00:25 INFO - PROCESS | 1637 | RSS: Main: 252977152 22:00:25 INFO - PROCESS | 1637 | 22:02:09 INFO - PROCESS | 1637 | ************************* 22:02:09 INFO - PROCESS | 1637 | A coding exception was thrown and uncaught in a Task. 22:02:09 INFO - PROCESS | 1637 | 22:02:09 ERROR - PROCESS | 1637 | Full message: TypeError: NetworkError when attempting to fetch resource. 22:02:09 INFO - PROCESS | 1637 | Full stack: 22:02:09 INFO - PROCESS | 1637 | ************************* 22:03:27 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-ext.html (next: http://localhost:49236/tests/dromaeo/cssquery-jquery.html) 22:03:28 INFO - PROCESS | 1637 | RSS: Main: 263389184 22:03:28 INFO - PROCESS | 1637 | 22:06:30 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49236/tests/dromaeo/cssquery-mootools.html) 22:06:31 INFO - PROCESS | 1637 | RSS: Main: 272052224 22:06:31 INFO - PROCESS | 1637 | 22:09:33 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49236/tests/dromaeo/cssquery-prototype.html) 22:09:33 INFO - PROCESS | 1637 | RSS: Main: 268206080 22:09:33 INFO - PROCESS | 1637 | 22:12:36 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49236/tests/dromaeo/cssquery-yui.html) 22:12:37 INFO - PROCESS | 1637 | RSS: Main: 273076224 22:12:37 INFO - PROCESS | 1637 | 22:15:39 INFO - PROCESS | 1637 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-yui.html (next: http://localhost:49236/tests/dromaeo/cssquery-dojo.html) 22:15:40 INFO - PROCESS | 1637 | RSS: Main: 275447808 22:15:40 INFO - PROCESS | 1637 | 22:15:40 INFO - PROCESS | 1637 | __start_tp_report 22:15:40 INFO - PROCESS | 1637 | _x_x_mozilla_page_load 22:15:40 INFO - PROCESS | 1637 | _x_x_mozilla_page_load_details 22:15:40 INFO - PROCESS | 1637 | |i|pagename|runs| 22:15:40 INFO - PROCESS | 1637 | |0;dojo.html;2873.0806142034553;3385.5937287525485;3388.1698983748984;3388.644192359802;3411.8635254589817;5437.157240627699;5727.885442291162;5733.65598064116;5803.767849286029;5804.187413762066;5174.637775355726;5663.8584035399135;5810.883782324351;5868.61853979491;5878.676672782995;4322.805473753673;4495.98840260942;5306.283651707009;5357.303550538424;5411.540019098362;5098.286239926395;5100.744962751866;5403.081476149064;5410.242566040757;5418.8916221675545;5060.291559181699;5227.634065615409;5380.946190538084;5405.27028851104;5407.972485227794;4339.435873336446;5213.869653258661;5285.233641122054;5286.577073834084;5304.761285742151;4358.738475691469;5257.526822585947;5288.603354748409;5297.311349524537;5311.7344132793205;5097.108006098918;5744.971275143597;5767.307923049261;5818.709064546798;5899.941000589982;6155.72299246535;6309.652969086698;6327.46216571592;6343.809685709436;6345.777897773602;5553.861153471155;6001.9196544621955;6211.409916058013;6275.6548389838545;6308.653024083674;5973.313068997078;5980.308861012518;5992.760289588455;6011.909821352683;6038.6678732670125;6244.937550624436;6276.246850377984;6318.810435686844;6332.366763323723;6348.206474190726;6093.238345206849;6143.723532441051;6153.292371377349;6218.9378106219265;6231.781887633911;6261.530385221126;6273.498120150377;6299.874002519924;6304.716287767062;6307.463865571386;5048.242763585492;6106.369660900722;6145.26256849172;6161.137440758297;6242.09489624002;6209.5032397408095;6246.937530624727;6286.654234017173;6296.937030629635;6299.811005669746;4953.331300274417;4999.925001124986;5040.6219533534395;5042.243663450438;5061.013102445061;4462.129884672458;4474.753888536162;4553.157665831765;4569.405977223007;4596.10375976688;5996.4603185713495;6001.639901605918;6005.549583781234;6008;6012.669303188279;6235.376462353728;6294.213223347082;6297.275813281532;6318;6320.525960552977;5851.502622277069;5937.139114828326;5938.6139900907165;5967.910481342783;5968.552358573124;5589.077802162679;5589.664620122724;5598.384177740445;5624.3250809903075;5632.986062508706;6420.486361091102;6420.486361091195;6472.190976127984;6493;6499.740010399721;6217;6251.468625166912;6257.2491301044165;6309.873802524015;6323;6306.684665766785;6431.292557818542;6435.03474478832;6468.935310646833;6474.449671777763;6276.152719382825;6290.150829637941;6297.370262973666;6302.590331628429;6318.336574659595;4579.152856721517;6102.0651310563235;6192.0711893214575;6292.465140463112;6379.553431259767;5872.1778950946045;6129.175413489998;6157.476614487639;6176.969115154396;6210.3168651449205;5192.506711862367;6047.455728984412;6166.845828854314;6206.782762603287;6209.565330427007;5370.677759334452;5491.148871924858;5995.91006134917;6034.185384972972;6064.939350606437;6132.753252973555;6160.9383906160365;6183.443490085914;6186.690665466798;6254;5997.5501837363245;6137.478314343152;6159.876802464015;6161.137440758387;6174.506039516918 22:15:40 INFO - PROCESS | 1637 | |1;ext.html;433.9197248509024;462.70155749541544;499.48054023815234;523.4451481429685;530.19675192084;40088.79955600222;42239.78880105599;42268.78865605671;42337.94155146123;42414.36378454325;42518.787406063006;42913.35629965553;42967.78516107415;42995.78502107493;43020;9449;9671.129598336149;9709;11125.165612579049;11470.942645286803;11255.887441125566;11353.978141967222;11360;11380.487878045507;11389.715257118556;11391.145664075186;11398.430078496041;11405.486753096131;11412.372319522423;11413.714657133596;11322.377269250188;11370.488328025258;11370.886291137065;11373.147013973985;11377.1467139964;11136.109111271078;11200.21598488098;11213.66359009231;11228.606998755004;11230;37321.626783732085;37573.06067348311;37675;37759.811200944096;37895.431568526496;3565.5186549815703;3711.962880371189;4010.4786377770993;4042.7776472294013;4056.492938382702;20650.48373790652;26480.337991550172;26647;27273.63631818401;28242.717572824215;3115.17207853408;3161.8260995645232;3214.357128574271;3217.549543063973;3256.5115232715048;334673;449686.75156624336;450533;451176;452571;55544.444555554735;56027.43972560222;56128.15807762805;56336.71831640816;56384.436155637915;49297.26054109191;57688.71155644279;57742.422575774544;58115;58173.54566135796;14845.851541484446;15241.695166096615;15369;15392.384304627913;15422.228888555528;9796.853047204298;10721;11008;11972.281663100242;12024.458899349374;14564.85435145635;18153.82000169985;18246.908765456086;18264.543386415447;18299.908500457677;7960.323372513401;8139.674413023531;8179.509229446133;8184;8186.099529051799;869.2828416556366;881.8809460722848;889.2485849457198;909.8498747706554;919.9494027828533;29925;30083.548746768814;30460.23849403738;30490.390192196475;30537.69462305393;1081.7133459633203;1089.096050278267;1114.899659030691;1128.7968165730083;1158.7276989907366;15596.688066238612;15781.68436631261;15892.205389730698;16117.838821611635;16475.423360182336;637543;641823;644373;645394;645435;59106.40893591095;59536;59579.40420595825;59614.403855961755;59710.70144649249;1413.9929300353433;1462.9414823407158;1470.3309993952726;1477.4976507987335;1478.0540454109378;24918.875405622857;28191;28485;28774;30079.849600751855;39611.60388396079;52161.95676086533;52399;52482;52544.737276313375;596150;599418;600960;603183;604250;25469.61795573105;26173;26259.21222363332;27037.86481067582;39499.80250098731;42851;43354.56645433505;43573.12853742971;43852.12295754131;44103.89740256391;32395.866144685766;33555.49666755049;35698.28603427865;35832;36829.44755828718;35588.64411355957;35596;36139.45790813088;36154.4576831353;36544.45183322199;34102.65897340995;34664.65335346614;34776.47835282422;35163.82418087996;35857.82071089628;25731;30764.846175768977;31529.68470315359;31949.520757189126;31973.36053278968;22464.887675561517;23780.643290351003;24026.51946961016;24307.27078187657;24353.025878964643 22:15:40 INFO - PROCESS | 1637 | |2;jquery.html;330.9751768617354;385.62016413832373;391.15900813251517;404.25616864968487;411.99267790252844;15754.06067046373;20526.794732052695;20882.37352879414;20934;21124.366269011916;18838.43484695458;20155.596888062268;20861.374158775223;21514.462138446546;21848.344549663496;4759.428868535781;5591.888162236763;5828.009238429478;5847.8245652630485;5878.970605146989;5025.447200807908;5046.899062018758;5164.6384753067305;5756;5833.300003999526;5560.582956278296;5724.624280923412;5772.740226689809;5783.103618939072;5797.507211886974;5666.376698563155;5696;5719.19931209631;5746;5753.741081651336;4859.805607775685;4983.825566105169;5008.599312055063;5013.749312534359;5021.949780502185;20182.89908550448;25438.745612543822;25833.48333033329;25929.61105583418;25965.870170649214;2754.297654098212;2776.5557510798176;2802.691703912568;2804.9158525244275;2831.278024103861;15227.619309517351;15608.60978475536;15714.21428928549;15836.683266334609;16341.591460213589;1627.560558649158;1644.6464010237737;1892.4228110426313;1894;1898.1268616436455;127277.36361318227;129281;130570.34714826365;131868.02197966943;132388;10925.781484370427;11499.770004600028;11666.591669291534;11717.238379505476;11949.22330048544;14146.080504767157;14227.78658320126;14231.932605054455;14243.002989790823;14246.287685615885;8002;8199.180081991872;8200.507969521848;8321.750347489586;8339.666413343519;7907.604619768988;7966.0839003514175;7976.800579985546;8127.796805079802;8148.633311500997;7005.299470053056;7236.819079522948;7246.782596522113;7249.601271930094;7258.237885021997;1917.9616407672047;1955.2863204930381;1957.9216831326587;1966.0562929793493;1966.7443232379699;1052.6789329254498;1064.5050051725984;1066.6106870992032;1079.7840431913648;1120.6918097523246;22304.665430018562;22657.660135097987;22760.31719048431;22869.771302287096;22944.311670649906;1087.1846115413439;1090.1333439915286;1111.0223003756641;1123.359894553944;1129.1644183304295;11086.833697494543;11712.121590880719;11880.584179553674;11902.226355286879;11928.940355298342;723749;729313;734471;744478;750658;113032;113952.29071563933;114438.4278078621;125738.37130814287;128007;1029.4183786160795;1040.4433628008978;1060.7719340341864;1064.5209655654987;1064.920130990179;19079.904600476908;19247.615047699248;19431.319903803298;19459.4162175135;19568.80431195727;43635;54319.45680543144;54993.1751023743;55039.724801375734;56607.71696141493;76186;77356.61321693356;78361;78636.60681696555;79074.81387779073;7933.087694915159;8235.711750088718;8256.174382561696;8257.587120643824;8282.875756863772;13502.91976641886;13515.864841351853;13594.456221751414;13841.51554695621;14049.22729249901;10162.441065741456;11208;11227.831582526105;11265.211435199784;12066.215695979734;10654.987776161252;10964.342139471657;11433.59982400612;11567.595134170264;11631.069514438655;11679.474423651127;11706.297622142698;12071.517139314328;12083.939580302042;12130.090243231616;7201.927980720336;7435.144958329645;7587.393008559192;7743.6515356810205;8456.95771521163;10021.899781002388;11463.31220126795;11890.940545297219;11923.880761192277;11928.582499612472 22:15:40 INFO - PROCESS | 1637 | |3;mootools.html;1435.8779503742182;1441.3874103506014;1455.7234125516152;1467.1197281631023;1481.3926290221004;7580.204078571754;7820.3743700503965;8114.350851931846;8567.91432085679;8573.442726222791;7233.963830180842;8072.798180045502;8201.097879233295;8326.209010144026;8452.4505907116;1934.7871734109237;1938.2247101159508;2302.735185453669;2323.570139524185;2347.190219374313;2075.2321640992855;2205.2391924786;2354.1878052072007;2364.7517010713887;2368.798652114572;2285.211601997308;2292.7707229277107;2317.1889838556453;2321;2323.6398358254496;1900.334882791019;1913.3972798568545;2288.5079707862724;2312.5259321839067;2317.918872839451;2262.2195342606774;2269.2851751698163;2295.896684649195;2303.8272129590346;2309.5034567568055;7180.102487189101;8472.152784721478;8559.486430814171;8564.528950907697;8567;4029.5164580250266;4955.959248557807;5093.2105523643895;5187.403448603383;5226.947730522684;2859.6139521164587;3015.9849200754074;3214.9839250803598;3309.8179600121985;3566.8751593694356;1188.696882295018;1198.3049831097942;1219.743853790705;1235.8208059831275;1246.2896149194962;8858.158474944934;10738.194635402298;10860.67417977462;10901.781964360747;10923.563057477691;4501.7974191161475;4656.743879086615;4730.929036064462;4741.786619602126;4793.952060479351;7944.44388892772;9105;9433.528323583794;9523.523823808782;9567.521623918776;5194.142966410575;5269.841904742864;5275.3142091528625;5291.391489978625;5311.814086506954;4151.335786274242;4793.472718001017;4796.664233503621;4799.3760811094335;4822.252550854623;3842.9615703842605;3843.8462461501226;3914.099757055836;3922.5292964844402;3979.144483935968;1232.5932442294202;1276.3299267884436;1279.8528169260653;1281.705207802192;1296.3842174967003;919.2370332623906;934.56075644447;935.2050756856753;942.3238004068342;963.2679163835536;4645.976770116195;4927.310176575283;5378.408375078739;5601.60788744784;5751.3673495916255;574.8074395077613;579.9739011744482;581.1109003225073;585.4409039367479;589.0898561722149;3336.8164750938445;3671.4125739881492;3765.7928813915496;3832.559255685576;3862.304785138646;9545.52272386378;10013.649522266685;10063.19494440443;10103;10143.543540540693;9944.552495137736;9950.35322704022;10029.648962286285;10135.037171468699;10150.137238334824;7750.302472777477;7930.048394192734;7941.761747147595;7949.284564389232;7956.085050219183;5451.318585176853;5530.225768392348;5544.63959842609;5596.832095037155;5615.18579805934;9054.32092593045;9089;9151.542422878963;9226.261899047926;9301.581428835583;9123.863142053007;9240.306976976888;9363.063693630582;9389.436633801994;9425.198858096865;6609.239937407259;6787.185537735306;6911.3779759821855;7108.751193708195;7199.784006479814;7785.493942893694;7990.480618759994;8004.479708819141;8016.078151012708;8018.278354948083;6139.416755408229;6719.496037797087;7112.644367781693;7151.106111736033;7162.46281528877;6144.56988010835;6700.128983232344;6998.475114366341;7031.08595882532;7068.505204635626;5450.601735236642;6388.616682999035;6433.903491447539;6435.06691529735;6439.967800160969;4794.376731024944;5041.823536176363;5048.293238946624;5119.87200319995;5148.562372198405;4394.824207031683;4980.477049909708;5006.924896126634;5032.0690672225755;5283.762230699705 22:15:40 INFO - PROCESS | 1637 | |4;prototype.html;359.15598343891855;361.26662874365013;361.70882439636114;364.49153430963804;368.8008475423272;4886.560209581137;5115.930441734957;5233.633645644806;5460.972695136524;5608;5005.174146265871;5123.359580052494;5447.536959358459;5515.641483303592;5528.226048353234;4051.392291156328;4145.419641250227;4205.285101532747;4254.277017807793;4307.870763877073;4241.363795430695;4246.57534246576;4278.764667943262;4304.741715497064;4386.912261754763;4268.338407546835;4287.614114729674;4292.356146578023;4294.763787991659;4296.957030429687;3583.6595523425494;3872.1094148346037;4039.7880635809433;4294.616487849243;4295;3470.4794280857823;3756.7182461315238;3776.6412190841825;3795.582485926546;3808.8857334280015;4248.362745588156;4705.3647757552635;4825.252085926687;5306.718111212177;5370.301860758115;3823.674987626055;4613.6078433333205;4822.517748225185;4976.527229913152;5011.498850114996;4746.199742152112;5372.865678358073;5619.915701264485;5859.2090067841145;5954.345022047572;4056.7565946043333;4257.9993701480125;4272.594103560188;4279.636230920344;4281.764502952336;5884.823035392938;6094.573379863411;6100.877982440371;6108.816735497898;6163.722632481505;4277.95190178402;4289.485261768607;4303;4305.095929854703;4332.740035597874;3925.5878132795647;4128.876133715993;4407.6914615976575;4418.806922131007;4439.178751930903;4570.268756998864;4688.8124475021295;4773.355596994361;5022.84931452057;5029.64792464524;5209.905919756893;5813.59304848665;5835.724067613634;5853.9170253503235;5854.8629750141745;1423.896480227811;1433.9139651620728;1443.1311451669903;1443.2639353929571;1466.3767898643034;936.7611259128879;942.797932635881;988.367444835306;1005.6781829814389;1016.5120742043861;4304.698671092993;4514.210013247669;4698.1778188816825;5147.948520514747;5330.040592693274;5223.399309079505;5263.26314315996;5788.681622510718;5874.473976190194;6019.307779605314;5090.567301779316;5335.624787674147;5363.980843639696;5394.597404674734;5404.891902161935;2667.5065112954167;2672.759451649361;2876;2890.580865774451;2908.665503467086;4793.352897358882;5554.222408862763;5729.541636669056;5810.477057064884;5858.648481091148;5459.863503412367;5718.17018554067;5773.220615216976;5787.479126878517;5807.709614519257;4994.90010199794;5159.690418574897;5809.854753631109;5823.79616713413;5853.395239118551;4884.58347079343;5040.36995375578;5422.267993820784;5712.542996560348;5746.367899530965;5066.44269130388;5309.65487243328;5369.54358879484;5473.917891231555;5596.944030559643;4997.1260777208545;5004.6246531511015;5400.5418536994;5765.740541675551;5826.883462330815;3786.9431958520095;3849.021662321294;3864.9806750966063;3958.6041395860184;4039.030632648202;4838.76607968028;5197.688138711689;5591.002433260239;5602.691851948182;5667.319921609434;4343.478782546114;5022.17134172872;5230.0585894539345;5251.553617942365;5469.589780766379;4570.474395444567;4772.856814295576;5322.707251101072;5456.743739882898;5814.331351894417;5583.69289689071;5660.320761508646;5713.286363863522;5734.254546908875;5784.884302314014;4715.740634265148;5482.835514934558;5614.326280846324;5718.7998420056765;5796.116262214713;4910.754462276944;5567.498925096761;5645.661260324394;5662.462066103713;5671.702960301085 22:15:40 INFO - PROCESS | 1637 | |5;yui.html;721.7690339091492;723.3598265534999;772.3357912195514;835.8453686068078;840.0801122770566;14871.25643717814;15763.92118039411;15829.762553561706;15864.889457737963;15865.920670396647;13343.132696374729;14361.784573231384;14488.5653430397;14501.854981450182;14513.346899389526;827.954462504562;829.3323874281216;840.6511297811401;841.238678995507;841.7180244618061;831.4221615976899;835.5613303015934;836.0761358698642;840.5293035899886;840.8360369727906;876.1588874680283;884.5179377239419;889.3063410539789;891.5631340643102;891.6879092317702;872.5637181409295;875.6278581602857;884.3500027479797;887.786931136529;888.1739981816908;638.8019713888663;645.9063435801783;646.660503235805;653.4413076819299;653.9280679125293;12139.603945546287;12881.420336084899;13549.119307244997;13579.388927498287;14070.92964535181;9797.559109840075;11915.523379064914;11954.940225298818;12079.758404831855;12370.567030153901;10821.513031913582;10844.511996960156;10917;10987.560497580087;11099.667009989713;3639.8726044588575;3655.890323290279;3670.8069877289986;3675.2465744522574;3718.4794128821986;72018.63990680066;92645.53677231638;99275;103303;110715.23211919687;8428.4942903426;11588.94205528967;11672.533098675958;11678.76642467146;11924;23238.3028509145;23247.582830015228;23388.883055584953;23629.7637023631;23673.644895326583;7945.960270198728;8123.75628731139;8126.837463250702;8197.262246397853;8325.916740832636;4275.187714334266;4283.1433713257475;4380.14587155508;4399.978000109979;4458.4426946631675;3566.32239874423;3621.348157331653;3651.4887915691825;3659.323025240339;3663.432168013962;671.0673261008264;689.6069240532948;693.8300116471396;699.6047233313162;702.115334678309;666.8999650052531;673.6564352180424;712.975045873392;719.9604021778853;721.8881073433627;9163;9373.953130234306;9493.857592136124;10388.428636424918;10499.737506562396;636.6625688385163;660.2044536333707;664.2129077043719;668.38174688414;673.9224989126216;5002.449730529639;5015.3229314042865;5030.84907452777;5051.116054690415;5060.7722652480725;107390.57617797775;107948;108070;108130;109141;57089.71455142698;74657.13357166115;75347;76216.61891690506;78471;51790.22314665283;71194;72100.55798884098;77168;81110.59444702856;10754.085902698045;11120.054795342487;11357.659270221906;11371.374574398487;11437.485313160762;46520.7673961628;46834.76582617065;51615;51824.48175518347;52875.73562132318;52644;66738;67693.96918092466;70103.59792804014;73372.89940650787;12982.545610903573;13140;13528.255945923069;13564.38960246811;13959.790603141164;14640.560783176521;14789.630259243606;14889.47886823956;15165.393384264506;15358.692826143639;12959.287239201934;13302.875839160613;13612.38744256491;13654.249016304198;13927.4429022842;13731.45074197021;13758.518451854137;13853.238071905738;13953.164065223364;14331.426742930584;13568.457261709422;13637.795433068313;13673.86326136726;13983;14129.222892740587;9563.665271715456;10950.616728414467;11097;11167.497462614365;11301.71745706331;9474.57364418589;9701.757456063655;10870.641169853769;10872.347659140476;10979.28634638746 22:15:40 INFO - PROCESS | 1637 | __end_tp_report 22:15:40 INFO - PROCESS | 1637 | __start_cc_report 22:15:40 INFO - PROCESS | 1637 | _x_x_mozilla_cycle_collect,372 22:15:40 INFO - PROCESS | 1637 | __end_cc_report 22:15:40 INFO - PROCESS | 1637 | __startTimestamp1484892940136__endTimestamp 22:15:40 INFO - PROCESS | 1637 | 22:15:40 INFO - PROCESS | 1637 | ------- Summary: start ------- 22:15:40 INFO - PROCESS | 1637 | Number of tests: 6 22:15:40 INFO - PROCESS | 1637 | 22:15:40 INFO - PROCESS | 1637 | [#0] dojo.html Cycles:165 Average:5768.11 Median:6011.91 stddev:692.79 (11.5%) stddev-sans-first:656.63 22:15:40 INFO - PROCESS | 1637 | Values: 2873.1 3385.6 3388.2 3388.6 3411.9 5437.2 5727.9 5733.7 5803.8 5804.2 5174.6 5663.9 5810.9 5868.6 5878.7 4322.8 4496.0 5306.3 5357.3 5411.5 5098.3 5100.7 5403.1 5410.2 5418.9 5060.3 5227.6 5380.9 5405.3 5408.0 4339.4 5213.9 5285.2 5286.6 5304.8 4358.7 5257.5 5288.6 5297.3 5311.7 5097.1 5745.0 5767.3 5818.7 5899.9 6155.7 6309.7 6327.5 6343.8 6345.8 5553.9 6001.9 6211.4 6275.7 6308.7 5973.3 5980.3 5992.8 6011.9 6038.7 6244.9 6276.2 6318.8 6332.4 6348.2 6093.2 6143.7 6153.3 6218.9 6231.8 6261.5 6273.5 6299.9 6304.7 6307.5 5048.2 6106.4 6145.3 6161.1 6242.1 6209.5 6246.9 6286.7 6296.9 6299.8 4953.3 4999.9 5040.6 5042.2 5061.0 4462.1 4474.8 4553.2 4569.4 4596.1 5996.5 6001.6 6005.5 6008.0 6012.7 6235.4 6294.2 6297.3 6318.0 6320.5 5851.5 5937.1 5938.6 5967.9 5968.6 5589.1 5589.7 5598.4 5624.3 5633.0 6420.5 6420.5 6472.2 6493.0 6499.7 6217.0 6251.5 6257.2 6309.9 6323.0 6306.7 6431.3 6435.0 6468.9 6474.4 6276.2 6290.2 6297.4 6302.6 6318.3 4579.2 6102.1 6192.1 6292.5 6379.6 5872.2 6129.2 6157.5 6177.0 6210.3 5192.5 6047.5 6166.8 6206.8 6209.6 5370.7 5491.1 5995.9 6034.2 6064.9 6132.8 6160.9 6183.4 6186.7 6254.0 5997.6 6137.5 6159.9 6161.1 6174.5 22:15:40 INFO - PROCESS | 1637 | 22:15:40 INFO - PROCESS | 1637 | [#1] ext.html Cycles:180 Average:68519.63 Median:34970.15 stddev:151325.08 (432.7%) stddev-sans-first:151663.24 22:15:40 INFO - PROCESS | 1637 | Values: 433.9 462.7 499.5 523.4 530.2 40088.8 42239.8 42268.8 42337.9 42414.4 42518.8 42913.4 42967.8 42995.8 43020.0 9449.0 9671.1 9709.0 11125.2 11470.9 11255.9 11354.0 11360.0 11380.5 11389.7 11391.1 11398.4 11405.5 11412.4 11413.7 11322.4 11370.5 11370.9 11373.1 11377.1 11136.1 11200.2 11213.7 11228.6 11230.0 37321.6 37573.1 37675.0 37759.8 37895.4 3565.5 3712.0 4010.5 4042.8 4056.5 20650.5 26480.3 26647.0 27273.6 28242.7 3115.2 3161.8 3214.4 3217.5 3256.5 334673.0 449686.8 450533.0 451176.0 452571.0 55544.4 56027.4 56128.2 56336.7 56384.4 49297.3 57688.7 57742.4 58115.0 58173.5 14845.9 15241.7 15369.0 15392.4 15422.2 9796.9 10721.0 11008.0 11972.3 12024.5 14564.9 18153.8 18246.9 18264.5 18299.9 7960.3 8139.7 8179.5 8184.0 8186.1 869.3 881.9 889.2 909.8 919.9 29925.0 30083.5 30460.2 30490.4 30537.7 1081.7 1089.1 1114.9 1128.8 1158.7 15596.7 15781.7 15892.2 16117.8 16475.4 637543.0 641823.0 644373.0 645394.0 645435.0 59106.4 59536.0 59579.4 59614.4 59710.7 1414.0 1462.9 1470.3 1477.5 1478.1 24918.9 28191.0 28485.0 28774.0 30079.8 39611.6 52162.0 52399.0 52482.0 52544.7 596150.0 599418.0 600960.0 603183.0 604250.0 25469.6 26173.0 26259.2 27037.9 39499.8 42851.0 43354.6 43573.1 43852.1 44103.9 32395.9 33555.5 35698.3 35832.0 36829.4 35588.6 35596.0 36139.5 36154.5 36544.5 34102.7 34664.7 34776.5 35163.8 35857.8 25731.0 30764.8 31529.7 31949.5 31973.4 22464.9 23780.6 24026.5 24307.3 24353.0 22:15:40 INFO - PROCESS | 1637 | 22:15:40 INFO - PROCESS | 1637 | [#2] jquery.html Cycles:180 Average:39263.56 Median:20871.87 stddev:121834.79 (583.7%) stddev-sans-first:122141.50 22:15:40 INFO - PROCESS | 1637 | Values: 331.0 385.6 391.2 404.3 412.0 15754.1 20526.8 20882.4 20934.0 21124.4 18838.4 20155.6 20861.4 21514.5 21848.3 4759.4 5591.9 5828.0 5847.8 5879.0 5025.4 5046.9 5164.6 5756.0 5833.3 5560.6 5724.6 5772.7 5783.1 5797.5 5666.4 5696.0 5719.2 5746.0 5753.7 4859.8 4983.8 5008.6 5013.7 5021.9 20182.9 25438.7 25833.5 25929.6 25965.9 2754.3 2776.6 2802.7 2804.9 2831.3 15227.6 15608.6 15714.2 15836.7 16341.6 1627.6 1644.6 1892.4 1894.0 1898.1 127277.4 129281.0 130570.3 131868.0 132388.0 10925.8 11499.8 11666.6 11717.2 11949.2 14146.1 14227.8 14231.9 14243.0 14246.3 8002.0 8199.2 8200.5 8321.8 8339.7 7907.6 7966.1 7976.8 8127.8 8148.6 7005.3 7236.8 7246.8 7249.6 7258.2 1918.0 1955.3 1957.9 1966.1 1966.7 1052.7 1064.5 1066.6 1079.8 1120.7 22304.7 22657.7 22760.3 22869.8 22944.3 1087.2 1090.1 1111.0 1123.4 1129.2 11086.8 11712.1 11880.6 11902.2 11928.9 723749.0 729313.0 734471.0 744478.0 750658.0 113032.0 113952.3 114438.4 125738.4 128007.0 1029.4 1040.4 1060.8 1064.5 1064.9 19079.9 19247.6 19431.3 19459.4 19568.8 43635.0 54319.5 54993.2 55039.7 56607.7 76186.0 77356.6 78361.0 78636.6 79074.8 7933.1 8235.7 8256.2 8257.6 8282.9 13502.9 13515.9 13594.5 13841.5 14049.2 10162.4 11208.0 11227.8 11265.2 12066.2 10655.0 10964.3 11433.6 11567.6 11631.1 11679.5 11706.3 12071.5 12083.9 12130.1 7201.9 7435.1 7587.4 7743.7 8457.0 10021.9 11463.3 11890.9 11923.9 11928.6 22:15:40 INFO - PROCESS | 1637 | 22:15:40 INFO - PROCESS | 1637 | [#3] mootools.html Cycles:180 Average:5359.15 Median:5190.77 stddev:2951.14 (56.9%) stddev-sans-first:2944.69 22:15:40 INFO - PROCESS | 1637 | Values: 1435.9 1441.4 1455.7 1467.1 1481.4 7580.2 7820.4 8114.4 8567.9 8573.4 7234.0 8072.8 8201.1 8326.2 8452.5 1934.8 1938.2 2302.7 2323.6 2347.2 2075.2 2205.2 2354.2 2364.8 2368.8 2285.2 2292.8 2317.2 2321.0 2323.6 1900.3 1913.4 2288.5 2312.5 2317.9 2262.2 2269.3 2295.9 2303.8 2309.5 7180.1 8472.2 8559.5 8564.5 8567.0 4029.5 4956.0 5093.2 5187.4 5226.9 2859.6 3016.0 3215.0 3309.8 3566.9 1188.7 1198.3 1219.7 1235.8 1246.3 8858.2 10738.2 10860.7 10901.8 10923.6 4501.8 4656.7 4730.9 4741.8 4794.0 7944.4 9105.0 9433.5 9523.5 9567.5 5194.1 5269.8 5275.3 5291.4 5311.8 4151.3 4793.5 4796.7 4799.4 4822.3 3843.0 3843.8 3914.1 3922.5 3979.1 1232.6 1276.3 1279.9 1281.7 1296.4 919.2 934.6 935.2 942.3 963.3 4646.0 4927.3 5378.4 5601.6 5751.4 574.8 580.0 581.1 585.4 589.1 3336.8 3671.4 3765.8 3832.6 3862.3 9545.5 10013.6 10063.2 10103.0 10143.5 9944.6 9950.4 10029.6 10135.0 10150.1 7750.3 7930.0 7941.8 7949.3 7956.1 5451.3 5530.2 5544.6 5596.8 5615.2 9054.3 9089.0 9151.5 9226.3 9301.6 9123.9 9240.3 9363.1 9389.4 9425.2 6609.2 6787.2 6911.4 7108.8 7199.8 7785.5 7990.5 8004.5 8016.1 8018.3 6139.4 6719.5 7112.6 7151.1 7162.5 6144.6 6700.1 6998.5 7031.1 7068.5 5450.6 6388.6 6433.9 6435.1 6440.0 4794.4 5041.8 5048.3 5119.9 5148.6 4394.8 4980.5 5006.9 5032.1 5283.8 22:15:40 INFO - PROCESS | 1637 | 22:15:40 INFO - PROCESS | 1637 | [#4] prototype.html Cycles:180 Average:4610.39 Median:5022.51 stddev:1344.49 (26.8%) stddev-sans-first:1309.85 22:15:40 INFO - PROCESS | 1637 | Values: 359.2 361.3 361.7 364.5 368.8 4886.6 5115.9 5233.6 5461.0 5608.0 5005.2 5123.4 5447.5 5515.6 5528.2 4051.4 4145.4 4205.3 4254.3 4307.9 4241.4 4246.6 4278.8 4304.7 4386.9 4268.3 4287.6 4292.4 4294.8 4297.0 3583.7 3872.1 4039.8 4294.6 4295.0 3470.5 3756.7 3776.6 3795.6 3808.9 4248.4 4705.4 4825.3 5306.7 5370.3 3823.7 4613.6 4822.5 4976.5 5011.5 4746.2 5372.9 5619.9 5859.2 5954.3 4056.8 4258.0 4272.6 4279.6 4281.8 5884.8 6094.6 6100.9 6108.8 6163.7 4278.0 4289.5 4303.0 4305.1 4332.7 3925.6 4128.9 4407.7 4418.8 4439.2 4570.3 4688.8 4773.4 5022.8 5029.6 5209.9 5813.6 5835.7 5853.9 5854.9 1423.9 1433.9 1443.1 1443.3 1466.4 936.8 942.8 988.4 1005.7 1016.5 4304.7 4514.2 4698.2 5147.9 5330.0 5223.4 5263.3 5788.7 5874.5 6019.3 5090.6 5335.6 5364.0 5394.6 5404.9 2667.5 2672.8 2876.0 2890.6 2908.7 4793.4 5554.2 5729.5 5810.5 5858.6 5459.9 5718.2 5773.2 5787.5 5807.7 4994.9 5159.7 5809.9 5823.8 5853.4 4884.6 5040.4 5422.3 5712.5 5746.4 5066.4 5309.7 5369.5 5473.9 5596.9 4997.1 5004.6 5400.5 5765.7 5826.9 3786.9 3849.0 3865.0 3958.6 4039.0 4838.8 5197.7 5591.0 5602.7 5667.3 4343.5 5022.2 5230.1 5251.6 5469.6 4570.5 4772.9 5322.7 5456.7 5814.3 5583.7 5660.3 5713.3 5734.3 5784.9 4715.7 5482.8 5614.3 5718.8 5796.1 4910.8 5567.5 5645.7 5662.5 5671.7 22:15:40 INFO - PROCESS | 1637 | 22:15:40 INFO - PROCESS | 1637 | [#5] yui.html Cycles:180 Average:19626.20 Median:4331.64 stddev:27748.61 (640.6%) stddev-sans-first:27790.15 22:15:40 INFO - PROCESS | 1637 | Values: 721.8 723.4 772.3 835.8 840.1 14871.3 15763.9 15829.8 15864.9 15865.9 13343.1 14361.8 14488.6 14501.9 14513.3 828.0 829.3 840.7 841.2 841.7 831.4 835.6 836.1 840.5 840.8 876.2 884.5 889.3 891.6 891.7 872.6 875.6 884.4 887.8 888.2 638.8 645.9 646.7 653.4 653.9 12139.6 12881.4 13549.1 13579.4 14070.9 9797.6 11915.5 11954.9 12079.8 12370.6 10821.5 10844.5 10917.0 10987.6 11099.7 3639.9 3655.9 3670.8 3675.2 3718.5 72018.6 92645.5 99275.0 103303.0 110715.2 8428.5 11588.9 11672.5 11678.8 11924.0 23238.3 23247.6 23388.9 23629.8 23673.6 7946.0 8123.8 8126.8 8197.3 8325.9 4275.2 4283.1 4380.1 4400.0 4458.4 3566.3 3621.3 3651.5 3659.3 3663.4 671.1 689.6 693.8 699.6 702.1 666.9 673.7 713.0 720.0 721.9 9163.0 9374.0 9493.9 10388.4 10499.7 636.7 660.2 664.2 668.4 673.9 5002.4 5015.3 5030.8 5051.1 5060.8 107390.6 107948.0 108070.0 108130.0 109141.0 57089.7 74657.1 75347.0 76216.6 78471.0 51790.2 71194.0 72100.6 77168.0 81110.6 10754.1 11120.1 11357.7 11371.4 11437.5 46520.8 46834.8 51615.0 51824.5 52875.7 52644.0 66738.0 67694.0 70103.6 73372.9 12982.5 13140.0 13528.3 13564.4 13959.8 14640.6 14789.6 14889.5 15165.4 15358.7 12959.3 13302.9 13612.4 13654.2 13927.4 13731.5 13758.5 13853.2 13953.2 14331.4 13568.5 13637.8 13673.9 13983.0 14129.2 9563.7 10950.6 11097.0 11167.5 11301.7 9474.6 9701.8 10870.6 10872.3 10979.3 22:15:40 INFO - PROCESS | 1637 | -------- Summary: end -------- 22:15:40 INFO - PROCESS | 1637 | 22:15:40 INFO - TEST-INFO | 1637: exit 0 22:15:40 INFO - TEST-OK | dromaeo_css | took 1115579ms 22:15:40 INFO - TEST-START | kraken 22:15:40 INFO - operating with platform_type : mac_ 22:15:40 INFO - Initialising browser for kraken test... 22:15:40 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/tmpf05_t4/profile http://localhost:49236/getInfo.html) 22:15:43 INFO - PROCESS | 1648 | 2017-01-19 22:15:43.593 plugin-container[1649:9997] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 22:15:43 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:15:43 INFO - PROCESS | 1648 | 2017-01-19 22:15:43.596 plugin-container[1649:9997] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 22:15:43 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:15:43 INFO - PROCESS | 1648 | 2017-01-19 22:15:43.596 plugin-container[1649:9997] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:15:43 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 22:15:43 INFO - PROCESS | 1648 | colorDepth:24 22:15:43 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 22:15:43 INFO - PROCESS | 1648 | __metrics 22:15:43 ERROR - PROCESS | 1648 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3269: TypeError: this.tabs is undefined 22:15:44 INFO - TEST-INFO | 1648: exit 0 22:15:44 INFO - Browser initialized. 22:15:44 INFO - Running cycle 1/1 for kraken test... 22:15:44 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/T/tmpf05_t4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:15:46 INFO - PROCESS | 1650 | 2017-01-19 22:15:46.772 plugin-container[1651:10112] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4847, name = 'com.apple.tsm.portname' 22:15:46 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:15:46 INFO - PROCESS | 1650 | 2017-01-19 22:15:46.773 plugin-container[1651:10112] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x451b, name = 'com.apple.CFPasteboardClient' 22:15:46 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:15:46 INFO - PROCESS | 1650 | 2017-01-19 22:15:46.773 plugin-container[1651:10112] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:15:56 INFO - PROCESS | 1650 | RSS: Main: 231809024 22:15:56 INFO - PROCESS | 1650 | 22:15:59 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection) 22:16:00 INFO - PROCESS | 1650 | RSS: Main: 245665792 22:16:00 INFO - PROCESS | 1650 | 22:16:03 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-dft) 22:16:03 INFO - PROCESS | 1650 | RSS: Main: 253722624 22:16:03 INFO - PROCESS | 1650 | 22:16:07 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-fft) 22:16:07 INFO - PROCESS | 1650 | RSS: Main: 256401408 22:16:07 INFO - PROCESS | 1650 | 22:16:09 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator) 22:16:10 INFO - PROCESS | 1650 | RSS: Main: 259076096 22:16:10 INFO - PROCESS | 1650 | 22:16:12 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:16:13 INFO - PROCESS | 1650 | RSS: Main: 259104768 22:16:13 INFO - PROCESS | 1650 | 22:16:15 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom) 22:16:16 INFO - PROCESS | 1650 | RSS: Main: 264732672 22:16:16 INFO - PROCESS | 1650 | 22:16:19 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate) 22:16:20 INFO - PROCESS | 1650 | RSS: Main: 264048640 22:16:20 INFO - PROCESS | 1650 | 22:16:23 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial) 22:16:23 INFO - PROCESS | 1650 | RSS: Main: 263966720 22:16:23 INFO - PROCESS | 1650 | 22:16:25 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:16:26 INFO - PROCESS | 1650 | RSS: Main: 263786496 22:16:26 INFO - PROCESS | 1650 | 22:16:28 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:16:28 INFO - PROCESS | 1650 | RSS: Main: 263483392 22:16:28 INFO - PROCESS | 1650 | 22:16:31 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:16:31 INFO - PROCESS | 1650 | RSS: Main: 262983680 22:16:31 INFO - PROCESS | 1650 | 22:16:34 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:16:35 INFO - PROCESS | 1650 | RSS: Main: 263921664 22:16:35 INFO - PROCESS | 1650 | 22:16:38 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:16:39 INFO - PROCESS | 1650 | RSS: Main: 263176192 22:16:39 INFO - PROCESS | 1650 | 22:16:41 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49236/tests/kraken/driver.html?testName=ai-astar) 22:16:41 INFO - PROCESS | 1650 | RSS: Main: 265261056 22:16:41 INFO - PROCESS | 1650 | 22:16:41 INFO - PROCESS | 1650 | __start_tp_report 22:16:41 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 22:16:41 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 22:16:41 INFO - PROCESS | 1650 | |i|pagename|runs| 22:16:41 INFO - PROCESS | 1650 | |0;ai-astar;106;123;159;117;117;118;117;123;118;118 22:16:41 INFO - PROCESS | 1650 | |1;audio-beat-detection;149;115;147;165;138;118;132;99;115;132 22:16:41 INFO - PROCESS | 1650 | |2;audio-dft;189;255;255;272;220;198;192;207;193;195 22:16:41 INFO - PROCESS | 1650 | |3;audio-fft;88;51;59;96;83;51;71;55;73;81 22:16:41 INFO - PROCESS | 1650 | |4;audio-oscillator;97;66;82;72;72;74;110;74;72;80 22:16:41 INFO - PROCESS | 1650 | |5;imaging-gaussian-blur;91;75;137;132;106;88;93;89;91;88 22:16:41 INFO - PROCESS | 1650 | |6;imaging-darkroom;109;178;102;102;101;102;103;157;122;108 22:16:41 INFO - PROCESS | 1650 | |7;imaging-desaturate;101;123;92;94;93;94;76;148;148;92 22:16:41 INFO - PROCESS | 1650 | |8;json-parse-financial;63;61;61;62;64;62;62;64;62;62 22:16:41 INFO - PROCESS | 1650 | |9;json-stringify-tinderbox;74;69;69;84;111;113;105;74;72;71 22:16:41 INFO - PROCESS | 1650 | |10;stanford-crypto-aes;90;92;88;90;92;84;92;87;87;89 22:16:41 INFO - PROCESS | 1650 | |11;stanford-crypto-ccm;182;155;135;136;179;152;163;141;192;167 22:16:41 INFO - PROCESS | 1650 | |12;stanford-crypto-pbkdf2;180;175;174;173;215;172;242;247;178;177 22:16:41 INFO - PROCESS | 1650 | |13;stanford-crypto-sha256-iterative;68;69;68;80;89;83;91;89;82;68 22:16:41 INFO - PROCESS | 1650 | __end_tp_report 22:16:41 INFO - PROCESS | 1650 | __start_cc_report 22:16:41 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,917 22:16:41 INFO - PROCESS | 1650 | __end_cc_report 22:16:41 INFO - PROCESS | 1650 | __startTimestamp1484893001354__endTimestamp 22:16:41 INFO - PROCESS | 1650 | 22:16:41 INFO - PROCESS | 1650 | ------- Summary: start ------- 22:16:41 INFO - PROCESS | 1650 | Number of tests: 14 22:16:41 INFO - PROCESS | 1650 | 22:16:41 INFO - PROCESS | 1650 | [#0] ai-astar Cycles:10 Average:121.60 Median:118.00 stddev:13.94 (11.8%) stddev-sans-first:13.59 22:16:41 INFO - PROCESS | 1650 | Values: 106.0 123.0 159.0 117.0 117.0 118.0 117.0 123.0 118.0 118.0 22:16:41 INFO - PROCESS | 1650 | 22:16:41 INFO - PROCESS | 1650 | [#1] audio-beat-detection Cycles:10 Average:131.00 Median:142.50 stddev:19.70 (13.8%) stddev-sans-first:19.79 22:16:41 INFO - PROCESS | 1650 | Values: 149.0 115.0 147.0 165.0 138.0 118.0 132.0 99.0 115.0 132.0 22:16:41 INFO - PROCESS | 1650 | 22:16:41 INFO - PROCESS | 1650 | [#2] audio-dft Cycles:10 Average:217.60 Median:213.50 stddev:31.36 (14.7%) stddev-sans-first:31.50 22:16:41 INFO - PROCESS | 1650 | Values: 189.0 255.0 255.0 272.0 220.0 198.0 192.0 207.0 193.0 195.0 22:16:41 INFO - PROCESS | 1650 | 22:16:41 INFO - PROCESS | 1650 | [#3] audio-fft Cycles:10 Average:70.80 Median:77.00 stddev:16.20 (21.0%) stddev-sans-first:15.94 22:16:41 INFO - PROCESS | 1650 | Values: 88.0 51.0 59.0 96.0 83.0 51.0 71.0 55.0 73.0 81.0 22:16:41 INFO - PROCESS | 1650 | 22:16:41 INFO - PROCESS | 1650 | [#4] audio-oscillator Cycles:10 Average:79.90 Median:74.00 stddev:13.55 (18.3%) stddev-sans-first:12.88 22:16:41 INFO - PROCESS | 1650 | Values: 97.0 66.0 82.0 72.0 72.0 74.0 110.0 74.0 72.0 80.0 22:16:41 INFO - PROCESS | 1650 | 22:16:41 INFO - PROCESS | 1650 | [#5] imaging-gaussian-blur Cycles:10 Average:99.00 Median:88.50 stddev:20.18 (22.8%) stddev-sans-first:21.19 22:16:41 INFO - PROCESS | 1650 | Values: 91.0 75.0 137.0 132.0 106.0 88.0 93.0 89.0 91.0 88.0 22:16:41 INFO - PROCESS | 1650 | 22:16:41 INFO - PROCESS | 1650 | [#6] imaging-darkroom Cycles:10 Average:118.40 Median:108.50 stddev:27.08 (25.0%) stddev-sans-first:28.50 22:16:41 INFO - PROCESS | 1650 | Values: 109.0 178.0 102.0 102.0 101.0 102.0 103.0 157.0 122.0 108.0 22:16:41 INFO - PROCESS | 1650 | 22:16:41 INFO - PROCESS | 1650 | [#7] imaging-desaturate Cycles:10 Average:106.10 Median:92.00 stddev:24.92 (27.1%) stddev-sans-first:26.37 22:16:41 INFO - PROCESS | 1650 | Values: 101.0 123.0 92.0 94.0 93.0 94.0 76.0 148.0 148.0 92.0 22:16:41 INFO - PROCESS | 1650 | 22:16:41 INFO - PROCESS | 1650 | [#8] json-parse-financial Cycles:10 Average:62.30 Median:62.00 stddev:1.06 (1.7%) stddev-sans-first:1.09 22:16:41 INFO - PROCESS | 1650 | Values: 63.0 61.0 61.0 62.0 64.0 62.0 62.0 64.0 62.0 62.0 22:16:41 INFO - PROCESS | 1650 | 22:16:41 INFO - PROCESS | 1650 | [#9] json-stringify-tinderbox Cycles:10 Average:84.20 Median:71.50 stddev:18.18 (25.4%) stddev-sans-first:18.90 22:16:41 INFO - PROCESS | 1650 | Values: 74.0 69.0 69.0 84.0 111.0 113.0 105.0 74.0 72.0 71.0 22:16:41 INFO - PROCESS | 1650 | 22:16:41 INFO - PROCESS | 1650 | [#10] stanford-crypto-aes Cycles:10 Average:89.10 Median:90.00 stddev:2.64 (2.9%) stddev-sans-first:2.78 22:16:41 INFO - PROCESS | 1650 | Values: 90.0 92.0 88.0 90.0 92.0 84.0 92.0 87.0 87.0 89.0 22:16:41 INFO - PROCESS | 1650 | 22:16:41 INFO - PROCESS | 1650 | [#11] stanford-crypto-ccm Cycles:10 Average:160.20 Median:165.00 stddev:19.94 (12.1%) stddev-sans-first:19.52 22:16:41 INFO - PROCESS | 1650 | Values: 182.0 155.0 135.0 136.0 179.0 152.0 163.0 141.0 192.0 167.0 22:16:41 INFO - PROCESS | 1650 | 22:16:41 INFO - PROCESS | 1650 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:193.30 Median:179.00 stddev:29.77 (16.6%) stddev-sans-first:31.18 22:16:41 INFO - PROCESS | 1650 | Values: 180.0 175.0 174.0 173.0 215.0 172.0 242.0 247.0 178.0 177.0 22:16:41 INFO - PROCESS | 1650 | 22:16:41 INFO - PROCESS | 1650 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.70 Median:82.50 stddev:9.62 (11.7%) stddev-sans-first:9.39 22:16:41 INFO - PROCESS | 1650 | Values: 68.0 69.0 68.0 80.0 89.0 83.0 91.0 89.0 82.0 68.0 22:16:41 INFO - PROCESS | 1650 | -------- Summary: end -------- 22:16:41 INFO - PROCESS | 1650 | 22:16:42 INFO - TEST-INFO | 1650: exit 0 22:16:42 INFO - TEST-OK | kraken | took 61448ms 22:16:42 INFO - SUITE-END | took 1177s 22:16:42 INFO - Completed test suite (00:19:37) 22:16:42 INFO - javascript benchmark 22:16:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7316.23102341, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2873.0806142034553, 3385.5937287525485, 3388.1698983748984, 3388.644192359802, 3411.8635254589817, 5437.157240627699, 5727.885442291162, 5733.65598064116, 5803.767849286029, 5804.187413762066, 5174.637775355726, 5663.8584035399135, 5810.883782324351, 5868.61853979491, 5878.676672782995, 4322.805473753673, 4495.98840260942, 5306.283651707009, 5357.303550538424, 5411.540019098362, 5098.286239926395, 5100.744962751866, 5403.081476149064, 5410.242566040757, 5418.8916221675545, 5060.291559181699, 5227.634065615409, 5380.946190538084, 5405.27028851104, 5407.972485227794, 4339.435873336446, 5213.869653258661, 5285.233641122054, 5286.577073834084, 5304.761285742151, 4358.738475691469, 5257.526822585947, 5288.603354748409, 5297.311349524537, 5311.7344132793205, 5097.108006098918, 5744.971275143597, 5767.307923049261, 5818.709064546798, 5899.941000589982, 6155.72299246535, 6309.652969086698, 6327.46216571592, 6343.809685709436, 6345.777897773602, 5553.861153471155, 6001.9196544621955, 6211.409916058013, 6275.6548389838545, 6308.653024083674, 5973.313068997078, 5980.308861012518, 5992.760289588455, 6011.909821352683, 6038.6678732670125, 6244.937550624436, 6276.246850377984, 6318.810435686844, 6332.366763323723, 6348.206474190726, 6093.238345206849, 6143.723532441051, 6153.292371377349, 6218.9378106219265, 6231.781887633911, 6261.530385221126, 6273.498120150377, 6299.874002519924, 6304.716287767062, 6307.463865571386, 5048.242763585492, 6106.369660900722, 6145.26256849172, 6161.137440758297, 6242.09489624002, 6209.5032397408095, 6246.937530624727, 6286.654234017173, 6296.937030629635, 6299.811005669746, 4953.331300274417, 4999.925001124986, 5040.6219533534395, 5042.243663450438, 5061.013102445061, 4462.129884672458, 4474.753888536162, 4553.157665831765, 4569.405977223007, 4596.10375976688, 5996.4603185713495, 6001.639901605918, 6005.549583781234, 6008.0, 6012.669303188279, 6235.376462353728, 6294.213223347082, 6297.275813281532, 6318.0, 6320.525960552977, 5851.502622277069, 5937.139114828326, 5938.6139900907165, 5967.910481342783, 5968.552358573124, 5589.077802162679, 5589.664620122724, 5598.384177740445, 5624.3250809903075, 5632.986062508706, 6420.486361091102, 6420.486361091195, 6472.190976127984, 6493.0, 6499.740010399721, 6217.0, 6251.468625166912, 6257.2491301044165, 6309.873802524015, 6323.0, 6306.684665766785, 6431.292557818542, 6435.03474478832, 6468.935310646833, 6474.449671777763, 6276.152719382825, 6290.150829637941, 6297.370262973666, 6302.590331628429, 6318.336574659595, 4579.152856721517, 6102.0651310563235, 6192.0711893214575, 6292.465140463112, 6379.553431259767, 5872.1778950946045, 6129.175413489998, 6157.476614487639, 6176.969115154396, 6210.3168651449205, 5192.506711862367, 6047.455728984412, 6166.845828854314, 6206.782762603287, 6209.565330427007, 5370.677759334452, 5491.148871924858, 5995.91006134917, 6034.185384972972, 6064.939350606437, 6132.753252973555, 6160.9383906160365, 6183.443490085914, 6186.690665466798, 6254.0, 5997.5501837363245, 6137.478314343152, 6159.876802464015, 6161.137440758387, 6174.506039516918], "value": 5723.945457126192, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [433.9197248509024, 462.70155749541544, 499.48054023815234, 523.4451481429685, 530.19675192084, 40088.79955600222, 42239.78880105599, 42268.78865605671, 42337.94155146123, 42414.36378454325, 42518.787406063006, 42913.35629965553, 42967.78516107415, 42995.78502107493, 43020.0, 9449.0, 9671.129598336149, 9709.0, 11125.165612579049, 11470.942645286803, 11255.887441125566, 11353.978141967222, 11360.0, 11380.487878045507, 11389.715257118556, 11391.145664075186, 11398.430078496041, 11405.486753096131, 11412.372319522423, 11413.714657133596, 11322.377269250188, 11370.488328025258, 11370.886291137065, 11373.147013973985, 11377.1467139964, 11136.109111271078, 11200.21598488098, 11213.66359009231, 11228.606998755004, 11230.0, 37321.626783732085, 37573.06067348311, 37675.0, 37759.811200944096, 37895.431568526496, 3565.5186549815703, 3711.962880371189, 4010.4786377770993, 4042.7776472294013, 4056.492938382702, 20650.48373790652, 26480.337991550172, 26647.0, 27273.63631818401, 28242.717572824215, 3115.17207853408, 3161.8260995645232, 3214.357128574271, 3217.549543063973, 3256.5115232715048, 334673.0, 449686.75156624336, 450533.0, 451176.0, 452571.0, 55544.444555554735, 56027.43972560222, 56128.15807762805, 56336.71831640816, 56384.436155637915, 49297.26054109191, 57688.71155644279, 57742.422575774544, 58115.0, 58173.54566135796, 14845.851541484446, 15241.695166096615, 15369.0, 15392.384304627913, 15422.228888555528, 9796.853047204298, 10721.0, 11008.0, 11972.281663100242, 12024.458899349374, 14564.85435145635, 18153.82000169985, 18246.908765456086, 18264.543386415447, 18299.908500457677, 7960.323372513401, 8139.674413023531, 8179.509229446133, 8184.0, 8186.099529051799, 869.2828416556366, 881.8809460722848, 889.2485849457198, 909.8498747706554, 919.9494027828533, 29925.0, 30083.548746768814, 30460.23849403738, 30490.390192196475, 30537.69462305393, 1081.7133459633203, 1089.096050278267, 1114.899659030691, 1128.7968165730083, 1158.7276989907366, 15596.688066238612, 15781.68436631261, 15892.205389730698, 16117.838821611635, 16475.423360182336, 637543.0, 641823.0, 644373.0, 645394.0, 645435.0, 59106.40893591095, 59536.0, 59579.40420595825, 59614.403855961755, 59710.70144649249, 1413.9929300353433, 1462.9414823407158, 1470.3309993952726, 1477.4976507987335, 1478.0540454109378, 24918.875405622857, 28191.0, 28485.0, 28774.0, 30079.849600751855, 39611.60388396079, 52161.95676086533, 52399.0, 52482.0, 52544.737276313375, 596150.0, 599418.0, 600960.0, 603183.0, 604250.0, 25469.61795573105, 26173.0, 26259.21222363332, 27037.86481067582, 39499.80250098731, 42851.0, 43354.56645433505, 43573.12853742971, 43852.12295754131, 44103.89740256391, 32395.866144685766, 33555.49666755049, 35698.28603427865, 35832.0, 36829.44755828718, 35588.64411355957, 35596.0, 36139.45790813088, 36154.4576831353, 36544.45183322199, 34102.65897340995, 34664.65335346614, 34776.47835282422, 35163.82418087996, 35857.82071089628, 25731.0, 30764.846175768977, 31529.68470315359, 31949.520757189126, 31973.36053278968, 22464.887675561517, 23780.643290351003, 24026.51946961016, 24307.27078187657, 24353.025878964643], "value": 19856.186288945926, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [330.9751768617354, 385.62016413832373, 391.15900813251517, 404.25616864968487, 411.99267790252844, 15754.06067046373, 20526.794732052695, 20882.37352879414, 20934.0, 21124.366269011916, 18838.43484695458, 20155.596888062268, 20861.374158775223, 21514.462138446546, 21848.344549663496, 4759.428868535781, 5591.888162236763, 5828.009238429478, 5847.8245652630485, 5878.970605146989, 5025.447200807908, 5046.899062018758, 5164.6384753067305, 5756.0, 5833.300003999526, 5560.582956278296, 5724.624280923412, 5772.740226689809, 5783.103618939072, 5797.507211886974, 5666.376698563155, 5696.0, 5719.19931209631, 5746.0, 5753.741081651336, 4859.805607775685, 4983.825566105169, 5008.599312055063, 5013.749312534359, 5021.949780502185, 20182.89908550448, 25438.745612543822, 25833.48333033329, 25929.61105583418, 25965.870170649214, 2754.297654098212, 2776.5557510798176, 2802.691703912568, 2804.9158525244275, 2831.278024103861, 15227.619309517351, 15608.60978475536, 15714.21428928549, 15836.683266334609, 16341.591460213589, 1627.560558649158, 1644.6464010237737, 1892.4228110426313, 1894.0, 1898.1268616436455, 127277.36361318227, 129281.0, 130570.34714826365, 131868.02197966943, 132388.0, 10925.781484370427, 11499.770004600028, 11666.591669291534, 11717.238379505476, 11949.22330048544, 14146.080504767157, 14227.78658320126, 14231.932605054455, 14243.002989790823, 14246.287685615885, 8002.0, 8199.180081991872, 8200.507969521848, 8321.750347489586, 8339.666413343519, 7907.604619768988, 7966.0839003514175, 7976.800579985546, 8127.796805079802, 8148.633311500997, 7005.299470053056, 7236.819079522948, 7246.782596522113, 7249.601271930094, 7258.237885021997, 1917.9616407672047, 1955.2863204930381, 1957.9216831326587, 1966.0562929793493, 1966.7443232379699, 1052.6789329254498, 1064.5050051725984, 1066.6106870992032, 1079.7840431913648, 1120.6918097523246, 22304.665430018562, 22657.660135097987, 22760.31719048431, 22869.771302287096, 22944.311670649906, 1087.1846115413439, 1090.1333439915286, 1111.0223003756641, 1123.359894553944, 1129.1644183304295, 11086.833697494543, 11712.121590880719, 11880.584179553674, 11902.226355286879, 11928.940355298342, 723749.0, 729313.0, 734471.0, 744478.0, 750658.0, 113032.0, 113952.29071563933, 114438.4278078621, 125738.37130814287, 128007.0, 1029.4183786160795, 1040.4433628008978, 1060.7719340341864, 1064.5209655654987, 1064.920130990179, 19079.904600476908, 19247.615047699248, 19431.319903803298, 19459.4162175135, 19568.80431195727, 43635.0, 54319.45680543144, 54993.1751023743, 55039.724801375734, 56607.71696141493, 76186.0, 77356.61321693356, 78361.0, 78636.60681696555, 79074.81387779073, 7933.087694915159, 8235.711750088718, 8256.174382561696, 8257.587120643824, 8282.875756863772, 13502.91976641886, 13515.864841351853, 13594.456221751414, 13841.51554695621, 14049.22729249901, 10162.441065741456, 11208.0, 11227.831582526105, 11265.211435199784, 12066.215695979734, 10654.987776161252, 10964.342139471657, 11433.59982400612, 11567.595134170264, 11631.069514438655, 11679.474423651127, 11706.297622142698, 12071.517139314328, 12083.939580302042, 12130.090243231616, 7201.927980720336, 7435.144958329645, 7587.393008559192, 7743.6515356810205, 8456.95771521163, 10021.899781002388, 11463.31220126795, 11890.940545297219, 11923.880761192277, 11928.582499612472], "value": 10008.914664192647, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1435.8779503742182, 1441.3874103506014, 1455.7234125516152, 1467.1197281631023, 1481.3926290221004, 7580.204078571754, 7820.3743700503965, 8114.350851931846, 8567.91432085679, 8573.442726222791, 7233.963830180842, 8072.798180045502, 8201.097879233295, 8326.209010144026, 8452.4505907116, 1934.7871734109237, 1938.2247101159508, 2302.735185453669, 2323.570139524185, 2347.190219374313, 2075.2321640992855, 2205.2391924786, 2354.1878052072007, 2364.7517010713887, 2368.798652114572, 2285.211601997308, 2292.7707229277107, 2317.1889838556453, 2321.0, 2323.6398358254496, 1900.334882791019, 1913.3972798568545, 2288.5079707862724, 2312.5259321839067, 2317.918872839451, 2262.2195342606774, 2269.2851751698163, 2295.896684649195, 2303.8272129590346, 2309.5034567568055, 7180.102487189101, 8472.152784721478, 8559.486430814171, 8564.528950907697, 8567.0, 4029.5164580250266, 4955.959248557807, 5093.2105523643895, 5187.403448603383, 5226.947730522684, 2859.6139521164587, 3015.9849200754074, 3214.9839250803598, 3309.8179600121985, 3566.8751593694356, 1188.696882295018, 1198.3049831097942, 1219.743853790705, 1235.8208059831275, 1246.2896149194962, 8858.158474944934, 10738.194635402298, 10860.67417977462, 10901.781964360747, 10923.563057477691, 4501.7974191161475, 4656.743879086615, 4730.929036064462, 4741.786619602126, 4793.952060479351, 7944.44388892772, 9105.0, 9433.528323583794, 9523.523823808782, 9567.521623918776, 5194.142966410575, 5269.841904742864, 5275.3142091528625, 5291.391489978625, 5311.814086506954, 4151.335786274242, 4793.472718001017, 4796.664233503621, 4799.3760811094335, 4822.252550854623, 3842.9615703842605, 3843.8462461501226, 3914.099757055836, 3922.5292964844402, 3979.144483935968, 1232.5932442294202, 1276.3299267884436, 1279.8528169260653, 1281.705207802192, 1296.3842174967003, 919.2370332623906, 934.56075644447, 935.2050756856753, 942.3238004068342, 963.2679163835536, 4645.976770116195, 4927.310176575283, 5378.408375078739, 5601.60788744784, 5751.3673495916255, 574.8074395077613, 579.9739011744482, 581.1109003225073, 585.4409039367479, 589.0898561722149, 3336.8164750938445, 3671.4125739881492, 3765.7928813915496, 3832.559255685576, 3862.304785138646, 9545.52272386378, 10013.649522266685, 10063.19494440443, 10103.0, 10143.543540540693, 9944.552495137736, 9950.35322704022, 10029.648962286285, 10135.037171468699, 10150.137238334824, 7750.302472777477, 7930.048394192734, 7941.761747147595, 7949.284564389232, 7956.085050219183, 5451.318585176853, 5530.225768392348, 5544.63959842609, 5596.832095037155, 5615.18579805934, 9054.32092593045, 9089.0, 9151.542422878963, 9226.261899047926, 9301.581428835583, 9123.863142053007, 9240.306976976888, 9363.063693630582, 9389.436633801994, 9425.198858096865, 6609.239937407259, 6787.185537735306, 6911.3779759821855, 7108.751193708195, 7199.784006479814, 7785.493942893694, 7990.480618759994, 8004.479708819141, 8016.078151012708, 8018.278354948083, 6139.416755408229, 6719.496037797087, 7112.644367781693, 7151.106111736033, 7162.46281528877, 6144.56988010835, 6700.128983232344, 6998.475114366341, 7031.08595882532, 7068.505204635626, 5450.601735236642, 6388.616682999035, 6433.903491447539, 6435.06691529735, 6439.967800160969, 4794.376731024944, 5041.823536176363, 5048.293238946624, 5119.87200319995, 5148.562372198405, 4394.824207031683, 4980.477049909708, 5006.924896126634, 5032.0690672225755, 5283.762230699705], "value": 4315.721957125859, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.15598343891855, 361.26662874365013, 361.70882439636114, 364.49153430963804, 368.8008475423272, 4886.560209581137, 5115.930441734957, 5233.633645644806, 5460.972695136524, 5608.0, 5005.174146265871, 5123.359580052494, 5447.536959358459, 5515.641483303592, 5528.226048353234, 4051.392291156328, 4145.419641250227, 4205.285101532747, 4254.277017807793, 4307.870763877073, 4241.363795430695, 4246.57534246576, 4278.764667943262, 4304.741715497064, 4386.912261754763, 4268.338407546835, 4287.614114729674, 4292.356146578023, 4294.763787991659, 4296.957030429687, 3583.6595523425494, 3872.1094148346037, 4039.7880635809433, 4294.616487849243, 4295.0, 3470.4794280857823, 3756.7182461315238, 3776.6412190841825, 3795.582485926546, 3808.8857334280015, 4248.362745588156, 4705.3647757552635, 4825.252085926687, 5306.718111212177, 5370.301860758115, 3823.674987626055, 4613.6078433333205, 4822.517748225185, 4976.527229913152, 5011.498850114996, 4746.199742152112, 5372.865678358073, 5619.915701264485, 5859.2090067841145, 5954.345022047572, 4056.7565946043333, 4257.9993701480125, 4272.594103560188, 4279.636230920344, 4281.764502952336, 5884.823035392938, 6094.573379863411, 6100.877982440371, 6108.816735497898, 6163.722632481505, 4277.95190178402, 4289.485261768607, 4303.0, 4305.095929854703, 4332.740035597874, 3925.5878132795647, 4128.876133715993, 4407.6914615976575, 4418.806922131007, 4439.178751930903, 4570.268756998864, 4688.8124475021295, 4773.355596994361, 5022.84931452057, 5029.64792464524, 5209.905919756893, 5813.59304848665, 5835.724067613634, 5853.9170253503235, 5854.8629750141745, 1423.896480227811, 1433.9139651620728, 1443.1311451669903, 1443.2639353929571, 1466.3767898643034, 936.7611259128879, 942.797932635881, 988.367444835306, 1005.6781829814389, 1016.5120742043861, 4304.698671092993, 4514.210013247669, 4698.1778188816825, 5147.948520514747, 5330.040592693274, 5223.399309079505, 5263.26314315996, 5788.681622510718, 5874.473976190194, 6019.307779605314, 5090.567301779316, 5335.624787674147, 5363.980843639696, 5394.597404674734, 5404.891902161935, 2667.5065112954167, 2672.759451649361, 2876.0, 2890.580865774451, 2908.665503467086, 4793.352897358882, 5554.222408862763, 5729.541636669056, 5810.477057064884, 5858.648481091148, 5459.863503412367, 5718.17018554067, 5773.220615216976, 5787.479126878517, 5807.709614519257, 4994.90010199794, 5159.690418574897, 5809.854753631109, 5823.79616713413, 5853.395239118551, 4884.58347079343, 5040.36995375578, 5422.267993820784, 5712.542996560348, 5746.367899530965, 5066.44269130388, 5309.65487243328, 5369.54358879484, 5473.917891231555, 5596.944030559643, 4997.1260777208545, 5004.6246531511015, 5400.5418536994, 5765.740541675551, 5826.883462330815, 3786.9431958520095, 3849.021662321294, 3864.9806750966063, 3958.6041395860184, 4039.030632648202, 4838.76607968028, 5197.688138711689, 5591.002433260239, 5602.691851948182, 5667.319921609434, 4343.478782546114, 5022.17134172872, 5230.0585894539345, 5251.553617942365, 5469.589780766379, 4570.474395444567, 4772.856814295576, 5322.707251101072, 5456.743739882898, 5814.331351894417, 5583.69289689071, 5660.320761508646, 5713.286363863522, 5734.254546908875, 5784.884302314014, 4715.740634265148, 5482.835514934558, 5614.326280846324, 5718.7998420056765, 5796.116262214713, 4910.754462276944, 5567.498925096761, 5645.661260324394, 5662.462066103713, 5671.702960301085], "value": 4201.986569425228, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [721.7690339091492, 723.3598265534999, 772.3357912195514, 835.8453686068078, 840.0801122770566, 14871.25643717814, 15763.92118039411, 15829.762553561706, 15864.889457737963, 15865.920670396647, 13343.132696374729, 14361.784573231384, 14488.5653430397, 14501.854981450182, 14513.346899389526, 827.954462504562, 829.3323874281216, 840.6511297811401, 841.238678995507, 841.7180244618061, 831.4221615976899, 835.5613303015934, 836.0761358698642, 840.5293035899886, 840.8360369727906, 876.1588874680283, 884.5179377239419, 889.3063410539789, 891.5631340643102, 891.6879092317702, 872.5637181409295, 875.6278581602857, 884.3500027479797, 887.786931136529, 888.1739981816908, 638.8019713888663, 645.9063435801783, 646.660503235805, 653.4413076819299, 653.9280679125293, 12139.603945546287, 12881.420336084899, 13549.119307244997, 13579.388927498287, 14070.92964535181, 9797.559109840075, 11915.523379064914, 11954.940225298818, 12079.758404831855, 12370.567030153901, 10821.513031913582, 10844.511996960156, 10917.0, 10987.560497580087, 11099.667009989713, 3639.8726044588575, 3655.890323290279, 3670.8069877289986, 3675.2465744522574, 3718.4794128821986, 72018.63990680066, 92645.53677231638, 99275.0, 103303.0, 110715.23211919687, 8428.4942903426, 11588.94205528967, 11672.533098675958, 11678.76642467146, 11924.0, 23238.3028509145, 23247.582830015228, 23388.883055584953, 23629.7637023631, 23673.644895326583, 7945.960270198728, 8123.75628731139, 8126.837463250702, 8197.262246397853, 8325.916740832636, 4275.187714334266, 4283.1433713257475, 4380.14587155508, 4399.978000109979, 4458.4426946631675, 3566.32239874423, 3621.348157331653, 3651.4887915691825, 3659.323025240339, 3663.432168013962, 671.0673261008264, 689.6069240532948, 693.8300116471396, 699.6047233313162, 702.115334678309, 666.8999650052531, 673.6564352180424, 712.975045873392, 719.9604021778853, 721.8881073433627, 9163.0, 9373.953130234306, 9493.857592136124, 10388.428636424918, 10499.737506562396, 636.6625688385163, 660.2044536333707, 664.2129077043719, 668.38174688414, 673.9224989126216, 5002.449730529639, 5015.3229314042865, 5030.84907452777, 5051.116054690415, 5060.7722652480725, 107390.57617797775, 107948.0, 108070.0, 108130.0, 109141.0, 57089.71455142698, 74657.13357166115, 75347.0, 76216.61891690506, 78471.0, 51790.22314665283, 71194.0, 72100.55798884098, 77168.0, 81110.59444702856, 10754.085902698045, 11120.054795342487, 11357.659270221906, 11371.374574398487, 11437.485313160762, 46520.7673961628, 46834.76582617065, 51615.0, 51824.48175518347, 52875.73562132318, 52644.0, 66738.0, 67693.96918092466, 70103.59792804014, 73372.89940650787, 12982.545610903573, 13140.0, 13528.255945923069, 13564.38960246811, 13959.790603141164, 14640.560783176521, 14789.630259243606, 14889.47886823956, 15165.393384264506, 15358.692826143639, 12959.287239201934, 13302.875839160613, 13612.38744256491, 13654.249016304198, 13927.4429022842, 13731.45074197021, 13758.518451854137, 13853.238071905738, 13953.164065223364, 14331.426742930584, 13568.457261709422, 13637.795433068313, 13673.86326136726, 13983.0, 14129.222892740587, 9563.665271715456, 10950.616728414467, 11097.0, 11167.497462614365, 11301.71745706331, 9474.57364418589, 9701.757456063655, 10870.641169853769, 10872.347659140476, 10979.28634638746], "value": 7433.454107264518, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1612.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 123.0, 159.0, 117.0, 117.0, 118.0, 117.0, 123.0, 118.0, 118.0], "value": 121.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 115.0, 147.0, 165.0, 138.0, 118.0, 132.0, 99.0, 115.0, 132.0], "value": 131.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [189.0, 255.0, 255.0, 272.0, 220.0, 198.0, 192.0, 207.0, 193.0, 195.0], "value": 217.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 51.0, 59.0, 96.0, 83.0, 51.0, 71.0, 55.0, 73.0, 81.0], "value": 70.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 66.0, 82.0, 72.0, 72.0, 74.0, 110.0, 74.0, 72.0, 80.0], "value": 79.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 75.0, 137.0, 132.0, 106.0, 88.0, 93.0, 89.0, 91.0, 88.0], "value": 99.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 178.0, 102.0, 102.0, 101.0, 102.0, 103.0, 157.0, 122.0, 108.0], "value": 118.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 123.0, 92.0, 94.0, 93.0, 94.0, 76.0, 148.0, 148.0, 92.0], "value": 106.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 61.0, 62.0, 64.0, 62.0, 62.0, 64.0, 62.0, 62.0], "value": 62.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 69.0, 69.0, 84.0, 111.0, 113.0, 105.0, 74.0, 72.0, 71.0], "value": 84.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 92.0, 88.0, 90.0, 92.0, 84.0, 92.0, 87.0, 87.0, 89.0], "value": 89.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 155.0, 135.0, 136.0, 179.0, 152.0, 163.0, 141.0, 192.0, 167.0], "value": 160.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 175.0, 174.0, 173.0, 215.0, 172.0, 242.0, 247.0, 178.0, 177.0], "value": 193.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 68.0, 80.0, 89.0, 83.0, 91.0, 89.0, 82.0, 68.0], "value": 78.7, "unit": "score"}]}]} 22:16:42 INFO - Return code: 0 22:16:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:16:42 ERROR - # TBPL SUCCESS # 22:16:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:16:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:16:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:16:42 INFO - 2017-01-19 22:16:42.669 screenresolution[1655:10499] starting screenresolution argv=screenresolution get 22:16:42 INFO - 2017-01-19 22:16:42.672 screenresolution[1655:10499] Display 0: 1600x1200x32@60 22:16:42 INFO - 2017-01-19 22:16:42.678 screenresolution[1656:10502] starting screenresolution argv=screenresolution list 22:16:42 INFO - Available Modes on Display 0 22:16:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:16:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:16:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:16:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:16:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:16:42 INFO - Intel Iris: 22:16:42 INFO - Chipset Model: Intel Iris 22:16:42 INFO - Type: GPU 22:16:42 INFO - Bus: Built-In 22:16:42 INFO - VRAM (Dynamic, Max): 1536 MB 22:16:42 INFO - Vendor: Intel (0x8086) 22:16:42 INFO - Device ID: 0x0a2e 22:16:42 INFO - Revision ID: 0x0009 22:16:42 INFO - Displays: 22:16:42 INFO - Display: 22:16:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:16:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:16:42 INFO - Display Serial Number: 1600x1200 60 22:16:42 INFO - Main Display: Yes 22:16:42 INFO - Mirror: Off 22:16:42 INFO - Online: Yes 22:16:42 INFO - Rotation: Supported 22:16:42 INFO - Return code: 0 22:16:42 INFO - Running post-action listener: _resource_record_post_action 22:16:42 INFO - [mozharness: 2017-01-20 06:16:42.915018Z] Finished run-tests step (success) 22:16:42 INFO - Running post-run listener: _resource_record_post_run 22:16:42 INFO - Total resource usage - Wall time: 1200s; CPU: 55.0%; Read bytes: 38177280; Write bytes: 554879488; Read time: 238; Write time: 1745 22:16:42 INFO - TinderboxPrint: CPU usage
54.5% 22:16:42 INFO - TinderboxPrint: I/O read bytes / time
38,177,280 / 238 22:16:42 INFO - TinderboxPrint: I/O write bytes / time
554,879,488 / 1,745 22:16:42 INFO - TinderboxPrint: CPU idle
2,183.3 (45.5%) 22:16:42 INFO - TinderboxPrint: CPU system
967.6 (20.2%) 22:16:42 INFO - TinderboxPrint: CPU user
1,650.8 (34.4%) 22:16:42 INFO - TinderboxPrint: Swap in / out
289,837,056 / 0 22:16:42 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 249612800; Write bytes: 241893376; Read time: 18029; Write time: 509 22:16:42 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 34843136; Write bytes: 301402624; Read time: 214; Write time: 1203 22:16:43 INFO - Running post-run listener: _upload_blobber_files 22:16:43 INFO - Blob upload gear active. 22:16:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:16:43 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 22:16:43 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:16:43 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:16:43 INFO - (blobuploader) - INFO - Open directory for files ... 22:16:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:16:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:16:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:16:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:16:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:16:45 INFO - (blobuploader) - INFO - Done attempting. 22:16:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:16:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:16:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:16:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:16:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:16:45 INFO - (blobuploader) - INFO - Done attempting. 22:16:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 22:16:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:16:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:16:47 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 22:16:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:16:47 INFO - (blobuploader) - INFO - Done attempting. 22:16:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:16:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:16:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:16:49 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:16:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:16:49 INFO - (blobuploader) - INFO - Done attempting. 22:16:49 INFO - (blobuploader) - INFO - Iteration through files over. 22:16:49 INFO - Return code: 0 22:16:49 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:16:49 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:16:49 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/6fd0f5c9396861356666e1ea564b6d7bf77f080c68a89092bb6714cc43a2a9db28b00d047b3dc4f349e42914b61c5e63c5a4b8bc2e62c8b2d538d69c143ab03a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/60adc33926918fb6dfc265539eb189e573e993bb5a30cc361e1e6f3b7683a642e0a9e6500f009a1a87eab1270d5bd7e855956a4adb47618018a8fd855a88f229", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/de7d1289eb484769c66065b3ae03d41120c497f0f2081f9a2952443f1ac3dc679dedd90b9a6527400f1a5fd20865c7d9e34b10c01d5c79fced15ef20661f02cb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/50954122a5ab6b74cb641a4c20edd6dafb72a0351b4ca02bd9387f27de26ab612d6b0ab9a01962a326b65f0e20bffdd78038240f5975c20baa11112f51c4ae34"} 22:16:49 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:16:49 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:16:49 INFO - Contents: 22:16:49 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/6fd0f5c9396861356666e1ea564b6d7bf77f080c68a89092bb6714cc43a2a9db28b00d047b3dc4f349e42914b61c5e63c5a4b8bc2e62c8b2d538d69c143ab03a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/60adc33926918fb6dfc265539eb189e573e993bb5a30cc361e1e6f3b7683a642e0a9e6500f009a1a87eab1270d5bd7e855956a4adb47618018a8fd855a88f229", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/de7d1289eb484769c66065b3ae03d41120c497f0f2081f9a2952443f1ac3dc679dedd90b9a6527400f1a5fd20865c7d9e34b10c01d5c79fced15ef20661f02cb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/50954122a5ab6b74cb641a4c20edd6dafb72a0351b4ca02bd9387f27de26ab612d6b0ab9a01962a326b65f0e20bffdd78038240f5975c20baa11112f51c4ae34"} 22:16:49 INFO - Running post-run listener: copy_logs_to_upload_dir 22:16:49 INFO - Copying logs to upload dir... 22:16:49 INFO - mkdir: /builds/slave/test/build/upload/logs 22:16:49 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.674278 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-01-19 22:16:49.637695) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-19 22:16:49.640968) ========= 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.aWuXcfye6E/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.HrLB51YzXP/Listeners TMPDIR=/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/6fd0f5c9396861356666e1ea564b6d7bf77f080c68a89092bb6714cc43a2a9db28b00d047b3dc4f349e42914b61c5e63c5a4b8bc2e62c8b2d538d69c143ab03a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/60adc33926918fb6dfc265539eb189e573e993bb5a30cc361e1e6f3b7683a642e0a9e6500f009a1a87eab1270d5bd7e855956a4adb47618018a8fd855a88f229", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/de7d1289eb484769c66065b3ae03d41120c497f0f2081f9a2952443f1ac3dc679dedd90b9a6527400f1a5fd20865c7d9e34b10c01d5c79fced15ef20661f02cb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/50954122a5ab6b74cb641a4c20edd6dafb72a0351b4ca02bd9387f27de26ab612d6b0ab9a01962a326b65f0e20bffdd78038240f5975c20baa11112f51c4ae34"} build_url:https://queue.taskcluster.net/v1/task/JV6kSIXdSnqzhHQCwyNtng/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011267 build_url: 'https://queue.taskcluster.net/v1/task/JV6kSIXdSnqzhHQCwyNtng/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/6fd0f5c9396861356666e1ea564b6d7bf77f080c68a89092bb6714cc43a2a9db28b00d047b3dc4f349e42914b61c5e63c5a4b8bc2e62c8b2d538d69c143ab03a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/60adc33926918fb6dfc265539eb189e573e993bb5a30cc361e1e6f3b7683a642e0a9e6500f009a1a87eab1270d5bd7e855956a4adb47618018a8fd855a88f229", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/de7d1289eb484769c66065b3ae03d41120c497f0f2081f9a2952443f1ac3dc679dedd90b9a6527400f1a5fd20865c7d9e34b10c01d5c79fced15ef20661f02cb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/50954122a5ab6b74cb641a4c20edd6dafb72a0351b4ca02bd9387f27de26ab612d6b0ab9a01962a326b65f0e20bffdd78038240f5975c20baa11112f51c4ae34"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-19 22:16:49.674786) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-19 22:16:49.675106) ========= 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.aWuXcfye6E/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.HrLB51YzXP/Listeners TMPDIR=/var/folders/r_/4zqfsk7s6jg6x142dct5tjd400000w/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.005162 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-19 22:16:49.723865) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-19 22:16:49.724179) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-19 22:16:49.724500) ========= ========= Total master_lag: 3.07 =========