builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0342 starttime: 1485238257.36 results: success (0) buildid: 20170123195043 builduid: 073220fafa584cdeb7fa162cb82c1601 revision: 7e83a181036b71708716f64d644f10b38a479e69 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-23 22:10:57.359040) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-23 22:10:57.359547) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-23 22:10:57.359895) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-23 22:10:57.387730) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-23 22:10:57.388116) ========= 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.CP7klMr5wC/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.noO7KnsgoR/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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-23 22:10:57-- 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% 159M=0s 2017-01-23 22:10:57 (159 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.487677 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-23 22:10:57.910381) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-23 22:10:57.910701) ========= 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.CP7klMr5wC/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.noO7KnsgoR/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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.019780 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-23 22:10:57.961501) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-23 22:10:57.961826) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 7e83a181036b71708716f64d644f10b38a479e69 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 7e83a181036b71708716f64d644f10b38a479e69 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CP7klMr5wC/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.noO7KnsgoR/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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-23 22:10:58,052 truncating revision to first 12 chars 2017-01-23 22:10:58,052 Setting DEBUG logging. 2017-01-23 22:10:58,052 attempt 1/10 2017-01-23 22:10:58,052 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/7e83a181036b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-23 22:10:58,681 unpacking tar archive at: mozilla-beta-7e83a181036b/testing/mozharness/ program finished with exit code 0 elapsedTime=0.873947 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-23 22:10:58.859438) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-23 22:10:58.859779) ========= script_repo_revision: 7e83a181036b71708716f64d644f10b38a479e69 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-23 22:10:58.860224) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-23 22:10:58.860687) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-23 22:10:58.883747) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2017-01-23 22:10:58.884140) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CP7klMr5wC/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.noO7KnsgoR/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 22:10:59 INFO - MultiFileLogger online at 20170123 22:10:59 in /builds/slave/test 22:10:59 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 22:10:59 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:10:59 INFO - {'append_to_log': False, 22:10:59 INFO - 'base_work_dir': '/builds/slave/test', 22:10:59 INFO - 'blob_upload_branch': 'Mozilla-Beta', 22:10:59 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:10:59 INFO - 'branch': 'Mozilla-Beta', 22:10:59 INFO - 'buildbot_json_path': 'buildprops.json', 22:10:59 INFO - 'config_files': ('talos/mac_config.py',), 22:10:59 INFO - 'default_actions': ('clobber', 22:10:59 INFO - 'read-buildbot-config', 22:10:59 INFO - 'download-and-extract', 22:10:59 INFO - 'populate-webroot', 22:10:59 INFO - 'create-virtualenv', 22:10:59 INFO - 'install', 22:10:59 INFO - 'run-tests'), 22:10:59 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:10:59 INFO - 'download_minidump_stackwalk': True, 22:10:59 INFO - 'download_symbols': 'ondemand', 22:10:59 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:10:59 INFO - 'tooltool.py': '/tools/tooltool.py', 22:10:59 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:10:59 INFO - '/tools/misc-python/virtualenv.py')}, 22:10:59 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:10:59 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:10:59 INFO - 'installer_path': 'installer.exe', 22:10:59 INFO - 'log_level': 'info', 22:10:59 INFO - 'log_name': 'talos', 22:10:59 INFO - 'log_to_console': True, 22:10:59 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:10:59 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:10:59 INFO - 'opt_config_files': (), 22:10:59 INFO - 'pip_index': False, 22:10:59 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:10:59 INFO - 'cmd': ('bash', 22:10:59 INFO - '-c', 22:10:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:10:59 INFO - 'enabled': True, 22:10:59 INFO - 'halt_on_failure': False, 22:10:59 INFO - 'name': 'check_screen_resolution'},), 22:10:59 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:10:59 INFO - 'cmd': ('bash', 22:10:59 INFO - '-c', 22:10:59 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:10:59 INFO - 'enabled': True, 22:10:59 INFO - 'halt_on_failure': False, 22:10:59 INFO - 'name': 'check_screen_resolution'},), 22:10:59 INFO - 'run_cmd_checks_enabled': True, 22:10:59 INFO - 'sps_profile': False, 22:10:59 INFO - 'sps_profile_interval': 0, 22:10:59 INFO - 'suite': 'dromaeojs-e10s', 22:10:59 INFO - 'system_bits': '32', 22:10:59 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:10:59 INFO - 'title': 't-yosemite-r7-0342', 22:10:59 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:10:59 INFO - 'use_talos_json': True, 22:10:59 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:10:59 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:10:59 INFO - 'work_dir': 'build'} 22:10:59 INFO - [mozharness: 2017-01-24 06:10:59.093293Z] Running clobber step. 22:10:59 INFO - Running pre-action listener: _resource_record_pre_action 22:10:59 INFO - Running main action method: clobber 22:10:59 INFO - rmtree: /builds/slave/test/build 22:10:59 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:11:00 INFO - Running post-action listener: _resource_record_post_action 22:11:00 INFO - [mozharness: 2017-01-24 06:11:00.054709Z] Finished clobber step (success) 22:11:00 INFO - [mozharness: 2017-01-24 06:11:00.054874Z] Running read-buildbot-config step. 22:11:00 INFO - Running pre-action listener: _resource_record_pre_action 22:11:00 INFO - Running main action method: read_buildbot_config 22:11:00 INFO - Using buildbot properties: 22:11:00 INFO - { 22:11:00 INFO - "project": "", 22:11:00 INFO - "product": "firefox", 22:11:00 INFO - "script_repo_revision": "production", 22:11:00 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 22:11:00 INFO - "repository": "", 22:11:00 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 22:11:00 INFO - "buildid": "20170123195043", 22:11:00 INFO - "pgo_build": "False", 22:11:00 INFO - "basedir": "/builds/slave/test", 22:11:00 INFO - "buildnumber": 4, 22:11:00 INFO - "slavename": "t-yosemite-r7-0342", 22:11:00 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 22:11:00 INFO - "platform": "yosemite_r7", 22:11:00 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 22:11:00 INFO - "branch": "mozilla-beta", 22:11:00 INFO - "repo_path": "releases/mozilla-beta", 22:11:00 INFO - "revision": "7e83a181036b71708716f64d644f10b38a479e69", 22:11:00 INFO - "stage_platform": "macosx64", 22:11:00 INFO - "builduid": "073220fafa584cdeb7fa162cb82c1601", 22:11:00 INFO - "slavebuilddir": "test" 22:11:00 INFO - } 22:11:00 INFO - Found installer url https://queue.taskcluster.net/v1/task/CDmdux7rT528pwzaswH3Pw/artifacts/public/build/firefox-52.0.en-US.mac.dmg. 22:11:00 INFO - Running post-action listener: _resource_record_post_action 22:11:00 INFO - [mozharness: 2017-01-24 06:11:00.058231Z] Finished read-buildbot-config step (success) 22:11:00 INFO - [mozharness: 2017-01-24 06:11:00.058324Z] Running download-and-extract step. 22:11:00 INFO - Running pre-action listener: _resource_record_pre_action 22:11:00 INFO - Running main action method: download_and_extract 22:11:00 INFO - mkdir: /builds/slave/test/build/tests 22:11:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:11:00 INFO - trying https://queue.taskcluster.net/v1/task/CDmdux7rT528pwzaswH3Pw/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json 22:11:00 INFO - Downloading https://queue.taskcluster.net/v1/task/CDmdux7rT528pwzaswH3Pw/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 22:11:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CDmdux7rT528pwzaswH3Pw/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json'}, attempt #1 22:11:01 INFO - Downloaded 1165 bytes. 22:11:01 INFO - Reading from file /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 22:11:01 INFO - Using the following test package requirements: 22:11:01 INFO - {u'common': [u'firefox-52.0.en-US.mac.common.tests.zip'], 22:11:01 INFO - u'cppunittest': [u'firefox-52.0.en-US.mac.common.tests.zip', 22:11:01 INFO - u'firefox-52.0.en-US.mac.cppunittest.tests.zip'], 22:11:01 INFO - u'gtest': [u'firefox-52.0.en-US.mac.common.tests.zip', 22:11:01 INFO - u'firefox-52.0.en-US.mac.gtest.tests.zip'], 22:11:01 INFO - u'jittest': [u'firefox-52.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 22:11:01 INFO - u'mochitest': [u'firefox-52.0.en-US.mac.common.tests.zip', 22:11:01 INFO - u'firefox-52.0.en-US.mac.mochitest.tests.zip'], 22:11:01 INFO - u'mozbase': [u'firefox-52.0.en-US.mac.common.tests.zip'], 22:11:01 INFO - u'reftest': [u'firefox-52.0.en-US.mac.common.tests.zip', 22:11:01 INFO - u'firefox-52.0.en-US.mac.reftest.tests.zip'], 22:11:01 INFO - u'talos': [u'firefox-52.0.en-US.mac.common.tests.zip', 22:11:01 INFO - u'firefox-52.0.en-US.mac.talos.tests.zip'], 22:11:01 INFO - u'web-platform': [u'firefox-52.0.en-US.mac.common.tests.zip', 22:11:01 INFO - u'firefox-52.0.en-US.mac.web-platform.tests.zip'], 22:11:01 INFO - u'xpcshell': [u'firefox-52.0.en-US.mac.common.tests.zip', 22:11:01 INFO - u'firefox-52.0.en-US.mac.xpcshell.tests.zip']} 22:11:01 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip'] for test suite category: common 22:11:01 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CDmdux7rT528pwzaswH3Pw/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 22:11:01 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CDmdux7rT528pwzaswH3Pw/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 22:11:01 INFO - Fetch https://queue.taskcluster.net/v1/task/CDmdux7rT528pwzaswH3Pw/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 22:11:02 INFO - Http code: 200 22:11:02 INFO - via: 1.1 11dd60d1f68e8258294f92935b53a91f.cloudfront.net (CloudFront) 22:11:02 INFO - x-amz-cf-id: pFOxGCU_qWyQYyWE2vl48_20WSTgZj5UTpvCMxQJGwRjiH6wAEuDAw== 22:11:02 INFO - x-amz-version-id: C_3yEL9a7OAyfiKcESOXIcFO0axl_gQ8 22:11:02 INFO - Expected file size: 16086055 22:11:02 INFO - Obtained file size: 16086055 22:11:09 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip', u'firefox-52.0.en-US.mac.talos.tests.zip'] for test suite category: talos 22:11:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CDmdux7rT528pwzaswH3Pw/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 22:11:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CDmdux7rT528pwzaswH3Pw/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 22:11:09 INFO - Fetch https://queue.taskcluster.net/v1/task/CDmdux7rT528pwzaswH3Pw/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 22:11:09 INFO - Http code: 200 22:11:09 INFO - via: 1.1 ba26a6eef2785fa2e20e601890b882b2.cloudfront.net (CloudFront) 22:11:09 INFO - x-amz-cf-id: nCmvoKhg4V4j3xw6TORbl1tWMy1q3lGwup38HDrkluyFtyxMkcfihQ== 22:11:09 INFO - x-amz-version-id: C_3yEL9a7OAyfiKcESOXIcFO0axl_gQ8 22:11:10 INFO - Expected file size: 16086055 22:11:10 INFO - Obtained file size: 16086055 22:11:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CDmdux7rT528pwzaswH3Pw/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip 22:11:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CDmdux7rT528pwzaswH3Pw/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip'}, attempt #1 22:11:16 INFO - Fetch https://queue.taskcluster.net/v1/task/CDmdux7rT528pwzaswH3Pw/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip into memory 22:11:17 INFO - Http code: 200 22:11:17 INFO - via: 1.1 66ed69e8b47ad05050331602c798132f.cloudfront.net (CloudFront) 22:11:17 INFO - x-amz-cf-id: _7D2botYoWeYY9Ly5QVtAl6OeRH0jfJT3RKFP2abyPQ19bOWE1IeRw== 22:11:17 INFO - x-amz-version-id: h5uND0mXWhTjXBhvnsvIZ4X6Jo0MWgYK 22:11:17 INFO - Expected file size: 14402992 22:11:17 INFO - Obtained file size: 14402992 22:11:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:11:17 INFO - trying https://queue.taskcluster.net/v1/task/CDmdux7rT528pwzaswH3Pw/artifacts/public/build/firefox-52.0.en-US.mac.dmg 22:11:17 INFO - Downloading https://queue.taskcluster.net/v1/task/CDmdux7rT528pwzaswH3Pw/artifacts/public/build/firefox-52.0.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0.en-US.mac.dmg 22:11:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CDmdux7rT528pwzaswH3Pw/artifacts/public/build/firefox-52.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0.en-US.mac.dmg'}, attempt #1 22:11:20 INFO - Downloaded 82109637 bytes. 22:11:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CDmdux7rT528pwzaswH3Pw/artifacts/public/build/firefox-52.0.en-US.mac.dmg 22:11:20 INFO - mkdir: /builds/slave/test/properties 22:11:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:11:20 INFO - Writing to file /builds/slave/test/properties/build_url 22:11:20 INFO - Contents: 22:11:20 INFO - build_url:https://queue.taskcluster.net/v1/task/CDmdux7rT528pwzaswH3Pw/artifacts/public/build/firefox-52.0.en-US.mac.dmg 22:11:21 INFO - Running post-action listener: _resource_record_post_action 22:11:21 INFO - Running post-action listener: set_extra_try_arguments 22:11:21 INFO - [mozharness: 2017-01-24 06:11:21.126738Z] Finished download-and-extract step (success) 22:11:21 INFO - [mozharness: 2017-01-24 06:11:21.126857Z] Running populate-webroot step. 22:11:21 INFO - Running pre-action listener: _resource_record_pre_action 22:11:21 INFO - Running main action method: populate_webroot 22:11:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:11:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:11:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:11:21 INFO - '--tppagecycles', 22:11:21 INFO - '7'], 22:11:21 INFO - 'tests': ['tsvgm']}}, 22:11:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:11:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:11:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:11:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:11:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:11:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:11:21 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:11:21 INFO - 'talos_options': ['--disable-e10s'], 22:11:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:11:21 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:11:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:11:21 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:11:21 INFO - 'talos_options': ['--disable-e10s'], 22:11:21 INFO - 'tests': ['damp', 'tps']}, 22:11:21 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:11:21 INFO - 'tests': ['damp', 'tps']}, 22:11:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:11:21 INFO - 'tests': ['dromaeo_dom']}, 22:11:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:11:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:11:21 INFO - 'tests': ['basic_compositor_video']}, 22:11:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:11:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:11:21 INFO - 'tests': ['a11yr', 22:11:21 INFO - 'ts_paint', 22:11:21 INFO - 'tpaint', 22:11:21 INFO - 'sessionrestore', 22:11:21 INFO - 'sessionrestore_no_auto_restore', 22:11:21 INFO - 'tabpaint']}, 22:11:21 INFO - 'other-e10s': {'tests': ['a11yr', 22:11:21 INFO - 'ts_paint', 22:11:21 INFO - 'tpaint', 22:11:21 INFO - 'sessionrestore', 22:11:21 INFO - 'sessionrestore_no_auto_restore', 22:11:21 INFO - 'tabpaint']}, 22:11:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:11:21 INFO - 'ts_paint', 22:11:21 INFO - 'tpaint', 22:11:21 INFO - 'sessionrestore', 22:11:21 INFO - 'sessionrestore_no_auto_restore', 22:11:21 INFO - 'tabpaint']}, 22:11:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:11:21 INFO - 'ts_paint', 22:11:21 INFO - 'tpaint', 22:11:21 INFO - 'sessionrestore', 22:11:21 INFO - 'sessionrestore_no_auto_restore', 22:11:21 INFO - 'tabpaint']}, 22:11:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:11:21 INFO - 'tests': ['a11yr', 22:11:21 INFO - 'ts_paint', 22:11:21 INFO - 'tpaint', 22:11:21 INFO - 'sessionrestore', 22:11:21 INFO - 'sessionrestore_no_auto_restore', 22:11:21 INFO - 'tabpaint']}, 22:11:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:11:21 INFO - 'tests': ['a11yr', 22:11:21 INFO - 'ts_paint', 22:11:21 INFO - 'tpaint', 22:11:21 INFO - 'sessionrestore', 22:11:21 INFO - 'sessionrestore_no_auto_restore', 22:11:21 INFO - 'tabpaint']}, 22:11:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:11:21 INFO - 'tests': ['tsvgx', 22:11:21 INFO - 'tsvgr_opacity', 22:11:21 INFO - 'tart', 22:11:21 INFO - 'tscrollx', 22:11:21 INFO - 'cart']}, 22:11:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:11:21 INFO - 'tsvgr_opacity', 22:11:21 INFO - 'tart', 22:11:21 INFO - 'tscrollx', 22:11:21 INFO - 'cart']}, 22:11:21 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:11:21 INFO - 'talos_options': ['--disable-e10s'], 22:11:21 INFO - 'tests': ['tp5o']}, 22:11:21 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:11:21 INFO - 'tests': ['tp5o']}, 22:11:21 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:11:21 INFO - 'talos_options': ['--disable-e10s', 22:11:21 INFO - '--xperf_path', 22:11:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:11:21 INFO - 'tests': ['tp5n']}, 22:11:21 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:11:21 INFO - 'talos_options': ['--xperf_path', 22:11:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:11:21 INFO - 'tests': ['tp5n']}}, 22:11:21 INFO - 'talos.zip': {'path': '', 22:11:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:11:21 INFO - Running post-action listener: _resource_record_post_action 22:11:21 INFO - [mozharness: 2017-01-24 06:11:21.145416Z] Finished populate-webroot step (success) 22:11:21 INFO - [mozharness: 2017-01-24 06:11:21.145547Z] Running create-virtualenv step. 22:11:21 INFO - Running pre-action listener: _resource_record_pre_action 22:11:21 INFO - Running main action method: create_virtualenv 22:11:21 INFO - Creating virtualenv /builds/slave/test/build/venv 22:11:21 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 22:11:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:11:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:11:21 INFO - Using real prefix '/tools/python27' 22:11:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:11:21 INFO - Installing distribute.............................................................................................................................................................................................done. 22:11:24 INFO - Installing pip.................done. 22:11:24 INFO - Return code: 0 22:11:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 22:11:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 22:11:25 INFO - Reading from file tmpfile_stdout 22:11:25 INFO - Output received: 22:11:25 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) 22:11:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:11:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:11:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:11:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:11:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:11:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d385490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2fb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe51bc9d0a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d199d30>, '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 0x10cfffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d191b20>, '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.CP7klMr5wC/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.noO7KnsgoR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 22:11:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:11:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:11:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CP7klMr5wC/Render', 22:11:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:11:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:11:25 INFO - 'HOME': '/Users/cltbld', 22:11:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:11:25 INFO - 'LOGNAME': 'cltbld', 22:11:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:11:25 INFO - 'MOZ_NO_REMOTE': '1', 22:11:25 INFO - 'NO_EM_RESTART': '1', 22:11:25 INFO - 'PAGER': '/bin/cat', 22:11:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:11:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:11:25 INFO - 'PWD': '/builds/slave/test', 22:11:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:11:25 INFO - 'SHELL': '/bin/bash', 22:11:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.noO7KnsgoR/Listeners', 22:11:25 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 22:11:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:11:25 INFO - 'USER': 'cltbld', 22:11:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:11:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:11:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:11:25 INFO - 'XPC_FLAGS': '0x0', 22:11:25 INFO - 'XPC_SERVICE_NAME': '0', 22:11:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:11:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:11:25 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 22:11:25 INFO - Cleaning up... 22:11:25 INFO - Return code: 0 22:11:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:11:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:11:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:11:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:11:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:11:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d385490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2fb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe51bc9d0a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d199d30>, '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 0x10cfffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d191b20>, '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.CP7klMr5wC/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.noO7KnsgoR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 22:11:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 22:11:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 22:11:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CP7klMr5wC/Render', 22:11:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:11:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:11:25 INFO - 'HOME': '/Users/cltbld', 22:11:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:11:25 INFO - 'LOGNAME': 'cltbld', 22:11:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:11:25 INFO - 'MOZ_NO_REMOTE': '1', 22:11:25 INFO - 'NO_EM_RESTART': '1', 22:11:25 INFO - 'PAGER': '/bin/cat', 22:11:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:11:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:11:25 INFO - 'PWD': '/builds/slave/test', 22:11:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:11:25 INFO - 'SHELL': '/bin/bash', 22:11:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.noO7KnsgoR/Listeners', 22:11:25 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 22:11:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:11:25 INFO - 'USER': 'cltbld', 22:11:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:11:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:11:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:11:25 INFO - 'XPC_FLAGS': '0x0', 22:11:25 INFO - 'XPC_SERVICE_NAME': '0', 22:11:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:11:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:11:25 INFO - Downloading/unpacking psutil>=3.1.1 22:11:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:11:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:11:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:11:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:11:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:11:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:11:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:11:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:11:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:11:29 INFO - Installing collected packages: psutil 22:11:29 INFO - Running setup.py install for psutil 22:11:29 INFO - building 'psutil._psutil_osx' extension 22:11:29 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 22:11:30 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 22:11:30 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 22:11:30 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 22:11:30 INFO - building 'psutil._psutil_posix' extension 22:11:30 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 22:11:30 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:11:30 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:11:30 INFO - ^ 22:11:30 INFO - 1 warning generated. 22:11:30 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 22:11:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:11:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:11:30 INFO - Successfully installed psutil 22:11:30 INFO - Cleaning up... 22:11:30 INFO - Return code: 0 22:11:30 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:11:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:11:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:11:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:11:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:11:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:30 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 0x10d385490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2fb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe51bc9d0a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d199d30>, '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 0x10cfffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d191b20>, '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.CP7klMr5wC/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.noO7KnsgoR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 22:11:30 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 22:11:30 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 22:11:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CP7klMr5wC/Render', 22:11:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:11:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:11:30 INFO - 'HOME': '/Users/cltbld', 22:11:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:11:30 INFO - 'LOGNAME': 'cltbld', 22:11:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:11:30 INFO - 'MOZ_NO_REMOTE': '1', 22:11:30 INFO - 'NO_EM_RESTART': '1', 22:11:30 INFO - 'PAGER': '/bin/cat', 22:11:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:11:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:11:30 INFO - 'PWD': '/builds/slave/test', 22:11:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:11:30 INFO - 'SHELL': '/bin/bash', 22:11:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.noO7KnsgoR/Listeners', 22:11:30 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 22:11:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:11:30 INFO - 'USER': 'cltbld', 22:11:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:11:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:11:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:11:30 INFO - 'XPC_FLAGS': '0x0', 22:11:30 INFO - 'XPC_SERVICE_NAME': '0', 22:11:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:11:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:11:30 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:11:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:11:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:11:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:11:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:11:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:11:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:11:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:11:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:11:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:11:33 INFO - Installing collected packages: mozsystemmonitor 22:11:33 INFO - Running setup.py install for mozsystemmonitor 22:11:33 INFO - Successfully installed mozsystemmonitor 22:11:33 INFO - Cleaning up... 22:11:33 INFO - Return code: 0 22:11:33 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:11:33 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')]} 22:11:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:11:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:33 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')]} 22:11:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:11:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:33 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 0x10d385490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2fb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe51bc9d0a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d199d30>, '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 0x10cfffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d191b20>, '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.CP7klMr5wC/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.noO7KnsgoR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 22:11:33 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 22:11:33 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 22:11:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CP7klMr5wC/Render', 22:11:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:11:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:11:33 INFO - 'HOME': '/Users/cltbld', 22:11:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:11:33 INFO - 'LOGNAME': 'cltbld', 22:11:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:11:33 INFO - 'MOZ_NO_REMOTE': '1', 22:11:33 INFO - 'NO_EM_RESTART': '1', 22:11:33 INFO - 'PAGER': '/bin/cat', 22:11:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:11:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:11:33 INFO - 'PWD': '/builds/slave/test', 22:11:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:11:33 INFO - 'SHELL': '/bin/bash', 22:11:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.noO7KnsgoR/Listeners', 22:11:33 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 22:11:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:11:33 INFO - 'USER': 'cltbld', 22:11:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:11:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:11:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:11:33 INFO - 'XPC_FLAGS': '0x0', 22:11:33 INFO - 'XPC_SERVICE_NAME': '0', 22:11:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:11:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:11:34 INFO - Downloading/unpacking jsonschema==2.5.1 22:11:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:11:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:11:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:11:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:11:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:11:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:11:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:11:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:11:36 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 22:11:36 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 22:11:36 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 22:11:36 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 22:11:36 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 22:11:36 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 22:11:37 INFO - Downloading functools32-3.2.3-2.tar.gz 22:11:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:11:37 INFO - warning: no files found matching '*.txt' 22:11:37 INFO - no previously-included directories found matching 'build' 22:11:37 INFO - no previously-included directories found matching 'dist' 22:11:37 INFO - no previously-included directories found matching '.git*' 22:11:37 INFO - Installing collected packages: jsonschema, functools32 22:11:37 INFO - Running setup.py install for functools32 22:11:37 INFO - warning: no files found matching '*.txt' 22:11:37 INFO - no previously-included directories found matching 'build' 22:11:37 INFO - no previously-included directories found matching 'dist' 22:11:37 INFO - no previously-included directories found matching '.git*' 22:11:37 INFO - Successfully installed jsonschema functools32 22:11:37 INFO - Cleaning up... 22:11:37 INFO - Return code: 0 22:11:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:11:37 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')]} 22:11:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:11:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:37 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')]} 22:11:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:11:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:37 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 0x10d385490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2fb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe51bc9d0a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d199d30>, '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 0x10cfffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d191b20>, '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.CP7klMr5wC/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.noO7KnsgoR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 22:11:37 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 22:11:37 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 22:11:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CP7klMr5wC/Render', 22:11:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:11:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:11:37 INFO - 'HOME': '/Users/cltbld', 22:11:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:11:37 INFO - 'LOGNAME': 'cltbld', 22:11:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:11:37 INFO - 'MOZ_NO_REMOTE': '1', 22:11:37 INFO - 'NO_EM_RESTART': '1', 22:11:37 INFO - 'PAGER': '/bin/cat', 22:11:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:11:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:11:37 INFO - 'PWD': '/builds/slave/test', 22:11:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:11:37 INFO - 'SHELL': '/bin/bash', 22:11:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.noO7KnsgoR/Listeners', 22:11:37 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 22:11:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:11:37 INFO - 'USER': 'cltbld', 22:11:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:11:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:11:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:11:37 INFO - 'XPC_FLAGS': '0x0', 22:11:37 INFO - 'XPC_SERVICE_NAME': '0', 22:11:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:11:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:11:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:11:37 INFO - Cleaning up... 22:11:37 INFO - Return code: 0 22:11:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:11:37 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')]} 22:11:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:11:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:37 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')]} 22:11:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:11:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:37 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 0x10d385490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2fb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe51bc9d0a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d199d30>, '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 0x10cfffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d191b20>, '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.CP7klMr5wC/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.noO7KnsgoR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 22:11:37 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 22:11:37 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 22:11:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CP7klMr5wC/Render', 22:11:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:11:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:11:37 INFO - 'HOME': '/Users/cltbld', 22:11:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:11:37 INFO - 'LOGNAME': 'cltbld', 22:11:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:11:37 INFO - 'MOZ_NO_REMOTE': '1', 22:11:37 INFO - 'NO_EM_RESTART': '1', 22:11:37 INFO - 'PAGER': '/bin/cat', 22:11:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:11:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:11:37 INFO - 'PWD': '/builds/slave/test', 22:11:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:11:37 INFO - 'SHELL': '/bin/bash', 22:11:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.noO7KnsgoR/Listeners', 22:11:37 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 22:11:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:11:37 INFO - 'USER': 'cltbld', 22:11:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:11:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:11:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:11:37 INFO - 'XPC_FLAGS': '0x0', 22:11:37 INFO - 'XPC_SERVICE_NAME': '0', 22:11:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:11:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:11:38 INFO - Downloading/unpacking blobuploader==1.2.4 22:11:38 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 22:11:38 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 22:11:38 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 22:11:38 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 22:11:38 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 22:11:38 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 22:11:40 INFO - Downloading blobuploader-1.2.4.tar.gz 22:11:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:11:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:11:40 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 22:11:40 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 22:11:40 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 22:11:40 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 22:11:40 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 22:11:40 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 22:11:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:11:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:11:41 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 22:11:41 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 22:11:41 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 22:11:41 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 22:11:41 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 22:11:41 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 22:11:41 INFO - Downloading docopt-0.6.1.tar.gz 22:11:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:11:41 INFO - Installing collected packages: blobuploader, requests, docopt 22:11:41 INFO - Running setup.py install for blobuploader 22:11:42 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:11:42 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:11:42 INFO - Running setup.py install for requests 22:11:42 INFO - Running setup.py install for docopt 22:11:42 INFO - Successfully installed blobuploader requests docopt 22:11:42 INFO - Cleaning up... 22:11:42 INFO - Return code: 0 22:11:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:11:42 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')]} 22:11:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:11:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:42 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')]} 22:11:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:11:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:42 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 0x10d385490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2fb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe51bc9d0a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d199d30>, '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 0x10cfffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d191b20>, '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.CP7klMr5wC/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.noO7KnsgoR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 22:11:42 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 22:11:42 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 22:11:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CP7klMr5wC/Render', 22:11:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:11:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:11:42 INFO - 'HOME': '/Users/cltbld', 22:11:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:11:42 INFO - 'LOGNAME': 'cltbld', 22:11:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:11:42 INFO - 'MOZ_NO_REMOTE': '1', 22:11:42 INFO - 'NO_EM_RESTART': '1', 22:11:42 INFO - 'PAGER': '/bin/cat', 22:11:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:11:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:11:42 INFO - 'PWD': '/builds/slave/test', 22:11:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:11:42 INFO - 'SHELL': '/bin/bash', 22:11:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.noO7KnsgoR/Listeners', 22:11:42 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 22:11:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:11:42 INFO - 'USER': 'cltbld', 22:11:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:11:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:11:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:11:42 INFO - 'XPC_FLAGS': '0x0', 22:11:42 INFO - 'XPC_SERVICE_NAME': '0', 22:11:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:11:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:11:43 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-kcub8W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:11:43 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-Ie1gkl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:11:43 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-0P5ecS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:11:43 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-W8g0L5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:11:43 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-qXeGlx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:11:43 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-4gsHsG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:11:43 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-7eb26J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:11:43 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-3Gm68Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:11:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:11:43 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-8Kc_e0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:11:44 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-_f_SPf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:11:44 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-B5rA3e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:11:44 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-SKA3XI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:11:44 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-siIHqN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:11:44 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-yeBaq6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:11:44 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-5X7aCr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:11:44 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-9doGqU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:11:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:11:44 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-XqrIPw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:11:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:11:44 INFO - Running setup.py install for manifestparser 22:11:45 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:11:45 INFO - Running setup.py install for mozcrash 22:11:45 INFO - Running setup.py install for mozdebug 22:11:45 INFO - Running setup.py install for mozdevice 22:11:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:11:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:11:45 INFO - Running setup.py install for mozfile 22:11:45 INFO - Running setup.py install for mozhttpd 22:11:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:11:45 INFO - Running setup.py install for mozinfo 22:11:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:11:45 INFO - Running setup.py install for mozInstall 22:11:46 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:11:46 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:11:46 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:11:46 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:11:46 INFO - Running setup.py install for mozleak 22:11:46 INFO - Running setup.py install for mozlog 22:11:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:11:46 INFO - Running setup.py install for moznetwork 22:11:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:11:46 INFO - Running setup.py install for mozprocess 22:11:46 INFO - Running setup.py install for mozprofile 22:11:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:11:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:11:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:11:46 INFO - Running setup.py install for mozrunner 22:11:47 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:11:47 INFO - Running setup.py install for mozscreenshot 22:11:47 INFO - Running setup.py install for moztest 22:11:47 INFO - Running setup.py install for mozversion 22:11:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:11:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:11:47 INFO - Cleaning up... 22:11:47 INFO - Return code: 0 22:11:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:11:47 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')]} 22:11:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:11:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:47 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')]} 22:11:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:11:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:47 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 0x10d385490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2fb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe51bc9d0a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d199d30>, '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 0x10cfffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d191b20>, '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.CP7klMr5wC/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.noO7KnsgoR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 22:11:47 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 22:11:47 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 22:11:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CP7klMr5wC/Render', 22:11:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:11:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:11:47 INFO - 'HOME': '/Users/cltbld', 22:11:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:11:47 INFO - 'LOGNAME': 'cltbld', 22:11:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:11:47 INFO - 'MOZ_NO_REMOTE': '1', 22:11:47 INFO - 'NO_EM_RESTART': '1', 22:11:47 INFO - 'PAGER': '/bin/cat', 22:11:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:11:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:11:47 INFO - 'PWD': '/builds/slave/test', 22:11:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:11:47 INFO - 'SHELL': '/bin/bash', 22:11:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.noO7KnsgoR/Listeners', 22:11:47 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 22:11:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:11:47 INFO - 'USER': 'cltbld', 22:11:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:11:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:11:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:11:47 INFO - 'XPC_FLAGS': '0x0', 22:11:47 INFO - 'XPC_SERVICE_NAME': '0', 22:11:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:11:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:11:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:11:47 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-efY4FH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:11:47 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)) 22:11:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:11:47 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-PJoGPH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:11:48 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)) 22:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:11:48 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-IODp6k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:11:48 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)) 22:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:11:48 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-eiqZ4r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:11:48 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)) 22:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:11:48 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-PfWqj0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:11:48 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)) 22:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:11:48 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-e1rtgO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:11:48 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)) 22:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:11:48 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-yTE8Pg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:11:48 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)) 22:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:11:48 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-fBitgY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:11:48 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)) 22:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:11:48 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-wJuuTD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:11:48 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)) 22:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:11:48 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-7Bf7Ex-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:11:48 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)) 22:11:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:11:48 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-dGZxT2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:11:49 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)) 22:11:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:11:49 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-pBdIm2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:11:49 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)) 22:11:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:11:49 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-p9F0H2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:11:49 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)) 22:11:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:11:49 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-CfOPMT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:11:49 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)) 22:11:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:11:49 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-dErl4z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:11:49 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)) 22:11:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:11:49 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-ZDGwy7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:11:49 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)) 22:11:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:11:49 INFO - Running setup.py (path:/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/pip-8V8JMg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:11:49 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)) 22:11:49 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)) 22:11:49 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)) 22:11:49 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)) 22:11:49 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)) 22:11:49 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)) 22:11:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:11:49 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 22:11:49 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 22:11:49 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 22:11:49 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 22:11:49 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 22:11:49 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 22:11:52 INFO - Downloading blessings-1.6.tar.gz 22:11:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:11:52 INFO - Installing collected packages: blessings 22:11:52 INFO - Running setup.py install for blessings 22:11:52 INFO - Successfully installed blessings 22:11:52 INFO - Cleaning up... 22:11:52 INFO - Return code: 0 22:11:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:11:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:11:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:11:52 INFO - Reading from file tmpfile_stdout 22:11:52 INFO - Current package versions: 22:11:52 INFO - blessings == 1.6 22:11:52 INFO - blobuploader == 1.2.4 22:11:52 INFO - docopt == 0.6.1 22:11:52 INFO - functools32 == 3.2.3-2 22:11:52 INFO - jsonschema == 2.5.1 22:11:52 INFO - manifestparser == 1.1 22:11:52 INFO - mozInstall == 1.12 22:11:52 INFO - mozcrash == 1.0 22:11:52 INFO - mozdebug == 0.1 22:11:52 INFO - mozdevice == 0.48 22:11:52 INFO - mozfile == 1.2 22:11:52 INFO - mozhttpd == 0.7 22:11:52 INFO - mozinfo == 0.9 22:11:52 INFO - mozleak == 0.1 22:11:52 INFO - mozlog == 3.4 22:11:52 INFO - moznetwork == 0.27 22:11:52 INFO - mozprocess == 0.23 22:11:52 INFO - mozprofile == 0.28 22:11:52 INFO - mozrunner == 6.13 22:11:52 INFO - mozscreenshot == 0.1 22:11:52 INFO - mozsystemmonitor == 0.3 22:11:52 INFO - moztest == 0.8 22:11:52 INFO - mozversion == 1.4 22:11:52 INFO - psutil == 3.1.1 22:11:52 INFO - requests == 1.2.3 22:11:52 INFO - wsgiref == 0.1.2 22:11:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:11:52 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')]} 22:11:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:11:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:52 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')]} 22:11:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:11:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:52 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 0x10d385490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2fb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe51bc9d0a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d199d30>, '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 0x10cfffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d191b20>, '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.CP7klMr5wC/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.noO7KnsgoR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 22:11:52 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 22:11:52 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 22:11:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CP7klMr5wC/Render', 22:11:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:11:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:11:52 INFO - 'HOME': '/Users/cltbld', 22:11:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:11:52 INFO - 'LOGNAME': 'cltbld', 22:11:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:11:52 INFO - 'MOZ_NO_REMOTE': '1', 22:11:52 INFO - 'NO_EM_RESTART': '1', 22:11:52 INFO - 'PAGER': '/bin/cat', 22:11:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:11:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:11:52 INFO - 'PWD': '/builds/slave/test', 22:11:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:11:52 INFO - 'SHELL': '/bin/bash', 22:11:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.noO7KnsgoR/Listeners', 22:11:52 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 22:11:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:11:52 INFO - 'USER': 'cltbld', 22:11:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:11:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:11:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:11:52 INFO - 'XPC_FLAGS': '0x0', 22:11:52 INFO - 'XPC_SERVICE_NAME': '0', 22:11:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:11:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:11:53 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)) 22:11:53 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)) 22:11:53 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)) 22:11:53 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)) 22:11:53 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)) 22:11:53 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)) 22:11:53 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)) 22:11:53 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)) 22:11:53 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)) 22:11:53 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)) 22:11:53 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)) 22:11:53 INFO - Cleaning up... 22:11:53 INFO - Return code: 0 22:11:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:11:53 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')]} 22:11:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:11:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:53 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')]} 22:11:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:11:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:53 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 0x10d385490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2fb570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe51bc9d0a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d199d30>, '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 0x10cfffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d191b20>, '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.CP7klMr5wC/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.noO7KnsgoR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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 22:11:53 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 22:11:53 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 22:11:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CP7klMr5wC/Render', 22:11:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:11:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:11:53 INFO - 'HOME': '/Users/cltbld', 22:11:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:11:53 INFO - 'LOGNAME': 'cltbld', 22:11:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:11:53 INFO - 'MOZ_NO_REMOTE': '1', 22:11:53 INFO - 'NO_EM_RESTART': '1', 22:11:53 INFO - 'PAGER': '/bin/cat', 22:11:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:11:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:11:53 INFO - 'PWD': '/builds/slave/test', 22:11:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:11:53 INFO - 'SHELL': '/bin/bash', 22:11:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.noO7KnsgoR/Listeners', 22:11:53 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 22:11:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:11:53 INFO - 'USER': 'cltbld', 22:11:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:11:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:11:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:11:53 INFO - 'XPC_FLAGS': '0x0', 22:11:53 INFO - 'XPC_SERVICE_NAME': '0', 22:11:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:11:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:11:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:11:53 INFO - Cleaning up... 22:11:53 INFO - Return code: 0 22:11:53 INFO - Running post-action listener: _resource_record_post_action 22:11:53 INFO - Running post-action listener: _start_resource_monitoring 22:11:53 INFO - Starting resource monitoring. 22:11:53 INFO - [mozharness: 2017-01-24 06:11:53.531125Z] Finished create-virtualenv step (success) 22:11:53 INFO - [mozharness: 2017-01-24 06:11:53.532202Z] Running install step. 22:11:53 INFO - Running pre-action listener: _resource_record_pre_action 22:11:53 INFO - Running main action method: install 22:11:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:11:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:11:53 INFO - Reading from file tmpfile_stdout 22:11:53 INFO - Detecting whether we're running mozinstall >=1.0... 22:11:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:11:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:11:53 INFO - Reading from file tmpfile_stdout 22:11:53 INFO - Output received: 22:11:53 INFO - Usage: mozinstall [options] installer 22:11:53 INFO - Options: 22:11:53 INFO - -h, --help show this help message and exit 22:11:53 INFO - -d DEST, --destination=DEST 22:11:53 INFO - Directory to install application into. [default: 22:11:53 INFO - "/builds/slave/test"] 22:11:53 INFO - --app=APP Application being installed. [default: firefox] 22:11:53 INFO - mkdir: /builds/slave/test/build/application 22:11:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 22:11:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0.en-US.mac.dmg --destination /builds/slave/test/build/application 22:12:15 INFO - Reading from file tmpfile_stdout 22:12:15 INFO - Output received: 22:12:15 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 22:12:15 INFO - Running post-action listener: _resource_record_post_action 22:12:15 INFO - [mozharness: 2017-01-24 06:12:15.338215Z] Finished install step (success) 22:12:15 INFO - [mozharness: 2017-01-24 06:12:15.338357Z] Running run-tests step. 22:12:15 INFO - Running pre-action listener: _resource_record_pre_action 22:12:15 INFO - Running main action method: run_tests 22:12:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:12:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:12:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:12:15 INFO - 2017-01-23 22:12:15.352 screenresolution[1893:14224] starting screenresolution argv=screenresolution get 22:12:15 INFO - 2017-01-23 22:12:15.355 screenresolution[1893:14224] Display 0: 1600x1200x32@60 22:12:15 INFO - 2017-01-23 22:12:15.361 screenresolution[1894:14227] starting screenresolution argv=screenresolution list 22:12:15 INFO - Available Modes on Display 0 22:12:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:12:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:12:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:12:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:12:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:12:15 INFO - Intel Iris: 22:12:15 INFO - Chipset Model: Intel Iris 22:12:15 INFO - Type: GPU 22:12:15 INFO - Bus: Built-In 22:12:15 INFO - VRAM (Dynamic, Max): 1536 MB 22:12:15 INFO - Vendor: Intel (0x8086) 22:12:15 INFO - Device ID: 0x0a2e 22:12:15 INFO - Revision ID: 0x0009 22:12:15 INFO - Displays: 22:12:15 INFO - Display: 22:12:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:12:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:12:15 INFO - Display Serial Number: 1600x1200 60 22:12:15 INFO - Main Display: Yes 22:12:15 INFO - Mirror: Off 22:12:15 INFO - Online: Yes 22:12:15 INFO - Rotation: Supported 22:12:15 INFO - Return code: 0 22:12:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:12:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:12:15 INFO - Python 2.7.3 22:12:15 INFO - Return code: 0 22:12:15 INFO - grabbing minidump binary from tooltool 22:12:15 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')]} 22:12:15 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 0x10d199d30>, '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 0x10cfffdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d191b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:12:15 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 22:12:15 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 22:12:15 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 22:12:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:12:15 INFO - Return code: 0 22:12:15 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:12:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:12:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:12:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:12:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:12:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CDmdux7rT528pwzaswH3Pw/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0342', '--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 22:12:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/CDmdux7rT528pwzaswH3Pw/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0342 --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 22:12:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CP7klMr5wC/Render', 22:12:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:12:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:12:15 INFO - 'HOME': '/Users/cltbld', 22:12:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:12:15 INFO - 'LOGNAME': 'cltbld', 22:12:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:12:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:12:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:12:15 INFO - 'MOZ_NO_REMOTE': '1', 22:12:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:12:15 INFO - 'NO_EM_RESTART': '1', 22:12:15 INFO - 'PAGER': '/bin/cat', 22:12:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:12:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:12:15 INFO - 'PWD': '/builds/slave/test', 22:12:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:12:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:12:15 INFO - 'SHELL': '/bin/bash', 22:12:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.noO7KnsgoR/Listeners', 22:12:15 INFO - 'TMPDIR': '/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/', 22:12:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:12:15 INFO - 'USER': 'cltbld', 22:12:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:12:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:12:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:12:15 INFO - 'XPC_FLAGS': '0x0', 22:12:15 INFO - 'XPC_SERVICE_NAME': '0', 22:12:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:12:15 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/CDmdux7rT528pwzaswH3Pw/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0342', '--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 22:12:15 INFO - mozversion application_buildid: 20170123195043 22:12:15 INFO - mozversion application_changeset: 7e83a181036b71708716f64d644f10b38a479e69 22:12:15 INFO - mozversion application_display_name: Firefox 22:12:15 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:12:15 INFO - mozversion application_name: Firefox 22:12:15 INFO - mozversion application_remotingname: firefox 22:12:15 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 22:12:15 INFO - mozversion application_vendor: Mozilla 22:12:15 INFO - mozversion application_version: 52.0 22:12:15 INFO - mozversion platform_buildid: 20170123195043 22:12:15 INFO - mozversion platform_changeset: 7e83a181036b71708716f64d644f10b38a479e69 22:12:15 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 22:12:15 INFO - mozversion platform_version: 52.0 22:12:15 INFO - using testdate: 1485238335 22:12:15 INFO - actual date: 1485238335 22:12:15 INFO - starting webserver on 'localhost:49369' 22:12:15 INFO - SUITE-START | Running 2 tests 22:12:15 INFO - TEST-START | dromaeo_css 22:12:15 INFO - operating with platform_type : mac_ 22:12:15 INFO - Initialising browser for dromaeo_css test... 22:12:15 INFO - TEST-INFO | started process 1904 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/tmpW7U0WL/profile http://localhost:49369/getInfo.html) 22:12:18 INFO - PROCESS | 1904 | 2017-01-23 22:12:18.626 plugin-container[1907:14404] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4d47, name = 'com.apple.tsm.portname' 22:12:18 INFO - PROCESS | 1904 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:12:18 INFO - PROCESS | 1904 | 2017-01-23 22:12:18.627 plugin-container[1907:14404] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x921b, name = 'com.apple.CFPasteboardClient' 22:12:18 INFO - PROCESS | 1904 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:12:18 INFO - PROCESS | 1904 | 2017-01-23 22:12:18.627 plugin-container[1907:14404] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:12:18 INFO - PROCESS | 1904 | __metrics Screen width/height:1600/1200 22:12:18 INFO - PROCESS | 1904 | colorDepth:24 22:12:18 INFO - PROCESS | 1904 | Browser inner width/height: 1024/689 22:12:18 INFO - PROCESS | 1904 | __metrics 22:12:18 ERROR - PROCESS | 1904 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3181: TypeError: this.tabs is undefined 22:12:19 INFO - TEST-INFO | 1904: exit 0 22:12:19 INFO - Browser initialized. 22:12:19 INFO - Running cycle 1/1 for dromaeo_css test... 22:12:19 INFO - TEST-INFO | started process 1908 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/tmpW7U0WL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:12:22 INFO - PROCESS | 1908 | 2017-01-23 22:12:22.075 plugin-container[1909:14519] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4643, name = 'com.apple.tsm.portname' 22:12:22 INFO - PROCESS | 1908 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:12:22 INFO - PROCESS | 1908 | 2017-01-23 22:12:22.076 plugin-container[1909:14519] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8727, name = 'com.apple.CFPasteboardClient' 22:12:22 INFO - PROCESS | 1908 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:12:22 INFO - PROCESS | 1908 | 2017-01-23 22:12:22.076 plugin-container[1909:14519] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:12:31 INFO - PROCESS | 1908 | RSS: Main: 178348032 22:12:31 INFO - PROCESS | 1908 | 22:15:34 INFO - PROCESS | 1908 | Cycle 1(1): loaded http://localhost:49369/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49369/tests/dromaeo/cssquery-ext.html) 22:15:36 INFO - PROCESS | 1908 | RSS: Main: 182026240 22:15:36 INFO - PROCESS | 1908 | 22:18:38 INFO - PROCESS | 1908 | Cycle 1(1): loaded http://localhost:49369/tests/dromaeo/cssquery-ext.html (next: http://localhost:49369/tests/dromaeo/cssquery-jquery.html) 22:18:38 INFO - PROCESS | 1908 | RSS: Main: 194109440 22:18:38 INFO - PROCESS | 1908 | 22:21:40 INFO - PROCESS | 1908 | Cycle 1(1): loaded http://localhost:49369/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49369/tests/dromaeo/cssquery-mootools.html) 22:21:41 INFO - PROCESS | 1908 | RSS: Main: 195739648 22:21:41 INFO - PROCESS | 1908 | 22:24:43 INFO - PROCESS | 1908 | Cycle 1(1): loaded http://localhost:49369/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49369/tests/dromaeo/cssquery-prototype.html) 22:24:44 INFO - PROCESS | 1908 | RSS: Main: 199401472 22:24:44 INFO - PROCESS | 1908 | 22:27:46 INFO - PROCESS | 1908 | Cycle 1(1): loaded http://localhost:49369/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49369/tests/dromaeo/cssquery-yui.html) 22:27:47 INFO - PROCESS | 1908 | RSS: Main: 201678848 22:27:47 INFO - PROCESS | 1908 | 22:30:49 INFO - PROCESS | 1908 | Cycle 1(1): loaded http://localhost:49369/tests/dromaeo/cssquery-yui.html (next: http://localhost:49369/tests/dromaeo/cssquery-dojo.html) 22:30:49 INFO - PROCESS | 1908 | RSS: Main: 202772480 22:30:49 INFO - PROCESS | 1908 | 22:30:49 INFO - PROCESS | 1908 | __start_tp_report 22:30:49 INFO - PROCESS | 1908 | _x_x_mozilla_page_load 22:30:49 INFO - PROCESS | 1908 | _x_x_mozilla_page_load_details 22:30:49 INFO - PROCESS | 1908 | |i|pagename|runs| 22:30:49 INFO - PROCESS | 1908 | |0;dojo.html;3013.4123845850054;3597.640235976401;3608.621094785047;3614.2410093880285;3662.7252956028287;5967.641941483508;6058.787942422011;6073.908891366633;6080.331163572009;6099.176611157503;6067.24159480065;6071.150038994544;6122.9387706122925;6132.969335153318;6150.815475535741;4650.581447669709;5409.972950135254;5424.728763561826;5457;5491.28613280273;5489.533389661863;5501.697406642633;5514.779408823662;5518.944810551903;5559.722013899309;5335.33308336458;5348.197770334462;5484.396716361197;5500.037493438652;5515.558755299587;5416.052190866602;5416.1063424534905;5422.43064478228;5430.782768689248;5455.672659640434;5250.3962044364625;5370.43610420904;5395.622306438551;5423.050966080959;5430.5384042356445;6074.362191969868;6161.476274516672;6185.134081228586;6213.068039794022;6233.688315584202;6231.9688401557705;6366.681665916732;6384.68076596173;6395.42441180296;6406.455451286646;6259.405356491126;6326.08271800591;6463.967680161569;6499.545031847772;6500.219973603151;6118.010103775202;6122.663253521099;6129.356417576179;6148.341572317011;6158.322584515699;6556.311587283362;6595.70319335631;6620.635865027373;6629.138212032405;6653.168353955755;6277.1525844011385;6351.650659213695;6353.30113687494;6361.268454127742;6379.521535884827;6306.810795676137;6319.146915166394;6336.873262534724;6392.904106438407;6435.2599451063725;6373.298937116913;6380.234371875405;6389.169407977026;6395.20059992501;6396.0725694774;6256.092866534327;6336.873262534724;6375.6174629522375;6386;6441.613503189824;5111.846644600668;5154.587632989352;5165.199394093921;5167.767450464738;5174.301469301596;3566.268914872445;3922.058705910561;4601.3558101865765;4641.8375356863025;4661.95338046622;6135.417135372132;6218.533609979269;6248.062790581449;6266.874662506724;6267.905981410283;6250.843728906722;6310.116583678288;6328.303886572566;6380.330065343072;6410.391012853772;6187.752489900354;6196.721147548371;6204.441600255998;6214.40963108504;6245.718942647592;4972;5558.471945165203;5575.69333686643;5632.211490391349;5713.342965559014;6469.902951455638;6527.118838956775;6565.179352580832;6584.407403333723;6646.8005959821285;5569.860753481195;6455.483561315177;6474.967625161844;6481.0926470295135;6509.218893732783;6455.354464553507;6637.236717777518;6638.83402914931;6676.332366763285;6678.799636010927;6306.432421082125;6327.90508142369;6328.462080723006;6351.96824015877;6433.935660643334;5620.381758006534;6301.676647903808;6475.611463312216;6484.28672845996;6495.9025614614875;6275.497960163085;6317.30509643948;6321.683915804283;6337.873242535031;6359.04614307839;5280.7359632019015;6181.1655426516845;6233.376662333731;6256.906146407717;6301.212348456443;5790.812883358817;5918.875413671389;5982.940170598413;6140.8771824564155;6352.301246862749;6266.310705822265;6269.655168965751;6291.748330066746;6296.653684047421;6303.905441418291;6113.932424433696;6131.9272270139245;6178.258608966773;6213.596116252429;6235.282942461675 22:30:49 INFO - PROCESS | 1908 | |1;ext.html;465.8020341354924;500.0499051801577;500.6420409407273;536.5841472858534;564.6837770848327;39782.403263951026;41421.79289103554;41607.37588936165;43422;43450.782746086305;42824.78587607065;43038.784806076;43258.78370608143;43349.34975975355;43399.915002124966;9724.367916085466;9888.40669559825;9890.802183956317;9898.455584942825;9925.503724813767;9724;9771.853422198672;9782.41305521671;9800.55897484615;9802.215822734164;9800.656977005807;9811.411315321067;9811.460369679664;9831.311808173396;9851.310408271424;9750.268729845255;9750.90249097507;9783.119519243266;9828.508574571315;9841.507924603813;8265.793355166108;9774.853377199348;9801.411915285105;9822.410655360629;9846.852297215548;40695.59304406948;40696.18607627831;40963;41095.97260068492;41171.588284117075;3261.657525959764;3393.3552625001416;3395.066356751888;3810.657040866335;4140.710150289451;30402.69597304021;31042.379152417052;31094.911678091157;31626;31676.841615792004;2967.925801854971;3253.6583658715967;3303.487959366302;3352.346292472969;3411.215420453285;473565.6321718404;520338;520829;522750;528226;64185.67907160434;64796.67601661962;64833;65158.67420662866;65265.02102468467;64490.677546611965;66276;67156.6642166786;67227.32772672309;67368.66315668488;16413.261403236644;16605.00369977805;16977.745333820003;17242.827571724374;17316.30734770595;13073.607791766262;13128.737425251442;13151.473941042443;13166.341682915816;13200.471981120647;17877.910610446863;19413.51466213328;19457.51356216106;19506.414807555797;19526.12132454043;3325.334933013407;7129.215786263507;8143.918560814434;8336.87367237286;8427.367947403969;880.4893161966045;962.5476026267643;984.7587341101332;1080.8378743188428;1098.19282827122;23018;30275;30362;30901.38197236043;31042.827002110087;1079.4494807648157;1117.1286396610656;1122.6968718446137;1138.9772204556027;1163.5229555882047;15596.220188990505;16082.597935051483;16130.677386452206;16258.18709064542;16330.265138068815;674890;683106;683475;684056.5797170983;685662;40009.999750006486;48436.515634843905;48649.75675121602;49029.509704903205;49294;1402.7404930087966;1424.4302279088447;1433.3802802832731;1440.7694768836936;1446.7902154187584;30236.84881575578;30753.31270649738;30848.074557763302;31725.5241171378;31905.361892762478;50762.49237507725;50780;51229.7438512805;51361;51423.742881285354;527742;618963;619894;621282.8935855292;623413;26462.470750585264;27304.317392065357;27338.589921151597;27349.863250684415;27359;45446.77276613596;45750;47219.81950451266;47938.04123917572;49720.751396242784;34082;39069.41395879121;39428.80285598554;40012.399814003395;41740;38912.22175556416;39361.606383935796;39878.2024359517;40430.595694042684;40679;34649.82675086609;36789;39165;39446.80276598599;39485;27055.86472067627;31821.5226771594;35309.646903530636;36194.593520174494;36384.63615363812;23208.535829283657;26500.867495662398;26643.733562664125;26644.200673979038;26686 22:30:49 INFO - PROCESS | 1908 | |2;jquery.html;378.21331630209187;385.70300868331384;389.242922515707;399.1518024198578;408.9143324473522;19452.70820937685;20589.308474745692;21067.68398474024;21160.576788464223;21198.152073917026;19797.901010494963;20344.59310813783;20435.591288174226;20556.383308500768;20842.68735968962;5993;6036.758529658809;6177.876442471148;6228.532860035492;6253.562250642457;4378.1681480518755;4643.349931009661;6174.8147555573405;6180.381961803829;6261.186045814061;6500.41496265336;6775.0514927910135;6815.7955261341995;6883.759068432606;6886.827829304282;6607.471402287805;6837.076994605714;6875.99999999995;6901.137357830271;6931.376176144146;5941.881162376728;5944.078667806497;5960.956832554329;5971.940280597225;6033.698315084228;25063.62404563933;25343.873280633477;25353.87323063373;25407.364815879566;26264.73735262642;2298.321995011476;2316.4324740438533;2319.1071437496516;2454.656348111248;3235.1750303672648;15136.54590362291;15417.15205663688;15542.922285388613;16325.346986120541;16479.91760041204;1922.884626922389;1934.2456441987636;2012.7886571909885;2025.5037515808574;2045.243259993797;118444;119112;122345;122814.54370912621;127384.36307818402;10825.188110891715;10981.61564345244;11410.201285909907;11453.48459319316;11542.422878856192;14015.299235038206;14084.366203520662;14102.15387076758;14118.152910825178;14123;6873.76272270986;8756.299496040301;8768.210861022539;8854.822903542023;8924.643014279485;8822.2060014599;8873.511956842307;8875.934887813504;8922.241609463268;9038.231750301167;6706.798796036127;6782.626955517494;6826.590404575742;6830.863382732317;6836.213835408992;1630.8695304375622;1717.6736420080395;1906.790253072161;1922.7500424944667;1950.6878899376031;871.4771137317558;878.6485405837716;920.6501529418778;1010.5907107621425;1041;21219.68170477444;22061.889690551765;22432.88783556072;23261.88369058144;23367.766322336898;936.4708939449189;951.528993148396;955.3169483819103;973.6494861849728;1029.45953374479;10676.039156475954;11294.870512948639;11300.660980170607;11301.773964520664;11316.038136758303;587163.1283687109;613815;630987.8450607718;643899;647858;135908.32045839707;137307;139415;139496;139986.30006850103;1021.3208216536069;1027.8715160604925;1034.6637342863598;1049.2130901823632;1053.7102296868422;18034.458966231035;18851.622967540297;19050.809491904904;19078.523036924187;19151.904240478707;55358.44641553533;55481.445185549244;56491;56793.43206568047;60767;52899;78004;87881.56059219918;91034.08965910522;93047;12373.13388062827;12777.616671499869;12954.870451295366;12995.594713656434;13069.954403647494;11651.650450486499;13714.65713357174;13952.790708139182;14005.789913151515;14030.438782448588;11665;11906.464209110783;11925.642230733092;11980.981616562689;12068;8107.513549187068;8112.513249205066;11846.111541634584;11854.051675866083;11937.761244775229;8344.582770861554;8421.115782842713;8490.15098490146;8618.482891026559;8651.82696346057;12495.312757798409;12945;13010.544630937871;13019.804702929274;13046.021548384098;12077;12095.39523023863;12222.327771972627;12248.632541023784;12311.507539698312 22:30:49 INFO - PROCESS | 1908 | |3;mootools.html;1472.3153733513918;1513.5081098642943;1675.1373042882917;1779.2082523277147;1832.3678330975808;7649.770506884803;7880.960595197031;8101.513909165455;8463.915360846397;8606.268467180282;7970.36237101032;8302.08677045526;8315.376346773986;8316.29311508521;8316.542590157538;2331.463763334434;2385.4394217358995;2386.570417324881;2388.414838364603;2390.8445951013214;2391.198948352304;2415.04605680756;2432.5134973005383;2439.3657649011207;2445.8287919845616;2373.774491423312;2385.8926348314367;2392.473655795722;2401.735809061002;2402.8558286502866;2367.266147494274;2375.2043065573052;2394;2401.2436082633913;2401.3036219496325;1866.514706176404;2399.748026457214;2422.454947636785;2428.0287884846025;2434.7565243475688;8278.71024514139;8314.667413303461;8339.916600833974;8340.541270230135;8430.747077587683;4581.3128030795315;5149.124648809711;5285.4450282720145;5289.973550132225;5302.3637163540625;3501;3508.1755787389943;3528.8235588220487;3590.0127464947086;3621.040424287566;1073.8711354637494;1074.3929679730927;1099.8570185875785;1107.634480621393;1116.1238427834112;10705.785884282348;10821.242513024014;10872.782544349147;10930.56277748889;10974.396408197546;4463.910721785611;4661.650376221797;4730.526947305242;4739.7630118493935;4742.857714268577;8965.013848476663;9566.13904748576;9584.28117891161;9612.951935240279;9620.470874101851;5246.396664383645;5262.526372626482;5271.947280527223;5284.497972692588;5288.603354748332;4343.522212556616;4358.6295164911335;4748.738819364969;4766.165920963817;4836.202026665631;3368.646292139339;3904.570497245301;3938.940915886264;3993.8602148924647;4041.2321658884716;1102.5479553383075;1276.6872116331558;1303.3743802974625;1306.5231190615357;1319.3403298350825;789.2344425906862;815.6411179081185;871.2072014466805;877.8990416102102;957.1624828275259;5623.2408624835125;5683.8010669626365;5881.617694849821;5946.524278057745;6031.396860314021;934.7195841247734;941.2375975459762;952.1858810716737;954.1269738189479;987.957704621631;3857.1128640412862;3964.60353964607;3986.740861843971;3988.980055099706;4022.8390864365097;9877.901220987698;9989.750256243507;10179.134773544332;10222.591096356211;10265.538050787733;10110.848337274947;10150.898491014996;10173.694789156338;10187.847182292271;10217.18262538995;7510.986016887759;7745.883811742829;7953.562554059582;7961.920380796234;7963.124056353912;5559.416261292506;5627.52166065889;5644.407337229532;5764.423557644202;5768.884622307614;9253.213476854542;9258.074192580689;9284.860727088964;9323.440593564408;9422.104900034486;8750.343724220582;8756.649734010824;9586.904130958601;9615.51922403891;9647.662331818352;6388.68056597159;6564.80305590833;6708.42978346846;6786.69459874297;6949.965250173716;7856.607169641609;8017.318527925192;8109.553974531457;8117.8376432472205;8118.715844945398;7139.178994415709;7288.599127047851;7301.890471642823;7301.890471643036;7317.926820731725;7042.542234754725;7141.607211603204;7177.282271772781;7182.281771822776;7301.306375894282;6662;6780.1524809398825;6803.965980170067;6834.316568343126;6840.179178498612;4911.312416261798;4926.458089610212;4948.950510494849;5009.298698182184;5059.785651443701;4959.851204463872;5003.849884503471;5020.548150666457;5068.29043933857;5093.92359114621 22:30:49 INFO - PROCESS | 1908 | |4;prototype.html;289.6668830844529;311.58870291215607;318.06013230902676;323.2742493102984;324.4111936834645;4570.840020599279;5392.43379445158;5544.1960915667205;5588.497035266825;5668.178114173443;4815.951840481594;5344.984452953936;5453.918191227125;5532.944670553294;5559.305086864142;3719.367707489733;4247.681423893205;4249.999999999993;4295.312749960007;4308.375285583588;3557.4130268505664;3947.269755095302;4182.644475219595;4236.618704316611;4238.4913810342805;3151.558781770554;3732.8693495727657;3816.656500914917;4170.228507726065;4177.908347822619;3218.5494030835703;4138.606832350937;4164.479440069991;4178.435911151986;4205.621494065518;2845.445138198052;3187.4581321175456;3610.62088480711;3640.8361623726996;3644;4367.624198377533;5345.919811202835;5398.676079435208;5422.810201642963;5445.646033007842;4804.519548045202;4805.255185446262;4829;4842.685225460334;4865.577437782129;5816.302043754734;5839.562032847553;5842.678652674102;5844.766209351621;5856.32652244993;3639.090227443139;3681.1717363593243;3819.350710379242;3980.3034468967817;3990.5235063026576;5559.41487208446;6119.44924956756;6158.384161583851;6170.784022559189;6184.84537886552;3722.62773722631;4029.2143032109043;4036.2129384769687;4041.5150181977765;4044.939325910114;3693.538307711536;3818.4654148419245;4075.8369665213654;4091.40674602187;4095.3037983542868;4148.730332528376;4374.58441448062;4789.3055506952;4798.520147985174;4815.590674792682;4650.395613513332;5623.493885550321;5864.38423965486;5900.905178661514;5991.940080599225;1100.2188446203124;1103.5034234594305;1176.452949378526;1258.5406326690875;1309.768817311724;765.8391737735027;866.9293422623056;877.9596178528465;895.4850960697624;900.6127365233012;4768.284757286366;5232.2413250078525;5256.342957130359;5260.868478288074;5263.973680131651;5055.848324550269;5100.897982040413;5636.082922368996;5674.687892165888;5705.515031222392;4445.377647129404;4448.955510444855;4705.882352941204;5251.36983561975;5254.448282930313;2890.2629829393372;3021.4863473209607;3035.4536183487185;3055.731871273441;3058.2813038935833;5382.6585435335755;5989.610675306091;6030.366811484819;6036.426539478743;6055.666938318347;5184.8185313514605;5237.16205407133;5518.91721624176;5594.328680558359;5838.649681019152;5333.013392522395;5468.0977638689965;5551.861203469945;5835.270591176103;5968.791092311748;5187.481251874782;5219.5041471060185;5509.779608815603;5786.594938354275;5820.82537523875;5049.924251136163;5208.869778255574;5633.718314084362;5725.971370143123;5731.312242530923;5169.7673604687125;5720.942790572208;5841.750925893477;5894.936772129892;5979.940200597938;3649.671529562352;3664.6518580734787;3768.1144930941755;3793.905152371213;3829.8276577554634;5708.257926469532;5715.828525144252;5731.111677689964;5754.625949313281;5845.970770146122;5395.56835453155;5741.483266506035;5823.883522329615;5937.287525496795;5987.880242395215;5297.947020529899;5404.972975135099;5918.704064796657;5969.283685957713;5974.701264936823;4635.953640463552;5092.770825312796;5654.818380656626;5727.677946790768;5794.652320860593;5949;5960.5231581474245;5970.164172018257;5973.074173503114;5981.880362392641;4622.745748983838;5522.696251706195;5804.535637148933;5838.065909454467;5894.646321220741 22:30:49 INFO - PROCESS | 1908 | |5;yui.html;683.2757277286076;710.2719712294897;723.2153113871449;759.9164091949888;834.1741675741018;14883.10701357918;15555.377784888591;15563.688726225482;15593.766093508606;15644.921775391122;14065.367058482367;15156;15748.606284842885;15769.763453548207;16106.228314885382;708.2032713197652;730.5543618392776;879.2174964281794;886.813769108488;916.3722849847842;890.3989806880362;891.4383938119008;908.8136931928972;911.7583840282298;912.4616476278994;960.9759756006121;961.9134277914986;963.9132478076972;964.4695417520371;967.0233064604764;928.0302084321866;930.3859452761213;945.6926498887855;960.3085778239656;961.2982522758425;685.2736099734298;710.4139085254685;712.4834494991142;713.8072720365473;716.2694052066863;14755.704885902329;15234.466793662279;15687.764683529756;15712.92143539275;15850.76223856643;13797.448102075905;14183.290835458289;14232.715345693132;14305.57083287503;14378.928105359511;11654.766904661945;11848.822267665992;11867.762644747057;11876.228045177037;11965.700857478545;3605.369060414417;3664.5052917856015;3778.395456726938;3798.3542797724454;3849.557300910375;70990;74517.62741186314;76746.23253767447;96519.55220671695;97888.51055744676;10609.257351985287;10704.625338113128;10812.729681758019;10825;10825.729356765987;17034.31862725502;20806.167753289697;20877.26929557458;20998.47503812417;21012.26457073995;7692.384609231248;7843.098043724931;7852.685892564347;8024.358051355995;8026.197380261927;4365.890852728644;4385.254506733863;4541.795619197144;4569.702969306984;4674.95325046752;3446.069561218457;3542.3269578780464;3577.4633804929467;3583.552055993001;3584.766990145632;644.4007073421669;650;652.5236577298599;660.8281846719888;670.0686046395417;539.5279130760584;569.4334137533181;606.5390303369383;629.5561629051508;677.376813331736;9298.581563829644;9423.528823558794;9451.33840631163;9673.12941835224;9686.32195746291;592.62960649594;630.8422894276431;683.993160068393;694.9374556289958;700.4256509662029;4416.757078360657;4432.623227025674;4531.660125490602;4553.954460455419;4563.067146115731;117569;118350.40824795821;118384;119144.8085519151;122299;68495;103967.96032039734;103975.48012260042;107812;109482.45258773655;75600;91652.54173729089;92623.07376926279;92972;95843;8743.256823169848;8984.23633991092;8995.505247211466;9016.18854303116;9048.592813323548;44491.77754111209;58514;59290.40709593021;59358.70320648369;59876.70061649838;50958.74520627373;77643.61178194072;81588;81887.59056204681;87698.56150719206;10916.672499825017;11316.830247546459;11462.312261264351;11646.883531164918;11864.644060678194;12429.689257768629;12462.813057804322;12923.28921909304;13037.674058148623;13111.40998655082;11954.521819127138;12084.093692972887;12085.637430877088;12167.817482737943;12621.116521843383;11699.532018719156;11837.46731397072;12229.510819567118;12308.630741077783;12314.322712250912;11755.764884702428;12137.817932731192;12147.75704485923;12534.373281336078;12557.623271301876;9979.151772099453;10368.11870990944;10438.63464778729;10673.46632668379;10777.515011824644;10031.398116112765;10626.946865265934;10636.52135653882;10698.304610200312;10706.946465267936 22:30:49 INFO - PROCESS | 1908 | __end_tp_report 22:30:49 INFO - PROCESS | 1908 | __start_cc_report 22:30:49 INFO - PROCESS | 1908 | _x_x_mozilla_cycle_collect,358 22:30:49 INFO - PROCESS | 1908 | __end_cc_report 22:30:49 INFO - PROCESS | 1908 | __startTimestamp1485239449974__endTimestamp 22:30:49 INFO - PROCESS | 1908 | 22:30:49 INFO - PROCESS | 1908 | ------- Summary: start ------- 22:30:49 INFO - PROCESS | 1908 | Number of tests: 6 22:30:49 INFO - PROCESS | 1908 | 22:30:49 INFO - PROCESS | 1908 | [#0] dojo.html Cycles:165 Average:5954.82 Median:6231.97 stddev:687.38 (11.0%) stddev-sans-first:649.61 22:30:49 INFO - PROCESS | 1908 | Values: 3013.4 3597.6 3608.6 3614.2 3662.7 5967.6 6058.8 6073.9 6080.3 6099.2 6067.2 6071.2 6122.9 6133.0 6150.8 4650.6 5410.0 5424.7 5457.0 5491.3 5489.5 5501.7 5514.8 5518.9 5559.7 5335.3 5348.2 5484.4 5500.0 5515.6 5416.1 5416.1 5422.4 5430.8 5455.7 5250.4 5370.4 5395.6 5423.1 5430.5 6074.4 6161.5 6185.1 6213.1 6233.7 6232.0 6366.7 6384.7 6395.4 6406.5 6259.4 6326.1 6464.0 6499.5 6500.2 6118.0 6122.7 6129.4 6148.3 6158.3 6556.3 6595.7 6620.6 6629.1 6653.2 6277.2 6351.7 6353.3 6361.3 6379.5 6306.8 6319.1 6336.9 6392.9 6435.3 6373.3 6380.2 6389.2 6395.2 6396.1 6256.1 6336.9 6375.6 6386.0 6441.6 5111.8 5154.6 5165.2 5167.8 5174.3 3566.3 3922.1 4601.4 4641.8 4662.0 6135.4 6218.5 6248.1 6266.9 6267.9 6250.8 6310.1 6328.3 6380.3 6410.4 6187.8 6196.7 6204.4 6214.4 6245.7 4972.0 5558.5 5575.7 5632.2 5713.3 6469.9 6527.1 6565.2 6584.4 6646.8 5569.9 6455.5 6475.0 6481.1 6509.2 6455.4 6637.2 6638.8 6676.3 6678.8 6306.4 6327.9 6328.5 6352.0 6433.9 5620.4 6301.7 6475.6 6484.3 6495.9 6275.5 6317.3 6321.7 6337.9 6359.0 5280.7 6181.2 6233.4 6256.9 6301.2 5790.8 5918.9 5982.9 6140.9 6352.3 6266.3 6269.7 6291.7 6296.7 6303.9 6113.9 6131.9 6178.3 6213.6 6235.3 22:30:49 INFO - PROCESS | 1908 | 22:30:49 INFO - PROCESS | 1908 | [#1] ext.html Cycles:180 Average:72970.66 Median:41029.49 stddev:161532.35 (393.7%) stddev-sans-first:161893.76 22:30:49 INFO - PROCESS | 1908 | Values: 465.8 500.0 500.6 536.6 564.7 39782.4 41421.8 41607.4 43422.0 43450.8 42824.8 43038.8 43258.8 43349.3 43399.9 9724.4 9888.4 9890.8 9898.5 9925.5 9724.0 9771.9 9782.4 9800.6 9802.2 9800.7 9811.4 9811.5 9831.3 9851.3 9750.3 9750.9 9783.1 9828.5 9841.5 8265.8 9774.9 9801.4 9822.4 9846.9 40695.6 40696.2 40963.0 41096.0 41171.6 3261.7 3393.4 3395.1 3810.7 4140.7 30402.7 31042.4 31094.9 31626.0 31676.8 2967.9 3253.7 3303.5 3352.3 3411.2 473565.6 520338.0 520829.0 522750.0 528226.0 64185.7 64796.7 64833.0 65158.7 65265.0 64490.7 66276.0 67156.7 67227.3 67368.7 16413.3 16605.0 16977.7 17242.8 17316.3 13073.6 13128.7 13151.5 13166.3 13200.5 17877.9 19413.5 19457.5 19506.4 19526.1 3325.3 7129.2 8143.9 8336.9 8427.4 880.5 962.5 984.8 1080.8 1098.2 23018.0 30275.0 30362.0 30901.4 31042.8 1079.4 1117.1 1122.7 1139.0 1163.5 15596.2 16082.6 16130.7 16258.2 16330.3 674890.0 683106.0 683475.0 684056.6 685662.0 40010.0 48436.5 48649.8 49029.5 49294.0 1402.7 1424.4 1433.4 1440.8 1446.8 30236.8 30753.3 30848.1 31725.5 31905.4 50762.5 50780.0 51229.7 51361.0 51423.7 527742.0 618963.0 619894.0 621282.9 623413.0 26462.5 27304.3 27338.6 27349.9 27359.0 45446.8 45750.0 47219.8 47938.0 49720.8 34082.0 39069.4 39428.8 40012.4 41740.0 38912.2 39361.6 39878.2 40430.6 40679.0 34649.8 36789.0 39165.0 39446.8 39485.0 27055.9 31821.5 35309.6 36194.6 36384.6 23208.5 26500.9 26643.7 26644.2 26686.0 22:30:49 INFO - PROCESS | 1908 | 22:30:49 INFO - PROCESS | 1908 | [#2] jquery.html Cycles:180 Average:36948.65 Median:21208.92 stddev:104390.84 (492.2%) stddev-sans-first:104647.57 22:30:49 INFO - PROCESS | 1908 | Values: 378.2 385.7 389.2 399.2 408.9 19452.7 20589.3 21067.7 21160.6 21198.2 19797.9 20344.6 20435.6 20556.4 20842.7 5993.0 6036.8 6177.9 6228.5 6253.6 4378.2 4643.3 6174.8 6180.4 6261.2 6500.4 6775.1 6815.8 6883.8 6886.8 6607.5 6837.1 6876.0 6901.1 6931.4 5941.9 5944.1 5961.0 5971.9 6033.7 25063.6 25343.9 25353.9 25407.4 26264.7 2298.3 2316.4 2319.1 2454.7 3235.2 15136.5 15417.2 15542.9 16325.3 16479.9 1922.9 1934.2 2012.8 2025.5 2045.2 118444.0 119112.0 122345.0 122814.5 127384.4 10825.2 10981.6 11410.2 11453.5 11542.4 14015.3 14084.4 14102.2 14118.2 14123.0 6873.8 8756.3 8768.2 8854.8 8924.6 8822.2 8873.5 8875.9 8922.2 9038.2 6706.8 6782.6 6826.6 6830.9 6836.2 1630.9 1717.7 1906.8 1922.8 1950.7 871.5 878.6 920.7 1010.6 1041.0 21219.7 22061.9 22432.9 23261.9 23367.8 936.5 951.5 955.3 973.6 1029.5 10676.0 11294.9 11300.7 11301.8 11316.0 587163.1 613815.0 630987.8 643899.0 647858.0 135908.3 137307.0 139415.0 139496.0 139986.3 1021.3 1027.9 1034.7 1049.2 1053.7 18034.5 18851.6 19050.8 19078.5 19151.9 55358.4 55481.4 56491.0 56793.4 60767.0 52899.0 78004.0 87881.6 91034.1 93047.0 12373.1 12777.6 12954.9 12995.6 13070.0 11651.7 13714.7 13952.8 14005.8 14030.4 11665.0 11906.5 11925.6 11981.0 12068.0 8107.5 8112.5 11846.1 11854.1 11937.8 8344.6 8421.1 8490.2 8618.5 8651.8 12495.3 12945.0 13010.5 13019.8 13046.0 12077.0 12095.4 12222.3 12248.6 12311.5 22:30:49 INFO - PROCESS | 1908 | 22:30:49 INFO - PROCESS | 1908 | [#3] mootools.html Cycles:180 Average:5498.43 Median:5254.46 stddev:2965.24 (56.4%) stddev-sans-first:2958.12 22:30:49 INFO - PROCESS | 1908 | Values: 1472.3 1513.5 1675.1 1779.2 1832.4 7649.8 7881.0 8101.5 8463.9 8606.3 7970.4 8302.1 8315.4 8316.3 8316.5 2331.5 2385.4 2386.6 2388.4 2390.8 2391.2 2415.0 2432.5 2439.4 2445.8 2373.8 2385.9 2392.5 2401.7 2402.9 2367.3 2375.2 2394.0 2401.2 2401.3 1866.5 2399.7 2422.5 2428.0 2434.8 8278.7 8314.7 8339.9 8340.5 8430.7 4581.3 5149.1 5285.4 5290.0 5302.4 3501.0 3508.2 3528.8 3590.0 3621.0 1073.9 1074.4 1099.9 1107.6 1116.1 10705.8 10821.2 10872.8 10930.6 10974.4 4463.9 4661.7 4730.5 4739.8 4742.9 8965.0 9566.1 9584.3 9613.0 9620.5 5246.4 5262.5 5271.9 5284.5 5288.6 4343.5 4358.6 4748.7 4766.2 4836.2 3368.6 3904.6 3938.9 3993.9 4041.2 1102.5 1276.7 1303.4 1306.5 1319.3 789.2 815.6 871.2 877.9 957.2 5623.2 5683.8 5881.6 5946.5 6031.4 934.7 941.2 952.2 954.1 988.0 3857.1 3964.6 3986.7 3989.0 4022.8 9877.9 9989.8 10179.1 10222.6 10265.5 10110.8 10150.9 10173.7 10187.8 10217.2 7511.0 7745.9 7953.6 7961.9 7963.1 5559.4 5627.5 5644.4 5764.4 5768.9 9253.2 9258.1 9284.9 9323.4 9422.1 8750.3 8756.6 9586.9 9615.5 9647.7 6388.7 6564.8 6708.4 6786.7 6950.0 7856.6 8017.3 8109.6 8117.8 8118.7 7139.2 7288.6 7301.9 7301.9 7317.9 7042.5 7141.6 7177.3 7182.3 7301.3 6662.0 6780.2 6804.0 6834.3 6840.2 4911.3 4926.5 4949.0 5009.3 5059.8 4959.9 5003.8 5020.5 5068.3 5093.9 22:30:49 INFO - PROCESS | 1908 | 22:30:49 INFO - PROCESS | 1908 | [#4] prototype.html Cycles:180 Average:4618.69 Median:5244.27 stddev:1448.36 (27.6%) stddev-sans-first:1415.51 22:30:49 INFO - PROCESS | 1908 | Values: 289.7 311.6 318.1 323.3 324.4 4570.8 5392.4 5544.2 5588.5 5668.2 4816.0 5345.0 5453.9 5532.9 5559.3 3719.4 4247.7 4250.0 4295.3 4308.4 3557.4 3947.3 4182.6 4236.6 4238.5 3151.6 3732.9 3816.7 4170.2 4177.9 3218.5 4138.6 4164.5 4178.4 4205.6 2845.4 3187.5 3610.6 3640.8 3644.0 4367.6 5345.9 5398.7 5422.8 5445.6 4804.5 4805.3 4829.0 4842.7 4865.6 5816.3 5839.6 5842.7 5844.8 5856.3 3639.1 3681.2 3819.4 3980.3 3990.5 5559.4 6119.4 6158.4 6170.8 6184.8 3722.6 4029.2 4036.2 4041.5 4044.9 3693.5 3818.5 4075.8 4091.4 4095.3 4148.7 4374.6 4789.3 4798.5 4815.6 4650.4 5623.5 5864.4 5900.9 5991.9 1100.2 1103.5 1176.5 1258.5 1309.8 765.8 866.9 878.0 895.5 900.6 4768.3 5232.2 5256.3 5260.9 5264.0 5055.8 5100.9 5636.1 5674.7 5705.5 4445.4 4449.0 4705.9 5251.4 5254.4 2890.3 3021.5 3035.5 3055.7 3058.3 5382.7 5989.6 6030.4 6036.4 6055.7 5184.8 5237.2 5518.9 5594.3 5838.6 5333.0 5468.1 5551.9 5835.3 5968.8 5187.5 5219.5 5509.8 5786.6 5820.8 5049.9 5208.9 5633.7 5726.0 5731.3 5169.8 5720.9 5841.8 5894.9 5979.9 3649.7 3664.7 3768.1 3793.9 3829.8 5708.3 5715.8 5731.1 5754.6 5846.0 5395.6 5741.5 5823.9 5937.3 5987.9 5297.9 5405.0 5918.7 5969.3 5974.7 4636.0 5092.8 5654.8 5727.7 5794.7 5949.0 5960.5 5970.2 5973.1 5981.9 4622.7 5522.7 5804.5 5838.1 5894.6 22:30:49 INFO - PROCESS | 1908 | 22:30:49 INFO - PROCESS | 1908 | [#5] yui.html Cycles:180 Average:21049.38 Median:4424.69 stddev:31445.36 (710.7%) stddev-sans-first:31496.39 22:30:49 INFO - PROCESS | 1908 | Values: 683.3 710.3 723.2 759.9 834.2 14883.1 15555.4 15563.7 15593.8 15644.9 14065.4 15156.0 15748.6 15769.8 16106.2 708.2 730.6 879.2 886.8 916.4 890.4 891.4 908.8 911.8 912.5 961.0 961.9 963.9 964.5 967.0 928.0 930.4 945.7 960.3 961.3 685.3 710.4 712.5 713.8 716.3 14755.7 15234.5 15687.8 15712.9 15850.8 13797.4 14183.3 14232.7 14305.6 14378.9 11654.8 11848.8 11867.8 11876.2 11965.7 3605.4 3664.5 3778.4 3798.4 3849.6 70990.0 74517.6 76746.2 96519.6 97888.5 10609.3 10704.6 10812.7 10825.0 10825.7 17034.3 20806.2 20877.3 20998.5 21012.3 7692.4 7843.1 7852.7 8024.4 8026.2 4365.9 4385.3 4541.8 4569.7 4675.0 3446.1 3542.3 3577.5 3583.6 3584.8 644.4 650.0 652.5 660.8 670.1 539.5 569.4 606.5 629.6 677.4 9298.6 9423.5 9451.3 9673.1 9686.3 592.6 630.8 684.0 694.9 700.4 4416.8 4432.6 4531.7 4554.0 4563.1 117569.0 118350.4 118384.0 119144.8 122299.0 68495.0 103968.0 103975.5 107812.0 109482.5 75600.0 91652.5 92623.1 92972.0 95843.0 8743.3 8984.2 8995.5 9016.2 9048.6 44491.8 58514.0 59290.4 59358.7 59876.7 50958.7 77643.6 81588.0 81887.6 87698.6 10916.7 11316.8 11462.3 11646.9 11864.6 12429.7 12462.8 12923.3 13037.7 13111.4 11954.5 12084.1 12085.6 12167.8 12621.1 11699.5 11837.5 12229.5 12308.6 12314.3 11755.8 12137.8 12147.8 12534.4 12557.6 9979.2 10368.1 10438.6 10673.5 10777.5 10031.4 10626.9 10636.5 10698.3 10706.9 22:30:49 INFO - PROCESS | 1908 | -------- Summary: end -------- 22:30:49 INFO - PROCESS | 1908 | 22:30:50 INFO - TEST-INFO | 1908: exit 0 22:30:50 INFO - TEST-OK | dromaeo_css | took 1114623ms 22:30:50 INFO - TEST-START | kraken 22:30:50 INFO - operating with platform_type : mac_ 22:30:50 INFO - Initialising browser for kraken test... 22:30:50 INFO - TEST-INFO | started process 1917 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/tmpbwQAgi/profile http://localhost:49369/getInfo.html) 22:30:53 INFO - PROCESS | 1917 | 2017-01-23 22:30:53.289 plugin-container[1919:16547] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8637, name = 'com.apple.tsm.portname' 22:30:53 INFO - PROCESS | 1917 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:30:53 INFO - PROCESS | 1917 | 2017-01-23 22:30:53.290 plugin-container[1919:16547] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x890b, name = 'com.apple.CFPasteboardClient' 22:30:53 INFO - PROCESS | 1917 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:30:53 INFO - PROCESS | 1917 | 2017-01-23 22:30:53.290 plugin-container[1919:16547] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:30:53 INFO - PROCESS | 1917 | __metrics Screen width/height:1600/1200 22:30:53 INFO - PROCESS | 1917 | colorDepth:24 22:30:53 INFO - PROCESS | 1917 | Browser inner width/height: 1024/689 22:30:53 INFO - PROCESS | 1917 | __metrics 22:30:53 ERROR - PROCESS | 1917 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3181: TypeError: this.tabs is undefined 22:30:54 INFO - TEST-INFO | 1917: exit 0 22:30:54 INFO - Browser initialized. 22:30:54 INFO - Running cycle 1/1 for kraken test... 22:30:54 INFO - TEST-INFO | started process 1920 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/T/tmpbwQAgi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:30:56 INFO - PROCESS | 1920 | 2017-01-23 22:30:56.641 plugin-container[1921:16674] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 22:30:56 INFO - PROCESS | 1920 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:30:56 INFO - PROCESS | 1920 | 2017-01-23 22:30:56.644 plugin-container[1921:16674] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 22:30:56 INFO - PROCESS | 1920 | See /usr/include/servers/bootstrap_defs.h for the error codes. 22:30:56 INFO - PROCESS | 1920 | 2017-01-23 22:30:56.644 plugin-container[1921:16674] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 22:31:06 INFO - PROCESS | 1920 | RSS: Main: 178700288 22:31:06 INFO - PROCESS | 1920 | 22:31:09 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:49369/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49369/tests/kraken/driver.html?testName=audio-beat-detection) 22:31:10 INFO - PROCESS | 1920 | RSS: Main: 190795776 22:31:10 INFO - PROCESS | 1920 | 22:31:12 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:49369/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49369/tests/kraken/driver.html?testName=audio-dft) 22:31:13 INFO - PROCESS | 1920 | RSS: Main: 199434240 22:31:13 INFO - PROCESS | 1920 | 22:31:16 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:49369/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49369/tests/kraken/driver.html?testName=audio-fft) 22:31:17 INFO - PROCESS | 1920 | RSS: Main: 202764288 22:31:17 INFO - PROCESS | 1920 | 22:31:19 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:49369/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49369/tests/kraken/driver.html?testName=audio-oscillator) 22:31:19 INFO - PROCESS | 1920 | RSS: Main: 202964992 22:31:19 INFO - PROCESS | 1920 | 22:31:22 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:49369/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49369/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:31:22 INFO - PROCESS | 1920 | RSS: Main: 203055104 22:31:22 INFO - PROCESS | 1920 | 22:31:25 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:49369/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49369/tests/kraken/driver.html?testName=imaging-darkroom) 22:31:26 INFO - PROCESS | 1920 | RSS: Main: 208232448 22:31:26 INFO - PROCESS | 1920 | 22:31:29 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:49369/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49369/tests/kraken/driver.html?testName=imaging-desaturate) 22:31:30 INFO - PROCESS | 1920 | RSS: Main: 207650816 22:31:30 INFO - PROCESS | 1920 | 22:31:32 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:49369/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49369/tests/kraken/driver.html?testName=json-parse-financial) 22:31:33 INFO - PROCESS | 1920 | RSS: Main: 207659008 22:31:33 INFO - PROCESS | 1920 | 22:31:35 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:49369/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49369/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:31:35 INFO - PROCESS | 1920 | RSS: Main: 206688256 22:31:35 INFO - PROCESS | 1920 | 22:31:37 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:49369/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49369/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:31:38 INFO - PROCESS | 1920 | RSS: Main: 206712832 22:31:38 INFO - PROCESS | 1920 | 22:31:40 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:49369/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49369/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:31:41 INFO - PROCESS | 1920 | RSS: Main: 206499840 22:31:41 INFO - PROCESS | 1920 | 22:31:43 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:49369/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49369/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:31:44 INFO - PROCESS | 1920 | RSS: Main: 206712832 22:31:44 INFO - PROCESS | 1920 | 22:31:47 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:49369/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49369/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:31:48 INFO - PROCESS | 1920 | RSS: Main: 206876672 22:31:48 INFO - PROCESS | 1920 | 22:31:50 INFO - PROCESS | 1920 | Cycle 1(1): loaded http://localhost:49369/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49369/tests/kraken/driver.html?testName=ai-astar) 22:31:50 INFO - PROCESS | 1920 | RSS: Main: 208707584 22:31:50 INFO - PROCESS | 1920 | 22:31:50 INFO - PROCESS | 1920 | __start_tp_report 22:31:50 INFO - PROCESS | 1920 | _x_x_mozilla_page_load 22:31:50 INFO - PROCESS | 1920 | _x_x_mozilla_page_load_details 22:31:50 INFO - PROCESS | 1920 | |i|pagename|runs| 22:31:50 INFO - PROCESS | 1920 | |0;ai-astar;106;141;175;120;118;120;120;119;124;173 22:31:50 INFO - PROCESS | 1920 | |1;audio-beat-detection;140;112;127;141;112;136;116;114;155;146 22:31:50 INFO - PROCESS | 1920 | |2;audio-dft;195;202;196;199;193;196;200;195;191;192 22:31:50 INFO - PROCESS | 1920 | |3;audio-fft;82;60;78;108;82;60;60;59;76;59 22:31:50 INFO - PROCESS | 1920 | |4;audio-oscillator;80;71;90;66;93;105;120;84;69;87 22:31:50 INFO - PROCESS | 1920 | |5;imaging-gaussian-blur;92;89;92;88;92;88;129;96;136;132 22:31:50 INFO - PROCESS | 1920 | |6;imaging-darkroom;104;96;132;95;155;153;132;95;154;100 22:31:50 INFO - PROCESS | 1920 | |7;imaging-desaturate;133;124;86;105;141;89;92;90;87;89 22:31:50 INFO - PROCESS | 1920 | |8;json-parse-financial;55;54;55;54;67;58;60;60;59;58 22:31:50 INFO - PROCESS | 1920 | |9;json-stringify-tinderbox;65;74;66;77;73;69;70;69;69;69 22:31:50 INFO - PROCESS | 1920 | |10;stanford-crypto-aes;82;85;82;90;84;83;85;84;84;90 22:31:50 INFO - PROCESS | 1920 | |11;stanford-crypto-ccm;135;151;138;152;151;158;159;165;202;159 22:31:50 INFO - PROCESS | 1920 | |12;stanford-crypto-pbkdf2;221;162;164;206;237;189;167;165;183;237 22:31:50 INFO - PROCESS | 1920 | |13;stanford-crypto-sha256-iterative;67;77;76;63;85;89;89;91;73;62 22:31:50 INFO - PROCESS | 1920 | __end_tp_report 22:31:50 INFO - PROCESS | 1920 | __start_cc_report 22:31:50 INFO - PROCESS | 1920 | _x_x_mozilla_cycle_collect,918 22:31:50 INFO - PROCESS | 1920 | __end_cc_report 22:31:50 INFO - PROCESS | 1920 | __startTimestamp1485239510517__endTimestamp 22:31:50 INFO - PROCESS | 1920 | 22:31:50 INFO - PROCESS | 1920 | ------- Summary: start ------- 22:31:50 INFO - PROCESS | 1920 | Number of tests: 14 22:31:50 INFO - PROCESS | 1920 | 22:31:50 INFO - PROCESS | 1920 | [#0] ai-astar Cycles:10 Average:131.60 Median:122.00 stddev:23.91 (19.6%) stddev-sans-first:23.50 22:31:50 INFO - PROCESS | 1920 | Values: 106.0 141.0 175.0 120.0 118.0 120.0 120.0 119.0 124.0 173.0 22:31:50 INFO - PROCESS | 1920 | 22:31:50 INFO - PROCESS | 1920 | [#1] audio-beat-detection Cycles:10 Average:129.90 Median:138.00 stddev:15.80 (11.4%) stddev-sans-first:16.33 22:31:50 INFO - PROCESS | 1920 | Values: 140.0 112.0 127.0 141.0 112.0 136.0 116.0 114.0 155.0 146.0 22:31:50 INFO - PROCESS | 1920 | 22:31:50 INFO - PROCESS | 1920 | [#2] audio-dft Cycles:10 Average:195.90 Median:196.00 stddev:3.54 (1.8%) stddev-sans-first:3.74 22:31:50 INFO - PROCESS | 1920 | Values: 195.0 202.0 196.0 199.0 193.0 196.0 200.0 195.0 191.0 192.0 22:31:50 INFO - PROCESS | 1920 | 22:31:50 INFO - PROCESS | 1920 | [#3] audio-fft Cycles:10 Average:72.40 Median:68.00 stddev:16.04 (23.6%) stddev-sans-first:16.64 22:31:50 INFO - PROCESS | 1920 | Values: 82.0 60.0 78.0 108.0 82.0 60.0 60.0 59.0 76.0 59.0 22:31:50 INFO - PROCESS | 1920 | 22:31:50 INFO - PROCESS | 1920 | [#4] audio-oscillator Cycles:10 Average:86.50 Median:82.00 stddev:16.78 (20.5%) stddev-sans-first:17.63 22:31:50 INFO - PROCESS | 1920 | Values: 80.0 71.0 90.0 66.0 93.0 105.0 120.0 84.0 69.0 87.0 22:31:50 INFO - PROCESS | 1920 | 22:31:50 INFO - PROCESS | 1920 | [#5] imaging-gaussian-blur Cycles:10 Average:103.40 Median:90.50 stddev:20.17 (22.3%) stddev-sans-first:20.97 22:31:50 INFO - PROCESS | 1920 | Values: 92.0 89.0 92.0 88.0 92.0 88.0 129.0 96.0 136.0 132.0 22:31:50 INFO - PROCESS | 1920 | 22:31:50 INFO - PROCESS | 1920 | [#6] imaging-darkroom Cycles:10 Average:121.60 Median:154.50 stddev:26.28 (17.0%) stddev-sans-first:27.09 22:31:50 INFO - PROCESS | 1920 | Values: 104.0 96.0 132.0 95.0 155.0 153.0 132.0 95.0 154.0 100.0 22:31:50 INFO - PROCESS | 1920 | 22:31:50 INFO - PROCESS | 1920 | [#7] imaging-desaturate Cycles:10 Average:103.60 Median:88.00 stddev:21.11 (24.0%) stddev-sans-first:19.53 22:31:50 INFO - PROCESS | 1920 | Values: 133.0 124.0 86.0 105.0 141.0 89.0 92.0 90.0 87.0 89.0 22:31:50 INFO - PROCESS | 1920 | 22:31:50 INFO - PROCESS | 1920 | [#8] json-parse-financial Cycles:10 Average:58.00 Median:58.50 stddev:3.94 (6.7%) stddev-sans-first:4.03 22:31:50 INFO - PROCESS | 1920 | Values: 55.0 54.0 55.0 54.0 67.0 58.0 60.0 60.0 59.0 58.0 22:31:50 INFO - PROCESS | 1920 | 22:31:50 INFO - PROCESS | 1920 | [#9] json-stringify-tinderbox Cycles:10 Average:70.10 Median:69.50 stddev:3.63 (5.2%) stddev-sans-first:3.35 22:31:50 INFO - PROCESS | 1920 | Values: 65.0 74.0 66.0 77.0 73.0 69.0 70.0 69.0 69.0 69.0 22:31:50 INFO - PROCESS | 1920 | 22:31:50 INFO - PROCESS | 1920 | [#10] stanford-crypto-aes Cycles:10 Average:84.90 Median:84.50 stddev:2.88 (3.4%) stddev-sans-first:2.86 22:31:50 INFO - PROCESS | 1920 | Values: 82.0 85.0 82.0 90.0 84.0 83.0 85.0 84.0 84.0 90.0 22:31:50 INFO - PROCESS | 1920 | 22:31:50 INFO - PROCESS | 1920 | [#11] stanford-crypto-ccm Cycles:10 Average:157.00 Median:158.50 stddev:18.38 (11.6%) stddev-sans-first:17.69 22:31:50 INFO - PROCESS | 1920 | Values: 135.0 151.0 138.0 152.0 151.0 158.0 159.0 165.0 202.0 159.0 22:31:50 INFO - PROCESS | 1920 | 22:31:50 INFO - PROCESS | 1920 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:193.10 Median:197.50 stddev:30.19 (15.3%) stddev-sans-first:30.29 22:31:50 INFO - PROCESS | 1920 | Values: 221.0 162.0 164.0 206.0 237.0 189.0 167.0 165.0 183.0 237.0 22:31:50 INFO - PROCESS | 1920 | 22:31:50 INFO - PROCESS | 1920 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.20 Median:81.00 stddev:10.98 (13.6%) stddev-sans-first:11.01 22:31:50 INFO - PROCESS | 1920 | Values: 67.0 77.0 76.0 63.0 85.0 89.0 89.0 91.0 73.0 62.0 22:31:50 INFO - PROCESS | 1920 | -------- Summary: end -------- 22:31:50 INFO - PROCESS | 1920 | 22:31:51 INFO - TEST-INFO | 1920: exit 0 22:31:51 INFO - TEST-OK | kraken | took 60954ms 22:31:51 INFO - SUITE-END | took 1175s 22:31:51 INFO - Completed test suite (00:19:35) 22:31:51 INFO - javascript benchmark 22:31:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7452.843109708142, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3013.4123845850054, 3597.640235976401, 3608.621094785047, 3614.2410093880285, 3662.7252956028287, 5967.641941483508, 6058.787942422011, 6073.908891366633, 6080.331163572009, 6099.176611157503, 6067.24159480065, 6071.150038994544, 6122.9387706122925, 6132.969335153318, 6150.815475535741, 4650.581447669709, 5409.972950135254, 5424.728763561826, 5457.0, 5491.28613280273, 5489.533389661863, 5501.697406642633, 5514.779408823662, 5518.944810551903, 5559.722013899309, 5335.33308336458, 5348.197770334462, 5484.396716361197, 5500.037493438652, 5515.558755299587, 5416.052190866602, 5416.1063424534905, 5422.43064478228, 5430.782768689248, 5455.672659640434, 5250.3962044364625, 5370.43610420904, 5395.622306438551, 5423.050966080959, 5430.5384042356445, 6074.362191969868, 6161.476274516672, 6185.134081228586, 6213.068039794022, 6233.688315584202, 6231.9688401557705, 6366.681665916732, 6384.68076596173, 6395.42441180296, 6406.455451286646, 6259.405356491126, 6326.08271800591, 6463.967680161569, 6499.545031847772, 6500.219973603151, 6118.010103775202, 6122.663253521099, 6129.356417576179, 6148.341572317011, 6158.322584515699, 6556.311587283362, 6595.70319335631, 6620.635865027373, 6629.138212032405, 6653.168353955755, 6277.1525844011385, 6351.650659213695, 6353.30113687494, 6361.268454127742, 6379.521535884827, 6306.810795676137, 6319.146915166394, 6336.873262534724, 6392.904106438407, 6435.2599451063725, 6373.298937116913, 6380.234371875405, 6389.169407977026, 6395.20059992501, 6396.0725694774, 6256.092866534327, 6336.873262534724, 6375.6174629522375, 6386.0, 6441.613503189824, 5111.846644600668, 5154.587632989352, 5165.199394093921, 5167.767450464738, 5174.301469301596, 3566.268914872445, 3922.058705910561, 4601.3558101865765, 4641.8375356863025, 4661.95338046622, 6135.417135372132, 6218.533609979269, 6248.062790581449, 6266.874662506724, 6267.905981410283, 6250.843728906722, 6310.116583678288, 6328.303886572566, 6380.330065343072, 6410.391012853772, 6187.752489900354, 6196.721147548371, 6204.441600255998, 6214.40963108504, 6245.718942647592, 4972.0, 5558.471945165203, 5575.69333686643, 5632.211490391349, 5713.342965559014, 6469.902951455638, 6527.118838956775, 6565.179352580832, 6584.407403333723, 6646.8005959821285, 5569.860753481195, 6455.483561315177, 6474.967625161844, 6481.0926470295135, 6509.218893732783, 6455.354464553507, 6637.236717777518, 6638.83402914931, 6676.332366763285, 6678.799636010927, 6306.432421082125, 6327.90508142369, 6328.462080723006, 6351.96824015877, 6433.935660643334, 5620.381758006534, 6301.676647903808, 6475.611463312216, 6484.28672845996, 6495.9025614614875, 6275.497960163085, 6317.30509643948, 6321.683915804283, 6337.873242535031, 6359.04614307839, 5280.7359632019015, 6181.1655426516845, 6233.376662333731, 6256.906146407717, 6301.212348456443, 5790.812883358817, 5918.875413671389, 5982.940170598413, 6140.8771824564155, 6352.301246862749, 6266.310705822265, 6269.655168965751, 6291.748330066746, 6296.653684047421, 6303.905441418291, 6113.932424433696, 6131.9272270139245, 6178.258608966773, 6213.596116252429, 6235.282942461675], "value": 5910.008781049788, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [465.8020341354924, 500.0499051801577, 500.6420409407273, 536.5841472858534, 564.6837770848327, 39782.403263951026, 41421.79289103554, 41607.37588936165, 43422.0, 43450.782746086305, 42824.78587607065, 43038.784806076, 43258.78370608143, 43349.34975975355, 43399.915002124966, 9724.367916085466, 9888.40669559825, 9890.802183956317, 9898.455584942825, 9925.503724813767, 9724.0, 9771.853422198672, 9782.41305521671, 9800.55897484615, 9802.215822734164, 9800.656977005807, 9811.411315321067, 9811.460369679664, 9831.311808173396, 9851.310408271424, 9750.268729845255, 9750.90249097507, 9783.119519243266, 9828.508574571315, 9841.507924603813, 8265.793355166108, 9774.853377199348, 9801.411915285105, 9822.410655360629, 9846.852297215548, 40695.59304406948, 40696.18607627831, 40963.0, 41095.97260068492, 41171.588284117075, 3261.657525959764, 3393.3552625001416, 3395.066356751888, 3810.657040866335, 4140.710150289451, 30402.69597304021, 31042.379152417052, 31094.911678091157, 31626.0, 31676.841615792004, 2967.925801854971, 3253.6583658715967, 3303.487959366302, 3352.346292472969, 3411.215420453285, 473565.6321718404, 520338.0, 520829.0, 522750.0, 528226.0, 64185.67907160434, 64796.67601661962, 64833.0, 65158.67420662866, 65265.02102468467, 64490.677546611965, 66276.0, 67156.6642166786, 67227.32772672309, 67368.66315668488, 16413.261403236644, 16605.00369977805, 16977.745333820003, 17242.827571724374, 17316.30734770595, 13073.607791766262, 13128.737425251442, 13151.473941042443, 13166.341682915816, 13200.471981120647, 17877.910610446863, 19413.51466213328, 19457.51356216106, 19506.414807555797, 19526.12132454043, 3325.334933013407, 7129.215786263507, 8143.918560814434, 8336.87367237286, 8427.367947403969, 880.4893161966045, 962.5476026267643, 984.7587341101332, 1080.8378743188428, 1098.19282827122, 23018.0, 30275.0, 30362.0, 30901.38197236043, 31042.827002110087, 1079.4494807648157, 1117.1286396610656, 1122.6968718446137, 1138.9772204556027, 1163.5229555882047, 15596.220188990505, 16082.597935051483, 16130.677386452206, 16258.18709064542, 16330.265138068815, 674890.0, 683106.0, 683475.0, 684056.5797170983, 685662.0, 40009.999750006486, 48436.515634843905, 48649.75675121602, 49029.509704903205, 49294.0, 1402.7404930087966, 1424.4302279088447, 1433.3802802832731, 1440.7694768836936, 1446.7902154187584, 30236.84881575578, 30753.31270649738, 30848.074557763302, 31725.5241171378, 31905.361892762478, 50762.49237507725, 50780.0, 51229.7438512805, 51361.0, 51423.742881285354, 527742.0, 618963.0, 619894.0, 621282.8935855292, 623413.0, 26462.470750585264, 27304.317392065357, 27338.589921151597, 27349.863250684415, 27359.0, 45446.77276613596, 45750.0, 47219.81950451266, 47938.04123917572, 49720.751396242784, 34082.0, 39069.41395879121, 39428.80285598554, 40012.399814003395, 41740.0, 38912.22175556416, 39361.606383935796, 39878.2024359517, 40430.595694042684, 40679.0, 34649.82675086609, 36789.0, 39165.0, 39446.80276598599, 39485.0, 27055.86472067627, 31821.5226771594, 35309.646903530636, 36194.593520174494, 36384.63615363812, 23208.535829283657, 26500.867495662398, 26643.733562664125, 26644.200673979038, 26686.0], "value": 20330.19439666176, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.21331630209187, 385.70300868331384, 389.242922515707, 399.1518024198578, 408.9143324473522, 19452.70820937685, 20589.308474745692, 21067.68398474024, 21160.576788464223, 21198.152073917026, 19797.901010494963, 20344.59310813783, 20435.591288174226, 20556.383308500768, 20842.68735968962, 5993.0, 6036.758529658809, 6177.876442471148, 6228.532860035492, 6253.562250642457, 4378.1681480518755, 4643.349931009661, 6174.8147555573405, 6180.381961803829, 6261.186045814061, 6500.41496265336, 6775.0514927910135, 6815.7955261341995, 6883.759068432606, 6886.827829304282, 6607.471402287805, 6837.076994605714, 6875.99999999995, 6901.137357830271, 6931.376176144146, 5941.881162376728, 5944.078667806497, 5960.956832554329, 5971.940280597225, 6033.698315084228, 25063.62404563933, 25343.873280633477, 25353.87323063373, 25407.364815879566, 26264.73735262642, 2298.321995011476, 2316.4324740438533, 2319.1071437496516, 2454.656348111248, 3235.1750303672648, 15136.54590362291, 15417.15205663688, 15542.922285388613, 16325.346986120541, 16479.91760041204, 1922.884626922389, 1934.2456441987636, 2012.7886571909885, 2025.5037515808574, 2045.243259993797, 118444.0, 119112.0, 122345.0, 122814.54370912621, 127384.36307818402, 10825.188110891715, 10981.61564345244, 11410.201285909907, 11453.48459319316, 11542.422878856192, 14015.299235038206, 14084.366203520662, 14102.15387076758, 14118.152910825178, 14123.0, 6873.76272270986, 8756.299496040301, 8768.210861022539, 8854.822903542023, 8924.643014279485, 8822.2060014599, 8873.511956842307, 8875.934887813504, 8922.241609463268, 9038.231750301167, 6706.798796036127, 6782.626955517494, 6826.590404575742, 6830.863382732317, 6836.213835408992, 1630.8695304375622, 1717.6736420080395, 1906.790253072161, 1922.7500424944667, 1950.6878899376031, 871.4771137317558, 878.6485405837716, 920.6501529418778, 1010.5907107621425, 1041.0, 21219.68170477444, 22061.889690551765, 22432.88783556072, 23261.88369058144, 23367.766322336898, 936.4708939449189, 951.528993148396, 955.3169483819103, 973.6494861849728, 1029.45953374479, 10676.039156475954, 11294.870512948639, 11300.660980170607, 11301.773964520664, 11316.038136758303, 587163.1283687109, 613815.0, 630987.8450607718, 643899.0, 647858.0, 135908.32045839707, 137307.0, 139415.0, 139496.0, 139986.30006850103, 1021.3208216536069, 1027.8715160604925, 1034.6637342863598, 1049.2130901823632, 1053.7102296868422, 18034.458966231035, 18851.622967540297, 19050.809491904904, 19078.523036924187, 19151.904240478707, 55358.44641553533, 55481.445185549244, 56491.0, 56793.43206568047, 60767.0, 52899.0, 78004.0, 87881.56059219918, 91034.08965910522, 93047.0, 12373.13388062827, 12777.616671499869, 12954.870451295366, 12995.594713656434, 13069.954403647494, 11651.650450486499, 13714.65713357174, 13952.790708139182, 14005.789913151515, 14030.438782448588, 11665.0, 11906.464209110783, 11925.642230733092, 11980.981616562689, 12068.0, 8107.513549187068, 8112.513249205066, 11846.111541634584, 11854.051675866083, 11937.761244775229, 8344.582770861554, 8421.115782842713, 8490.15098490146, 8618.482891026559, 8651.82696346057, 12495.312757798409, 12945.0, 13010.544630937871, 13019.804702929274, 13046.021548384098, 12077.0, 12095.39523023863, 12222.327771972627, 12248.632541023784, 12311.507539698312], "value": 10299.420649196636, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1472.3153733513918, 1513.5081098642943, 1675.1373042882917, 1779.2082523277147, 1832.3678330975808, 7649.770506884803, 7880.960595197031, 8101.513909165455, 8463.915360846397, 8606.268467180282, 7970.36237101032, 8302.08677045526, 8315.376346773986, 8316.29311508521, 8316.542590157538, 2331.463763334434, 2385.4394217358995, 2386.570417324881, 2388.414838364603, 2390.8445951013214, 2391.198948352304, 2415.04605680756, 2432.5134973005383, 2439.3657649011207, 2445.8287919845616, 2373.774491423312, 2385.8926348314367, 2392.473655795722, 2401.735809061002, 2402.8558286502866, 2367.266147494274, 2375.2043065573052, 2394.0, 2401.2436082633913, 2401.3036219496325, 1866.514706176404, 2399.748026457214, 2422.454947636785, 2428.0287884846025, 2434.7565243475688, 8278.71024514139, 8314.667413303461, 8339.916600833974, 8340.541270230135, 8430.747077587683, 4581.3128030795315, 5149.124648809711, 5285.4450282720145, 5289.973550132225, 5302.3637163540625, 3501.0, 3508.1755787389943, 3528.8235588220487, 3590.0127464947086, 3621.040424287566, 1073.8711354637494, 1074.3929679730927, 1099.8570185875785, 1107.634480621393, 1116.1238427834112, 10705.785884282348, 10821.242513024014, 10872.782544349147, 10930.56277748889, 10974.396408197546, 4463.910721785611, 4661.650376221797, 4730.526947305242, 4739.7630118493935, 4742.857714268577, 8965.013848476663, 9566.13904748576, 9584.28117891161, 9612.951935240279, 9620.470874101851, 5246.396664383645, 5262.526372626482, 5271.947280527223, 5284.497972692588, 5288.603354748332, 4343.522212556616, 4358.6295164911335, 4748.738819364969, 4766.165920963817, 4836.202026665631, 3368.646292139339, 3904.570497245301, 3938.940915886264, 3993.8602148924647, 4041.2321658884716, 1102.5479553383075, 1276.6872116331558, 1303.3743802974625, 1306.5231190615357, 1319.3403298350825, 789.2344425906862, 815.6411179081185, 871.2072014466805, 877.8990416102102, 957.1624828275259, 5623.2408624835125, 5683.8010669626365, 5881.617694849821, 5946.524278057745, 6031.396860314021, 934.7195841247734, 941.2375975459762, 952.1858810716737, 954.1269738189479, 987.957704621631, 3857.1128640412862, 3964.60353964607, 3986.740861843971, 3988.980055099706, 4022.8390864365097, 9877.901220987698, 9989.750256243507, 10179.134773544332, 10222.591096356211, 10265.538050787733, 10110.848337274947, 10150.898491014996, 10173.694789156338, 10187.847182292271, 10217.18262538995, 7510.986016887759, 7745.883811742829, 7953.562554059582, 7961.920380796234, 7963.124056353912, 5559.416261292506, 5627.52166065889, 5644.407337229532, 5764.423557644202, 5768.884622307614, 9253.213476854542, 9258.074192580689, 9284.860727088964, 9323.440593564408, 9422.104900034486, 8750.343724220582, 8756.649734010824, 9586.904130958601, 9615.51922403891, 9647.662331818352, 6388.68056597159, 6564.80305590833, 6708.42978346846, 6786.69459874297, 6949.965250173716, 7856.607169641609, 8017.318527925192, 8109.553974531457, 8117.8376432472205, 8118.715844945398, 7139.178994415709, 7288.599127047851, 7301.890471642823, 7301.890471643036, 7317.926820731725, 7042.542234754725, 7141.607211603204, 7177.282271772781, 7182.281771822776, 7301.306375894282, 6662.0, 6780.1524809398825, 6803.965980170067, 6834.316568343126, 6840.179178498612, 4911.312416261798, 4926.458089610212, 4948.950510494849, 5009.298698182184, 5059.785651443701, 4959.851204463872, 5003.849884503471, 5020.548150666457, 5068.29043933857, 5093.92359114621], "value": 4481.744954869334, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [289.6668830844529, 311.58870291215607, 318.06013230902676, 323.2742493102984, 324.4111936834645, 4570.840020599279, 5392.43379445158, 5544.1960915667205, 5588.497035266825, 5668.178114173443, 4815.951840481594, 5344.984452953936, 5453.918191227125, 5532.944670553294, 5559.305086864142, 3719.367707489733, 4247.681423893205, 4249.999999999993, 4295.312749960007, 4308.375285583588, 3557.4130268505664, 3947.269755095302, 4182.644475219595, 4236.618704316611, 4238.4913810342805, 3151.558781770554, 3732.8693495727657, 3816.656500914917, 4170.228507726065, 4177.908347822619, 3218.5494030835703, 4138.606832350937, 4164.479440069991, 4178.435911151986, 4205.621494065518, 2845.445138198052, 3187.4581321175456, 3610.62088480711, 3640.8361623726996, 3644.0, 4367.624198377533, 5345.919811202835, 5398.676079435208, 5422.810201642963, 5445.646033007842, 4804.519548045202, 4805.255185446262, 4829.0, 4842.685225460334, 4865.577437782129, 5816.302043754734, 5839.562032847553, 5842.678652674102, 5844.766209351621, 5856.32652244993, 3639.090227443139, 3681.1717363593243, 3819.350710379242, 3980.3034468967817, 3990.5235063026576, 5559.41487208446, 6119.44924956756, 6158.384161583851, 6170.784022559189, 6184.84537886552, 3722.62773722631, 4029.2143032109043, 4036.2129384769687, 4041.5150181977765, 4044.939325910114, 3693.538307711536, 3818.4654148419245, 4075.8369665213654, 4091.40674602187, 4095.3037983542868, 4148.730332528376, 4374.58441448062, 4789.3055506952, 4798.520147985174, 4815.590674792682, 4650.395613513332, 5623.493885550321, 5864.38423965486, 5900.905178661514, 5991.940080599225, 1100.2188446203124, 1103.5034234594305, 1176.452949378526, 1258.5406326690875, 1309.768817311724, 765.8391737735027, 866.9293422623056, 877.9596178528465, 895.4850960697624, 900.6127365233012, 4768.284757286366, 5232.2413250078525, 5256.342957130359, 5260.868478288074, 5263.973680131651, 5055.848324550269, 5100.897982040413, 5636.082922368996, 5674.687892165888, 5705.515031222392, 4445.377647129404, 4448.955510444855, 4705.882352941204, 5251.36983561975, 5254.448282930313, 2890.2629829393372, 3021.4863473209607, 3035.4536183487185, 3055.731871273441, 3058.2813038935833, 5382.6585435335755, 5989.610675306091, 6030.366811484819, 6036.426539478743, 6055.666938318347, 5184.8185313514605, 5237.16205407133, 5518.91721624176, 5594.328680558359, 5838.649681019152, 5333.013392522395, 5468.0977638689965, 5551.861203469945, 5835.270591176103, 5968.791092311748, 5187.481251874782, 5219.5041471060185, 5509.779608815603, 5786.594938354275, 5820.82537523875, 5049.924251136163, 5208.869778255574, 5633.718314084362, 5725.971370143123, 5731.312242530923, 5169.7673604687125, 5720.942790572208, 5841.750925893477, 5894.936772129892, 5979.940200597938, 3649.671529562352, 3664.6518580734787, 3768.1144930941755, 3793.905152371213, 3829.8276577554634, 5708.257926469532, 5715.828525144252, 5731.111677689964, 5754.625949313281, 5845.970770146122, 5395.56835453155, 5741.483266506035, 5823.883522329615, 5937.287525496795, 5987.880242395215, 5297.947020529899, 5404.972975135099, 5918.704064796657, 5969.283685957713, 5974.701264936823, 4635.953640463552, 5092.770825312796, 5654.818380656626, 5727.677946790768, 5794.652320860593, 5949.0, 5960.5231581474245, 5970.164172018257, 5973.074173503114, 5981.880362392641, 4622.745748983838, 5522.696251706195, 5804.535637148933, 5838.065909454467, 5894.646321220741], "value": 4148.105754271387, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [683.2757277286076, 710.2719712294897, 723.2153113871449, 759.9164091949888, 834.1741675741018, 14883.10701357918, 15555.377784888591, 15563.688726225482, 15593.766093508606, 15644.921775391122, 14065.367058482367, 15156.0, 15748.606284842885, 15769.763453548207, 16106.228314885382, 708.2032713197652, 730.5543618392776, 879.2174964281794, 886.813769108488, 916.3722849847842, 890.3989806880362, 891.4383938119008, 908.8136931928972, 911.7583840282298, 912.4616476278994, 960.9759756006121, 961.9134277914986, 963.9132478076972, 964.4695417520371, 967.0233064604764, 928.0302084321866, 930.3859452761213, 945.6926498887855, 960.3085778239656, 961.2982522758425, 685.2736099734298, 710.4139085254685, 712.4834494991142, 713.8072720365473, 716.2694052066863, 14755.704885902329, 15234.466793662279, 15687.764683529756, 15712.92143539275, 15850.76223856643, 13797.448102075905, 14183.290835458289, 14232.715345693132, 14305.57083287503, 14378.928105359511, 11654.766904661945, 11848.822267665992, 11867.762644747057, 11876.228045177037, 11965.700857478545, 3605.369060414417, 3664.5052917856015, 3778.395456726938, 3798.3542797724454, 3849.557300910375, 70990.0, 74517.62741186314, 76746.23253767447, 96519.55220671695, 97888.51055744676, 10609.257351985287, 10704.625338113128, 10812.729681758019, 10825.0, 10825.729356765987, 17034.31862725502, 20806.167753289697, 20877.26929557458, 20998.47503812417, 21012.26457073995, 7692.384609231248, 7843.098043724931, 7852.685892564347, 8024.358051355995, 8026.197380261927, 4365.890852728644, 4385.254506733863, 4541.795619197144, 4569.702969306984, 4674.95325046752, 3446.069561218457, 3542.3269578780464, 3577.4633804929467, 3583.552055993001, 3584.766990145632, 644.4007073421669, 650.0, 652.5236577298599, 660.8281846719888, 670.0686046395417, 539.5279130760584, 569.4334137533181, 606.5390303369383, 629.5561629051508, 677.376813331736, 9298.581563829644, 9423.528823558794, 9451.33840631163, 9673.12941835224, 9686.32195746291, 592.62960649594, 630.8422894276431, 683.993160068393, 694.9374556289958, 700.4256509662029, 4416.757078360657, 4432.623227025674, 4531.660125490602, 4553.954460455419, 4563.067146115731, 117569.0, 118350.40824795821, 118384.0, 119144.8085519151, 122299.0, 68495.0, 103967.96032039734, 103975.48012260042, 107812.0, 109482.45258773655, 75600.0, 91652.54173729089, 92623.07376926279, 92972.0, 95843.0, 8743.256823169848, 8984.23633991092, 8995.505247211466, 9016.18854303116, 9048.592813323548, 44491.77754111209, 58514.0, 59290.40709593021, 59358.70320648369, 59876.70061649838, 50958.74520627373, 77643.61178194072, 81588.0, 81887.59056204681, 87698.56150719206, 10916.672499825017, 11316.830247546459, 11462.312261264351, 11646.883531164918, 11864.644060678194, 12429.689257768629, 12462.813057804322, 12923.28921909304, 13037.674058148623, 13111.40998655082, 11954.521819127138, 12084.093692972887, 12085.637430877088, 12167.817482737943, 12621.116521843383, 11699.532018719156, 11837.46731397072, 12229.510819567118, 12308.630741077783, 12314.322712250912, 11755.764884702428, 12137.817932731192, 12147.75704485923, 12534.373281336078, 12557.623271301876, 9979.151772099453, 10368.11870990944, 10438.63464778729, 10673.46632668379, 10777.515011824644, 10031.398116112765, 10626.946865265934, 10636.52135653882, 10698.304610200312, 10706.946465267936], "value": 7448.094625895731, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1585.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 141.0, 175.0, 120.0, 118.0, 120.0, 120.0, 119.0, 124.0, 173.0], "value": 131.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 112.0, 127.0, 141.0, 112.0, 136.0, 116.0, 114.0, 155.0, 146.0], "value": 129.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 202.0, 196.0, 199.0, 193.0, 196.0, 200.0, 195.0, 191.0, 192.0], "value": 195.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 60.0, 78.0, 108.0, 82.0, 60.0, 60.0, 59.0, 76.0, 59.0], "value": 72.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 71.0, 90.0, 66.0, 93.0, 105.0, 120.0, 84.0, 69.0, 87.0], "value": 86.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 89.0, 92.0, 88.0, 92.0, 88.0, 129.0, 96.0, 136.0, 132.0], "value": 103.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 96.0, 132.0, 95.0, 155.0, 153.0, 132.0, 95.0, 154.0, 100.0], "value": 121.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 124.0, 86.0, 105.0, 141.0, 89.0, 92.0, 90.0, 87.0, 89.0], "value": 103.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 55.0, 54.0, 67.0, 58.0, 60.0, 60.0, 59.0, 58.0], "value": 58.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 74.0, 66.0, 77.0, 73.0, 69.0, 70.0, 69.0, 69.0, 69.0], "value": 70.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 85.0, 82.0, 90.0, 84.0, 83.0, 85.0, 84.0, 84.0, 90.0], "value": 84.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 151.0, 138.0, 152.0, 151.0, 158.0, 159.0, 165.0, 202.0, 159.0], "value": 157.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [221.0, 162.0, 164.0, 206.0, 237.0, 189.0, 167.0, 165.0, 183.0, 237.0], "value": 193.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 77.0, 76.0, 63.0, 85.0, 89.0, 89.0, 91.0, 73.0, 62.0], "value": 77.2, "unit": "score"}]}]} 22:31:51 INFO - Return code: 0 22:31:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:31:51 ERROR - # TBPL SUCCESS # 22:31:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:31:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:31:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:31:51 INFO - 2017-01-23 22:31:51.843 screenresolution[1925:17065] starting screenresolution argv=screenresolution get 22:31:51 INFO - 2017-01-23 22:31:51.848 screenresolution[1925:17065] Display 0: 1600x1200x32@60 22:31:51 INFO - 2017-01-23 22:31:51.854 screenresolution[1926:17068] starting screenresolution argv=screenresolution list 22:31:51 INFO - Available Modes on Display 0 22:31:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:31:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:31:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:31:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:31:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:31:52 INFO - Intel Iris: 22:31:52 INFO - Chipset Model: Intel Iris 22:31:52 INFO - Type: GPU 22:31:52 INFO - Bus: Built-In 22:31:52 INFO - VRAM (Dynamic, Max): 1536 MB 22:31:52 INFO - Vendor: Intel (0x8086) 22:31:52 INFO - Device ID: 0x0a2e 22:31:52 INFO - Revision ID: 0x0009 22:31:52 INFO - Displays: 22:31:52 INFO - Display: 22:31:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:31:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:31:52 INFO - Display Serial Number: 1600x1200 60 22:31:52 INFO - Main Display: Yes 22:31:52 INFO - Mirror: Off 22:31:52 INFO - Online: Yes 22:31:52 INFO - Rotation: Supported 22:31:52 INFO - Return code: 0 22:31:52 INFO - Running post-action listener: _resource_record_post_action 22:31:52 INFO - [mozharness: 2017-01-24 06:31:52.093718Z] Finished run-tests step (success) 22:31:52 INFO - Running post-run listener: _resource_record_post_run 22:31:52 INFO - Total resource usage - Wall time: 1198s; CPU: 54.0%; Read bytes: 2622464; Write bytes: 505990144; Read time: 26; Write time: 1612 22:31:52 INFO - TinderboxPrint: CPU usage
54.2% 22:31:52 INFO - TinderboxPrint: I/O read bytes / time
2,622,464 / 26 22:31:52 INFO - TinderboxPrint: I/O write bytes / time
505,990,144 / 1,612 22:31:52 INFO - TinderboxPrint: CPU idle
2,195.1 (45.8%) 22:31:52 INFO - TinderboxPrint: CPU system
970.4 (20.2%) 22:31:52 INFO - TinderboxPrint: CPU user
1,628.1 (34.0%) 22:31:52 INFO - TinderboxPrint: Swap in / out
195,108,864 / 0 22:31:52 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 180724224; Write bytes: 177580032; Read time: 16648; Write time: 375 22:31:52 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 2622464; Write bytes: 310686720; Read time: 26; Write time: 1182 22:31:52 INFO - Running post-run listener: _upload_blobber_files 22:31:52 INFO - Blob upload gear active. 22:31:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:31:52 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 22:31:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:31:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:31:52 INFO - (blobuploader) - INFO - Open directory for files ... 22:31:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 22:31:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:31:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:31:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 22:31:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:31:53 INFO - (blobuploader) - INFO - Done attempting. 22:31:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 22:31:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:31:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:31:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 22:31:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:31:54 INFO - (blobuploader) - INFO - Done attempting. 22:31:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:31:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:31:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:31:56 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:31:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:31:56 INFO - (blobuploader) - INFO - Done attempting. 22:31:56 INFO - (blobuploader) - INFO - Iteration through files over. 22:31:56 INFO - Return code: 0 22:31:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:31:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:31:56 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4e472ebfb81c2384f570cda107fab45fa804d93c57f58ce1564ed3fe3efe38c796304043176706a2c7755f1779a7fcca3ff649531bfc4d6d254f6298a0e0d402", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/72edd6a871558cd0b71e470d56bb0e1903c2a20debe4c33e3f72f72ebb457b388842944739b3c6ed9e39243509d0786d780e749cadfd6b6e8eb9e7cbf6bebbc6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f504626d31eb3e2465e1f50a1023d9de7a36c56522f12fe08c40791c5e1b6369acc2c35b0b14da7f10e64f39ff745fb77e0720228a8991530d05fc5dd82e2bfc"} 22:31:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:31:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:31:56 INFO - Contents: 22:31:56 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4e472ebfb81c2384f570cda107fab45fa804d93c57f58ce1564ed3fe3efe38c796304043176706a2c7755f1779a7fcca3ff649531bfc4d6d254f6298a0e0d402", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/72edd6a871558cd0b71e470d56bb0e1903c2a20debe4c33e3f72f72ebb457b388842944739b3c6ed9e39243509d0786d780e749cadfd6b6e8eb9e7cbf6bebbc6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f504626d31eb3e2465e1f50a1023d9de7a36c56522f12fe08c40791c5e1b6369acc2c35b0b14da7f10e64f39ff745fb77e0720228a8991530d05fc5dd82e2bfc"} 22:31:56 INFO - Running post-run listener: copy_logs_to_upload_dir 22:31:56 INFO - Copying logs to upload dir... 22:31:56 INFO - mkdir: /builds/slave/test/build/upload/logs 22:31:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1257.954103 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 57 secs) (at 2017-01-23 22:31:56.883969) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-23 22:31:56.887216) ========= 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.CP7klMr5wC/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.noO7KnsgoR/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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-Beta/sha512/4e472ebfb81c2384f570cda107fab45fa804d93c57f58ce1564ed3fe3efe38c796304043176706a2c7755f1779a7fcca3ff649531bfc4d6d254f6298a0e0d402", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/72edd6a871558cd0b71e470d56bb0e1903c2a20debe4c33e3f72f72ebb457b388842944739b3c6ed9e39243509d0786d780e749cadfd6b6e8eb9e7cbf6bebbc6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f504626d31eb3e2465e1f50a1023d9de7a36c56522f12fe08c40791c5e1b6369acc2c35b0b14da7f10e64f39ff745fb77e0720228a8991530d05fc5dd82e2bfc"} build_url:https://queue.taskcluster.net/v1/task/CDmdux7rT528pwzaswH3Pw/artifacts/public/build/firefox-52.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011114 build_url: 'https://queue.taskcluster.net/v1/task/CDmdux7rT528pwzaswH3Pw/artifacts/public/build/firefox-52.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4e472ebfb81c2384f570cda107fab45fa804d93c57f58ce1564ed3fe3efe38c796304043176706a2c7755f1779a7fcca3ff649531bfc4d6d254f6298a0e0d402", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/72edd6a871558cd0b71e470d56bb0e1903c2a20debe4c33e3f72f72ebb457b388842944739b3c6ed9e39243509d0786d780e749cadfd6b6e8eb9e7cbf6bebbc6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f504626d31eb3e2465e1f50a1023d9de7a36c56522f12fe08c40791c5e1b6369acc2c35b0b14da7f10e64f39ff745fb77e0720228a8991530d05fc5dd82e2bfc"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-23 22:31:56.921406) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-23 22:31:56.921786) ========= 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.CP7klMr5wC/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.noO7KnsgoR/Listeners TMPDIR=/var/folders/p8/w8ptrvz123vfhzsj5l69kq9c00000w/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.005184 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-23 22:31:56.970628) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-23 22:31:56.970969) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-23 22:31:56.971316) ========= ========= Total master_lag: 0.20 =========