builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0114 starttime: 1483610771.4 results: success (0) buildid: 20170105000039 builduid: f1784b19fb9a4d5cb62b26a695eae701 revision: 5af63cf90f2874f840c132461bff7f6130483362 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-05 02:06:11.395917) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-05 02:06:11.396379) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-05 02:06:11.396678) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-05 02:06:11.423541) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 02:06:11.423957) ========= 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.nDQFIJv0OY/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.aesc1kfPhF/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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-05 02:06:11-- 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% 62.4M=0s 2017-01-05 02:06:11 (62.4 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.179963 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 02:06:11.629674) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 02:06:11.630258) ========= 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.nDQFIJv0OY/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.aesc1kfPhF/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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.032116 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 02:06:11.687462) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-05 02:06:11.688099) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 5af63cf90f2874f840c132461bff7f6130483362 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 5af63cf90f2874f840c132461bff7f6130483362 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nDQFIJv0OY/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.aesc1kfPhF/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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-05 02:06:11,781 truncating revision to first 12 chars 2017-01-05 02:06:11,781 Setting DEBUG logging. 2017-01-05 02:06:11,781 attempt 1/10 2017-01-05 02:06:11,781 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/5af63cf90f28?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-05 02:06:13,465 unpacking tar archive at: mozilla-aurora-5af63cf90f28/testing/mozharness/ program finished with exit code 0 elapsedTime=2.106043 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-05 02:06:13.818755) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-05 02:06:13.819081) ========= script_repo_revision: 5af63cf90f2874f840c132461bff7f6130483362 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-05 02:06:13.819484) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-05 02:06:13.819757) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-05 02:06:13.839837) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 30 secs) (at 2017-01-05 02:06:13.840256) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nDQFIJv0OY/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.aesc1kfPhF/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 02:06:14 INFO - MultiFileLogger online at 20170105 02:06:14 in /builds/slave/test 02:06:14 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:06:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:06:14 INFO - {'append_to_log': False, 02:06:14 INFO - 'base_work_dir': '/builds/slave/test', 02:06:14 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:06:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:06:14 INFO - 'branch': 'Mozilla-Aurora', 02:06:14 INFO - 'buildbot_json_path': 'buildprops.json', 02:06:14 INFO - 'config_files': ('talos/mac_config.py',), 02:06:14 INFO - 'default_actions': ('clobber', 02:06:14 INFO - 'read-buildbot-config', 02:06:14 INFO - 'download-and-extract', 02:06:14 INFO - 'populate-webroot', 02:06:14 INFO - 'create-virtualenv', 02:06:14 INFO - 'install', 02:06:14 INFO - 'run-tests'), 02:06:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:06:14 INFO - 'download_minidump_stackwalk': True, 02:06:14 INFO - 'download_symbols': 'ondemand', 02:06:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:06:14 INFO - 'tooltool.py': '/tools/tooltool.py', 02:06:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:06:14 INFO - '/tools/misc-python/virtualenv.py')}, 02:06:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:06:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:06:14 INFO - 'installer_path': 'installer.exe', 02:06:14 INFO - 'log_level': 'info', 02:06:14 INFO - 'log_name': 'talos', 02:06:14 INFO - 'log_to_console': True, 02:06:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:06:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:06:14 INFO - 'opt_config_files': (), 02:06:14 INFO - 'pip_index': False, 02:06:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:06:14 INFO - 'cmd': ('bash', 02:06:14 INFO - '-c', 02:06:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:06:14 INFO - 'enabled': True, 02:06:14 INFO - 'halt_on_failure': False, 02:06:14 INFO - 'name': 'check_screen_resolution'},), 02:06:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:06:14 INFO - 'cmd': ('bash', 02:06:14 INFO - '-c', 02:06:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:06:14 INFO - 'enabled': True, 02:06:14 INFO - 'halt_on_failure': False, 02:06:14 INFO - 'name': 'check_screen_resolution'},), 02:06:14 INFO - 'run_cmd_checks_enabled': True, 02:06:14 INFO - 'sps_profile': False, 02:06:14 INFO - 'sps_profile_interval': 0, 02:06:14 INFO - 'suite': 'dromaeojs-e10s', 02:06:14 INFO - 'system_bits': '32', 02:06:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:06:14 INFO - 'title': 't-yosemite-r7-0114', 02:06:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:06:14 INFO - 'use_talos_json': True, 02:06:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:06:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:06:14 INFO - 'work_dir': 'build'} 02:06:14 INFO - [mozharness: 2017-01-05 10:06:14.048971Z] Running clobber step. 02:06:14 INFO - Running pre-action listener: _resource_record_pre_action 02:06:14 INFO - Running main action method: clobber 02:06:14 INFO - rmtree: /builds/slave/test/build 02:06:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:06:16 INFO - Running post-action listener: _resource_record_post_action 02:06:16 INFO - [mozharness: 2017-01-05 10:06:16.250585Z] Finished clobber step (success) 02:06:16 INFO - [mozharness: 2017-01-05 10:06:16.250712Z] Running read-buildbot-config step. 02:06:16 INFO - Running pre-action listener: _resource_record_pre_action 02:06:16 INFO - Running main action method: read_buildbot_config 02:06:16 INFO - Using buildbot properties: 02:06:16 INFO - { 02:06:16 INFO - "project": "", 02:06:16 INFO - "product": "firefox", 02:06:16 INFO - "script_repo_revision": "production", 02:06:16 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 02:06:16 INFO - "repository": "", 02:06:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 02:06:16 INFO - "buildid": "20170105000039", 02:06:16 INFO - "pgo_build": "False", 02:06:16 INFO - "basedir": "/builds/slave/test", 02:06:16 INFO - "buildnumber": 24, 02:06:16 INFO - "slavename": "t-yosemite-r7-0114", 02:06:16 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 02:06:16 INFO - "platform": "yosemite_r7", 02:06:16 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:06:16 INFO - "branch": "mozilla-aurora", 02:06:16 INFO - "repo_path": "releases/mozilla-aurora", 02:06:16 INFO - "revision": "5af63cf90f2874f840c132461bff7f6130483362", 02:06:16 INFO - "stage_platform": "macosx64", 02:06:16 INFO - "builduid": "f1784b19fb9a4d5cb62b26a695eae701", 02:06:16 INFO - "slavebuilddir": "test" 02:06:16 INFO - } 02:06:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/VHUmfEpWTgC9DwzM_p0lYQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 02:06:16 INFO - Running post-action listener: _resource_record_post_action 02:06:16 INFO - [mozharness: 2017-01-05 10:06:16.254102Z] Finished read-buildbot-config step (success) 02:06:16 INFO - [mozharness: 2017-01-05 10:06:16.254235Z] Running download-and-extract step. 02:06:16 INFO - Running pre-action listener: _resource_record_pre_action 02:06:16 INFO - Running main action method: download_and_extract 02:06:16 INFO - mkdir: /builds/slave/test/build/tests 02:06:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:06:16 INFO - trying https://queue.taskcluster.net/v1/task/VHUmfEpWTgC9DwzM_p0lYQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 02:06:16 INFO - Downloading https://queue.taskcluster.net/v1/task/VHUmfEpWTgC9DwzM_p0lYQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 02:06:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VHUmfEpWTgC9DwzM_p0lYQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 02:06:17 INFO - Downloaded 1199 bytes. 02:06:17 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 02:06:17 INFO - Using the following test package requirements: 02:06:17 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:06:17 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:06:17 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 02:06:17 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:06:17 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 02:06:17 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:06:17 INFO - u'jsshell-mac.zip'], 02:06:17 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:06:17 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 02:06:17 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 02:06:17 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:06:17 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 02:06:17 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:06:17 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 02:06:17 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:06:17 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 02:06:17 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 02:06:17 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 02:06:17 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:06:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VHUmfEpWTgC9DwzM_p0lYQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:06:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VHUmfEpWTgC9DwzM_p0lYQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:06:17 INFO - Fetch https://queue.taskcluster.net/v1/task/VHUmfEpWTgC9DwzM_p0lYQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:06:18 INFO - Http code: 200 02:06:18 INFO - via: 1.1 20f1c35f343f4b271ae8dcacfd7ea0e9.cloudfront.net (CloudFront) 02:06:18 INFO - x-amz-cf-id: 2Ro1ahS_oFfVMyheJYsTjGH2fNWkmAnMMz98qtVUXsX_MJLf8cFR4w== 02:06:18 INFO - x-amz-version-id: oQOpx2F3dbhUPxN0uyytyTnvCYFEY8FC 02:06:18 INFO - Expected file size: 16270055 02:06:18 INFO - Obtained file size: 16270055 02:06:25 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:06:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VHUmfEpWTgC9DwzM_p0lYQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 02:06:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VHUmfEpWTgC9DwzM_p0lYQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:06:25 INFO - Fetch https://queue.taskcluster.net/v1/task/VHUmfEpWTgC9DwzM_p0lYQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 02:06:26 INFO - Http code: 200 02:06:26 INFO - via: 1.1 23172d761378a3865feafcda78328053.cloudfront.net (CloudFront) 02:06:26 INFO - x-amz-cf-id: KzBvkGhkgkNXxN1McjOk_udcEFz9ZEKtL6HbdquifgkbQt47HkJcJA== 02:06:26 INFO - x-amz-version-id: oQOpx2F3dbhUPxN0uyytyTnvCYFEY8FC 02:06:26 INFO - Expected file size: 16270055 02:06:26 INFO - Obtained file size: 16270055 02:06:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VHUmfEpWTgC9DwzM_p0lYQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 02:06:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VHUmfEpWTgC9DwzM_p0lYQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:06:32 INFO - Fetch https://queue.taskcluster.net/v1/task/VHUmfEpWTgC9DwzM_p0lYQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 02:06:33 INFO - Http code: 200 02:06:33 INFO - via: 1.1 441811a054e8d055b893175754efd0c3.cloudfront.net (CloudFront) 02:06:33 INFO - x-amz-cf-id: Di2UGL6J8WKxmMF3OPqlOch1X_mgnAg8jsDe2SYVZop0xHvdktolWw== 02:06:33 INFO - x-amz-version-id: ykV2GsY1kiLOqjS.4iZeHCzBShOQdp8e 02:06:33 INFO - Expected file size: 14409038 02:06:33 INFO - Obtained file size: 14409038 02:06:34 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')]} 02:06:34 INFO - trying https://queue.taskcluster.net/v1/task/VHUmfEpWTgC9DwzM_p0lYQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:06:34 INFO - Downloading https://queue.taskcluster.net/v1/task/VHUmfEpWTgC9DwzM_p0lYQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 02:06:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VHUmfEpWTgC9DwzM_p0lYQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 02:06:50 INFO - Downloaded 109825464 bytes. 02:06:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VHUmfEpWTgC9DwzM_p0lYQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:06:50 INFO - mkdir: /builds/slave/test/properties 02:06:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:06:50 INFO - Writing to file /builds/slave/test/properties/build_url 02:06:50 INFO - Contents: 02:06:50 INFO - build_url:https://queue.taskcluster.net/v1/task/VHUmfEpWTgC9DwzM_p0lYQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 02:06:51 INFO - Running post-action listener: _resource_record_post_action 02:06:51 INFO - Running post-action listener: set_extra_try_arguments 02:06:51 INFO - [mozharness: 2017-01-05 10:06:51.104408Z] Finished download-and-extract step (success) 02:06:51 INFO - [mozharness: 2017-01-05 10:06:51.104532Z] Running populate-webroot step. 02:06:51 INFO - Running pre-action listener: _resource_record_pre_action 02:06:51 INFO - Running main action method: populate_webroot 02:06:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:06:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:06:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:06:51 INFO - '--tppagecycles', 02:06:51 INFO - '7'], 02:06:51 INFO - 'tests': ['tsvgm']}}, 02:06:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:06:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:06:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:06:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:06:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:06:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:06:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:06:51 INFO - 'talos_options': ['--disable-e10s'], 02:06:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:06:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:06:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:06:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:06:51 INFO - 'talos_options': ['--disable-e10s'], 02:06:51 INFO - 'tests': ['damp', 'tps']}, 02:06:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:06:51 INFO - 'tests': ['damp', 'tps']}, 02:06:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:06:51 INFO - 'tests': ['dromaeo_dom']}, 02:06:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:06:51 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:06:51 INFO - 'tests': ['basic_compositor_video']}, 02:06:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:06:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:06:51 INFO - 'tests': ['a11yr', 02:06:51 INFO - 'ts_paint', 02:06:51 INFO - 'tpaint', 02:06:51 INFO - 'sessionrestore', 02:06:51 INFO - 'sessionrestore_no_auto_restore', 02:06:51 INFO - 'tabpaint']}, 02:06:51 INFO - 'other-e10s': {'tests': ['a11yr', 02:06:51 INFO - 'ts_paint', 02:06:51 INFO - 'tpaint', 02:06:51 INFO - 'sessionrestore', 02:06:51 INFO - 'sessionrestore_no_auto_restore', 02:06:51 INFO - 'tabpaint']}, 02:06:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:06:51 INFO - 'ts_paint', 02:06:51 INFO - 'tpaint', 02:06:51 INFO - 'sessionrestore', 02:06:51 INFO - 'sessionrestore_no_auto_restore', 02:06:51 INFO - 'tabpaint']}, 02:06:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:06:51 INFO - 'ts_paint', 02:06:51 INFO - 'tpaint', 02:06:51 INFO - 'sessionrestore', 02:06:51 INFO - 'sessionrestore_no_auto_restore', 02:06:51 INFO - 'tabpaint']}, 02:06:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:06:51 INFO - 'tests': ['a11yr', 02:06:51 INFO - 'ts_paint', 02:06:51 INFO - 'tpaint', 02:06:51 INFO - 'sessionrestore', 02:06:51 INFO - 'sessionrestore_no_auto_restore', 02:06:51 INFO - 'tabpaint']}, 02:06:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:06:51 INFO - 'tests': ['a11yr', 02:06:51 INFO - 'ts_paint', 02:06:51 INFO - 'tpaint', 02:06:51 INFO - 'sessionrestore', 02:06:51 INFO - 'sessionrestore_no_auto_restore', 02:06:51 INFO - 'tabpaint']}, 02:06:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:06:51 INFO - 'tests': ['tsvgx', 02:06:51 INFO - 'tsvgr_opacity', 02:06:51 INFO - 'tart', 02:06:51 INFO - 'tscrollx', 02:06:51 INFO - 'cart']}, 02:06:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:06:51 INFO - 'tsvgr_opacity', 02:06:51 INFO - 'tart', 02:06:51 INFO - 'tscrollx', 02:06:51 INFO - 'cart']}, 02:06:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:06:51 INFO - 'talos_options': ['--disable-e10s'], 02:06:51 INFO - 'tests': ['tp5o']}, 02:06:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:06:51 INFO - 'tests': ['tp5o']}, 02:06:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:06:51 INFO - 'talos_options': ['--disable-e10s', 02:06:51 INFO - '--xperf_path', 02:06:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:06:51 INFO - 'tests': ['tp5n']}, 02:06:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:06:51 INFO - 'talos_options': ['--xperf_path', 02:06:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:06:51 INFO - 'tests': ['tp5n']}}, 02:06:51 INFO - 'talos.zip': {'path': '', 02:06:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:06:51 INFO - Running post-action listener: _resource_record_post_action 02:06:51 INFO - [mozharness: 2017-01-05 10:06:51.123446Z] Finished populate-webroot step (success) 02:06:51 INFO - [mozharness: 2017-01-05 10:06:51.123571Z] Running create-virtualenv step. 02:06:51 INFO - Running pre-action listener: _resource_record_pre_action 02:06:51 INFO - Running main action method: create_virtualenv 02:06:51 INFO - Creating virtualenv /builds/slave/test/build/venv 02:06:51 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 02:06:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:06:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:06:51 INFO - Using real prefix '/tools/python27' 02:06:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:06:51 INFO - Installing distribute.............................................................................................................................................................................................done. 02:06:54 INFO - Installing pip.................done. 02:06:54 INFO - Return code: 0 02:06:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:06:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:06:55 INFO - Reading from file tmpfile_stdout 02:06:55 INFO - Output received: 02:06:55 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) 02:06:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:06:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:06:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:06:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:06:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:06:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:55 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 0x10eaf3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea69570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f69d27590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e907d30>, '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 0x10e76ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8ffb20>, '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.nDQFIJv0OY/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.aesc1kfPhF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 02:06:55 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 02:06:55 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 02:06:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nDQFIJv0OY/Render', 02:06:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:06:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:06:55 INFO - 'HOME': '/Users/cltbld', 02:06:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:06:55 INFO - 'LOGNAME': 'cltbld', 02:06:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:06:55 INFO - 'MOZ_NO_REMOTE': '1', 02:06:55 INFO - 'NO_EM_RESTART': '1', 02:06:55 INFO - 'PAGER': '/bin/cat', 02:06:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:06:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:06:55 INFO - 'PWD': '/builds/slave/test', 02:06:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:06:55 INFO - 'SHELL': '/bin/bash', 02:06:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aesc1kfPhF/Listeners', 02:06:55 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 02:06:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:06:55 INFO - 'USER': 'cltbld', 02:06:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:06:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:06:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:06:55 INFO - 'XPC_FLAGS': '0x0', 02:06:55 INFO - 'XPC_SERVICE_NAME': '0', 02:06:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:06:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:06:55 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 02:06:55 INFO - Cleaning up... 02:06:55 INFO - Return code: 0 02:06:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:06:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:06:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:06:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:06:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:06:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:06:55 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 0x10eaf3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea69570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f69d27590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e907d30>, '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 0x10e76ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8ffb20>, '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.nDQFIJv0OY/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.aesc1kfPhF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 02:06:55 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 02:06:55 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 02:06:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nDQFIJv0OY/Render', 02:06:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:06:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:06:55 INFO - 'HOME': '/Users/cltbld', 02:06:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:06:55 INFO - 'LOGNAME': 'cltbld', 02:06:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:06:55 INFO - 'MOZ_NO_REMOTE': '1', 02:06:55 INFO - 'NO_EM_RESTART': '1', 02:06:55 INFO - 'PAGER': '/bin/cat', 02:06:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:06:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:06:55 INFO - 'PWD': '/builds/slave/test', 02:06:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:06:55 INFO - 'SHELL': '/bin/bash', 02:06:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aesc1kfPhF/Listeners', 02:06:55 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 02:06:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:06:55 INFO - 'USER': 'cltbld', 02:06:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:06:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:06:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:06:55 INFO - 'XPC_FLAGS': '0x0', 02:06:55 INFO - 'XPC_SERVICE_NAME': '0', 02:06:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:06:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:06:55 INFO - Downloading/unpacking psutil>=3.1.1 02:06:55 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 02:06:55 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 02:06:55 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 02:06:55 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 02:06:55 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 02:06:55 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 02:06:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:06:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:06:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:06:59 INFO - Installing collected packages: psutil 02:06:59 INFO - Running setup.py install for psutil 02:06:59 INFO - building 'psutil._psutil_osx' extension 02:06:59 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 02:07:00 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 02:07:00 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 02:07:00 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 02:07:00 INFO - building 'psutil._psutil_posix' extension 02:07:00 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 02:07:00 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:07:00 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:07:00 INFO - ^ 02:07:00 INFO - 1 warning generated. 02:07:00 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 02:07:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:07:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:07:01 INFO - Successfully installed psutil 02:07:01 INFO - Cleaning up... 02:07:01 INFO - Return code: 0 02:07:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:07:01 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')]} 02:07:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:07:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:07:01 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')]} 02:07:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:07:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:07:01 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 0x10eaf3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea69570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f69d27590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e907d30>, '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 0x10e76ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8ffb20>, '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.nDQFIJv0OY/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.aesc1kfPhF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 02:07:01 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 02:07:01 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 02:07:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nDQFIJv0OY/Render', 02:07:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:07:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:07:01 INFO - 'HOME': '/Users/cltbld', 02:07:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:07:01 INFO - 'LOGNAME': 'cltbld', 02:07:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:07:01 INFO - 'MOZ_NO_REMOTE': '1', 02:07:01 INFO - 'NO_EM_RESTART': '1', 02:07:01 INFO - 'PAGER': '/bin/cat', 02:07:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:07:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:07:01 INFO - 'PWD': '/builds/slave/test', 02:07:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:07:01 INFO - 'SHELL': '/bin/bash', 02:07:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aesc1kfPhF/Listeners', 02:07:01 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 02:07:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:07:01 INFO - 'USER': 'cltbld', 02:07:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:07:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:07:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:07:01 INFO - 'XPC_FLAGS': '0x0', 02:07:01 INFO - 'XPC_SERVICE_NAME': '0', 02:07:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:07:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:07:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:07:01 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 02:07:01 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 02:07:01 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 02:07:01 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 02:07:01 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 02:07:01 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 02:07:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:07:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:07:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:07:04 INFO - Installing collected packages: mozsystemmonitor 02:07:04 INFO - Running setup.py install for mozsystemmonitor 02:07:04 INFO - Successfully installed mozsystemmonitor 02:07:04 INFO - Cleaning up... 02:07:04 INFO - Return code: 0 02:07:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:07: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')]} 02:07:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:07:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:07:05 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')]} 02:07:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:07:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:07:06 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 0x10eaf3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea69570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f69d27590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e907d30>, '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 0x10e76ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8ffb20>, '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.nDQFIJv0OY/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.aesc1kfPhF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 02:07:06 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 02:07:06 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 02:07:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nDQFIJv0OY/Render', 02:07:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:07:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:07:06 INFO - 'HOME': '/Users/cltbld', 02:07:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:07:06 INFO - 'LOGNAME': 'cltbld', 02:07:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:07:06 INFO - 'MOZ_NO_REMOTE': '1', 02:07:06 INFO - 'NO_EM_RESTART': '1', 02:07:06 INFO - 'PAGER': '/bin/cat', 02:07:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:07:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:07:06 INFO - 'PWD': '/builds/slave/test', 02:07:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:07:06 INFO - 'SHELL': '/bin/bash', 02:07:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aesc1kfPhF/Listeners', 02:07:06 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 02:07:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:07:06 INFO - 'USER': 'cltbld', 02:07:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:07:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:07:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:07:06 INFO - 'XPC_FLAGS': '0x0', 02:07:06 INFO - 'XPC_SERVICE_NAME': '0', 02:07:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:07:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:07:06 INFO - Downloading/unpacking jsonschema==2.5.1 02:07:06 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 02:07:06 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 02:07:06 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 02:07:06 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 02:07:06 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 02:07:06 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 02:07:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:07:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:07:10 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 02:07:10 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 02:07:10 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 02:07:10 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 02:07:10 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 02:07:10 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 02:07:10 INFO - Downloading functools32-3.2.3-2.tar.gz 02:07:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:07:10 INFO - warning: no files found matching '*.txt' 02:07:10 INFO - no previously-included directories found matching 'build' 02:07:10 INFO - no previously-included directories found matching 'dist' 02:07:10 INFO - no previously-included directories found matching '.git*' 02:07:10 INFO - Installing collected packages: jsonschema, functools32 02:07:10 INFO - Running setup.py install for functools32 02:07:10 INFO - warning: no files found matching '*.txt' 02:07:10 INFO - no previously-included directories found matching 'build' 02:07:10 INFO - no previously-included directories found matching 'dist' 02:07:10 INFO - no previously-included directories found matching '.git*' 02:07:10 INFO - Successfully installed jsonschema functools32 02:07:10 INFO - Cleaning up... 02:07:10 INFO - Return code: 0 02:07:10 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:07:10 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')]} 02:07:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:07:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:07:10 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')]} 02:07:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:07:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:07:10 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 0x10eaf3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea69570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f69d27590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e907d30>, '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 0x10e76ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8ffb20>, '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.nDQFIJv0OY/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.aesc1kfPhF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 02:07:10 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 02:07:10 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 02:07:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nDQFIJv0OY/Render', 02:07:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:07:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:07:10 INFO - 'HOME': '/Users/cltbld', 02:07:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:07:10 INFO - 'LOGNAME': 'cltbld', 02:07:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:07:10 INFO - 'MOZ_NO_REMOTE': '1', 02:07:10 INFO - 'NO_EM_RESTART': '1', 02:07:10 INFO - 'PAGER': '/bin/cat', 02:07:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:07:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:07:10 INFO - 'PWD': '/builds/slave/test', 02:07:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:07:10 INFO - 'SHELL': '/bin/bash', 02:07:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aesc1kfPhF/Listeners', 02:07:10 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 02:07:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:07:10 INFO - 'USER': 'cltbld', 02:07:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:07:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:07:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:07:10 INFO - 'XPC_FLAGS': '0x0', 02:07:10 INFO - 'XPC_SERVICE_NAME': '0', 02:07:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:07:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:07:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:07:11 INFO - Cleaning up... 02:07:11 INFO - Return code: 0 02:07:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:07:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:07:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:07:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:07:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:07:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:07:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:07:11 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 0x10eaf3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea69570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f69d27590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e907d30>, '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 0x10e76ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8ffb20>, '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.nDQFIJv0OY/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.aesc1kfPhF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 02:07:11 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 02:07:11 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 02:07:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nDQFIJv0OY/Render', 02:07:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:07:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:07:11 INFO - 'HOME': '/Users/cltbld', 02:07:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:07:11 INFO - 'LOGNAME': 'cltbld', 02:07:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:07:11 INFO - 'MOZ_NO_REMOTE': '1', 02:07:11 INFO - 'NO_EM_RESTART': '1', 02:07:11 INFO - 'PAGER': '/bin/cat', 02:07:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:07:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:07:11 INFO - 'PWD': '/builds/slave/test', 02:07:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:07:11 INFO - 'SHELL': '/bin/bash', 02:07:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aesc1kfPhF/Listeners', 02:07:11 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 02:07:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:07:11 INFO - 'USER': 'cltbld', 02:07:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:07:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:07:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:07:11 INFO - 'XPC_FLAGS': '0x0', 02:07:11 INFO - 'XPC_SERVICE_NAME': '0', 02:07:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:07:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:07:11 INFO - Downloading/unpacking blobuploader==1.2.4 02:07:11 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 02:07:11 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 02:07:11 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 02:07:11 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 02:07:11 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 02:07:11 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 02:07:14 INFO - Downloading blobuploader-1.2.4.tar.gz 02:07:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:07:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:07:14 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 02:07:14 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 02:07:14 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 02:07:14 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 02:07:14 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 02:07:14 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 02:07:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:07:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:07:14 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 02:07:14 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 02:07:14 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 02:07:14 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 02:07:14 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 02:07:14 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 02:07:15 INFO - Downloading docopt-0.6.1.tar.gz 02:07:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:07:15 INFO - Installing collected packages: blobuploader, requests, docopt 02:07:15 INFO - Running setup.py install for blobuploader 02:07:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:07:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:07:15 INFO - Running setup.py install for requests 02:07:16 INFO - Running setup.py install for docopt 02:07:16 INFO - Successfully installed blobuploader requests docopt 02:07:16 INFO - Cleaning up... 02:07:16 INFO - Return code: 0 02:07:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:07:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:07:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:07:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:07:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:07:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:07:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:07:16 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 0x10eaf3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea69570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f69d27590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e907d30>, '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 0x10e76ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8ffb20>, '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.nDQFIJv0OY/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.aesc1kfPhF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 02:07:16 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 02:07:16 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 02:07:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nDQFIJv0OY/Render', 02:07:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:07:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:07:16 INFO - 'HOME': '/Users/cltbld', 02:07:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:07:16 INFO - 'LOGNAME': 'cltbld', 02:07:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:07:16 INFO - 'MOZ_NO_REMOTE': '1', 02:07:16 INFO - 'NO_EM_RESTART': '1', 02:07:16 INFO - 'PAGER': '/bin/cat', 02:07:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:07:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:07:16 INFO - 'PWD': '/builds/slave/test', 02:07:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:07:16 INFO - 'SHELL': '/bin/bash', 02:07:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aesc1kfPhF/Listeners', 02:07:16 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 02:07:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:07:16 INFO - 'USER': 'cltbld', 02:07:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:07:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:07:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:07:16 INFO - 'XPC_FLAGS': '0x0', 02:07:16 INFO - 'XPC_SERVICE_NAME': '0', 02:07:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:07:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:07:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:07:16 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-DFGATd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:07:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:07:16 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-JrUT5D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:07:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:07:16 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-E7vMyd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:07:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:07:16 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-dtvpMR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:07:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:07:17 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-mWnPAJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:07:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:07:17 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-ustaKQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:07:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:07:17 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-7tNJfz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:07:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:07:17 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-RVLyti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:07:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:07:17 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-Wp_hyv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:07:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:07:17 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-iQFNF2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:07:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:07:17 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-3lAU46-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:07:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:07:17 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-WO0JPO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:07:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:07:17 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-Wsb9e6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:07:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:07:18 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-1cp7DM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:07:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:07:18 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-5ydj4R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:07:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:07:18 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-b1iqsT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:07:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:07:18 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-XoFUS4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:07:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:07:18 INFO - Running setup.py install for manifestparser 02:07:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:07:18 INFO - Running setup.py install for mozcrash 02:07:18 INFO - Running setup.py install for mozdebug 02:07:18 INFO - Running setup.py install for mozdevice 02:07:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:07:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:07:19 INFO - Running setup.py install for mozfile 02:07:19 INFO - Running setup.py install for mozhttpd 02:07:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:07:19 INFO - Running setup.py install for mozinfo 02:07:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:07:19 INFO - Running setup.py install for mozInstall 02:07:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:07:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:07:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:07:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:07:19 INFO - Running setup.py install for mozleak 02:07:19 INFO - Running setup.py install for mozlog 02:07:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:07:19 INFO - Running setup.py install for moznetwork 02:07:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:07:20 INFO - Running setup.py install for mozprocess 02:07:20 INFO - Running setup.py install for mozprofile 02:07:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:07:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:07:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:07:20 INFO - Running setup.py install for mozrunner 02:07:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:07:20 INFO - Running setup.py install for mozscreenshot 02:07:20 INFO - Running setup.py install for moztest 02:07:20 INFO - Running setup.py install for mozversion 02:07:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:07:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:07:21 INFO - Cleaning up... 02:07:21 INFO - Return code: 0 02:07:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:07: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')]} 02:07:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:07:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:07: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')]} 02:07:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:07:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:07:21 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 0x10eaf3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea69570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f69d27590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e907d30>, '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 0x10e76ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8ffb20>, '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.nDQFIJv0OY/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.aesc1kfPhF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 02:07:21 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 02:07:21 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 02:07:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nDQFIJv0OY/Render', 02:07:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:07:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:07:21 INFO - 'HOME': '/Users/cltbld', 02:07:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:07:21 INFO - 'LOGNAME': 'cltbld', 02:07:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:07:21 INFO - 'MOZ_NO_REMOTE': '1', 02:07:21 INFO - 'NO_EM_RESTART': '1', 02:07:21 INFO - 'PAGER': '/bin/cat', 02:07:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:07:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:07:21 INFO - 'PWD': '/builds/slave/test', 02:07:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:07:21 INFO - 'SHELL': '/bin/bash', 02:07:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aesc1kfPhF/Listeners', 02:07:21 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 02:07:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:07:21 INFO - 'USER': 'cltbld', 02:07:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:07:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:07:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:07:21 INFO - 'XPC_FLAGS': '0x0', 02:07:21 INFO - 'XPC_SERVICE_NAME': '0', 02:07:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:07:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:07:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:07:21 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-jEPuEW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:07:21 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)) 02:07:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:07:21 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-VZvkOy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:07:21 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)) 02:07:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:07:21 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-OHv9gW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:07:21 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)) 02:07:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:07:21 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-VcMR3Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:07:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:07:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:07:21 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-QsPeVH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:07:21 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)) 02:07:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:07:21 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-cdKm27-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:07:21 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)) 02:07:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:07:21 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-pLLJXw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:07:22 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)) 02:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:07:22 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-FWJkY3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:07:22 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)) 02:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:07:22 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-Brr2pe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:07:22 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)) 02:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:07:22 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-ywXzWP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:07:22 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)) 02:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:07:22 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-hFkI4X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:07:22 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)) 02:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:07:22 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-2CFqbL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:07:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:07:22 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-H2IghN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:07:22 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)) 02:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:07:22 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-eElyJ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:07:22 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)) 02:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:07:22 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-xJG0zL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:07:22 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)) 02:07:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:07:22 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-WoAGdB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:07:23 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)) 02:07:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:07:23 INFO - Running setup.py (path:/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/pip-JRYwc9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:07:23 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)) 02:07:23 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)) 02:07:23 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)) 02:07:23 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)) 02:07:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:07:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:07:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:07:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:07:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:07:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:07:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:07:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:07:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:07:25 INFO - Downloading blessings-1.6.tar.gz 02:07:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:07:26 INFO - Installing collected packages: blessings 02:07:26 INFO - Running setup.py install for blessings 02:07:26 INFO - Successfully installed blessings 02:07:26 INFO - Cleaning up... 02:07:26 INFO - Return code: 0 02:07:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:07:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:07:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:07:26 INFO - Reading from file tmpfile_stdout 02:07:26 INFO - Current package versions: 02:07:26 INFO - blessings == 1.6 02:07:26 INFO - blobuploader == 1.2.4 02:07:26 INFO - docopt == 0.6.1 02:07:26 INFO - functools32 == 3.2.3-2 02:07:26 INFO - jsonschema == 2.5.1 02:07:26 INFO - manifestparser == 1.1 02:07:26 INFO - mozInstall == 1.12 02:07:26 INFO - mozcrash == 1.0 02:07:26 INFO - mozdebug == 0.1 02:07:26 INFO - mozdevice == 0.48 02:07:26 INFO - mozfile == 1.2 02:07:26 INFO - mozhttpd == 0.7 02:07:26 INFO - mozinfo == 0.9 02:07:26 INFO - mozleak == 0.1 02:07:26 INFO - mozlog == 3.4 02:07:26 INFO - moznetwork == 0.27 02:07:26 INFO - mozprocess == 0.23 02:07:26 INFO - mozprofile == 0.28 02:07:26 INFO - mozrunner == 6.13 02:07:26 INFO - mozscreenshot == 0.1 02:07:26 INFO - mozsystemmonitor == 0.3 02:07:26 INFO - moztest == 0.8 02:07:26 INFO - mozversion == 1.4 02:07:26 INFO - psutil == 3.1.1 02:07:26 INFO - requests == 1.2.3 02:07:26 INFO - wsgiref == 0.1.2 02:07:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:07:26 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')]} 02:07:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:07:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:07:26 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')]} 02:07:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:07:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:07:26 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 0x10eaf3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea69570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f69d27590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e907d30>, '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 0x10e76ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8ffb20>, '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.nDQFIJv0OY/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.aesc1kfPhF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 02:07:26 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 02:07:26 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 02:07:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nDQFIJv0OY/Render', 02:07:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:07:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:07:26 INFO - 'HOME': '/Users/cltbld', 02:07:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:07:26 INFO - 'LOGNAME': 'cltbld', 02:07:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:07:26 INFO - 'MOZ_NO_REMOTE': '1', 02:07:26 INFO - 'NO_EM_RESTART': '1', 02:07:26 INFO - 'PAGER': '/bin/cat', 02:07:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:07:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:07:26 INFO - 'PWD': '/builds/slave/test', 02:07:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:07:26 INFO - 'SHELL': '/bin/bash', 02:07:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aesc1kfPhF/Listeners', 02:07:26 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 02:07:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:07:26 INFO - 'USER': 'cltbld', 02:07:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:07:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:07:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:07:26 INFO - 'XPC_FLAGS': '0x0', 02:07:26 INFO - 'XPC_SERVICE_NAME': '0', 02:07:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:07:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:07:26 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)) 02:07:26 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)) 02:07:26 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)) 02:07:26 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)) 02:07:26 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)) 02:07:26 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)) 02:07:26 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)) 02:07:26 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)) 02:07:26 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)) 02:07:26 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)) 02:07:26 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)) 02:07:26 INFO - Cleaning up... 02:07:26 INFO - Return code: 0 02:07:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:07:26 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')]} 02:07:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:07:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:07:26 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')]} 02:07:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:07:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:07:26 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 0x10eaf3490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ea69570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f69d27590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e907d30>, '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 0x10e76ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8ffb20>, '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.nDQFIJv0OY/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.aesc1kfPhF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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 02:07:26 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 02:07:26 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 02:07:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nDQFIJv0OY/Render', 02:07:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:07:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:07:26 INFO - 'HOME': '/Users/cltbld', 02:07:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:07:26 INFO - 'LOGNAME': 'cltbld', 02:07:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:07:26 INFO - 'MOZ_NO_REMOTE': '1', 02:07:26 INFO - 'NO_EM_RESTART': '1', 02:07:26 INFO - 'PAGER': '/bin/cat', 02:07:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:07:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:07:26 INFO - 'PWD': '/builds/slave/test', 02:07:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:07:26 INFO - 'SHELL': '/bin/bash', 02:07:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aesc1kfPhF/Listeners', 02:07:26 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 02:07:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:07:26 INFO - 'USER': 'cltbld', 02:07:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:07:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:07:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:07:26 INFO - 'XPC_FLAGS': '0x0', 02:07:26 INFO - 'XPC_SERVICE_NAME': '0', 02:07:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:07:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:07:27 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:07:27 INFO - Cleaning up... 02:07:27 INFO - Return code: 0 02:07:27 INFO - Running post-action listener: _resource_record_post_action 02:07:27 INFO - Running post-action listener: _start_resource_monitoring 02:07:27 INFO - Starting resource monitoring. 02:07:27 INFO - [mozharness: 2017-01-05 10:07:27.148177Z] Finished create-virtualenv step (success) 02:07:27 INFO - [mozharness: 2017-01-05 10:07:27.149166Z] Running install step. 02:07:27 INFO - Running pre-action listener: _resource_record_pre_action 02:07:27 INFO - Running main action method: install 02:07:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:07:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:07:27 INFO - Reading from file tmpfile_stdout 02:07:27 INFO - Detecting whether we're running mozinstall >=1.0... 02:07:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:07:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:07:27 INFO - Reading from file tmpfile_stdout 02:07:27 INFO - Output received: 02:07:27 INFO - Usage: mozinstall [options] installer 02:07:27 INFO - Options: 02:07:27 INFO - -h, --help show this help message and exit 02:07:27 INFO - -d DEST, --destination=DEST 02:07:27 INFO - Directory to install application into. [default: 02:07:27 INFO - "/builds/slave/test"] 02:07:27 INFO - --app=APP Application being installed. [default: firefox] 02:07:27 INFO - mkdir: /builds/slave/test/build/application 02:07:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:07:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:07:59 INFO - Reading from file tmpfile_stdout 02:07:59 INFO - Output received: 02:07:59 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:07:59 INFO - Running post-action listener: _resource_record_post_action 02:07:59 INFO - [mozharness: 2017-01-05 10:07:59.460939Z] Finished install step (success) 02:07:59 INFO - [mozharness: 2017-01-05 10:07:59.461071Z] Running run-tests step. 02:07:59 INFO - Running pre-action listener: _resource_record_pre_action 02:07:59 INFO - Running main action method: run_tests 02:07:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:07:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:07:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:07:59 INFO - 2017-01-05 02:07:59.475 screenresolution[1625:7437] starting screenresolution argv=screenresolution get 02:07:59 INFO - 2017-01-05 02:07:59.478 screenresolution[1625:7437] Display 0: 1600x1200x32@60 02:07:59 INFO - 2017-01-05 02:07:59.483 screenresolution[1626:7439] starting screenresolution argv=screenresolution list 02:07:59 INFO - Available Modes on Display 0 02:07:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:07:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:07:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:07:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:07:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:07:59 INFO - Intel Iris: 02:07:59 INFO - Chipset Model: Intel Iris 02:07:59 INFO - Type: GPU 02:07:59 INFO - Bus: Built-In 02:07:59 INFO - VRAM (Dynamic, Max): 1536 MB 02:07:59 INFO - Vendor: Intel (0x8086) 02:07:59 INFO - Device ID: 0x0a2e 02:07:59 INFO - Revision ID: 0x0009 02:07:59 INFO - Displays: 02:07:59 INFO - Display: 02:07:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:07:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:07:59 INFO - Display Serial Number: 1600x1200 60 02:07:59 INFO - Main Display: Yes 02:07:59 INFO - Mirror: Off 02:07:59 INFO - Online: Yes 02:07:59 INFO - Rotation: Supported 02:07:59 INFO - Return code: 0 02:07:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:07:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:07:59 INFO - Python 2.7.3 02:07:59 INFO - Return code: 0 02:07:59 INFO - grabbing minidump binary from tooltool 02:07:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:07:59 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 0x10e907d30>, '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 0x10e76ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e8ffb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:07:59 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 02:07:59 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 02:07:59 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 02:07:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:07:59 INFO - Return code: 0 02:07:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:07:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:07:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:07:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:07:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:07:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VHUmfEpWTgC9DwzM_p0lYQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0114', '--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 02:07:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VHUmfEpWTgC9DwzM_p0lYQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0114 --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 02:07:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nDQFIJv0OY/Render', 02:07:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:07:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:07:59 INFO - 'HOME': '/Users/cltbld', 02:07:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:07:59 INFO - 'LOGNAME': 'cltbld', 02:07:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:07:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:07:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:07:59 INFO - 'MOZ_NO_REMOTE': '1', 02:07:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:07:59 INFO - 'NO_EM_RESTART': '1', 02:07:59 INFO - 'PAGER': '/bin/cat', 02:07:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:07:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:07:59 INFO - 'PWD': '/builds/slave/test', 02:07:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:07:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:07:59 INFO - 'SHELL': '/bin/bash', 02:07:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aesc1kfPhF/Listeners', 02:07:59 INFO - 'TMPDIR': '/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/', 02:07:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:07:59 INFO - 'USER': 'cltbld', 02:07:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:07:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:07:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:07:59 INFO - 'XPC_FLAGS': '0x0', 02:07:59 INFO - 'XPC_SERVICE_NAME': '0', 02:07:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:07:59 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VHUmfEpWTgC9DwzM_p0lYQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0114', '--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 02:08:00 INFO - mozversion application_buildid: 20170105000039 02:08:00 INFO - mozversion application_changeset: 5af63cf90f2874f840c132461bff7f6130483362 02:08:00 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:08:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:08:00 INFO - mozversion application_name: Firefox 02:08:00 INFO - mozversion application_remotingname: firefox-dev 02:08:00 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:08:00 INFO - mozversion application_vendor: Mozilla 02:08:00 INFO - mozversion application_version: 52.0a2 02:08:00 INFO - mozversion platform_buildid: 20170105000039 02:08:00 INFO - mozversion platform_changeset: 5af63cf90f2874f840c132461bff7f6130483362 02:08:00 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:08:00 INFO - mozversion platform_version: 52.0a2 02:08:00 INFO - using testdate: 1483610880 02:08:00 INFO - actual date: 1483610880 02:08:00 INFO - starting webserver on 'localhost:49240' 02:08:00 INFO - SUITE-START | Running 2 tests 02:08:00 INFO - TEST-START | dromaeo_css 02:08:00 INFO - operating with platform_type : mac_ 02:08:00 INFO - Initialising browser for dromaeo_css test... 02:08:00 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/tmp1owQsM/profile http://localhost:49240/getInfo.html) 02:08:03 INFO - PROCESS | 1636 | 2017-01-05 02:08:03.317 plugin-container[1638:7589] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 02:08:03 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:08:03 INFO - PROCESS | 1636 | 2017-01-05 02:08:03.320 plugin-container[1638:7589] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920f, name = 'com.apple.CFPasteboardClient' 02:08:03 INFO - PROCESS | 1636 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:08:03 INFO - PROCESS | 1636 | 2017-01-05 02:08:03.320 plugin-container[1638:7589] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:08:03 INFO - PROCESS | 1636 | __metrics Screen width/height:1600/1200 02:08:03 INFO - PROCESS | 1636 | colorDepth:24 02:08:03 INFO - PROCESS | 1636 | Browser inner width/height: 1024/689 02:08:03 INFO - PROCESS | 1636 | __metrics 02:08:03 ERROR - PROCESS | 1636 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 02:08:04 INFO - TEST-INFO | 1636: exit 0 02:08:04 INFO - Browser initialized. 02:08:04 INFO - Running cycle 1/1 for dromaeo_css test... 02:08:04 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/tmp1owQsM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:08:07 INFO - PROCESS | 1639 | 2017-01-05 02:08:07.038 plugin-container[1640:7715] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x833f, name = 'com.apple.tsm.portname' 02:08:07 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:08:07 INFO - PROCESS | 1639 | 2017-01-05 02:08:07.041 plugin-container[1640:7715] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x890b, name = 'com.apple.CFPasteboardClient' 02:08:07 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:08:07 INFO - PROCESS | 1639 | 2017-01-05 02:08:07.041 plugin-container[1640:7715] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:08:16 INFO - PROCESS | 1639 | RSS: Main: 229773312 02:08:16 INFO - PROCESS | 1639 | 02:11:20 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 02:11:22 INFO - PROCESS | 1639 | RSS: Main: 234291200 02:11:22 INFO - PROCESS | 1639 | 02:14:24 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 02:14:25 INFO - PROCESS | 1639 | RSS: Main: 243511296 02:14:25 INFO - PROCESS | 1639 | 02:17:27 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 02:17:27 INFO - PROCESS | 1639 | RSS: Main: 246325248 02:17:27 INFO - PROCESS | 1639 | 02:20:29 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 02:20:30 INFO - PROCESS | 1639 | RSS: Main: 246046720 02:20:30 INFO - PROCESS | 1639 | 02:23:33 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 02:23:34 INFO - PROCESS | 1639 | RSS: Main: 249266176 02:23:34 INFO - PROCESS | 1639 | 02:26:36 INFO - PROCESS | 1639 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 02:26:37 INFO - PROCESS | 1639 | RSS: Main: 251473920 02:26:37 INFO - PROCESS | 1639 | 02:26:37 INFO - PROCESS | 1639 | __start_tp_report 02:26:37 INFO - PROCESS | 1639 | _x_x_mozilla_page_load 02:26:37 INFO - PROCESS | 1639 | _x_x_mozilla_page_load_details 02:26:37 INFO - PROCESS | 1639 | |i|pagename|runs| 02:26:37 INFO - PROCESS | 1639 | |0;dojo.html;3334.433146365118;3379.7465190110734;3385.9661403385976;3397.2016576104597;3435.6220815710276;4332.480102387718;5728.026235539958;5739.339975902767;5812.24440822693;5882.6764527950945;5809.157672137538;5860.794872179475;5868.354481007086;5904.940950590493;5932.703364831763;4913.975430122844;5227.973860130694;5238.764255608507;5277.47225277473;5279.973600132013;4411.955880441203;5346.599005074616;5361.410244873061;5363.141897296433;5380.058489764295;5209.479052094779;5339.839804805862;5348.545373643245;5363.54409875161;5364.088105022156;5145.202493613496;5255.054090263751;5274.92087618686;5276.15581506957;5291.28567643367;5123.180291153435;5215.869603259911;5221.242919776611;5321.813736519203;5334.839954801362;5502.889942201134;5735.11105778605;5950.7322170502;5971;5989.131575921467;6170.537209709245;6185.721642526097;6247.625142491465;6253.093301471306;6282.246130464374;4700.882477938045;6179.918514259986;6199.473044791153;6236.43872051513;6253.312135665074;5889.558283128783;5925.762969481172;5931.139984702238;5931.466168044896;5957.314908785458;6149.600275982047;6196.47329976957;6219.346968568326;6223.190985171989;6251.468625166821;6167.722452489649;6193.163922870445;6211.223597050369;6221.4089661482085;6231.127642130105;6229.96885015572;6230.688465576703;6261.029540421242;6265.248170219603;6318.652474113968;5453.209284653783;6004.519638428916;6116.633002019893;6208.192934918432;6284.748610055546;5114.974425127851;5403.621746477787;6107.795737660903;6192.876142477216;6199.473044791153;4146.896327591834;4147.834086636501;4978.153713868651;4980.05378977993;5013.97213571217;4317.784110794448;4406;4425.491068527161;4445.955540444555;4449.399331090326;4374.518802931675;4727.83452579165;5765.1928729977835;5765.8558536036935;6019.615488437684;6158.630482171084;6177.227846519185;6188.938110618927;6232.002879539252;6233.189685340877;5744.51171650406;5790.507806836466;5812.360640329561;5847.6783776891825;5864.794732184353;5376.109917739842;5431.293931788842;5514.338279406416;5519.668819870821;5524.392316845144;6348;6348.174737284031;6364.395382438661;6365.0452432134625;6372;6145.002874065857;6222.782202622887;6246.312905580474;6254.937450625435;6280.623162610258;6228.4394404502;6239.875202496016;6271.592346497463;6359.872802544016;6379.6810159490915;5170.663906846043;5212.348456442945;6239.282482514569;6256.906146407717;6275.96862015687;5999.5800293979;6185.257769067741;6190.226221722285;6209.192804935329;6260.780872669435;6118.571699980958;6146.231721034871;6152.292486363946;6156.661383623944;6203.286622038523;6158.107074474266;6200.255969283715;6202.038684003986;6225.564210505221;6228.532860035425;5992.820215393545;5997.100434934795;6106.389361063858;6114.816555503342;6123.969380153071;6005.579609427298;6110.083487476736;6121.84695382619;6126.969365153146;6207.6585787782105;4784.803799050237;6017.127516510169;6035.36628653985;6036.456718895321;6040.1543783871175 02:26:37 INFO - PROCESS | 1639 | |1;ext.html;483.3957553058677;492.22228878372175;506.825145324863;518.2666526864485;538.9003034438629;38536.61463385366;39810;40066;42275;42637.57362426375;40822.59177408225;41263.79368103163;41296.96757581062;41329.76010719676;41375.37936930941;9349.532523373855;9520.666776662818;9525.380850244746;9528.237740980705;9569.377990430634;9321.021292764264;9369.063093690645;9444.05559444057;9448.622055117821;9451.905480945206;8134.593270336489;9444.622215111422;9449.81100377992;9483;9493.05069493052;9382.39014464061;9446.433214007113;9456.90543094574;9457.14885660287;9459.574319155654;9392.8591071134;9428.481433521154;9443.338966272364;9469.66856160031;9474.715758527254;39522.80238598789;39646.603533964866;39688.80155599232;39763.20473590541;39769.60230397688;2829.39168078862;3011.9397612047637;3273.214428537134;3350.27968986669;3403.1322012886803;24897.751022489723;28935.855320723473;29348.853255733586;29423.26441838971;30213.98470190549;2176.869387836735;2734.507788598052;2869.727375899307;2913.9708602914125;2939.250491124771;370676;375793;376200;377256;377929;59004.704976474844;59129.40870591239;59214.70392648095;59468;59541.70229148826;61939.761204775656;62381;62505.68747156235;62550.37449625537;62634.373656262855;13631.659208519668;15844.68310633804;16008.599785005468;16034.118123503085;16088.436904708278;11233.71915702114;12439.875601244054;12498.06264530164;12517.749645007048;12523.248605083723;16258.430954916757;16484.25820838041;16490.093044882407;16505.422310219084;16566.83433165677;7030.472714546328;7068.540544864567;7080.185778635419;7110.288971102952;7116.928830711627;839.6221700234919;952.7475219066953;1073.1843798712878;1088.5373716170595;1106.468894930438;24059;27381.589276160874;30008.699913001026;30263.848680756895;30644.774209031835;984.5175863826673;1013.503383342157;1022.2588623248234;1035.4667346316653;1055.630529314749;12767.361631918367;13086.411111500005;15554.300056497485;15766.842331576767;16337.591560210853;528865;871455;877423;890938;892406;57876;58448.70775646094;58470.41529584735;58492.70753646205;58542.12186817201;1323.3317174826843;1341.5908148014948;1367.9384427700777;1369.1237607930932;1375.9449622015206;29485;30580.541291881083;30834.845825770728;31049.379012419173;31078.378432431677;50051.49948500614;50139;50411;50467;50488.74755626198;539430;543712;548403;548946;549112;25801.096961606254;26142.86928565421;26159.607605886307;26185.73814261833;26238;43049.354259685504;44279.55720442755;44624;45572.31641525446;46805;37617.62382376251;38007.80996095002;38110.856674299815;38169.61830381772;38332.233355333294;36855;37444.62555374411;37935.62064379431;38563.614363856;38567.80716096402;35881.64118358783;36202.8189859049;37090.629093709795;37168.25663486769;37170.32733526944;31474.52788208133;33192.66807331896;33463.66536334605;33482.330353393285;33486.83256583799;25252.873735631205;25397.11110111137;25496.087793415198;25817.483650326514;25894.964201431732 02:26:37 INFO - PROCESS | 1639 | |2;jquery.html;334.1662551932928;365.35879531422347;365.8609728303245;376.7965298738681;393.9271234821558;13994.930025349871;15839;19478.805211947878;19628.50928726779;19716.605667886633;18711.251549937988;19023.52441188971;19286.32497862575;19697.704534431996;19846.801531984675;5533.031719449091;5806.680632565207;5855.531557475391;5869.941300587004;5888.793892213774;5539.889202215953;5700.942990570104;5756.366799652035;5757.107648314497;5787.508061814729;5109.697027258044;5342.412334643205;5722.54219662426;5737.598368114233;5738.368779434259;5503.215193659323;5643.6049476536655;5733.283339582552;5740.512056475204;5762.3949485304065;5018.673786203922;5035.169197082477;5040.470750571174;5043.949560504385;5050.292958985745;12569.245845249314;14306.28468576567;22218.88905554732;23160.42098947523;23200.419989500228;2136.5192831612917;2266.0822366941416;2311.91908283211;2392.509535545209;2423.87880605969;9841.655542055994;9925.50372481373;10105.444200568965;12361.567345142877;15305.234738263041;1845.4832646858767;1877.558773688182;1914.6457905287427;1927.9781715690706;1928.3829174664113;105062.47468762683;108189.45905270502;110409;111043.88956110417;113354;11360.091192704564;11552.942235288938;11751.941240293743;11764;11893.70265743346;14582;14593.197374144524;14597.197154156624;14612.926935365254;14623.122612643276;8365.623546940402;8435.156484351515;8486.74539763808;8500.914990850013;8502;8341.290990265774;8367.748967530984;8376.874346884802;8465.915340846635;8505.362097842686;4431.379606855043;4472.530384309665;4506.414166158378;4593.058423023273;4683.227267500893;1876.608735558799;1927.5181204698824;1935.98064019358;1937.8352840008474;1940.4857712706144;817.9631916563769;853.3216093205916;880.5465185429509;918.5728636184207;932.846080396727;16088.108875410724;16602.50192494227;16962.321507139823;19427.51431214231;20626.58746825085;887.3477993674644;891.2112780189578;926.1803304075806;939.8120375924706;940.8917974432891;10302.484875756183;10893.727656808644;10935.28920620157;10955.616553420592;10958.671239862817;600649;603066;607305;607937;612950;78400;112035.63893083221;122240;123219;127764;972.1153750087386;984.4979060679249;1029.4852573713144;1031.7730099378125;1036.1710631494925;15616.765748514008;17980.55048623795;18319.725204122216;18668.439946801616;18793.443663298767;45591.31613025874;48913;50643.746781267335;51103.48896510987;54182.45817541932;73474.26525734887;76304.61847690726;79506.6024669873;80006;80822.38355232745;7731.226877312224;7821.491603045783;7886.645100970355;8137.959310203411;8155.714549990722;10436.37381757097;12365.319907405179;12540.811887821508;12724.936375318064;12765.234085954873;9312.940661701123;10777.407242601732;10885.727856803644;10952.178586605876;11131.888681113085;10734.087602553558;10785.191110666574;10843.620473283712;11177.776444471228;11197.72005699864;9688.95155524246;10724.605801245789;11281.32312061279;11297;11338.376389298337;6577.868442631025;7715.498492597963;7850.097238817609;8078.878816817635;8287.254147126547;8128.431009829255;10324.896751032695;10760.892391076302;10963.616273430393;11006.119510439306 02:26:37 INFO - PROCESS | 1639 | |3;mootools.html;1385.5704731533226;1437.1161735532646;1565.428618554228;1692.0693618509817;1706.1384001079446;7503.924960750391;7747.22527747225;7962.641681124349;8437.325013998889;8457.196566326205;7870.96064519678;8163.10205877353;8182.795430114235;8265.462744921591;8268.421210515266;2312.7802858728396;2329.895154718042;2333.5216280662426;2335.6262997920335;2339;2324.7094113235844;2359.504504054151;2371.9762802372015;2380.6310021946624;2389.665446837444;2308.4690521180137;2310.2838120182796;2314.5139520700673;2335.054303007285;2349.8942547585316;2237.5301186750803;2237.7538470768204;2261.321603518946;2263.5472905418983;2273.5111950930464;2324.523472688095;2347.2958112566334;2349.8825058747166;2358.2925122463193;2358.952820943589;8279.917200827975;8347.373946954003;8400.327973762145;8411.32709383254;8431.74704758852;5164;5227.947720522822;5232.8691782705355;5245.396779370383;5257.684538927677;3180.872765089394;3358.630550639428;3405.3700065487965;3485.494603282535;3532.894013179609;976.3409698453515;992.6873034994026;1023.161007973462;1037.9931466477506;1044.2324891204958;9583.904160958371;9783.608655653765;10608.416537090457;10908.836367454494;11063.889361106367;4019.83920643171;4550.362949187051;4641.675082744176;4792.0895029944195;4818.855434336975;9432.717018489457;9477.004914484018;9482.525873706425;9490.288228382899;9521.333506654468;5249.317588713519;5255.343082114736;5259.39516955555;5264.157734762496;5274.762635681327;4318.35224716295;4323.308270676676;4741.336212930192;4805.687630304089;4825.179719447702;3785.6782173515558;3811.6569508744346;3889.7082718796205;3913.5499417566775;3921.784301863368;1267.5183430296613;1268.0679700420185;1272.3193091695898;1282.7049778550986;1284.78158713019;795.4193438789716;874.2306770042322;879.3404946290283;893.5755516129786;912.1334732004622;5580.218769372291;5655.349634791969;5694.402087780806;5840.0071987762185;5859.794907178229;899.6401439424283;984.2667212926416;1017.6946915925192;1026.8870424253325;1033.214756784834;3751.437284407306;3781.9810900945868;3836.2135762168687;3851.30676478231;3856.4408160816524;9865.112139907444;10274.897251027543;10281.280310378203;10290.845637315446;10300.278980471296;10222.539985700661;10243.282970192164;10250.282480226313;10267.383956962607;10286.331388459726;6273.780417685359;7791.649375778104;7799.844003119906;7852;7968.083670377865;5465.152901300305;5479.0959491683425;5567.359753628384;5624.04391253496;5676.68778217202;9326.626934922528;9446.811063778823;9454.527273636428;9497.097775711296;9503.334766566273;8397.622107005322;9099.681511147079;9171.63313467454;9635.518224088628;9642;5495.53287970511;6817.556858804361;7032.331928466788;7205.531640443354;7363.337299643058;7810.258025487569;8041.87937180931;8051.83896322082;8057.717979870677;8074.152214017444;5966.49284810796;6684.997250412477;6685.696289223749;6844.007618895332;7098.609576473344;6039.275286965592;6084.330723620486;7004.754833580801;7059.329363710439;7087.7164913402885;5949.494292985145;6460.741570337322;6656.101426307387;6689.565178263398;6752.189737231563;4774.045190961752;4796.35249241348;4818.229083346648;4825.30033145199;4884.48712885142;4891.926621100615;4917.409910810726;4954.702717836942;4970.62720295972;4994.525520075732 02:26:37 INFO - PROCESS | 1639 | |4;prototype.html;306.3551224672065;309.47080492358066;311.3648157758174;313.5046626330398;315.94786860168057;4545.636349092073;4623.468301145361;5383.066228905246;5391.7868479592125;5466.426025267349;5043.722595257259;5352.841823324238;5394.6493477924005;5398.541124004464;5400.972995135028;3535.727138230235;3944.250592387444;3998.260321840469;4171.207470580595;4187.246295666779;3323.7839540429914;3826.8519444166777;3963.9405408918888;4139.1100913303635;4202.558731333212;3494.1963348429877;3987.8006099695044;4169.332906734923;4173.833046678129;4174.812133454002;3832.9616703832885;4036.7174297798874;4098.7130900836955;4188.157210827227;4225.323948168279;3096.6593674695764;3523.788572685647;3526.612072672004;3540.2916768671985;3560.2701446203464;5312;5324.307839980816;5341.1187154119525;5345.30511033567;5358.33020872391;3809.657130858236;4033.7781422021776;4362.934555981663;4399.318105693591;4720.221163508017;4387.802548885308;5033.672811267293;5671;5731.627444216113;5737.569682273805;3624.5288112545463;3917.3144699677728;3925.7448265862627;3940.802959851996;3941.605839416064;4605.595293435501;4708.811647534095;4904.4605093439695;5637.746301416446;5926.436988486087;3268.6404495505476;3924.3917192835156;3932.311845427039;3934.7048971327263;3940.9802950985063;3565.3938830398893;3864.8163787459994;4024.195160967818;4025.2087820919996;4035.91928161441;4116.991337122365;4501.369808226851;4612.7001744886475;4660.650451216173;4714.4106986626675;5409.809027343932;5538.944610553924;5705.771769129271;5744.540436765049;5792.79725209624;1193.4331192683405;1235.209465941798;1306.2619619914908;1308.5485507499898;1311.6327428320085;819.8483280593109;828.1056459024239;846.9703560375357;866.4151697604092;887.9289656827567;4076.7553946763287;5035.647504674711;5298.019866324742;5337;5504.587155963319;5465.4261302563455;6005.459508644156;6022.608530445507;6058.515318774487;6071.453569178795;4411.904345435956;4695.826043489128;5520.696361700145;5559.416261292587;5563.5271001964475;2358.870262135599;2478.442350471166;2685.5837345211526;2872.4255148970287;3000.6249218847643;5257.371314342829;5575.741824561059;5611;5624.971875140598;5967.492763115097;4990.700557966534;5068.56917162038;5407.756650950717;5831.542114471382;5838.620489668158;5129.178761822392;5270.657407268515;5664.688442135722;5943.790353209219;5947.256592925884;5468.507834294933;5698.430156984268;5717.942820571908;5728.570357223141;5779.219805326227;4886.877828054327;5335.786568537215;5613.649421698216;5621.381648018796;5641.915371269352;5041.218611115283;5879.794207202727;5885.822245769093;5960.940390596212;5991.460768530853;3490.7556471046782;3495.720342372655;3595.993121925865;3783.5838057814167;3805.0724312015454;5028.723420211923;5029.773660185374;5599.860003499782;5657.858553536194;5695.345035320991;4887.3890763654545;5038.095476006125;5232.921506177487;5508.752106155152;5544.473275038865;5232.947670523246;5271.1039123348355;5465.480779325958;5496.340439147168;5560.27716396882;4394.67082446304;4787.61699064081;5331.546818520469;5394.514493695587;5418;5519.862003449946;5653.1802888580105;5677.3754886963225;5783;5808.854778630568;5634.52106570946;5647.322321321468;5654.6889921054735;5696.829095127153;5711.400302968294 02:26:37 INFO - PROCESS | 1639 | |5;yui.html;679.127321392011;726.6693654387251;748.3825843678965;763.8739607964686;779.8635238833205;14713.852861471381;15261;15315.157666328376;15373.46192883248;15391.92304038481;13506.729865402685;13577.59267221985;13629.795553066688;13650.976176786757;13730.176189428641;766.1801871996965;782.0810547606568;786.626352482569;795.3319211862034;796.6853093028251;786.2962648429681;793.131520984524;797.7566842113154;798.4929569723209;800.8118092248317;826.7850358906668;836.2849763452222;838.4591938199887;842.9915700842975;843.0389356134012;819.5492479136481;827.2017503109498;830.5349004557468;830.5515021888203;831.8627426474625;600.4595863722641;612.4182027074253;625.7497001199511;628.1927722876128;628.779927025542;10560.366378017343;12560.685982850411;12600.36998150098;12609.684757881127;13109;11645.6506304811;12054.517819287217;12066.577669781604;12185.32980686062;12418.130730848845;12027.873806238464;12122.39388030595;12190.2076365036;12319.69200769979;12724.809127863089;4319.416878721364;4353.194658988097;4355.216061108999;4377.124575084964;4426.380306757057;55834.9593109027;59836.70081649607;72555.27444725537;84999.57500212522;92841.07158928392;10397.376157430426;10408.5836566538;10513.737156571147;10600.257981941344;10682.78634427307;15704.842951570567;16599.41902033423;19711.014449277478;19922.30271940475;20078;6745.561538499982;7172.354488095992;7301.08736407949;7328.680837449204;7664.195259497784;4136.6483848872585;4174.165166966619;4189.72766770159;4256.170046840836;4363.258246098171;3317.1209629447735;3399.184195792992;3438.6905178534053;3620.5293311869286;3790.905227369338;641.3298103481873;659.5712786688595;662.6984721951501;668.5721138471382;669.521292276025;603.963762174271;646.8770933522616;658.3054877104605;662.2814246542555;677.2820809941478;8726.389152759373;9024.097590240923;9086.818713567194;9158.816823663623;9334.859977100214;564.7345747498669;647.2233320015999;654.7053825778323;656.7602824968852;670;3910.7458015228917;4153;4319.632831209383;4459.777011149429;4612.7924243409125;108449;108664;109402.90597094086;109575.45212273888;109598;97784.0221597775;99070;99536.50695239578;101597;102620.48689756503;87306;89086.10913890778;91554;92252;93233.53383233176;8451.197136272045;8877.511736854276;8983.406637345115;9521.095495927875;9676.22590192797;49681;51862.740686296325;52991.735041324544;53362.93274134418;55346.44653553413;61270.69364653148;66998;75514;75566.62216688882;78280.2171978273;12025.518979241082;12457.750844983231;12528.87471125277;12546.309952952675;12726.87273127257;13897.652558686113;14088.436462541384;14194.148351098967;14335.35490902891;14650.70698586001;11626.941865290957;11875.228110172811;11918.642440726791;11930.463129159036;11957.043436525231;12742.808857866954;13149.737005260033;13204;13321.200727956353;13784.655383615489;12314.692132696397;13046.608601741962;13160.276184809927;13637.908967282792;13648.24934628605;10510.737231569272;10907.07289880369;11546.59586914454;11633.825492617449;11768;10730.570777168827;11022.559097636005;11301.208915376174;11331.603393881174;11353.432328383382 02:26:37 INFO - PROCESS | 1639 | __end_tp_report 02:26:37 INFO - PROCESS | 1639 | __start_cc_report 02:26:37 INFO - PROCESS | 1639 | _x_x_mozilla_cycle_collect,364 02:26:37 INFO - PROCESS | 1639 | __end_cc_report 02:26:37 INFO - PROCESS | 1639 | __startTimestamp1483611997074__endTimestamp 02:26:37 INFO - PROCESS | 1639 | 02:26:37 INFO - PROCESS | 1639 | ------- Summary: start ------- 02:26:37 INFO - PROCESS | 1639 | Number of tests: 6 02:26:37 INFO - PROCESS | 1639 | 02:26:37 INFO - PROCESS | 1639 | [#0] dojo.html Cycles:165 Average:5709.45 Median:5997.10 stddev:687.68 (11.5%) stddev-sans-first:664.07 02:26:37 INFO - PROCESS | 1639 | Values: 3334.4 3379.7 3386.0 3397.2 3435.6 4332.5 5728.0 5739.3 5812.2 5882.7 5809.2 5860.8 5868.4 5904.9 5932.7 4914.0 5228.0 5238.8 5277.5 5280.0 4412.0 5346.6 5361.4 5363.1 5380.1 5209.5 5339.8 5348.5 5363.5 5364.1 5145.2 5255.1 5274.9 5276.2 5291.3 5123.2 5215.9 5221.2 5321.8 5334.8 5502.9 5735.1 5950.7 5971.0 5989.1 6170.5 6185.7 6247.6 6253.1 6282.2 4700.9 6179.9 6199.5 6236.4 6253.3 5889.6 5925.8 5931.1 5931.5 5957.3 6149.6 6196.5 6219.3 6223.2 6251.5 6167.7 6193.2 6211.2 6221.4 6231.1 6230.0 6230.7 6261.0 6265.2 6318.7 5453.2 6004.5 6116.6 6208.2 6284.7 5115.0 5403.6 6107.8 6192.9 6199.5 4146.9 4147.8 4978.2 4980.1 5014.0 4317.8 4406.0 4425.5 4446.0 4449.4 4374.5 4727.8 5765.2 5765.9 6019.6 6158.6 6177.2 6188.9 6232.0 6233.2 5744.5 5790.5 5812.4 5847.7 5864.8 5376.1 5431.3 5514.3 5519.7 5524.4 6348.0 6348.2 6364.4 6365.0 6372.0 6145.0 6222.8 6246.3 6254.9 6280.6 6228.4 6239.9 6271.6 6359.9 6379.7 5170.7 5212.3 6239.3 6256.9 6276.0 5999.6 6185.3 6190.2 6209.2 6260.8 6118.6 6146.2 6152.3 6156.7 6203.3 6158.1 6200.3 6202.0 6225.6 6228.5 5992.8 5997.1 6106.4 6114.8 6124.0 6005.6 6110.1 6121.8 6127.0 6207.7 4784.8 6017.1 6035.4 6036.5 6040.2 02:26:37 INFO - PROCESS | 1639 | 02:26:37 INFO - PROCESS | 1639 | [#1] ext.html Cycles:180 Average:70506.81 Median:38550.11 stddev:164680.71 (427.2%) stddev-sans-first:165058.76 02:26:37 INFO - PROCESS | 1639 | Values: 483.4 492.2 506.8 518.3 538.9 38536.6 39810.0 40066.0 42275.0 42637.6 40822.6 41263.8 41297.0 41329.8 41375.4 9349.5 9520.7 9525.4 9528.2 9569.4 9321.0 9369.1 9444.1 9448.6 9451.9 8134.6 9444.6 9449.8 9483.0 9493.1 9382.4 9446.4 9456.9 9457.1 9459.6 9392.9 9428.5 9443.3 9469.7 9474.7 39522.8 39646.6 39688.8 39763.2 39769.6 2829.4 3011.9 3273.2 3350.3 3403.1 24897.8 28935.9 29348.9 29423.3 30214.0 2176.9 2734.5 2869.7 2914.0 2939.3 370676.0 375793.0 376200.0 377256.0 377929.0 59004.7 59129.4 59214.7 59468.0 59541.7 61939.8 62381.0 62505.7 62550.4 62634.4 13631.7 15844.7 16008.6 16034.1 16088.4 11233.7 12439.9 12498.1 12517.7 12523.2 16258.4 16484.3 16490.1 16505.4 16566.8 7030.5 7068.5 7080.2 7110.3 7116.9 839.6 952.7 1073.2 1088.5 1106.5 24059.0 27381.6 30008.7 30263.8 30644.8 984.5 1013.5 1022.3 1035.5 1055.6 12767.4 13086.4 15554.3 15766.8 16337.6 528865.0 871455.0 877423.0 890938.0 892406.0 57876.0 58448.7 58470.4 58492.7 58542.1 1323.3 1341.6 1367.9 1369.1 1375.9 29485.0 30580.5 30834.8 31049.4 31078.4 50051.5 50139.0 50411.0 50467.0 50488.7 539430.0 543712.0 548403.0 548946.0 549112.0 25801.1 26142.9 26159.6 26185.7 26238.0 43049.4 44279.6 44624.0 45572.3 46805.0 37617.6 38007.8 38110.9 38169.6 38332.2 36855.0 37444.6 37935.6 38563.6 38567.8 35881.6 36202.8 37090.6 37168.3 37170.3 31474.5 33192.7 33463.7 33482.3 33486.8 25252.9 25397.1 25496.1 25817.5 25895.0 02:26:37 INFO - PROCESS | 1639 | 02:26:37 INFO - PROCESS | 1639 | [#2] jquery.html Cycles:180 Average:34063.98 Median:2408.19 stddev:100695.59 (4181.4%) stddev-sans-first:100946.21 02:26:37 INFO - PROCESS | 1639 | Values: 334.2 365.4 365.9 376.8 393.9 13994.9 15839.0 19478.8 19628.5 19716.6 18711.3 19023.5 19286.3 19697.7 19846.8 5533.0 5806.7 5855.5 5869.9 5888.8 5539.9 5700.9 5756.4 5757.1 5787.5 5109.7 5342.4 5722.5 5737.6 5738.4 5503.2 5643.6 5733.3 5740.5 5762.4 5018.7 5035.2 5040.5 5043.9 5050.3 12569.2 14306.3 22218.9 23160.4 23200.4 2136.5 2266.1 2311.9 2392.5 2423.9 9841.7 9925.5 10105.4 12361.6 15305.2 1845.5 1877.6 1914.6 1928.0 1928.4 105062.5 108189.5 110409.0 111043.9 113354.0 11360.1 11552.9 11751.9 11764.0 11893.7 14582.0 14593.2 14597.2 14612.9 14623.1 8365.6 8435.2 8486.7 8500.9 8502.0 8341.3 8367.7 8376.9 8465.9 8505.4 4431.4 4472.5 4506.4 4593.1 4683.2 1876.6 1927.5 1936.0 1937.8 1940.5 818.0 853.3 880.5 918.6 932.8 16088.1 16602.5 16962.3 19427.5 20626.6 887.3 891.2 926.2 939.8 940.9 10302.5 10893.7 10935.3 10955.6 10958.7 600649.0 603066.0 607305.0 607937.0 612950.0 78400.0 112035.6 122240.0 123219.0 127764.0 972.1 984.5 1029.5 1031.8 1036.2 15616.8 17980.6 18319.7 18668.4 18793.4 45591.3 48913.0 50643.7 51103.5 54182.5 73474.3 76304.6 79506.6 80006.0 80822.4 7731.2 7821.5 7886.6 8138.0 8155.7 10436.4 12365.3 12540.8 12724.9 12765.2 9312.9 10777.4 10885.7 10952.2 11131.9 10734.1 10785.2 10843.6 11177.8 11197.7 9689.0 10724.6 11281.3 11297.0 11338.4 6577.9 7715.5 7850.1 8078.9 8287.3 8128.4 10324.9 10760.9 10963.6 11006.1 02:26:37 INFO - PROCESS | 1639 | 02:26:37 INFO - PROCESS | 1639 | [#3] mootools.html Cycles:180 Average:5410.00 Median:5239.13 stddev:2948.95 (56.3%) stddev-sans-first:2941.71 02:26:37 INFO - PROCESS | 1639 | Values: 1385.6 1437.1 1565.4 1692.1 1706.1 7503.9 7747.2 7962.6 8437.3 8457.2 7871.0 8163.1 8182.8 8265.5 8268.4 2312.8 2329.9 2333.5 2335.6 2339.0 2324.7 2359.5 2372.0 2380.6 2389.7 2308.5 2310.3 2314.5 2335.1 2349.9 2237.5 2237.8 2261.3 2263.5 2273.5 2324.5 2347.3 2349.9 2358.3 2359.0 8279.9 8347.4 8400.3 8411.3 8431.7 5164.0 5227.9 5232.9 5245.4 5257.7 3180.9 3358.6 3405.4 3485.5 3532.9 976.3 992.7 1023.2 1038.0 1044.2 9583.9 9783.6 10608.4 10908.8 11063.9 4019.8 4550.4 4641.7 4792.1 4818.9 9432.7 9477.0 9482.5 9490.3 9521.3 5249.3 5255.3 5259.4 5264.2 5274.8 4318.4 4323.3 4741.3 4805.7 4825.2 3785.7 3811.7 3889.7 3913.5 3921.8 1267.5 1268.1 1272.3 1282.7 1284.8 795.4 874.2 879.3 893.6 912.1 5580.2 5655.3 5694.4 5840.0 5859.8 899.6 984.3 1017.7 1026.9 1033.2 3751.4 3782.0 3836.2 3851.3 3856.4 9865.1 10274.9 10281.3 10290.8 10300.3 10222.5 10243.3 10250.3 10267.4 10286.3 6273.8 7791.6 7799.8 7852.0 7968.1 5465.2 5479.1 5567.4 5624.0 5676.7 9326.6 9446.8 9454.5 9497.1 9503.3 8397.6 9099.7 9171.6 9635.5 9642.0 5495.5 6817.6 7032.3 7205.5 7363.3 7810.3 8041.9 8051.8 8057.7 8074.2 5966.5 6685.0 6685.7 6844.0 7098.6 6039.3 6084.3 7004.8 7059.3 7087.7 5949.5 6460.7 6656.1 6689.6 6752.2 4774.0 4796.4 4818.2 4825.3 4884.5 4891.9 4917.4 4954.7 4970.6 4994.5 02:26:37 INFO - PROCESS | 1639 | 02:26:37 INFO - PROCESS | 1639 | [#4] prototype.html Cycles:180 Average:4511.32 Median:5056.15 stddev:1406.96 (27.8%) stddev-sans-first:1375.05 02:26:37 INFO - PROCESS | 1639 | Values: 306.4 309.5 311.4 313.5 315.9 4545.6 4623.5 5383.1 5391.8 5466.4 5043.7 5352.8 5394.6 5398.5 5401.0 3535.7 3944.3 3998.3 4171.2 4187.2 3323.8 3826.9 3963.9 4139.1 4202.6 3494.2 3987.8 4169.3 4173.8 4174.8 3833.0 4036.7 4098.7 4188.2 4225.3 3096.7 3523.8 3526.6 3540.3 3560.3 5312.0 5324.3 5341.1 5345.3 5358.3 3809.7 4033.8 4362.9 4399.3 4720.2 4387.8 5033.7 5671.0 5731.6 5737.6 3624.5 3917.3 3925.7 3940.8 3941.6 4605.6 4708.8 4904.5 5637.7 5926.4 3268.6 3924.4 3932.3 3934.7 3941.0 3565.4 3864.8 4024.2 4025.2 4035.9 4117.0 4501.4 4612.7 4660.7 4714.4 5409.8 5538.9 5705.8 5744.5 5792.8 1193.4 1235.2 1306.3 1308.5 1311.6 819.8 828.1 847.0 866.4 887.9 4076.8 5035.6 5298.0 5337.0 5504.6 5465.4 6005.5 6022.6 6058.5 6071.5 4411.9 4695.8 5520.7 5559.4 5563.5 2358.9 2478.4 2685.6 2872.4 3000.6 5257.4 5575.7 5611.0 5625.0 5967.5 4990.7 5068.6 5407.8 5831.5 5838.6 5129.2 5270.7 5664.7 5943.8 5947.3 5468.5 5698.4 5717.9 5728.6 5779.2 4886.9 5335.8 5613.6 5621.4 5641.9 5041.2 5879.8 5885.8 5960.9 5991.5 3490.8 3495.7 3596.0 3783.6 3805.1 5028.7 5029.8 5599.9 5657.9 5695.3 4887.4 5038.1 5232.9 5508.8 5544.5 5232.9 5271.1 5465.5 5496.3 5560.3 4394.7 4787.6 5331.5 5394.5 5418.0 5519.9 5653.2 5677.4 5783.0 5808.9 5634.5 5647.3 5654.7 5696.8 5711.4 02:26:37 INFO - PROCESS | 1639 | 02:26:37 INFO - PROCESS | 1639 | [#5] yui.html Cycles:180 Average:20207.25 Median:4354.21 stddev:29742.17 (683.1%) stddev-sans-first:29789.46 02:26:37 INFO - PROCESS | 1639 | Values: 679.1 726.7 748.4 763.9 779.9 14713.9 15261.0 15315.2 15373.5 15391.9 13506.7 13577.6 13629.8 13651.0 13730.2 766.2 782.1 786.6 795.3 796.7 786.3 793.1 797.8 798.5 800.8 826.8 836.3 838.5 843.0 843.0 819.5 827.2 830.5 830.6 831.9 600.5 612.4 625.7 628.2 628.8 10560.4 12560.7 12600.4 12609.7 13109.0 11645.7 12054.5 12066.6 12185.3 12418.1 12027.9 12122.4 12190.2 12319.7 12724.8 4319.4 4353.2 4355.2 4377.1 4426.4 55835.0 59836.7 72555.3 84999.6 92841.1 10397.4 10408.6 10513.7 10600.3 10682.8 15704.8 16599.4 19711.0 19922.3 20078.0 6745.6 7172.4 7301.1 7328.7 7664.2 4136.6 4174.2 4189.7 4256.2 4363.3 3317.1 3399.2 3438.7 3620.5 3790.9 641.3 659.6 662.7 668.6 669.5 604.0 646.9 658.3 662.3 677.3 8726.4 9024.1 9086.8 9158.8 9334.9 564.7 647.2 654.7 656.8 670.0 3910.7 4153.0 4319.6 4459.8 4612.8 108449.0 108664.0 109402.9 109575.5 109598.0 97784.0 99070.0 99536.5 101597.0 102620.5 87306.0 89086.1 91554.0 92252.0 93233.5 8451.2 8877.5 8983.4 9521.1 9676.2 49681.0 51862.7 52991.7 53362.9 55346.4 61270.7 66998.0 75514.0 75566.6 78280.2 12025.5 12457.8 12528.9 12546.3 12726.9 13897.7 14088.4 14194.1 14335.4 14650.7 11626.9 11875.2 11918.6 11930.5 11957.0 12742.8 13149.7 13204.0 13321.2 13784.7 12314.7 13046.6 13160.3 13637.9 13648.2 10510.7 10907.1 11546.6 11633.8 11768.0 10730.6 11022.6 11301.2 11331.6 11353.4 02:26:37 INFO - PROCESS | 1639 | -------- Summary: end -------- 02:26:37 INFO - PROCESS | 1639 | 02:26:37 INFO - TEST-INFO | 1639: exit 0 02:26:37 INFO - TEST-OK | dromaeo_css | took 1117666ms 02:26:37 INFO - TEST-START | kraken 02:26:37 INFO - operating with platform_type : mac_ 02:26:37 INFO - Initialising browser for kraken test... 02:26:37 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/tmpYizTfX/profile http://localhost:49240/getInfo.html) 02:26:40 INFO - PROCESS | 1648 | 2017-01-05 02:26:40.545 plugin-container[1649:10090] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x424b, name = 'com.apple.tsm.portname' 02:26:40 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:26:40 INFO - PROCESS | 1648 | 2017-01-05 02:26:40.548 plugin-container[1649:10090] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 02:26:40 INFO - PROCESS | 1648 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:26:40 INFO - PROCESS | 1648 | 2017-01-05 02:26:40.548 plugin-container[1649:10090] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:26:40 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 02:26:40 INFO - PROCESS | 1648 | colorDepth:24 02:26:40 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 02:26:40 INFO - PROCESS | 1648 | __metrics 02:26:40 ERROR - PROCESS | 1648 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 02:26:41 INFO - TEST-INFO | 1648: exit 0 02:26:41 INFO - Browser initialized. 02:26:41 INFO - Running cycle 1/1 for kraken test... 02:26:41 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/T/tmpYizTfX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:26:44 INFO - PROCESS | 1650 | 2017-01-05 02:26:44.216 plugin-container[1651:10207] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 02:26:44 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:26:44 INFO - PROCESS | 1650 | 2017-01-05 02:26:44.216 plugin-container[1651:10207] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 02:26:44 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 02:26:44 INFO - PROCESS | 1650 | 2017-01-05 02:26:44.216 plugin-container[1651:10207] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 02:26:53 INFO - PROCESS | 1650 | RSS: Main: 229883904 02:26:53 INFO - PROCESS | 1650 | 02:26:56 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 02:26:57 INFO - PROCESS | 1650 | RSS: Main: 241606656 02:26:57 INFO - PROCESS | 1650 | 02:27:00 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 02:27:00 INFO - PROCESS | 1650 | RSS: Main: 249327616 02:27:00 INFO - PROCESS | 1650 | 02:27:04 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 02:27:04 INFO - PROCESS | 1650 | RSS: Main: 251023360 02:27:04 INFO - PROCESS | 1650 | 02:27:06 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 02:27:07 INFO - PROCESS | 1650 | RSS: Main: 252850176 02:27:07 INFO - PROCESS | 1650 | 02:27:09 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:27:10 INFO - PROCESS | 1650 | RSS: Main: 254894080 02:27:10 INFO - PROCESS | 1650 | 02:27:13 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 02:27:13 INFO - PROCESS | 1650 | RSS: Main: 258760704 02:27:13 INFO - PROCESS | 1650 | 02:27:16 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 02:27:17 INFO - PROCESS | 1650 | RSS: Main: 257757184 02:27:17 INFO - PROCESS | 1650 | 02:27:19 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 02:27:20 INFO - PROCESS | 1650 | RSS: Main: 258191360 02:27:20 INFO - PROCESS | 1650 | 02:27:22 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:27:22 INFO - PROCESS | 1650 | RSS: Main: 257077248 02:27:22 INFO - PROCESS | 1650 | 02:27:24 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:27:25 INFO - PROCESS | 1650 | RSS: Main: 257146880 02:27:25 INFO - PROCESS | 1650 | 02:27:27 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:27:28 INFO - PROCESS | 1650 | RSS: Main: 257130496 02:27:28 INFO - PROCESS | 1650 | 02:27:31 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:27:32 INFO - PROCESS | 1650 | RSS: Main: 257196032 02:27:32 INFO - PROCESS | 1650 | 02:27:34 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:27:35 INFO - PROCESS | 1650 | RSS: Main: 257552384 02:27:35 INFO - PROCESS | 1650 | 02:27:37 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 02:27:37 INFO - PROCESS | 1650 | RSS: Main: 259317760 02:27:37 INFO - PROCESS | 1650 | 02:27:37 INFO - PROCESS | 1650 | __start_tp_report 02:27:37 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 02:27:37 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 02:27:37 INFO - PROCESS | 1650 | |i|pagename|runs| 02:27:37 INFO - PROCESS | 1650 | |0;ai-astar;116;118;117;118;121;118;119;119;119;119 02:27:37 INFO - PROCESS | 1650 | |1;audio-beat-detection;181;103;144;160;148;140;142;123;140;142 02:27:37 INFO - PROCESS | 1650 | |2;audio-dft;211;239;194;193;269;275;198;198;198;197 02:27:37 INFO - PROCESS | 1650 | |3;audio-fft;79;62;57;82;60;61;66;81;96;62 02:27:37 INFO - PROCESS | 1650 | |4;audio-oscillator;82;71;90;73;72;94;83;74;100;120 02:27:37 INFO - PROCESS | 1650 | |5;imaging-gaussian-blur;93;84;93;75;135;115;93;90;92;91 02:27:37 INFO - PROCESS | 1650 | |6;imaging-darkroom;98;99;100;97;98;97;96;97;99;117 02:27:37 INFO - PROCESS | 1650 | |7;imaging-desaturate;87;93;86;95;91;88;91;94;88;88 02:27:37 INFO - PROCESS | 1650 | |8;json-parse-financial;59;59;57;60;60;61;60;61;61;62 02:27:37 INFO - PROCESS | 1650 | |9;json-stringify-tinderbox;69;75;73;79;76;69;72;70;74;77 02:27:37 INFO - PROCESS | 1650 | |10;stanford-crypto-aes;91;104;106;84;89;104;81;99;107;107 02:27:37 INFO - PROCESS | 1650 | |11;stanford-crypto-ccm;141;144;148;151;160;147;152;164;197;157 02:27:37 INFO - PROCESS | 1650 | |12;stanford-crypto-pbkdf2;168;169;189;176;168;168;166;165;166;168 02:27:37 INFO - PROCESS | 1650 | |13;stanford-crypto-sha256-iterative;69;69;69;67;69;68;70;67;66;69 02:27:37 INFO - PROCESS | 1650 | __end_tp_report 02:27:37 INFO - PROCESS | 1650 | __start_cc_report 02:27:37 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,980 02:27:37 INFO - PROCESS | 1650 | __end_cc_report 02:27:37 INFO - PROCESS | 1650 | __startTimestamp1483612057513__endTimestamp 02:27:37 INFO - PROCESS | 1650 | 02:27:37 INFO - PROCESS | 1650 | ------- Summary: start ------- 02:27:37 INFO - PROCESS | 1650 | Number of tests: 14 02:27:37 INFO - PROCESS | 1650 | 02:27:37 INFO - PROCESS | 1650 | [#0] ai-astar Cycles:10 Average:118.40 Median:119.00 stddev:1.35 (1.1%) stddev-sans-first:1.12 02:27:37 INFO - PROCESS | 1650 | Values: 116.0 118.0 117.0 118.0 121.0 118.0 119.0 119.0 119.0 119.0 02:27:37 INFO - PROCESS | 1650 | 02:27:37 INFO - PROCESS | 1650 | [#1] audio-beat-detection Cycles:10 Average:142.30 Median:143.00 stddev:20.48 (14.3%) stddev-sans-first:16.24 02:27:37 INFO - PROCESS | 1650 | Values: 181.0 103.0 144.0 160.0 148.0 140.0 142.0 123.0 140.0 142.0 02:27:37 INFO - PROCESS | 1650 | 02:27:37 INFO - PROCESS | 1650 | [#2] audio-dft Cycles:10 Average:217.20 Median:204.50 stddev:31.93 (15.6%) stddev-sans-first:33.79 02:27:37 INFO - PROCESS | 1650 | Values: 211.0 239.0 194.0 193.0 269.0 275.0 198.0 198.0 198.0 197.0 02:27:37 INFO - PROCESS | 1650 | 02:27:37 INFO - PROCESS | 1650 | [#3] audio-fft Cycles:10 Average:70.60 Median:72.50 stddev:12.96 (17.9%) stddev-sans-first:13.39 02:27:37 INFO - PROCESS | 1650 | Values: 79.0 62.0 57.0 82.0 60.0 61.0 66.0 81.0 96.0 62.0 02:27:37 INFO - PROCESS | 1650 | 02:27:37 INFO - PROCESS | 1650 | [#4] audio-oscillator Cycles:10 Average:85.90 Median:78.00 stddev:15.60 (20.0%) stddev-sans-first:16.48 02:27:37 INFO - PROCESS | 1650 | Values: 82.0 71.0 90.0 73.0 72.0 94.0 83.0 74.0 100.0 120.0 02:27:37 INFO - PROCESS | 1650 | 02:27:37 INFO - PROCESS | 1650 | [#5] imaging-gaussian-blur Cycles:10 Average:96.10 Median:91.50 stddev:16.90 (18.5%) stddev-sans-first:17.89 02:27:37 INFO - PROCESS | 1650 | Values: 93.0 84.0 93.0 75.0 135.0 115.0 93.0 90.0 92.0 91.0 02:27:37 INFO - PROCESS | 1650 | 02:27:37 INFO - PROCESS | 1650 | [#6] imaging-darkroom Cycles:10 Average:99.80 Median:97.50 stddev:6.16 (6.3%) stddev-sans-first:6.50 02:27:37 INFO - PROCESS | 1650 | Values: 98.0 99.0 100.0 97.0 98.0 97.0 96.0 97.0 99.0 117.0 02:27:37 INFO - PROCESS | 1650 | 02:27:37 INFO - PROCESS | 1650 | [#7] imaging-desaturate Cycles:10 Average:90.10 Median:91.00 stddev:3.14 (3.5%) stddev-sans-first:3.13 02:27:37 INFO - PROCESS | 1650 | Values: 87.0 93.0 86.0 95.0 91.0 88.0 91.0 94.0 88.0 88.0 02:27:37 INFO - PROCESS | 1650 | 02:27:37 INFO - PROCESS | 1650 | [#8] json-parse-financial Cycles:10 Average:60.00 Median:60.50 stddev:1.41 (2.3%) stddev-sans-first:1.45 02:27:37 INFO - PROCESS | 1650 | Values: 59.0 59.0 57.0 60.0 60.0 61.0 60.0 61.0 61.0 62.0 02:27:37 INFO - PROCESS | 1650 | 02:27:37 INFO - PROCESS | 1650 | [#9] json-stringify-tinderbox Cycles:10 Average:73.40 Median:74.50 stddev:3.44 (4.6%) stddev-sans-first:3.26 02:27:37 INFO - PROCESS | 1650 | Values: 69.0 75.0 73.0 79.0 76.0 69.0 72.0 70.0 74.0 77.0 02:27:37 INFO - PROCESS | 1650 | 02:27:37 INFO - PROCESS | 1650 | [#10] stanford-crypto-aes Cycles:10 Average:97.20 Median:82.50 stddev:10.04 (12.2%) stddev-sans-first:10.40 02:27:37 INFO - PROCESS | 1650 | Values: 91.0 104.0 106.0 84.0 89.0 104.0 81.0 99.0 107.0 107.0 02:27:37 INFO - PROCESS | 1650 | 02:27:37 INFO - PROCESS | 1650 | [#11] stanford-crypto-ccm Cycles:10 Average:156.10 Median:154.50 stddev:16.04 (10.4%) stddev-sans-first:16.06 02:27:37 INFO - PROCESS | 1650 | Values: 141.0 144.0 148.0 151.0 160.0 147.0 152.0 164.0 197.0 157.0 02:27:37 INFO - PROCESS | 1650 | 02:27:37 INFO - PROCESS | 1650 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:170.30 Median:168.00 stddev:7.23 (4.3%) stddev-sans-first:7.62 02:27:37 INFO - PROCESS | 1650 | Values: 168.0 169.0 189.0 176.0 168.0 168.0 166.0 165.0 166.0 168.0 02:27:37 INFO - PROCESS | 1650 | 02:27:37 INFO - PROCESS | 1650 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:69.00 stddev:1.25 (1.8%) stddev-sans-first:1.30 02:27:37 INFO - PROCESS | 1650 | Values: 69.0 69.0 69.0 67.0 69.0 68.0 70.0 67.0 66.0 69.0 02:27:37 INFO - PROCESS | 1650 | -------- Summary: end -------- 02:27:37 INFO - PROCESS | 1650 | 02:27:38 INFO - TEST-INFO | 1650: exit 0 02:27:38 INFO - TEST-OK | kraken | took 60812ms 02:27:38 INFO - SUITE-END | took 1178s 02:27:38 INFO - Completed test suite (00:19:38) 02:27:38 INFO - javascript benchmark 02:27:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7127.268240045615, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3334.433146365118, 3379.7465190110734, 3385.9661403385976, 3397.2016576104597, 3435.6220815710276, 4332.480102387718, 5728.026235539958, 5739.339975902767, 5812.24440822693, 5882.6764527950945, 5809.157672137538, 5860.794872179475, 5868.354481007086, 5904.940950590493, 5932.703364831763, 4913.975430122844, 5227.973860130694, 5238.764255608507, 5277.47225277473, 5279.973600132013, 4411.955880441203, 5346.599005074616, 5361.410244873061, 5363.141897296433, 5380.058489764295, 5209.479052094779, 5339.839804805862, 5348.545373643245, 5363.54409875161, 5364.088105022156, 5145.202493613496, 5255.054090263751, 5274.92087618686, 5276.15581506957, 5291.28567643367, 5123.180291153435, 5215.869603259911, 5221.242919776611, 5321.813736519203, 5334.839954801362, 5502.889942201134, 5735.11105778605, 5950.7322170502, 5971.0, 5989.131575921467, 6170.537209709245, 6185.721642526097, 6247.625142491465, 6253.093301471306, 6282.246130464374, 4700.882477938045, 6179.918514259986, 6199.473044791153, 6236.43872051513, 6253.312135665074, 5889.558283128783, 5925.762969481172, 5931.139984702238, 5931.466168044896, 5957.314908785458, 6149.600275982047, 6196.47329976957, 6219.346968568326, 6223.190985171989, 6251.468625166821, 6167.722452489649, 6193.163922870445, 6211.223597050369, 6221.4089661482085, 6231.127642130105, 6229.96885015572, 6230.688465576703, 6261.029540421242, 6265.248170219603, 6318.652474113968, 5453.209284653783, 6004.519638428916, 6116.633002019893, 6208.192934918432, 6284.748610055546, 5114.974425127851, 5403.621746477787, 6107.795737660903, 6192.876142477216, 6199.473044791153, 4146.896327591834, 4147.834086636501, 4978.153713868651, 4980.05378977993, 5013.97213571217, 4317.784110794448, 4406.0, 4425.491068527161, 4445.955540444555, 4449.399331090326, 4374.518802931675, 4727.83452579165, 5765.1928729977835, 5765.8558536036935, 6019.615488437684, 6158.630482171084, 6177.227846519185, 6188.938110618927, 6232.002879539252, 6233.189685340877, 5744.51171650406, 5790.507806836466, 5812.360640329561, 5847.6783776891825, 5864.794732184353, 5376.109917739842, 5431.293931788842, 5514.338279406416, 5519.668819870821, 5524.392316845144, 6348.0, 6348.174737284031, 6364.395382438661, 6365.0452432134625, 6372.0, 6145.002874065857, 6222.782202622887, 6246.312905580474, 6254.937450625435, 6280.623162610258, 6228.4394404502, 6239.875202496016, 6271.592346497463, 6359.872802544016, 6379.6810159490915, 5170.663906846043, 5212.348456442945, 6239.282482514569, 6256.906146407717, 6275.96862015687, 5999.5800293979, 6185.257769067741, 6190.226221722285, 6209.192804935329, 6260.780872669435, 6118.571699980958, 6146.231721034871, 6152.292486363946, 6156.661383623944, 6203.286622038523, 6158.107074474266, 6200.255969283715, 6202.038684003986, 6225.564210505221, 6228.532860035425, 5992.820215393545, 5997.100434934795, 6106.389361063858, 6114.816555503342, 6123.969380153071, 6005.579609427298, 6110.083487476736, 6121.84695382619, 6126.969365153146, 6207.6585787782105, 4784.803799050237, 6017.127516510169, 6035.36628653985, 6036.456718895321, 6040.1543783871175], "value": 5668.1507572166365, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [483.3957553058677, 492.22228878372175, 506.825145324863, 518.2666526864485, 538.9003034438629, 38536.61463385366, 39810.0, 40066.0, 42275.0, 42637.57362426375, 40822.59177408225, 41263.79368103163, 41296.96757581062, 41329.76010719676, 41375.37936930941, 9349.532523373855, 9520.666776662818, 9525.380850244746, 9528.237740980705, 9569.377990430634, 9321.021292764264, 9369.063093690645, 9444.05559444057, 9448.622055117821, 9451.905480945206, 8134.593270336489, 9444.622215111422, 9449.81100377992, 9483.0, 9493.05069493052, 9382.39014464061, 9446.433214007113, 9456.90543094574, 9457.14885660287, 9459.574319155654, 9392.8591071134, 9428.481433521154, 9443.338966272364, 9469.66856160031, 9474.715758527254, 39522.80238598789, 39646.603533964866, 39688.80155599232, 39763.20473590541, 39769.60230397688, 2829.39168078862, 3011.9397612047637, 3273.214428537134, 3350.27968986669, 3403.1322012886803, 24897.751022489723, 28935.855320723473, 29348.853255733586, 29423.26441838971, 30213.98470190549, 2176.869387836735, 2734.507788598052, 2869.727375899307, 2913.9708602914125, 2939.250491124771, 370676.0, 375793.0, 376200.0, 377256.0, 377929.0, 59004.704976474844, 59129.40870591239, 59214.70392648095, 59468.0, 59541.70229148826, 61939.761204775656, 62381.0, 62505.68747156235, 62550.37449625537, 62634.373656262855, 13631.659208519668, 15844.68310633804, 16008.599785005468, 16034.118123503085, 16088.436904708278, 11233.71915702114, 12439.875601244054, 12498.06264530164, 12517.749645007048, 12523.248605083723, 16258.430954916757, 16484.25820838041, 16490.093044882407, 16505.422310219084, 16566.83433165677, 7030.472714546328, 7068.540544864567, 7080.185778635419, 7110.288971102952, 7116.928830711627, 839.6221700234919, 952.7475219066953, 1073.1843798712878, 1088.5373716170595, 1106.468894930438, 24059.0, 27381.589276160874, 30008.699913001026, 30263.848680756895, 30644.774209031835, 984.5175863826673, 1013.503383342157, 1022.2588623248234, 1035.4667346316653, 1055.630529314749, 12767.361631918367, 13086.411111500005, 15554.300056497485, 15766.842331576767, 16337.591560210853, 528865.0, 871455.0, 877423.0, 890938.0, 892406.0, 57876.0, 58448.70775646094, 58470.41529584735, 58492.70753646205, 58542.12186817201, 1323.3317174826843, 1341.5908148014948, 1367.9384427700777, 1369.1237607930932, 1375.9449622015206, 29485.0, 30580.541291881083, 30834.845825770728, 31049.379012419173, 31078.378432431677, 50051.49948500614, 50139.0, 50411.0, 50467.0, 50488.74755626198, 539430.0, 543712.0, 548403.0, 548946.0, 549112.0, 25801.096961606254, 26142.86928565421, 26159.607605886307, 26185.73814261833, 26238.0, 43049.354259685504, 44279.55720442755, 44624.0, 45572.31641525446, 46805.0, 37617.62382376251, 38007.80996095002, 38110.856674299815, 38169.61830381772, 38332.233355333294, 36855.0, 37444.62555374411, 37935.62064379431, 38563.614363856, 38567.80716096402, 35881.64118358783, 36202.8189859049, 37090.629093709795, 37168.25663486769, 37170.32733526944, 31474.52788208133, 33192.66807331896, 33463.66536334605, 33482.330353393285, 33486.83256583799, 25252.873735631205, 25397.11110111137, 25496.087793415198, 25817.483650326514, 25894.964201431732], "value": 19404.67233413415, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [334.1662551932928, 365.35879531422347, 365.8609728303245, 376.7965298738681, 393.9271234821558, 13994.930025349871, 15839.0, 19478.805211947878, 19628.50928726779, 19716.605667886633, 18711.251549937988, 19023.52441188971, 19286.32497862575, 19697.704534431996, 19846.801531984675, 5533.031719449091, 5806.680632565207, 5855.531557475391, 5869.941300587004, 5888.793892213774, 5539.889202215953, 5700.942990570104, 5756.366799652035, 5757.107648314497, 5787.508061814729, 5109.697027258044, 5342.412334643205, 5722.54219662426, 5737.598368114233, 5738.368779434259, 5503.215193659323, 5643.6049476536655, 5733.283339582552, 5740.512056475204, 5762.3949485304065, 5018.673786203922, 5035.169197082477, 5040.470750571174, 5043.949560504385, 5050.292958985745, 12569.245845249314, 14306.28468576567, 22218.88905554732, 23160.42098947523, 23200.419989500228, 2136.5192831612917, 2266.0822366941416, 2311.91908283211, 2392.509535545209, 2423.87880605969, 9841.655542055994, 9925.50372481373, 10105.444200568965, 12361.567345142877, 15305.234738263041, 1845.4832646858767, 1877.558773688182, 1914.6457905287427, 1927.9781715690706, 1928.3829174664113, 105062.47468762683, 108189.45905270502, 110409.0, 111043.88956110417, 113354.0, 11360.091192704564, 11552.942235288938, 11751.941240293743, 11764.0, 11893.70265743346, 14582.0, 14593.197374144524, 14597.197154156624, 14612.926935365254, 14623.122612643276, 8365.623546940402, 8435.156484351515, 8486.74539763808, 8500.914990850013, 8502.0, 8341.290990265774, 8367.748967530984, 8376.874346884802, 8465.915340846635, 8505.362097842686, 4431.379606855043, 4472.530384309665, 4506.414166158378, 4593.058423023273, 4683.227267500893, 1876.608735558799, 1927.5181204698824, 1935.98064019358, 1937.8352840008474, 1940.4857712706144, 817.9631916563769, 853.3216093205916, 880.5465185429509, 918.5728636184207, 932.846080396727, 16088.108875410724, 16602.50192494227, 16962.321507139823, 19427.51431214231, 20626.58746825085, 887.3477993674644, 891.2112780189578, 926.1803304075806, 939.8120375924706, 940.8917974432891, 10302.484875756183, 10893.727656808644, 10935.28920620157, 10955.616553420592, 10958.671239862817, 600649.0, 603066.0, 607305.0, 607937.0, 612950.0, 78400.0, 112035.63893083221, 122240.0, 123219.0, 127764.0, 972.1153750087386, 984.4979060679249, 1029.4852573713144, 1031.7730099378125, 1036.1710631494925, 15616.765748514008, 17980.55048623795, 18319.725204122216, 18668.439946801616, 18793.443663298767, 45591.31613025874, 48913.0, 50643.746781267335, 51103.48896510987, 54182.45817541932, 73474.26525734887, 76304.61847690726, 79506.6024669873, 80006.0, 80822.38355232745, 7731.226877312224, 7821.491603045783, 7886.645100970355, 8137.959310203411, 8155.714549990722, 10436.37381757097, 12365.319907405179, 12540.811887821508, 12724.936375318064, 12765.234085954873, 9312.940661701123, 10777.407242601732, 10885.727856803644, 10952.178586605876, 11131.888681113085, 10734.087602553558, 10785.191110666574, 10843.620473283712, 11177.776444471228, 11197.72005699864, 9688.95155524246, 10724.605801245789, 11281.32312061279, 11297.0, 11338.376389298337, 6577.868442631025, 7715.498492597963, 7850.097238817609, 8078.878816817635, 8287.254147126547, 8128.431009829255, 10324.896751032695, 10760.892391076302, 10963.616273430393, 11006.119510439306], "value": 9219.689400653044, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1385.5704731533226, 1437.1161735532646, 1565.428618554228, 1692.0693618509817, 1706.1384001079446, 7503.924960750391, 7747.22527747225, 7962.641681124349, 8437.325013998889, 8457.196566326205, 7870.96064519678, 8163.10205877353, 8182.795430114235, 8265.462744921591, 8268.421210515266, 2312.7802858728396, 2329.895154718042, 2333.5216280662426, 2335.6262997920335, 2339.0, 2324.7094113235844, 2359.504504054151, 2371.9762802372015, 2380.6310021946624, 2389.665446837444, 2308.4690521180137, 2310.2838120182796, 2314.5139520700673, 2335.054303007285, 2349.8942547585316, 2237.5301186750803, 2237.7538470768204, 2261.321603518946, 2263.5472905418983, 2273.5111950930464, 2324.523472688095, 2347.2958112566334, 2349.8825058747166, 2358.2925122463193, 2358.952820943589, 8279.917200827975, 8347.373946954003, 8400.327973762145, 8411.32709383254, 8431.74704758852, 5164.0, 5227.947720522822, 5232.8691782705355, 5245.396779370383, 5257.684538927677, 3180.872765089394, 3358.630550639428, 3405.3700065487965, 3485.494603282535, 3532.894013179609, 976.3409698453515, 992.6873034994026, 1023.161007973462, 1037.9931466477506, 1044.2324891204958, 9583.904160958371, 9783.608655653765, 10608.416537090457, 10908.836367454494, 11063.889361106367, 4019.83920643171, 4550.362949187051, 4641.675082744176, 4792.0895029944195, 4818.855434336975, 9432.717018489457, 9477.004914484018, 9482.525873706425, 9490.288228382899, 9521.333506654468, 5249.317588713519, 5255.343082114736, 5259.39516955555, 5264.157734762496, 5274.762635681327, 4318.35224716295, 4323.308270676676, 4741.336212930192, 4805.687630304089, 4825.179719447702, 3785.6782173515558, 3811.6569508744346, 3889.7082718796205, 3913.5499417566775, 3921.784301863368, 1267.5183430296613, 1268.0679700420185, 1272.3193091695898, 1282.7049778550986, 1284.78158713019, 795.4193438789716, 874.2306770042322, 879.3404946290283, 893.5755516129786, 912.1334732004622, 5580.218769372291, 5655.349634791969, 5694.402087780806, 5840.0071987762185, 5859.794907178229, 899.6401439424283, 984.2667212926416, 1017.6946915925192, 1026.8870424253325, 1033.214756784834, 3751.437284407306, 3781.9810900945868, 3836.2135762168687, 3851.30676478231, 3856.4408160816524, 9865.112139907444, 10274.897251027543, 10281.280310378203, 10290.845637315446, 10300.278980471296, 10222.539985700661, 10243.282970192164, 10250.282480226313, 10267.383956962607, 10286.331388459726, 6273.780417685359, 7791.649375778104, 7799.844003119906, 7852.0, 7968.083670377865, 5465.152901300305, 5479.0959491683425, 5567.359753628384, 5624.04391253496, 5676.68778217202, 9326.626934922528, 9446.811063778823, 9454.527273636428, 9497.097775711296, 9503.334766566273, 8397.622107005322, 9099.681511147079, 9171.63313467454, 9635.518224088628, 9642.0, 5495.53287970511, 6817.556858804361, 7032.331928466788, 7205.531640443354, 7363.337299643058, 7810.258025487569, 8041.87937180931, 8051.83896322082, 8057.717979870677, 8074.152214017444, 5966.49284810796, 6684.997250412477, 6685.696289223749, 6844.007618895332, 7098.609576473344, 6039.275286965592, 6084.330723620486, 7004.754833580801, 7059.329363710439, 7087.7164913402885, 5949.494292985145, 6460.741570337322, 6656.101426307387, 6689.565178263398, 6752.189737231563, 4774.045190961752, 4796.35249241348, 4818.229083346648, 4825.30033145199, 4884.48712885142, 4891.926621100615, 4917.409910810726, 4954.702717836942, 4970.62720295972, 4994.525520075732], "value": 4403.2718770649535, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.3551224672065, 309.47080492358066, 311.3648157758174, 313.5046626330398, 315.94786860168057, 4545.636349092073, 4623.468301145361, 5383.066228905246, 5391.7868479592125, 5466.426025267349, 5043.722595257259, 5352.841823324238, 5394.6493477924005, 5398.541124004464, 5400.972995135028, 3535.727138230235, 3944.250592387444, 3998.260321840469, 4171.207470580595, 4187.246295666779, 3323.7839540429914, 3826.8519444166777, 3963.9405408918888, 4139.1100913303635, 4202.558731333212, 3494.1963348429877, 3987.8006099695044, 4169.332906734923, 4173.833046678129, 4174.812133454002, 3832.9616703832885, 4036.7174297798874, 4098.7130900836955, 4188.157210827227, 4225.323948168279, 3096.6593674695764, 3523.788572685647, 3526.612072672004, 3540.2916768671985, 3560.2701446203464, 5312.0, 5324.307839980816, 5341.1187154119525, 5345.30511033567, 5358.33020872391, 3809.657130858236, 4033.7781422021776, 4362.934555981663, 4399.318105693591, 4720.221163508017, 4387.802548885308, 5033.672811267293, 5671.0, 5731.627444216113, 5737.569682273805, 3624.5288112545463, 3917.3144699677728, 3925.7448265862627, 3940.802959851996, 3941.605839416064, 4605.595293435501, 4708.811647534095, 4904.4605093439695, 5637.746301416446, 5926.436988486087, 3268.6404495505476, 3924.3917192835156, 3932.311845427039, 3934.7048971327263, 3940.9802950985063, 3565.3938830398893, 3864.8163787459994, 4024.195160967818, 4025.2087820919996, 4035.91928161441, 4116.991337122365, 4501.369808226851, 4612.7001744886475, 4660.650451216173, 4714.4106986626675, 5409.809027343932, 5538.944610553924, 5705.771769129271, 5744.540436765049, 5792.79725209624, 1193.4331192683405, 1235.209465941798, 1306.2619619914908, 1308.5485507499898, 1311.6327428320085, 819.8483280593109, 828.1056459024239, 846.9703560375357, 866.4151697604092, 887.9289656827567, 4076.7553946763287, 5035.647504674711, 5298.019866324742, 5337.0, 5504.587155963319, 5465.4261302563455, 6005.459508644156, 6022.608530445507, 6058.515318774487, 6071.453569178795, 4411.904345435956, 4695.826043489128, 5520.696361700145, 5559.416261292587, 5563.5271001964475, 2358.870262135599, 2478.442350471166, 2685.5837345211526, 2872.4255148970287, 3000.6249218847643, 5257.371314342829, 5575.741824561059, 5611.0, 5624.971875140598, 5967.492763115097, 4990.700557966534, 5068.56917162038, 5407.756650950717, 5831.542114471382, 5838.620489668158, 5129.178761822392, 5270.657407268515, 5664.688442135722, 5943.790353209219, 5947.256592925884, 5468.507834294933, 5698.430156984268, 5717.942820571908, 5728.570357223141, 5779.219805326227, 4886.877828054327, 5335.786568537215, 5613.649421698216, 5621.381648018796, 5641.915371269352, 5041.218611115283, 5879.794207202727, 5885.822245769093, 5960.940390596212, 5991.460768530853, 3490.7556471046782, 3495.720342372655, 3595.993121925865, 3783.5838057814167, 3805.0724312015454, 5028.723420211923, 5029.773660185374, 5599.860003499782, 5657.858553536194, 5695.345035320991, 4887.3890763654545, 5038.095476006125, 5232.921506177487, 5508.752106155152, 5544.473275038865, 5232.947670523246, 5271.1039123348355, 5465.480779325958, 5496.340439147168, 5560.27716396882, 4394.67082446304, 4787.61699064081, 5331.546818520469, 5394.514493695587, 5418.0, 5519.862003449946, 5653.1802888580105, 5677.3754886963225, 5783.0, 5808.854778630568, 5634.52106570946, 5647.322321321468, 5654.6889921054735, 5696.829095127153, 5711.400302968294], "value": 4061.3032508083156, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [679.127321392011, 726.6693654387251, 748.3825843678965, 763.8739607964686, 779.8635238833205, 14713.852861471381, 15261.0, 15315.157666328376, 15373.46192883248, 15391.92304038481, 13506.729865402685, 13577.59267221985, 13629.795553066688, 13650.976176786757, 13730.176189428641, 766.1801871996965, 782.0810547606568, 786.626352482569, 795.3319211862034, 796.6853093028251, 786.2962648429681, 793.131520984524, 797.7566842113154, 798.4929569723209, 800.8118092248317, 826.7850358906668, 836.2849763452222, 838.4591938199887, 842.9915700842975, 843.0389356134012, 819.5492479136481, 827.2017503109498, 830.5349004557468, 830.5515021888203, 831.8627426474625, 600.4595863722641, 612.4182027074253, 625.7497001199511, 628.1927722876128, 628.779927025542, 10560.366378017343, 12560.685982850411, 12600.36998150098, 12609.684757881127, 13109.0, 11645.6506304811, 12054.517819287217, 12066.577669781604, 12185.32980686062, 12418.130730848845, 12027.873806238464, 12122.39388030595, 12190.2076365036, 12319.69200769979, 12724.809127863089, 4319.416878721364, 4353.194658988097, 4355.216061108999, 4377.124575084964, 4426.380306757057, 55834.9593109027, 59836.70081649607, 72555.27444725537, 84999.57500212522, 92841.07158928392, 10397.376157430426, 10408.5836566538, 10513.737156571147, 10600.257981941344, 10682.78634427307, 15704.842951570567, 16599.41902033423, 19711.014449277478, 19922.30271940475, 20078.0, 6745.561538499982, 7172.354488095992, 7301.08736407949, 7328.680837449204, 7664.195259497784, 4136.6483848872585, 4174.165166966619, 4189.72766770159, 4256.170046840836, 4363.258246098171, 3317.1209629447735, 3399.184195792992, 3438.6905178534053, 3620.5293311869286, 3790.905227369338, 641.3298103481873, 659.5712786688595, 662.6984721951501, 668.5721138471382, 669.521292276025, 603.963762174271, 646.8770933522616, 658.3054877104605, 662.2814246542555, 677.2820809941478, 8726.389152759373, 9024.097590240923, 9086.818713567194, 9158.816823663623, 9334.859977100214, 564.7345747498669, 647.2233320015999, 654.7053825778323, 656.7602824968852, 670.0, 3910.7458015228917, 4153.0, 4319.632831209383, 4459.777011149429, 4612.7924243409125, 108449.0, 108664.0, 109402.90597094086, 109575.45212273888, 109598.0, 97784.0221597775, 99070.0, 99536.50695239578, 101597.0, 102620.48689756503, 87306.0, 89086.10913890778, 91554.0, 92252.0, 93233.53383233176, 8451.197136272045, 8877.511736854276, 8983.406637345115, 9521.095495927875, 9676.22590192797, 49681.0, 51862.740686296325, 52991.735041324544, 53362.93274134418, 55346.44653553413, 61270.69364653148, 66998.0, 75514.0, 75566.62216688882, 78280.2171978273, 12025.518979241082, 12457.750844983231, 12528.87471125277, 12546.309952952675, 12726.87273127257, 13897.652558686113, 14088.436462541384, 14194.148351098967, 14335.35490902891, 14650.70698586001, 11626.941865290957, 11875.228110172811, 11918.642440726791, 11930.463129159036, 11957.043436525231, 12742.808857866954, 13149.737005260033, 13204.0, 13321.200727956353, 13784.655383615489, 12314.692132696397, 13046.608601741962, 13160.276184809927, 13637.908967282792, 13648.24934628605, 10510.737231569272, 10907.07289880369, 11546.59586914454, 11633.825492617449, 11768.0, 10730.570777168827, 11022.559097636005, 11301.208915376174, 11331.603393881174, 11353.432328383382], "value": 7227.491096457272, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1545.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 118.0, 117.0, 118.0, 121.0, 118.0, 119.0, 119.0, 119.0, 119.0], "value": 118.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 103.0, 144.0, 160.0, 148.0, 140.0, 142.0, 123.0, 140.0, 142.0], "value": 142.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [211.0, 239.0, 194.0, 193.0, 269.0, 275.0, 198.0, 198.0, 198.0, 197.0], "value": 217.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 62.0, 57.0, 82.0, 60.0, 61.0, 66.0, 81.0, 96.0, 62.0], "value": 70.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 71.0, 90.0, 73.0, 72.0, 94.0, 83.0, 74.0, 100.0, 120.0], "value": 85.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 84.0, 93.0, 75.0, 135.0, 115.0, 93.0, 90.0, 92.0, 91.0], "value": 96.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 99.0, 100.0, 97.0, 98.0, 97.0, 96.0, 97.0, 99.0, 117.0], "value": 99.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 93.0, 86.0, 95.0, 91.0, 88.0, 91.0, 94.0, 88.0, 88.0], "value": 90.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 57.0, 60.0, 60.0, 61.0, 60.0, 61.0, 61.0, 62.0], "value": 60.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 75.0, 73.0, 79.0, 76.0, 69.0, 72.0, 70.0, 74.0, 77.0], "value": 73.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 104.0, 106.0, 84.0, 89.0, 104.0, 81.0, 99.0, 107.0, 107.0], "value": 97.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 144.0, 148.0, 151.0, 160.0, 147.0, 152.0, 164.0, 197.0, 157.0], "value": 156.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 169.0, 189.0, 176.0, 168.0, 168.0, 166.0, 165.0, 166.0, 168.0], "value": 170.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 69.0, 67.0, 69.0, 68.0, 70.0, 67.0, 66.0, 69.0], "value": 68.3, "unit": "score"}]}]} 02:27:38 INFO - Return code: 0 02:27:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:27:38 ERROR - # TBPL SUCCESS # 02:27:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:27:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:27:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:27:38 INFO - 2017-01-05 02:27:38.862 screenresolution[1655:10605] starting screenresolution argv=screenresolution get 02:27:38 INFO - 2017-01-05 02:27:38.865 screenresolution[1655:10605] Display 0: 1600x1200x32@60 02:27:38 INFO - 2017-01-05 02:27:38.871 screenresolution[1656:10608] starting screenresolution argv=screenresolution list 02:27:38 INFO - Available Modes on Display 0 02:27:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:27:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:27:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:27:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:27:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:27:39 INFO - Intel Iris: 02:27:39 INFO - Chipset Model: Intel Iris 02:27:39 INFO - Type: GPU 02:27:39 INFO - Bus: Built-In 02:27:39 INFO - VRAM (Dynamic, Max): 1536 MB 02:27:39 INFO - Vendor: Intel (0x8086) 02:27:39 INFO - Device ID: 0x0a2e 02:27:39 INFO - Revision ID: 0x0009 02:27:39 INFO - Displays: 02:27:39 INFO - Display: 02:27:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:27:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:27:39 INFO - Display Serial Number: 1600x1200 60 02:27:39 INFO - Main Display: Yes 02:27:39 INFO - Mirror: Off 02:27:39 INFO - Online: Yes 02:27:39 INFO - Rotation: Supported 02:27:39 INFO - Return code: 0 02:27:39 INFO - Running post-action listener: _resource_record_post_action 02:27:39 INFO - [mozharness: 2017-01-05 10:27:39.112117Z] Finished run-tests step (success) 02:27:39 INFO - Running post-run listener: _resource_record_post_run 02:27:39 INFO - Total resource usage - Wall time: 1211s; CPU: 55.0%; Read bytes: 39815680; Write bytes: 662021120; Read time: 400; Write time: 1951 02:27:39 INFO - TinderboxPrint: CPU usage
54.6% 02:27:39 INFO - TinderboxPrint: I/O read bytes / time
39,815,680 / 400 02:27:39 INFO - TinderboxPrint: I/O write bytes / time
662,021,120 / 1,951 02:27:39 INFO - TinderboxPrint: CPU idle
2,198.8 (45.4%) 02:27:39 INFO - TinderboxPrint: CPU system
982.1 (20.3%) 02:27:39 INFO - TinderboxPrint: CPU user
1,664.8 (34.4%) 02:27:39 INFO - TinderboxPrint: Swap in / out
389,062,656 / 0 02:27:39 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 351601152; Write bytes: 345061376; Read time: 27544; Write time: 626 02:27:39 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 32387584; Write bytes: 309799936; Read time: 295; Write time: 1286 02:27:39 INFO - Running post-run listener: _upload_blobber_files 02:27:39 INFO - Blob upload gear active. 02:27:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:27:39 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 02:27:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:27:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:27:40 INFO - (blobuploader) - INFO - Open directory for files ... 02:27:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:27:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:27:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:27:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:27:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:27:40 INFO - (blobuploader) - INFO - Done attempting. 02:27:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:27:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:27:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:27:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:27:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:27:41 INFO - (blobuploader) - INFO - Done attempting. 02:27:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:27:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:27:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:27:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:27:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:27:43 INFO - (blobuploader) - INFO - Done attempting. 02:27:43 INFO - (blobuploader) - INFO - Iteration through files over. 02:27:43 INFO - Return code: 0 02:27:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:27:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:27:43 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d04a5afd583e7ae52071526f501833ae81ca7b10e6b06cd0ac08b262d0f4e762cef0441f16b4e0d211c03101fa5cfa0e7711dcfa05cd58e4d76dbd83c531c7bd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f298f09d151781746081675df8a7ccdd60b42da40974117fa130df99d46dc6e0915ed738a4f9125ad8f304d69c21a071f97b6c0abc5872e318abf265fa958ed1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9e59f6a75808e05baf238418a3094d94f7f631041a42061847a3c96ef8998c5c7fa9e0062df79305203095891a6599dcd658d9967d49deb2bc055aba8ff02974"} 02:27:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:27:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:27:43 INFO - Contents: 02:27:43 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d04a5afd583e7ae52071526f501833ae81ca7b10e6b06cd0ac08b262d0f4e762cef0441f16b4e0d211c03101fa5cfa0e7711dcfa05cd58e4d76dbd83c531c7bd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f298f09d151781746081675df8a7ccdd60b42da40974117fa130df99d46dc6e0915ed738a4f9125ad8f304d69c21a071f97b6c0abc5872e318abf265fa958ed1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9e59f6a75808e05baf238418a3094d94f7f631041a42061847a3c96ef8998c5c7fa9e0062df79305203095891a6599dcd658d9967d49deb2bc055aba8ff02974"} 02:27:43 INFO - Running post-run listener: copy_logs_to_upload_dir 02:27:43 INFO - Copying logs to upload dir... 02:27:43 INFO - mkdir: /builds/slave/test/build/upload/logs 02:27:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1290.004862 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 30 secs) (at 2017-01-05 02:27:43.883060) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-05 02:27:43.886873) ========= 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.nDQFIJv0OY/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.aesc1kfPhF/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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/Mozilla-Aurora/sha512/d04a5afd583e7ae52071526f501833ae81ca7b10e6b06cd0ac08b262d0f4e762cef0441f16b4e0d211c03101fa5cfa0e7711dcfa05cd58e4d76dbd83c531c7bd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f298f09d151781746081675df8a7ccdd60b42da40974117fa130df99d46dc6e0915ed738a4f9125ad8f304d69c21a071f97b6c0abc5872e318abf265fa958ed1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9e59f6a75808e05baf238418a3094d94f7f631041a42061847a3c96ef8998c5c7fa9e0062df79305203095891a6599dcd658d9967d49deb2bc055aba8ff02974"} build_url:https://queue.taskcluster.net/v1/task/VHUmfEpWTgC9DwzM_p0lYQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011332 build_url: 'https://queue.taskcluster.net/v1/task/VHUmfEpWTgC9DwzM_p0lYQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d04a5afd583e7ae52071526f501833ae81ca7b10e6b06cd0ac08b262d0f4e762cef0441f16b4e0d211c03101fa5cfa0e7711dcfa05cd58e4d76dbd83c531c7bd", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f298f09d151781746081675df8a7ccdd60b42da40974117fa130df99d46dc6e0915ed738a4f9125ad8f304d69c21a071f97b6c0abc5872e318abf265fa958ed1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9e59f6a75808e05baf238418a3094d94f7f631041a42061847a3c96ef8998c5c7fa9e0062df79305203095891a6599dcd658d9967d49deb2bc055aba8ff02974"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-05 02:27:43.921330) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 02:27:43.921661) ========= 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.nDQFIJv0OY/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.aesc1kfPhF/Listeners TMPDIR=/var/folders/fd/qd_slwb90wlgg0k7hddkt1cr00000w/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.005177 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 02:27:43.970397) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-05 02:27:43.970718) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-05 02:27:43.971030) ========= ========= Total master_lag: 0.18 =========