builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0173 starttime: 1485212465.63 results: success (0) buildid: 20170123123244 builduid: 59b91ff5d568477d88d452ba5d56c53d revision: c471ca9cfe84ee531d73ff74c34961e20575d327 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-23 15:01:05.632749) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-23 15:01:05.633219) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-23 15:01:05.633505) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-23 15:01:05.659267) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-23 15:01:05.659706) ========= 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.7a5Mahn9H7/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.e3vCdIwuUH/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 15:01:05-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.26M=0.001s 2017-01-23 15:01:06 (8.26 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.605008 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-23 15:01:06.288637) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-23 15:01:06.288977) ========= 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.7a5Mahn9H7/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.e3vCdIwuUH/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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.020134 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-23 15:01:06.337648) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-23 15:01:06.338038) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev c471ca9cfe84ee531d73ff74c34961e20575d327 --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 c471ca9cfe84ee531d73ff74c34961e20575d327 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7a5Mahn9H7/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.e3vCdIwuUH/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 15:01:06,426 truncating revision to first 12 chars 2017-01-23 15:01:06,426 Setting DEBUG logging. 2017-01-23 15:01:06,427 attempt 1/10 2017-01-23 15:01:06,427 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/c471ca9cfe84?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-23 15:01:07,202 unpacking tar archive at: mozilla-beta-c471ca9cfe84/testing/mozharness/ program finished with exit code 0 elapsedTime=1.029584 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-23 15:01:07.391211) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-23 15:01:07.391530) ========= script_repo_revision: c471ca9cfe84ee531d73ff74c34961e20575d327 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-23 15:01:07.391932) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-23 15:01:07.392200) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-23 15:01:07.412774) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-01-23 15:01:07.413184) ========= /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.7a5Mahn9H7/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.e3vCdIwuUH/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 15:01:07 INFO - MultiFileLogger online at 20170123 15:01:07 in /builds/slave/test 15:01:07 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 15:01:07 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:01:07 INFO - {'append_to_log': False, 15:01:07 INFO - 'base_work_dir': '/builds/slave/test', 15:01:07 INFO - 'blob_upload_branch': 'Mozilla-Beta', 15:01:07 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:01:07 INFO - 'branch': 'Mozilla-Beta', 15:01:07 INFO - 'buildbot_json_path': 'buildprops.json', 15:01:07 INFO - 'config_files': ('talos/mac_config.py',), 15:01:07 INFO - 'default_actions': ('clobber', 15:01:07 INFO - 'read-buildbot-config', 15:01:07 INFO - 'download-and-extract', 15:01:07 INFO - 'populate-webroot', 15:01:07 INFO - 'create-virtualenv', 15:01:07 INFO - 'install', 15:01:07 INFO - 'run-tests'), 15:01:07 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:01:07 INFO - 'download_minidump_stackwalk': True, 15:01:07 INFO - 'download_symbols': 'ondemand', 15:01:07 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:01:07 INFO - 'tooltool.py': '/tools/tooltool.py', 15:01:07 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:01:07 INFO - '/tools/misc-python/virtualenv.py')}, 15:01:07 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:01:07 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:01:07 INFO - 'installer_path': 'installer.exe', 15:01:07 INFO - 'log_level': 'info', 15:01:07 INFO - 'log_name': 'talos', 15:01:07 INFO - 'log_to_console': True, 15:01:07 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:01:07 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:01:07 INFO - 'opt_config_files': (), 15:01:07 INFO - 'pip_index': False, 15:01:07 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:01:07 INFO - 'cmd': ('bash', 15:01:07 INFO - '-c', 15:01:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:01:07 INFO - 'enabled': True, 15:01:07 INFO - 'halt_on_failure': False, 15:01:07 INFO - 'name': 'check_screen_resolution'},), 15:01:07 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:01:07 INFO - 'cmd': ('bash', 15:01:07 INFO - '-c', 15:01:07 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:01:07 INFO - 'enabled': True, 15:01:07 INFO - 'halt_on_failure': False, 15:01:07 INFO - 'name': 'check_screen_resolution'},), 15:01:07 INFO - 'run_cmd_checks_enabled': True, 15:01:07 INFO - 'sps_profile': False, 15:01:07 INFO - 'sps_profile_interval': 0, 15:01:07 INFO - 'suite': 'dromaeojs-e10s', 15:01:07 INFO - 'system_bits': '32', 15:01:07 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:01:07 INFO - 'title': 't-yosemite-r7-0173', 15:01:07 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:01:07 INFO - 'use_talos_json': True, 15:01:07 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:01:07 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:01:07 INFO - 'work_dir': 'build'} 15:01:07 INFO - [mozharness: 2017-01-23 23:01:07.631077Z] Running clobber step. 15:01:07 INFO - Running pre-action listener: _resource_record_pre_action 15:01:07 INFO - Running main action method: clobber 15:01:07 INFO - rmtree: /builds/slave/test/build 15:01:07 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:01:08 INFO - Running post-action listener: _resource_record_post_action 15:01:08 INFO - [mozharness: 2017-01-23 23:01:08.533461Z] Finished clobber step (success) 15:01:08 INFO - [mozharness: 2017-01-23 23:01:08.533583Z] Running read-buildbot-config step. 15:01:08 INFO - Running pre-action listener: _resource_record_pre_action 15:01:08 INFO - Running main action method: read_buildbot_config 15:01:08 INFO - Using buildbot properties: 15:01:08 INFO - { 15:01:08 INFO - "project": "", 15:01:08 INFO - "product": "firefox", 15:01:08 INFO - "script_repo_revision": "production", 15:01:08 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 15:01:08 INFO - "repository": "", 15:01:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 15:01:08 INFO - "buildid": "20170123123244", 15:01:08 INFO - "pgo_build": "False", 15:01:08 INFO - "basedir": "/builds/slave/test", 15:01:08 INFO - "buildnumber": 3, 15:01:08 INFO - "slavename": "t-yosemite-r7-0173", 15:01:08 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 15:01:08 INFO - "platform": "yosemite_r7", 15:01:08 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 15:01:08 INFO - "branch": "mozilla-beta", 15:01:08 INFO - "repo_path": "releases/mozilla-beta", 15:01:08 INFO - "revision": "c471ca9cfe84ee531d73ff74c34961e20575d327", 15:01:08 INFO - "stage_platform": "macosx64", 15:01:08 INFO - "builduid": "59b91ff5d568477d88d452ba5d56c53d", 15:01:08 INFO - "slavebuilddir": "test" 15:01:08 INFO - } 15:01:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/HuXj3xkQTjGzOQ_DiIqp9w/artifacts/public/build/firefox-52.0.en-US.mac.dmg. 15:01:08 INFO - Running post-action listener: _resource_record_post_action 15:01:08 INFO - [mozharness: 2017-01-23 23:01:08.536882Z] Finished read-buildbot-config step (success) 15:01:08 INFO - [mozharness: 2017-01-23 23:01:08.537011Z] Running download-and-extract step. 15:01:08 INFO - Running pre-action listener: _resource_record_pre_action 15:01:08 INFO - Running main action method: download_and_extract 15:01:08 INFO - mkdir: /builds/slave/test/build/tests 15:01:08 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')]} 15:01:08 INFO - trying https://queue.taskcluster.net/v1/task/HuXj3xkQTjGzOQ_DiIqp9w/artifacts/public/build/firefox-52.0.en-US.mac.test_packages.json 15:01:08 INFO - Downloading https://queue.taskcluster.net/v1/task/HuXj3xkQTjGzOQ_DiIqp9w/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 15:01:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HuXj3xkQTjGzOQ_DiIqp9w/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 15:01:10 INFO - Downloaded 1165 bytes. 15:01:10 INFO - Reading from file /builds/slave/test/build/firefox-52.0.en-US.mac.test_packages.json 15:01:10 INFO - Using the following test package requirements: 15:01:10 INFO - {u'common': [u'firefox-52.0.en-US.mac.common.tests.zip'], 15:01:10 INFO - u'cppunittest': [u'firefox-52.0.en-US.mac.common.tests.zip', 15:01:10 INFO - u'firefox-52.0.en-US.mac.cppunittest.tests.zip'], 15:01:10 INFO - u'gtest': [u'firefox-52.0.en-US.mac.common.tests.zip', 15:01:10 INFO - u'firefox-52.0.en-US.mac.gtest.tests.zip'], 15:01:10 INFO - u'jittest': [u'firefox-52.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 15:01:10 INFO - u'mochitest': [u'firefox-52.0.en-US.mac.common.tests.zip', 15:01:10 INFO - u'firefox-52.0.en-US.mac.mochitest.tests.zip'], 15:01:10 INFO - u'mozbase': [u'firefox-52.0.en-US.mac.common.tests.zip'], 15:01:10 INFO - u'reftest': [u'firefox-52.0.en-US.mac.common.tests.zip', 15:01:10 INFO - u'firefox-52.0.en-US.mac.reftest.tests.zip'], 15:01:10 INFO - u'talos': [u'firefox-52.0.en-US.mac.common.tests.zip', 15:01:10 INFO - u'firefox-52.0.en-US.mac.talos.tests.zip'], 15:01:10 INFO - u'web-platform': [u'firefox-52.0.en-US.mac.common.tests.zip', 15:01:10 INFO - u'firefox-52.0.en-US.mac.web-platform.tests.zip'], 15:01:10 INFO - u'xpcshell': [u'firefox-52.0.en-US.mac.common.tests.zip', 15:01:10 INFO - u'firefox-52.0.en-US.mac.xpcshell.tests.zip']} 15:01:10 INFO - Downloading packages: [u'firefox-52.0.en-US.mac.common.tests.zip'] for test suite category: common 15:01:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HuXj3xkQTjGzOQ_DiIqp9w/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 15:01:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HuXj3xkQTjGzOQ_DiIqp9w/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 15:01:10 INFO - Fetch https://queue.taskcluster.net/v1/task/HuXj3xkQTjGzOQ_DiIqp9w/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 15:01:10 INFO - Http code: 200 15:01:10 INFO - via: 1.1 e30ae5b3d9f6779a9b8bc992faad0b09.cloudfront.net (CloudFront) 15:01:10 INFO - x-amz-cf-id: c_IcCSnEmoG-Cy06IFNuuvLezFsGHDjtQpQsOJm54qEbv2MMZIOLuA== 15:01:10 INFO - x-amz-version-id: xa3zjuRPc8VAoj1pqvJGsXE5jMpsndLf 15:01:10 INFO - Expected file size: 16086053 15:01:10 INFO - Obtained file size: 16086053 15:01:17 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 15:01:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HuXj3xkQTjGzOQ_DiIqp9w/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip 15:01:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HuXj3xkQTjGzOQ_DiIqp9w/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip'}, attempt #1 15:01:17 INFO - Fetch https://queue.taskcluster.net/v1/task/HuXj3xkQTjGzOQ_DiIqp9w/artifacts/public/build/firefox-52.0.en-US.mac.common.tests.zip into memory 15:01:18 INFO - Http code: 200 15:01:18 INFO - via: 1.1 a1aa00de8387e7235a256b2a5b73ede8.cloudfront.net (CloudFront) 15:01:18 INFO - x-amz-cf-id: pRXcGeQcAjGzZ56rdJUoJvsj-qCMuCVb7DAxMCKg1zxpIXUHGR99_A== 15:01:18 INFO - x-amz-version-id: xa3zjuRPc8VAoj1pqvJGsXE5jMpsndLf 15:01:18 INFO - Expected file size: 16086053 15:01:18 INFO - Obtained file size: 16086053 15:01:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HuXj3xkQTjGzOQ_DiIqp9w/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip 15:01:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HuXj3xkQTjGzOQ_DiIqp9w/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip'}, attempt #1 15:01:25 INFO - Fetch https://queue.taskcluster.net/v1/task/HuXj3xkQTjGzOQ_DiIqp9w/artifacts/public/build/firefox-52.0.en-US.mac.talos.tests.zip into memory 15:01:26 INFO - Http code: 200 15:01:26 INFO - via: 1.1 5ed093c37d643652e278a198e0556a40.cloudfront.net (CloudFront) 15:01:26 INFO - x-amz-cf-id: 1aE-8vdoVi21Chf2zVH0QIsNDTqDvIIAP1Iy5ekRCXgd03QOVjRjPQ== 15:01:26 INFO - x-amz-version-id: NMN_HJfa4QwbkaNrHD6WnOXSH6qeZ705 15:01:26 INFO - Expected file size: 14402992 15:01:26 INFO - Obtained file size: 14402992 15:01:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:01:26 INFO - trying https://queue.taskcluster.net/v1/task/HuXj3xkQTjGzOQ_DiIqp9w/artifacts/public/build/firefox-52.0.en-US.mac.dmg 15:01:26 INFO - Downloading https://queue.taskcluster.net/v1/task/HuXj3xkQTjGzOQ_DiIqp9w/artifacts/public/build/firefox-52.0.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0.en-US.mac.dmg 15:01:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HuXj3xkQTjGzOQ_DiIqp9w/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 15:01:29 INFO - Downloaded 82107568 bytes. 15:01:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HuXj3xkQTjGzOQ_DiIqp9w/artifacts/public/build/firefox-52.0.en-US.mac.dmg 15:01:29 INFO - mkdir: /builds/slave/test/properties 15:01:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:01:29 INFO - Writing to file /builds/slave/test/properties/build_url 15:01:29 INFO - Contents: 15:01:29 INFO - build_url:https://queue.taskcluster.net/v1/task/HuXj3xkQTjGzOQ_DiIqp9w/artifacts/public/build/firefox-52.0.en-US.mac.dmg 15:01:29 INFO - Running post-action listener: _resource_record_post_action 15:01:29 INFO - Running post-action listener: set_extra_try_arguments 15:01:29 INFO - [mozharness: 2017-01-23 23:01:29.698499Z] Finished download-and-extract step (success) 15:01:29 INFO - [mozharness: 2017-01-23 23:01:29.698637Z] Running populate-webroot step. 15:01:29 INFO - Running pre-action listener: _resource_record_pre_action 15:01:29 INFO - Running main action method: populate_webroot 15:01:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:01:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:01:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:01:29 INFO - '--tppagecycles', 15:01:29 INFO - '7'], 15:01:29 INFO - 'tests': ['tsvgm']}}, 15:01:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:01:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:01:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:01:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:01:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:01:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:01:29 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:01:29 INFO - 'talos_options': ['--disable-e10s'], 15:01:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:01:29 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:01:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:01:29 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:01:29 INFO - 'talos_options': ['--disable-e10s'], 15:01:29 INFO - 'tests': ['damp', 'tps']}, 15:01:29 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:01:29 INFO - 'tests': ['damp', 'tps']}, 15:01:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:01:29 INFO - 'tests': ['dromaeo_dom']}, 15:01:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:01:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:01:29 INFO - 'tests': ['basic_compositor_video']}, 15:01:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:01:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:01:29 INFO - 'tests': ['a11yr', 15:01:29 INFO - 'ts_paint', 15:01:29 INFO - 'tpaint', 15:01:29 INFO - 'sessionrestore', 15:01:29 INFO - 'sessionrestore_no_auto_restore', 15:01:29 INFO - 'tabpaint']}, 15:01:29 INFO - 'other-e10s': {'tests': ['a11yr', 15:01:29 INFO - 'ts_paint', 15:01:29 INFO - 'tpaint', 15:01:29 INFO - 'sessionrestore', 15:01:29 INFO - 'sessionrestore_no_auto_restore', 15:01:29 INFO - 'tabpaint']}, 15:01:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:01:29 INFO - 'ts_paint', 15:01:29 INFO - 'tpaint', 15:01:29 INFO - 'sessionrestore', 15:01:29 INFO - 'sessionrestore_no_auto_restore', 15:01:29 INFO - 'tabpaint']}, 15:01:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:01:29 INFO - 'ts_paint', 15:01:29 INFO - 'tpaint', 15:01:29 INFO - 'sessionrestore', 15:01:29 INFO - 'sessionrestore_no_auto_restore', 15:01:29 INFO - 'tabpaint']}, 15:01:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:01:29 INFO - 'tests': ['a11yr', 15:01:29 INFO - 'ts_paint', 15:01:29 INFO - 'tpaint', 15:01:29 INFO - 'sessionrestore', 15:01:29 INFO - 'sessionrestore_no_auto_restore', 15:01:29 INFO - 'tabpaint']}, 15:01:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:01:29 INFO - 'tests': ['a11yr', 15:01:29 INFO - 'ts_paint', 15:01:29 INFO - 'tpaint', 15:01:29 INFO - 'sessionrestore', 15:01:29 INFO - 'sessionrestore_no_auto_restore', 15:01:29 INFO - 'tabpaint']}, 15:01:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:01:29 INFO - 'tests': ['tsvgx', 15:01:29 INFO - 'tsvgr_opacity', 15:01:29 INFO - 'tart', 15:01:29 INFO - 'tscrollx', 15:01:29 INFO - 'cart']}, 15:01:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:01:29 INFO - 'tsvgr_opacity', 15:01:29 INFO - 'tart', 15:01:29 INFO - 'tscrollx', 15:01:29 INFO - 'cart']}, 15:01:29 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:01:29 INFO - 'talos_options': ['--disable-e10s'], 15:01:29 INFO - 'tests': ['tp5o']}, 15:01:29 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:01:29 INFO - 'tests': ['tp5o']}, 15:01:29 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:01:29 INFO - 'talos_options': ['--disable-e10s', 15:01:29 INFO - '--xperf_path', 15:01:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:01:29 INFO - 'tests': ['tp5n']}, 15:01:29 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:01:29 INFO - 'talos_options': ['--xperf_path', 15:01:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:01:29 INFO - 'tests': ['tp5n']}}, 15:01:29 INFO - 'talos.zip': {'path': '', 15:01:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:01:29 INFO - Running post-action listener: _resource_record_post_action 15:01:29 INFO - [mozharness: 2017-01-23 23:01:29.717366Z] Finished populate-webroot step (success) 15:01:29 INFO - [mozharness: 2017-01-23 23:01:29.717473Z] Running create-virtualenv step. 15:01:29 INFO - Running pre-action listener: _resource_record_pre_action 15:01:29 INFO - Running main action method: create_virtualenv 15:01:29 INFO - Creating virtualenv /builds/slave/test/build/venv 15:01:29 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 15:01:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:01:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:01:29 INFO - Using real prefix '/tools/python27' 15:01:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:01:30 INFO - Installing distribute.............................................................................................................................................................................................done. 15:01:33 INFO - Installing pip.................done. 15:01:33 INFO - Return code: 0 15:01:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:01:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:01:33 INFO - Reading from file tmpfile_stdout 15:01:33 INFO - Output received: 15:01:33 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) 15:01:33 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:01: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')]} 15:01:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:01:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01: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')]} 15:01:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:01:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d4bf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d435570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee315e4fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2d3d30>, '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 0x10d139db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2cbb20>, '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.7a5Mahn9H7/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.e3vCdIwuUH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 15:01: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', 'pip>=1.5'] in /builds/slave/test/build 15:01: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 pip>=1.5 15:01:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7a5Mahn9H7/Render', 15:01:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:01:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:01:33 INFO - 'HOME': '/Users/cltbld', 15:01:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:01:33 INFO - 'LOGNAME': 'cltbld', 15:01:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:01:33 INFO - 'MOZ_NO_REMOTE': '1', 15:01:33 INFO - 'NO_EM_RESTART': '1', 15:01:33 INFO - 'PAGER': '/bin/cat', 15:01:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:01:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:01:33 INFO - 'PWD': '/builds/slave/test', 15:01:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:01:33 INFO - 'SHELL': '/bin/bash', 15:01:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e3vCdIwuUH/Listeners', 15:01:33 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 15:01:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:01:33 INFO - 'USER': 'cltbld', 15:01:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:01:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:01:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:01:33 INFO - 'XPC_FLAGS': '0x0', 15:01:33 INFO - 'XPC_SERVICE_NAME': '0', 15:01:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:01:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:01:33 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 15:01:33 INFO - Cleaning up... 15:01:33 INFO - Return code: 0 15:01:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:01: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')]} 15:01:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:01:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01: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')]} 15:01:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:01:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01: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', '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 0x10d4bf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d435570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee315e4fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2d3d30>, '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 0x10d139db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2cbb20>, '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.7a5Mahn9H7/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.e3vCdIwuUH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 15:01: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', 'psutil>=3.1.1'] in /builds/slave/test/build 15:01: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 psutil>=3.1.1 15:01:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7a5Mahn9H7/Render', 15:01:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:01:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:01:33 INFO - 'HOME': '/Users/cltbld', 15:01:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:01:33 INFO - 'LOGNAME': 'cltbld', 15:01:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:01:33 INFO - 'MOZ_NO_REMOTE': '1', 15:01:33 INFO - 'NO_EM_RESTART': '1', 15:01:33 INFO - 'PAGER': '/bin/cat', 15:01:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:01:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:01:33 INFO - 'PWD': '/builds/slave/test', 15:01:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:01:33 INFO - 'SHELL': '/bin/bash', 15:01:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e3vCdIwuUH/Listeners', 15:01:33 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 15:01:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:01:33 INFO - 'USER': 'cltbld', 15:01:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:01:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:01:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:01:33 INFO - 'XPC_FLAGS': '0x0', 15:01:33 INFO - 'XPC_SERVICE_NAME': '0', 15:01:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:01:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:01:34 INFO - Downloading/unpacking psutil>=3.1.1 15:01: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 15:01: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 15:01: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 15:01: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 15:01: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 15:01: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 15:01:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:01:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:01:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:01:38 INFO - Installing collected packages: psutil 15:01:38 INFO - Running setup.py install for psutil 15:01:38 INFO - building 'psutil._psutil_osx' extension 15:01:38 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 15:01:38 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 15:01:38 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 15:01:38 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 15:01:38 INFO - building 'psutil._psutil_posix' extension 15:01:38 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 15:01:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:01:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:01:38 INFO - ^ 15:01:38 INFO - 1 warning generated. 15:01:38 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 15:01:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:01:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:01:38 INFO - Successfully installed psutil 15:01:38 INFO - Cleaning up... 15:01:39 INFO - Return code: 0 15:01:39 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:01:39 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')]} 15:01:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:01:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01:39 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')]} 15:01:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:01:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01:39 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 0x10d4bf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d435570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee315e4fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2d3d30>, '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 0x10d139db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2cbb20>, '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.7a5Mahn9H7/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.e3vCdIwuUH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 15:01:39 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 15:01:39 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 15:01:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7a5Mahn9H7/Render', 15:01:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:01:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:01:39 INFO - 'HOME': '/Users/cltbld', 15:01:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:01:39 INFO - 'LOGNAME': 'cltbld', 15:01:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:01:39 INFO - 'MOZ_NO_REMOTE': '1', 15:01:39 INFO - 'NO_EM_RESTART': '1', 15:01:39 INFO - 'PAGER': '/bin/cat', 15:01:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:01:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:01:39 INFO - 'PWD': '/builds/slave/test', 15:01:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:01:39 INFO - 'SHELL': '/bin/bash', 15:01:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e3vCdIwuUH/Listeners', 15:01:39 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 15:01:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:01:39 INFO - 'USER': 'cltbld', 15:01:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:01:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:01:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:01:39 INFO - 'XPC_FLAGS': '0x0', 15:01:39 INFO - 'XPC_SERVICE_NAME': '0', 15:01:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:01:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:01:39 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:01:39 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 15:01:39 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 15:01:39 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 15:01:39 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 15:01:39 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 15:01:39 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 15:01:41 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:01:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:01:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:01:41 INFO - Installing collected packages: mozsystemmonitor 15:01:41 INFO - Running setup.py install for mozsystemmonitor 15:01:42 INFO - Successfully installed mozsystemmonitor 15:01:42 INFO - Cleaning up... 15:01:42 INFO - Return code: 0 15:01:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:01: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')]} 15:01:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:01:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01: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')]} 15:01:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:01:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d4bf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d435570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee315e4fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2d3d30>, '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 0x10d139db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2cbb20>, '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.7a5Mahn9H7/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.e3vCdIwuUH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 15:01:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:01:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:01:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7a5Mahn9H7/Render', 15:01:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:01:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:01:42 INFO - 'HOME': '/Users/cltbld', 15:01:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:01:42 INFO - 'LOGNAME': 'cltbld', 15:01:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:01:42 INFO - 'MOZ_NO_REMOTE': '1', 15:01:42 INFO - 'NO_EM_RESTART': '1', 15:01:42 INFO - 'PAGER': '/bin/cat', 15:01:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:01:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:01:42 INFO - 'PWD': '/builds/slave/test', 15:01:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:01:42 INFO - 'SHELL': '/bin/bash', 15:01:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e3vCdIwuUH/Listeners', 15:01:42 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 15:01:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:01:42 INFO - 'USER': 'cltbld', 15:01:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:01:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:01:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:01:42 INFO - 'XPC_FLAGS': '0x0', 15:01:42 INFO - 'XPC_SERVICE_NAME': '0', 15:01:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:01:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:01:42 INFO - Downloading/unpacking jsonschema==2.5.1 15:01:42 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 15:01:42 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 15:01:42 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 15:01:42 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 15:01:42 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 15:01:42 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 15:01:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:01:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:01:45 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 15:01:45 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 15:01:45 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 15:01:45 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 15:01:45 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 15:01:45 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 15:01:45 INFO - Downloading functools32-3.2.3-2.tar.gz 15:01:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:01:45 INFO - warning: no files found matching '*.txt' 15:01:45 INFO - no previously-included directories found matching 'build' 15:01:45 INFO - no previously-included directories found matching 'dist' 15:01:45 INFO - no previously-included directories found matching '.git*' 15:01:45 INFO - Installing collected packages: jsonschema, functools32 15:01:45 INFO - Running setup.py install for functools32 15:01:45 INFO - warning: no files found matching '*.txt' 15:01:45 INFO - no previously-included directories found matching 'build' 15:01:45 INFO - no previously-included directories found matching 'dist' 15:01:45 INFO - no previously-included directories found matching '.git*' 15:01:45 INFO - Successfully installed jsonschema functools32 15:01:45 INFO - Cleaning up... 15:01:45 INFO - Return code: 0 15:01:45 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:01:45 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')]} 15:01:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:01:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01:45 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')]} 15:01:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:01:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01:45 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 0x10d4bf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d435570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee315e4fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2d3d30>, '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 0x10d139db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2cbb20>, '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.7a5Mahn9H7/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.e3vCdIwuUH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 15:01:45 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 15:01:45 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 15:01:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7a5Mahn9H7/Render', 15:01:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:01:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:01:45 INFO - 'HOME': '/Users/cltbld', 15:01:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:01:45 INFO - 'LOGNAME': 'cltbld', 15:01:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:01:45 INFO - 'MOZ_NO_REMOTE': '1', 15:01:45 INFO - 'NO_EM_RESTART': '1', 15:01:45 INFO - 'PAGER': '/bin/cat', 15:01:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:01:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:01:45 INFO - 'PWD': '/builds/slave/test', 15:01:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:01:45 INFO - 'SHELL': '/bin/bash', 15:01:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e3vCdIwuUH/Listeners', 15:01:45 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 15:01:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:01:45 INFO - 'USER': 'cltbld', 15:01:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:01:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:01:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:01:45 INFO - 'XPC_FLAGS': '0x0', 15:01:45 INFO - 'XPC_SERVICE_NAME': '0', 15:01:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:01:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:01:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:01:46 INFO - Cleaning up... 15:01:46 INFO - Return code: 0 15:01:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:01:46 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')]} 15:01:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:01:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01:46 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')]} 15:01:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:01:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01:46 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 0x10d4bf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d435570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee315e4fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2d3d30>, '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 0x10d139db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2cbb20>, '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.7a5Mahn9H7/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.e3vCdIwuUH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 15:01:46 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 15:01:46 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 15:01:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7a5Mahn9H7/Render', 15:01:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:01:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:01:46 INFO - 'HOME': '/Users/cltbld', 15:01:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:01:46 INFO - 'LOGNAME': 'cltbld', 15:01:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:01:46 INFO - 'MOZ_NO_REMOTE': '1', 15:01:46 INFO - 'NO_EM_RESTART': '1', 15:01:46 INFO - 'PAGER': '/bin/cat', 15:01:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:01:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:01:46 INFO - 'PWD': '/builds/slave/test', 15:01:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:01:46 INFO - 'SHELL': '/bin/bash', 15:01:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e3vCdIwuUH/Listeners', 15:01:46 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 15:01:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:01:46 INFO - 'USER': 'cltbld', 15:01:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:01:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:01:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:01:46 INFO - 'XPC_FLAGS': '0x0', 15:01:46 INFO - 'XPC_SERVICE_NAME': '0', 15:01:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:01:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:01:46 INFO - Downloading/unpacking blobuploader==1.2.4 15:01:46 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 15:01:46 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 15:01:46 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 15:01:46 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 15:01:46 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 15:01:46 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 15:01:49 INFO - Downloading blobuploader-1.2.4.tar.gz 15:01:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:01:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:01: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 15:01: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 15:01: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 15:01: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 15:01: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 15:01: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 15:01:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:01:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:01: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 15:01: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 15:01: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 15:01: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 15:01: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 15:01: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 15:01:50 INFO - Downloading docopt-0.6.1.tar.gz 15:01:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:01:50 INFO - Installing collected packages: blobuploader, requests, docopt 15:01:50 INFO - Running setup.py install for blobuploader 15:01:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:01:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:01:50 INFO - Running setup.py install for requests 15:01:51 INFO - Running setup.py install for docopt 15:01:51 INFO - Successfully installed blobuploader requests docopt 15:01:51 INFO - Cleaning up... 15:01:51 INFO - Return code: 0 15:01:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:01:51 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')]} 15:01:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:01:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01:51 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')]} 15:01:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:01:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01:51 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 0x10d4bf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d435570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee315e4fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2d3d30>, '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 0x10d139db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2cbb20>, '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.7a5Mahn9H7/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.e3vCdIwuUH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 15:01:51 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 15:01:51 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 15:01:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7a5Mahn9H7/Render', 15:01:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:01:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:01:51 INFO - 'HOME': '/Users/cltbld', 15:01:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:01:51 INFO - 'LOGNAME': 'cltbld', 15:01:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:01:51 INFO - 'MOZ_NO_REMOTE': '1', 15:01:51 INFO - 'NO_EM_RESTART': '1', 15:01:51 INFO - 'PAGER': '/bin/cat', 15:01:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:01:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:01:51 INFO - 'PWD': '/builds/slave/test', 15:01:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:01:51 INFO - 'SHELL': '/bin/bash', 15:01:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e3vCdIwuUH/Listeners', 15:01:51 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 15:01:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:01:51 INFO - 'USER': 'cltbld', 15:01:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:01:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:01:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:01:51 INFO - 'XPC_FLAGS': '0x0', 15:01:51 INFO - 'XPC_SERVICE_NAME': '0', 15:01:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:01:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:01:51 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-zfYakd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:01:51 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-KvXpxm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:01:51 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-a4mJSF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:01:51 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-mnpw6N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:01:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:01:51 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-qntvz3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:01:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:01:52 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-gheM9a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:01:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:01:52 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-2IoqRX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:01:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:01:52 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-MRR_p5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:01:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:01:52 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-KYZrOw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:01:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:01:52 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-KTzoss-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:01:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:01:52 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-8dejNG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:01:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:01:52 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-zfwkcc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:01:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:01:52 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-m1FECv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:01:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:01:52 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-eLd3R6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:01:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:01:52 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-IGTTPa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:01:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:01:53 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-7FJ493-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:01:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:01:53 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-ZwiYpE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:01:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:01:53 INFO - Running setup.py install for manifestparser 15:01:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:01:53 INFO - Running setup.py install for mozcrash 15:01:53 INFO - Running setup.py install for mozdebug 15:01:53 INFO - Running setup.py install for mozdevice 15:01:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:01:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:01:53 INFO - Running setup.py install for mozfile 15:01:54 INFO - Running setup.py install for mozhttpd 15:01:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:01:54 INFO - Running setup.py install for mozinfo 15:01:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:01:54 INFO - Running setup.py install for mozInstall 15:01:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:01:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:01:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:01:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:01:54 INFO - Running setup.py install for mozleak 15:01:54 INFO - Running setup.py install for mozlog 15:01:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:01:54 INFO - Running setup.py install for moznetwork 15:01:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:01:54 INFO - Running setup.py install for mozprocess 15:01:55 INFO - Running setup.py install for mozprofile 15:01:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:01:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:01:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:01:55 INFO - Running setup.py install for mozrunner 15:01:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:01:55 INFO - Running setup.py install for mozscreenshot 15:01:55 INFO - Running setup.py install for moztest 15:01:55 INFO - Running setup.py install for mozversion 15:01:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:01:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:01:55 INFO - Cleaning up... 15:01:55 INFO - Return code: 0 15:01:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:01:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:01:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:01:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:01:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:01:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:01:55 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 0x10d4bf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d435570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee315e4fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2d3d30>, '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 0x10d139db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2cbb20>, '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.7a5Mahn9H7/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.e3vCdIwuUH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 15:01:55 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 15:01:55 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 15:01:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7a5Mahn9H7/Render', 15:01:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:01:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:01:55 INFO - 'HOME': '/Users/cltbld', 15:01:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:01:55 INFO - 'LOGNAME': 'cltbld', 15:01:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:01:55 INFO - 'MOZ_NO_REMOTE': '1', 15:01:55 INFO - 'NO_EM_RESTART': '1', 15:01:55 INFO - 'PAGER': '/bin/cat', 15:01:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:01:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:01:55 INFO - 'PWD': '/builds/slave/test', 15:01:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:01:55 INFO - 'SHELL': '/bin/bash', 15:01:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e3vCdIwuUH/Listeners', 15:01:55 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 15:01:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:01:55 INFO - 'USER': 'cltbld', 15:01:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:01:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:01:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:01:55 INFO - 'XPC_FLAGS': '0x0', 15:01:55 INFO - 'XPC_SERVICE_NAME': '0', 15:01:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:01:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:01:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:01:56 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-UFYcpD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:01:56 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)) 15:01:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:01:56 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-M7T1AL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:01:56 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)) 15:01:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:01:56 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-zCJ1X_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:01:56 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)) 15:01:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:01:56 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-Tr4jVn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:01:56 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)) 15:01:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:01:56 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-h7JOdx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:01:56 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)) 15:01:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:01:56 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-hG0_r0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:01:56 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)) 15:01:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:01:56 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-pHgiOm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:01:56 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)) 15:01:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:01:56 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-2FLL26-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:01:56 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)) 15:01:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:01:56 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-kbSyT3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:01:57 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)) 15:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:01:57 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-1gSrsj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:01:57 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)) 15:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:01:57 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-P9Df1U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:01:57 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)) 15:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:01:57 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-laFcsE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:01:57 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)) 15:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:01:57 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-wu48zc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:01:57 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)) 15:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:01:57 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-Nj_jEw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:01:57 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)) 15:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:01:57 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-yaHywQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:01:57 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)) 15:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:01:57 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-CuEmwx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:01:57 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)) 15:01:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:01:57 INFO - Running setup.py (path:/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/pip-oM27nK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:01:57 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)) 15:01:57 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)) 15:01:57 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)) 15:01:57 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)) 15:01:57 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)) 15:01:57 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)) 15:01:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:01:57 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 15:01:57 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 15:01:57 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 15:01:57 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 15:01:57 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 15:01:57 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 15:02:00 INFO - Downloading blessings-1.6.tar.gz 15:02:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:02:00 INFO - Installing collected packages: blessings 15:02:00 INFO - Running setup.py install for blessings 15:02:00 INFO - Successfully installed blessings 15:02:00 INFO - Cleaning up... 15:02:00 INFO - Return code: 0 15:02:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:02:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:02:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:02:01 INFO - Reading from file tmpfile_stdout 15:02:01 INFO - Current package versions: 15:02:01 INFO - blessings == 1.6 15:02:01 INFO - blobuploader == 1.2.4 15:02:01 INFO - docopt == 0.6.1 15:02:01 INFO - functools32 == 3.2.3-2 15:02:01 INFO - jsonschema == 2.5.1 15:02:01 INFO - manifestparser == 1.1 15:02:01 INFO - mozInstall == 1.12 15:02:01 INFO - mozcrash == 1.0 15:02:01 INFO - mozdebug == 0.1 15:02:01 INFO - mozdevice == 0.48 15:02:01 INFO - mozfile == 1.2 15:02:01 INFO - mozhttpd == 0.7 15:02:01 INFO - mozinfo == 0.9 15:02:01 INFO - mozleak == 0.1 15:02:01 INFO - mozlog == 3.4 15:02:01 INFO - moznetwork == 0.27 15:02:01 INFO - mozprocess == 0.23 15:02:01 INFO - mozprofile == 0.28 15:02:01 INFO - mozrunner == 6.13 15:02:01 INFO - mozscreenshot == 0.1 15:02:01 INFO - mozsystemmonitor == 0.3 15:02:01 INFO - moztest == 0.8 15:02:01 INFO - mozversion == 1.4 15:02:01 INFO - psutil == 3.1.1 15:02:01 INFO - requests == 1.2.3 15:02:01 INFO - wsgiref == 0.1.2 15:02:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:02:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:02:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:02:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:02:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:02:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:01 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 0x10d4bf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d435570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee315e4fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2d3d30>, '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 0x10d139db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2cbb20>, '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.7a5Mahn9H7/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.e3vCdIwuUH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 15:02:01 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 15:02:01 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 15:02:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7a5Mahn9H7/Render', 15:02:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:02:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:02:01 INFO - 'HOME': '/Users/cltbld', 15:02:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:02:01 INFO - 'LOGNAME': 'cltbld', 15:02:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:01 INFO - 'MOZ_NO_REMOTE': '1', 15:02:01 INFO - 'NO_EM_RESTART': '1', 15:02:01 INFO - 'PAGER': '/bin/cat', 15:02:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:02:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:01 INFO - 'PWD': '/builds/slave/test', 15:02:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:02:01 INFO - 'SHELL': '/bin/bash', 15:02:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e3vCdIwuUH/Listeners', 15:02:01 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 15:02:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:02:01 INFO - 'USER': 'cltbld', 15:02:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:02:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:02:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:01 INFO - 'XPC_FLAGS': '0x0', 15:02:01 INFO - 'XPC_SERVICE_NAME': '0', 15:02:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:02:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:02:01 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)) 15:02:01 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)) 15:02:01 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)) 15:02:01 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)) 15:02:01 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)) 15:02:01 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)) 15:02:01 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)) 15:02:01 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)) 15:02:01 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)) 15:02:01 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)) 15:02:01 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)) 15:02:01 INFO - Cleaning up... 15:02:01 INFO - Return code: 0 15:02:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:02:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:02:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:02:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:02:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:02:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:02:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d4bf490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d435570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fee315e4fe0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d2d3d30>, '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 0x10d139db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2cbb20>, '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.7a5Mahn9H7/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.e3vCdIwuUH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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 15:02:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 15:02:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 15:02:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7a5Mahn9H7/Render', 15:02:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:02:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:02:01 INFO - 'HOME': '/Users/cltbld', 15:02:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:02:01 INFO - 'LOGNAME': 'cltbld', 15:02:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:01 INFO - 'MOZ_NO_REMOTE': '1', 15:02:01 INFO - 'NO_EM_RESTART': '1', 15:02:01 INFO - 'PAGER': '/bin/cat', 15:02:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:02:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:01 INFO - 'PWD': '/builds/slave/test', 15:02:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:02:01 INFO - 'SHELL': '/bin/bash', 15:02:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e3vCdIwuUH/Listeners', 15:02:01 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 15:02:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:02:01 INFO - 'USER': 'cltbld', 15:02:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:02:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:02:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:01 INFO - 'XPC_FLAGS': '0x0', 15:02:01 INFO - 'XPC_SERVICE_NAME': '0', 15:02:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:02:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:02:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:02:01 INFO - Cleaning up... 15:02:01 INFO - Return code: 0 15:02:01 INFO - Running post-action listener: _resource_record_post_action 15:02:01 INFO - Running post-action listener: _start_resource_monitoring 15:02:01 INFO - Starting resource monitoring. 15:02:01 INFO - [mozharness: 2017-01-23 23:02:01.775462Z] Finished create-virtualenv step (success) 15:02:01 INFO - [mozharness: 2017-01-23 23:02:01.776480Z] Running install step. 15:02:01 INFO - Running pre-action listener: _resource_record_pre_action 15:02:01 INFO - Running main action method: install 15:02:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:02:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:02:02 INFO - Reading from file tmpfile_stdout 15:02:02 INFO - Detecting whether we're running mozinstall >=1.0... 15:02:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:02:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:02:02 INFO - Reading from file tmpfile_stdout 15:02:02 INFO - Output received: 15:02:02 INFO - Usage: mozinstall [options] installer 15:02:02 INFO - Options: 15:02:02 INFO - -h, --help show this help message and exit 15:02:02 INFO - -d DEST, --destination=DEST 15:02:02 INFO - Directory to install application into. [default: 15:02:02 INFO - "/builds/slave/test"] 15:02:02 INFO - --app=APP Application being installed. [default: firefox] 15:02:02 INFO - mkdir: /builds/slave/test/build/application 15:02:02 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'] 15:02:02 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 15:02:23 INFO - Reading from file tmpfile_stdout 15:02:23 INFO - Output received: 15:02:23 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 15:02:23 INFO - Running post-action listener: _resource_record_post_action 15:02:23 INFO - [mozharness: 2017-01-23 23:02:23.411533Z] Finished install step (success) 15:02:23 INFO - [mozharness: 2017-01-23 23:02:23.411664Z] Running run-tests step. 15:02:23 INFO - Running pre-action listener: _resource_record_pre_action 15:02:23 INFO - Running main action method: run_tests 15:02:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:02:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:02:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:02:23 INFO - 2017-01-23 15:02:23.426 screenresolution[1899:15023] starting screenresolution argv=screenresolution get 15:02:23 INFO - 2017-01-23 15:02:23.429 screenresolution[1899:15023] Display 0: 1600x1200x32@60 15:02:23 INFO - 2017-01-23 15:02:23.434 screenresolution[1900:15026] starting screenresolution argv=screenresolution list 15:02:23 INFO - Available Modes on Display 0 15:02:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:02:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:02:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:02:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:02:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:02:23 INFO - Intel Iris: 15:02:23 INFO - Chipset Model: Intel Iris 15:02:23 INFO - Type: GPU 15:02:23 INFO - Bus: Built-In 15:02:23 INFO - VRAM (Dynamic, Max): 1536 MB 15:02:23 INFO - Vendor: Intel (0x8086) 15:02:23 INFO - Device ID: 0x0a2e 15:02:23 INFO - Revision ID: 0x0009 15:02:23 INFO - Displays: 15:02:23 INFO - Display: 15:02:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:02:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:02:23 INFO - Display Serial Number: 1600x1200 60 15:02:23 INFO - Main Display: Yes 15:02:23 INFO - Mirror: Off 15:02:23 INFO - Online: Yes 15:02:23 INFO - Rotation: Supported 15:02:23 INFO - Return code: 0 15:02:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:02:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:02:23 INFO - Python 2.7.3 15:02:23 INFO - Return code: 0 15:02:23 INFO - grabbing minidump binary from tooltool 15:02:23 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')]} 15:02:23 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 0x10d2d3d30>, '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 0x10d139db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d2cbb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:02:23 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 15:02:23 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 15:02:23 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 15:02:23 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:02:23 INFO - Return code: 0 15:02:23 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:02:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:02:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:02:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:02:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:02:23 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/HuXj3xkQTjGzOQ_DiIqp9w/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0173', '--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 15:02:23 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/HuXj3xkQTjGzOQ_DiIqp9w/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0173 --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 15:02:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7a5Mahn9H7/Render', 15:02:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:02:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:02:23 INFO - 'HOME': '/Users/cltbld', 15:02:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:02:23 INFO - 'LOGNAME': 'cltbld', 15:02:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:02:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:02:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:02:23 INFO - 'MOZ_NO_REMOTE': '1', 15:02:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:02:23 INFO - 'NO_EM_RESTART': '1', 15:02:23 INFO - 'PAGER': '/bin/cat', 15:02:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:02:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:02:23 INFO - 'PWD': '/builds/slave/test', 15:02:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:02:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:02:23 INFO - 'SHELL': '/bin/bash', 15:02:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.e3vCdIwuUH/Listeners', 15:02:23 INFO - 'TMPDIR': '/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/', 15:02:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:02:23 INFO - 'USER': 'cltbld', 15:02:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:02:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:02:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:02:23 INFO - 'XPC_FLAGS': '0x0', 15:02:23 INFO - 'XPC_SERVICE_NAME': '0', 15:02:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:02:23 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/HuXj3xkQTjGzOQ_DiIqp9w/artifacts/public/build/firefox-52.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0173', '--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 15:02:24 INFO - mozversion application_buildid: 20170123123244 15:02:24 INFO - mozversion application_changeset: c471ca9cfe84ee531d73ff74c34961e20575d327 15:02:24 INFO - mozversion application_display_name: Firefox 15:02:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:02:24 INFO - mozversion application_name: Firefox 15:02:24 INFO - mozversion application_remotingname: firefox 15:02:24 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 15:02:24 INFO - mozversion application_vendor: Mozilla 15:02:24 INFO - mozversion application_version: 52.0 15:02:24 INFO - mozversion platform_buildid: 20170123123244 15:02:24 INFO - mozversion platform_changeset: c471ca9cfe84ee531d73ff74c34961e20575d327 15:02:24 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 15:02:24 INFO - mozversion platform_version: 52.0 15:02:24 INFO - using testdate: 1485212544 15:02:24 INFO - actual date: 1485212544 15:02:24 INFO - starting webserver on 'localhost:49365' 15:02:24 INFO - SUITE-START | Running 2 tests 15:02:24 INFO - TEST-START | dromaeo_css 15:02:24 INFO - operating with platform_type : mac_ 15:02:24 INFO - Initialising browser for dromaeo_css test... 15:02:24 INFO - TEST-INFO | started process 1910 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/tmpI6MzNo/profile http://localhost:49365/getInfo.html) 15:02:27 INFO - PROCESS | 1910 | 2017-01-23 15:02:27.182 plugin-container[1913:15161] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b3f, name = 'com.apple.tsm.portname' 15:02:27 INFO - PROCESS | 1910 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:02:27 INFO - PROCESS | 1910 | 2017-01-23 15:02:27.182 plugin-container[1913:15161] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 15:02:27 INFO - PROCESS | 1910 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:02:27 INFO - PROCESS | 1910 | 2017-01-23 15:02:27.182 plugin-container[1913:15161] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:02:27 INFO - PROCESS | 1910 | __metrics Screen width/height:1600/1200 15:02:27 INFO - PROCESS | 1910 | colorDepth:24 15:02:27 INFO - PROCESS | 1910 | Browser inner width/height: 1024/689 15:02:27 INFO - PROCESS | 1910 | __metrics 15:02:27 ERROR - PROCESS | 1910 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3181: TypeError: this.tabs is undefined 15:02:28 INFO - TEST-INFO | 1910: exit 0 15:02:28 INFO - Browser initialized. 15:02:28 INFO - Running cycle 1/1 for dromaeo_css test... 15:02:28 INFO - TEST-INFO | started process 1914 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/tmpI6MzNo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:02:30 INFO - PROCESS | 1914 | 2017-01-23 15:02:30.637 plugin-container[1915:15289] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 15:02:30 INFO - PROCESS | 1914 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:02:30 INFO - PROCESS | 1914 | 2017-01-23 15:02:30.637 plugin-container[1915:15289] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 15:02:30 INFO - PROCESS | 1914 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:02:30 INFO - PROCESS | 1914 | 2017-01-23 15:02:30.637 plugin-container[1915:15289] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:02:40 INFO - PROCESS | 1914 | RSS: Main: 178618368 15:02:40 INFO - PROCESS | 1914 | 15:05:42 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:49365/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49365/tests/dromaeo/cssquery-ext.html) 15:05:44 INFO - PROCESS | 1914 | RSS: Main: 185176064 15:05:44 INFO - PROCESS | 1914 | 15:08:45 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:49365/tests/dromaeo/cssquery-ext.html (next: http://localhost:49365/tests/dromaeo/cssquery-jquery.html) 15:08:46 INFO - PROCESS | 1914 | RSS: Main: 194146304 15:08:46 INFO - PROCESS | 1914 | 15:11:48 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:49365/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49365/tests/dromaeo/cssquery-mootools.html) 15:11:48 INFO - PROCESS | 1914 | RSS: Main: 196018176 15:11:48 INFO - PROCESS | 1914 | 15:14:50 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:49365/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49365/tests/dromaeo/cssquery-prototype.html) 15:14:51 INFO - PROCESS | 1914 | RSS: Main: 199008256 15:14:51 INFO - PROCESS | 1914 | 15:17:54 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:49365/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49365/tests/dromaeo/cssquery-yui.html) 15:17:54 INFO - PROCESS | 1914 | RSS: Main: 200335360 15:17:54 INFO - PROCESS | 1914 | 15:20:57 INFO - PROCESS | 1914 | Cycle 1(1): loaded http://localhost:49365/tests/dromaeo/cssquery-yui.html (next: http://localhost:49365/tests/dromaeo/cssquery-dojo.html) 15:20:57 INFO - PROCESS | 1914 | RSS: Main: 200609792 15:20:57 INFO - PROCESS | 1914 | 15:20:57 INFO - PROCESS | 1914 | __start_tp_report 15:20:57 INFO - PROCESS | 1914 | _x_x_mozilla_page_load 15:20:57 INFO - PROCESS | 1914 | _x_x_mozilla_page_load_details 15:20:57 INFO - PROCESS | 1914 | |i|pagename|runs| 15:20:57 INFO - PROCESS | 1914 | |0;dojo.html;3024.516077427612;3485.442329227324;3629.5100161478194;3652.086978255437;3684.299983003229;6092.908606370908;6110.266767987837;6138.600990935597;6157.199564056672;6187.535934804885;5072.442031376546;6119.3268740438625;6128.233970753656;6173.41352571505;6209.410106039919;5433.26650902129;5478.123500239963;5486.177073438997;5494.423085576017;5602;5426.75579598917;5457.645253058558;5557.861053473655;5562.916556251639;5585.162225666142;5334.3598768147685;5340.25236466895;5405.864853378657;5464.617476776627;5477.370102438231;5379.35447746273;5403.78384864605;5409.134538473864;5414.512693857571;5526.834194974157;5407.756650950717;5413.296271484682;5449.455054494559;5456.127019676833;5472.315960504937;6090.33006369299;6112.786052488142;6126.387361263883;6185.195924529782;6329.4619957303685;6320.115183874262;6365.4589359904;6371.553991220617;6399.77600783975;6450.387213214737;6513.478921686254;6515.902261466082;6532.771353002622;6537.150170477855;6551.05009773585;6053.152558641794;6123.84690382744;6135.171422439398;6166.075088736726;6218.160548325964;6668.666566671647;6691.76578819739;6705.530612857151;6723.798286051427;6731.259561448237;6361.554691171574;6369.490440764727;6375.74497020114;6378.043293506012;6379.74481020754;6182.350853160443;6328.145700330489;6378.968105159444;6483.870322593521;6523.575967562093;5092.719900405513;5288.2596436498925;6340.936590634127;6425.967870160619;6428;5646.632968857011;6124.295705993868;6401.07184458251;6405.327440618668;6434.678266086677;5129.9230511542355;5164.173732202905;5165.966806638688;5224.2947202127225;5233.293505376725;4646.86059418218;4647.86056418308;4687.086018226481;4713.481517033124;4758.571728544378;5232.816851410183;5366.8729566790635;5534.225208470817;6191.226096737908;6201.751929922752;6289.528285378524;6361.745530178741;6404.039394090831;6538.1827271591055;6558.704858281293;6160.1375807387;6208.192934918432;6217.96891015542;6229.875402491924;6235.625862448268;5713.2572765541045;5742.224799652077;5743.023685973395;5747.712614369349;5771.163181338682;6522.804315870531;6591.604503729792;6672.83317917056;6688.431483323875;6717.664116794141;6394.26465956421;6420.454261387647;6471.611703297817;6482.935170648422;6551.279359270381;6737.494687898329;6745.898811517733;6751.189857217166;6763.661816909036;6766.255711871591;6224.719887604978;6378.330275321025;6414.422701956846;6430.228372595318;6431.0031945048595;6314.684265786784;6402.807915762534;6415.71129299192;6466.062420948842;6531.444827189742;5022.824201152942;6312.179416675803;6330.8100756977365;6337.207849018872;6366.713497892513;6288.025356069817;6297.96851015742;6307.873842523216;6308.14839996613;6351.71417286214;5999.650078732425;6022.819315420545;6137.315698286874;6193.628382296896;6202.906956395748;6283.905741413974;6294.5279104068295;6308.179936608211;6308.558400912076;6309.274433440213;6187.721552530237;6191.102290167991;6201.348858369806;6205.131281620486;6208.130861679459 15:20:57 INFO - PROCESS | 1914 | |1;ext.html;444.70204962675024;530.3158924986767;541.6316904504938;542.0865841057818;544.850166204294;40044.19911601766;41819;41878.790606046925;41920.790396047974;41994.37008444876;42404.363934541005;42439;43148;43535.34696979548;43672.12655746891;9668.32321737477;9717.562709678046;9736.977617350183;9832.655857045005;9841.704748857546;9634.421934683907;9669;9723.46520941348;9762.219022478184;9768.90231097687;9723.854142187873;9748.31761776676;9749.220062395027;9806.901930980706;9815.950920245425;9676.806463870718;9721.611135554569;9727.027297270251;9745.269104817167;9765.462899540522;9720.805583888354;9751.756206094833;9788.216942644642;9794.461304628243;9833.164181044549;41470.37794433086;41749.582504174876;41798.79100604508;41849;41858.37212441785;3441.9311613767836;3512.262424890776;3619.7466177367596;3636.3091012707764;4036.4752582164133;30589.388212235852;31274.530882036786;31644;31992.520112198337;32202.194945126324;3180.3798259339437;3275.918102047444;3321.551590535271;3379.695827375548;3440;448832;471289;475121;476822;478097;63960.040599391046;64002;64025.67987160128;64172;64263;63909.36090639127;65731;65924.01113983295;66384.6680766593;66567.66716166388;16954.406595769087;16960.236789344508;17218.741718874226;17235.74146387805;17238.482845514656;12890.548830791065;13051.738965220642;13113.67215819593;13126.409311581001;13155;17303;17913.19390627425;18061.548461288574;18149.637007260044;18169.36407225741;6386.616802991835;6397.200349956255;6412.67936603168;6423.74305027803;6490.091387205795;887.4586502233632;940.3078637598335;984.5274268351232;1012.4128005756644;1039.8180318444392;27987.160385188476;29852.253693657833;31136.377272454425;31396;31418.842905785325;999.7003894936554;1059.6979860739652;1119.7424592343807;1155.7226265696172;1181.645506348092;15656.295466704025;16162.676746465006;16196.595085122966;16267.674646507003;16556.42052528156;646726;659759;660448;660908;661446;61053.38946610566;61060.69469652623;61104.38895611076;61121;61190.38809611936;1389.8679625435568;1421.4172189402295;1423.729491396652;1425.4796999095256;1430.4349781836117;30728.69271307258;30805.8459707709;31287.061388158392;31332;31428.52857207098;43440;54319;58330;58366.12450813326;61990;578987;583865;586604;587443;592421;27147.1855844325;27606.585901211096;27635.44729105447;27638.170854874385;27671.72328276772;49112.508874910796;49183.75408122937;49225;49507.75246123746;49719.751401242764;40246.79876600598;40887.1822563553;41133.58866411298;42053.579464204966;42143.157136857706;40382.79808600938;40601.39097913475;40845.183096337314;41958;42009.73970780882;38990.830275091794;40046.199076017736;40266;40335.3949690749;40342;34707;35782.46326305055;36089.27821443504;36453.384093486486;36864.63135368612;26942.32644183911;26957.59563606508;27025.45949080968;27073.864630676722;27143.04999325014 15:20:57 INFO - PROCESS | 1914 | |2;jquery.html;337.0411180192353;385.60090306532743;394.7256656623647;403.6952101163621;414.21506245664466;19092.809071909276;20242.89878550605;21036;21179;21202.78797212029;11848.822267665992;12354.876451235485;12373.938130309358;12442.377881105955;20492.69260961087;5351.812686555972;5624.0439125348585;5629.60592758507;5649.49154576088;5655.773769049254;5492.917606235909;5823.563232757539;6146;6188.47397971173;6213.161223234873;5562.415946325638;5988.2215312009375;6135.815925522219;6188.659623720693;6206.286277078126;6770.52606317558;6890.104286442781;6925.688344024531;6959.791206263795;6979.197392299849;5275.788968441258;5522.861928451781;5601.29983752031;6158.199434073586;6162;13521.729565408637;13542.187468751907;13745.862541374658;18696.158672859754;25252.747472525407;2252.3468194223656;2258.954820903573;2328.2898715891647;2575.2531765788085;2816.464871674396;10631.521581528772;12367;14642;15289.464868729652;16217.189140542925;1930.951726206842;1978.3867001229664;2032.2175962254491;2048.8873111978837;2050.1184490669007;121280.75517356939;122601.38699306536;125858;127904;129840;11660;11825.586104486301;11826.822597661041;11904.166708330507;12315.4458049388;14889.47886823956;15038.473653422077;15215.934884557975;15241.390344386322;15273.465428709942;8582.54096803545;8756.8686469703;8856.955715221382;8987.910120898707;8990.28077753778;8680.479171249744;8713.782155446037;8763.56182190888;8873.378863479624;8898.243649289754;4625.444946606362;4638.652101092432;4696.5303469653445;4762.618990480753;4781.474037855833;1984.4542750743371;2041.8672786268844;2058.989705051465;2066.3697572240612;2085.384811480617;857.3655494933829;912.9087091290818;938.4134915677702;983.306768728045;994.4331730913455;20699.689504657443;21564;21944.780552194592;22047.11811527521;22069.33791986243;954.613381580461;965.2615748952057;970.5632465390603;1010.9797804043878;1028.0541901450683;11029.83455248172;11127.554897804159;11998.280103193836;12012.279263244232;12329.273901653776;615624;620395;620819.8959005267;631578;634359;125886.74113258933;128641.3567932173;139550;139805.31070791002;141042.4739381503;1024.8513965475113;1039.6776999130293;1051.4374809477047;1052.894710528941;1058.454895728685;18607.97656128926;19398;19712;19754.308599198957;19775.011249437182;56525.86948261094;57533.71233143807;58051.4194858046;58898.411015891004;59746.701266493386;80366;81741.77387339313;82664;83442.16557834344;83620.58189709013;7158.176809666955;7245.492815502864;7662.616869156632;7857.135715071452;8020.398470114881;11675.649730508097;13331.66670833237;13499.527516536875;13588.93205533966;13595.32023398846;9924.801503970024;9967.800643986935;11380;11533.769324613628;11821.881781182077;9804.460754658277;11327.943360283145;11443.942780286045;11696.181267311207;11777;11633.41832908368;11664.6500604982;11675.474603642686;11754.941225293818;11986.520539178684;6599;7542.509736867086;7874.56689882062;7912.36701063925;7918.683252670059;11300.604478843201;11486.695159249748;11834;11884.108691847972;11925.4037298133 15:20:57 INFO - PROCESS | 1914 | |3;mootools.html;1477;1515.7650564162552;1733.1767410480024;1866.5240363707253;1893.1953919584173;7715.768526944201;7918.129005809371;8140.837183256332;8611.44025638334;8612.267957223636;8240.7939801505;8439.70461033864;8522.701705440311;8537.615807288672;8560.914390856089;2394.5210957808463;2424.103081859714;2439.7926176275037;2442.7312995570474;2446.290575733044;2424.1394305021727;2455.938601534967;2468.90124395024;2474.5793215153376;2481.913133040344;2403.9519209615796;2419.358869899478;2425.0542288507495;2432.963505547418;2442.5481285962064;2386.53462574798;2395.137750409851;2413.51729654069;2415.973211385172;2419.903203871843;2414.9275521734376;2429.829911906167;2438.2319569335605;2439.512097580491;2444.755524447559;8348.791280217982;8498.235158835672;8501.447405918596;8507.319414446829;8508.574571271412;5203.973980130075;5363.249145119687;5371.650842695212;5374.5700343972385;5375.408705042481;3505.1061979195397;3582.0865679251883;3597.5862775780597;3610.656987586175;3625.6374362563797;1053.4785281285735;1078.9892101079047;1093.1637297467444;1098.4288170151556;1107.1364335818075;10918.399488028155;10940.726481838017;11025.558977640885;11030.338179709162;11076.280041797258;3841.4814000110186;4498.302763071729;4672.392588963413;4770.522947705271;4848.7090774553635;9576.569054392568;9611.663591774255;9644.469554174588;9664.371815831948;9667.758306042406;5269.815556455505;5314.681119132864;5355.464453554691;5367.060764366221;5378.2470454136455;4431.711938723973;4464.843730469418;4875.219964805685;4910.705357678551;4943;3408.642092580293;3994.1412596291357;4094.6314831665295;4117.814698338582;4127.814248358831;1308.4112149532748;1309.4238535044549;1312.934353282332;1313.1005261395783;1322.312397553267;798.944073914832;842.8567143585606;866.8569686001865;924.089771574998;941.7174847545709;5826.650400975955;5867.794627188028;5892.234009578728;5926.607247296968;6088.299845517734;938.3009657804843;947.6020071569957;954.9570269337896;961.6153538584482;1019.6635110413694;3520.542329497149;3612.9277414451562;3670.1925576373155;3903.906906066306;3930.0174956260935;9926.354786938826;10136.239782016379;10224.59101635941;10248.077673009466;10253.692389228336;9967.651132210338;9973.900260997443;10125.443100629536;10168.745781355525;10216.693499195037;6981.2669669684965;7624.618769061524;7860.135385107634;7966.522008679498;7967.721129760431;5552.888942221052;5751;5756.251687280626;5857.765689372377;5895.587308888336;9057;9158.816823663356;9199.586018629045;9465.148136667733;9480.383775054599;9421.293402994666;9467.66863159786;9495.43027418357;9522.761930951781;9571.37786043905;6693.564918280491;6912.654367281716;7061.894071589033;7242.167150777517;7341.4126869849215;7816.57008864518;8075.878861817195;8084.959575202086;8087.757367278991;8090.6359213836695;6676.732930682912;7074.787756367317;7237.312455316736;7250.60121693312;7267.454940879561;6979.057827193467;7156.3917067047805;7172.426205903619;7199.496035277481;7295.270472952662;6626.469882409492;6754.324567543403;6778.542613338156;6835.07726456922;6839.1451068616425;4820.68665536739;4902.534259245366;4918.2868484068895;4960.727160006234;4986.675866068694;4203.47456567929;4912.8771780705765;4952.331435256338;5034.0686972910125;5048.444671086251 15:20:57 INFO - PROCESS | 1914 | |4;prototype.html;315.0815373187161;315.48103369956425;321.5257495194588;327.5054667452147;327.8131465064914;5338;5495.505404513603;5533;5614.838287259404;5674.120511320752;5282.385219316434;5388.946110538894;5458.2904222451025;5471.917921231185;5533.335999680034;4223.61987421132;4231.4710661167355;4234.814252009427;4274.63211772233;4277.657787377002;3501.072215862798;3628.328759179547;3663.5970043295215;3717.368047431943;4285.250081235787;3283.8029718216985;3905.3360928642055;4201.256478561393;4211.894702632443;4223;3694.741368104234;4203.096334288112;4217.882261200784;4222.641075508585;4231.8730438086595;3720.962790372089;3731.7574357666663;3736.346139425617;3740.5698344690163;3744.55065392152;3986.8205930732897;4594.74728889911;4795.304680821261;4923.926141107886;5350.228089197107;4201.424465825316;4754.263089221176;4758.762061896891;4764.976175119103;4771.308160316735;5755.107958266475;5771.653700777967;5790.478856902857;5801.129830525413;5829;3197.2486465680545;3685.6130106338715;3896.649301562873;3968.6428221460205;3971.364581666948;6030.276366835964;6041.939580604182;6054.939450605526;6063.545234107415;6106.847328816815;3535.2399234164263;3605;4029.576894426043;4061.7766022868436;4063.2686116499312;3294.5552350432386;3636.5272064813794;3818.1790914953417;3929.960700393017;4013.3779264214095;4684.953150468452;4739.881502962385;4741.596964258077;4754.643401744883;4756.6432517561325;4885.5358740919555;5802.33273173582;5847.532197424196;5864.384239654775;5880.676562789002;1132.4394424759632;1179.5930404010603;1216.6471723200173;1273.2106094221642;1286.2089814763854;870.5516658920661;892.4511425473287;896.7221709064557;900.5722281916169;911.8874972533498;4629.629629629621;5228.215767634885;5231.843044708665;5271.420143784258;5304.257403963448;5441.211024401439;5669.489745922887;5709.571782116358;5739.885202295931;5761.793890459988;4409.603135717801;4968.925466118011;5209.4009188943755;5248.711320877388;5323.361196656426;2540.61890716394;2668.9849163881536;2941.0147600554055;2956.63042119735;2995;5478.643888147258;5645.745941432645;5822.301323841166;5891.793787217427;5900.439458251545;4180.512595005966;4970.061675946384;5059.696418214845;5588.39548491229;5679.261695979496;5004.299398084271;5045.949540504548;5563.332400112013;5638.295213098363;5841;4777.63837306366;5047.510984259578;5141.948580514147;5626.831195064155;5676.637606974379;4470.664700147437;5193.689237932432;5999.5800293979;6090.421409966046;6111.327753947151;5875.970620146872;6055.304514735881;6259.280182778856;6328.702615963808;6366.777162799465;3802.467654528313;4000.3599424092004;4093.5906409358827;4119.691023173334;4147.647449966787;5451.827857010761;5788.971055144697;5789.768409263583;5963.0459126539545;6057.7879774207695;5320.255164276927;5379.354477462573;5679.586175875319;5909.552159720896;5945.524358051432;4684.9313699526865;5208.401033881152;5576.386597474356;5899.292084949833;5947.858770842579;4852.51474852526;5385.515303622693;5751.539876809761;5796.681182535001;5831.504322132666;5334.839954801362;5909.8227053188475;5960.403959604005;5990.9700451497465;6029.517638588814;4498.730076195438;5553.916691249715;5815.185873977731;5835.328937172109;5854.970725146347 15:20:57 INFO - PROCESS | 1914 | |5;yui.html;668.7692746002631;741.1624863903788;790.2966359939653;809.348474478045;829.8049958259808;14801.11193328401;15231.923840380783;15291.464798732046;15343.69312613747;15718.371265149395;14170.50403235887;15014.474493392734;15546.53360399187;15574;15997.200139993012;691.2603514239765;709.4289097276691;867.3451544084239;867.4578388507167;896.1665650944619;868.0754995929346;891.3359547137392;892.6830975003845;893.271983333581;893.5041052216018;906.4108329585756;915.4919019943928;943.7451887990238;949.7957939043139;953.461294368683;918.4994178172911;932.0213775535694;943.89617142115;949.2975198353238;953.5661274120293;674.6019848289533;675.7128220506314;676.8646270745821;708.4438715608222;710.9111361079865;15300.846991530165;15456.768148477782;15527.301271442811;15634.2182890855;15767.763483547642;14249.216293103875;14548.927255363762;14591.70816583673;14628.561143165616;14629.92685036568;11798.469068891834;11847.229930054518;11954.58158964432;11962.82055769164;11985.28088314704;3559.857605695769;3812.3519001769764;3862.5558060822805;3862.8454861805494;3876.3991581304945;109663.90336096617;111212;111470;113096.43451782688;113899;9985.30102892805;10450;10662.200334974752;10876.61931832398;10960.28758130719;21487.57024859494;21497.892510537346;21583.56832863334;21784.673229901564;21804.23685171043;7553.395728341719;7718.151003389623;7757.883631745528;7801.29788319054;7841.882371764428;4313.375453153783;4406.471223453206;4454.354118652842;4548.067646132535;4548.953740639605;3393.3043726036103;3471.548698669207;3504.667056629616;3506.982465087658;3515.876944306937;627.1815281058172;636.4494712074115;650.9349065093547;657.1555551116792;708.0653537330674;586.633354153654;589.8024161905723;616.9722362493699;633.2116514938839;641.2113100885937;7571.141505153378;7830.6084695764985;8811.647534098563;9128.90871091294;9239.676611318571;594.8750762339957;629.6820105846518;670.9261981182066;698.448225901532;699.9090118284693;4422.756748378806;4541.954580454153;4599.356090147382;4610.677252592353;4661.766911654404;100288;106555;112641;128236;132362;76521.6173919138;78986.81519777208;97562.51218743861;102625.40811071577;105228;90056;93086.99999999865;94425.44272350273;95242;98222;8497.532635705096;9508.952455237957;9889.25830562725;9943.30396872241;10011.699649010541;43928.12143757043;51816.704582384235;54376.18435723546;56661;59669.403305968124;78745;80372;81996;82637;83788.16211837804;12243.265404075784;12362.690932726753;12395.690107747378;12563.87436125627;12689.111762176559;13791.931040344734;13983.951203659562;14112.435502579781;14472.27638618086;14601.12393256408;12891.677708057374;13426.530071447452;14275.785863211853;14320.928395357956;14515.201663908587;13073.869261307265;13652.658683532594;14273.929455290687;14284.35720392564;14293.785593216318;13060.738785224052;13395.464181432633;13554.390052447467;13742.725145496834;13841.377138029015;9360.859587106335;11239.775204496027;11360.1479889007;11489.655310340702;11844.76310473803;10683.675304177597;10735;10781.245312828027;11446.02708769764;11471.827922581335 15:20:57 INFO - PROCESS | 1914 | __end_tp_report 15:20:57 INFO - PROCESS | 1914 | __start_cc_report 15:20:57 INFO - PROCESS | 1914 | _x_x_mozilla_cycle_collect,360 15:20:57 INFO - PROCESS | 1914 | __end_cc_report 15:20:57 INFO - PROCESS | 1914 | __startTimestamp1485213657693__endTimestamp 15:20:57 INFO - PROCESS | 1914 | 15:20:57 INFO - PROCESS | 1914 | ------- Summary: start ------- 15:20:57 INFO - PROCESS | 1914 | Number of tests: 6 15:20:57 INFO - PROCESS | 1914 | 15:20:57 INFO - PROCESS | 1914 | [#0] dojo.html Cycles:165 Average:5977.93 Median:6205.13 stddev:671.92 (10.8%) stddev-sans-first:632.78 15:20:57 INFO - PROCESS | 1914 | Values: 3024.5 3485.4 3629.5 3652.1 3684.3 6092.9 6110.3 6138.6 6157.2 6187.5 5072.4 6119.3 6128.2 6173.4 6209.4 5433.3 5478.1 5486.2 5494.4 5602.0 5426.8 5457.6 5557.9 5562.9 5585.2 5334.4 5340.3 5405.9 5464.6 5477.4 5379.4 5403.8 5409.1 5414.5 5526.8 5407.8 5413.3 5449.5 5456.1 5472.3 6090.3 6112.8 6126.4 6185.2 6329.5 6320.1 6365.5 6371.6 6399.8 6450.4 6513.5 6515.9 6532.8 6537.2 6551.1 6053.2 6123.8 6135.2 6166.1 6218.2 6668.7 6691.8 6705.5 6723.8 6731.3 6361.6 6369.5 6375.7 6378.0 6379.7 6182.4 6328.1 6379.0 6483.9 6523.6 5092.7 5288.3 6340.9 6426.0 6428.0 5646.6 6124.3 6401.1 6405.3 6434.7 5129.9 5164.2 5166.0 5224.3 5233.3 4646.9 4647.9 4687.1 4713.5 4758.6 5232.8 5366.9 5534.2 6191.2 6201.8 6289.5 6361.7 6404.0 6538.2 6558.7 6160.1 6208.2 6218.0 6229.9 6235.6 5713.3 5742.2 5743.0 5747.7 5771.2 6522.8 6591.6 6672.8 6688.4 6717.7 6394.3 6420.5 6471.6 6482.9 6551.3 6737.5 6745.9 6751.2 6763.7 6766.3 6224.7 6378.3 6414.4 6430.2 6431.0 6314.7 6402.8 6415.7 6466.1 6531.4 5022.8 6312.2 6330.8 6337.2 6366.7 6288.0 6298.0 6307.9 6308.1 6351.7 5999.7 6022.8 6137.3 6193.6 6202.9 6283.9 6294.5 6308.2 6308.6 6309.3 6187.7 6191.1 6201.3 6205.1 6208.1 15:20:57 INFO - PROCESS | 1914 | 15:20:57 INFO - PROCESS | 1914 | [#1] ext.html Cycles:180 Average:71445.58 Median:41939.40 stddev:153963.13 (367.1%) stddev-sans-first:154302.75 15:20:57 INFO - PROCESS | 1914 | Values: 444.7 530.3 541.6 542.1 544.9 40044.2 41819.0 41878.8 41920.8 41994.4 42404.4 42439.0 43148.0 43535.3 43672.1 9668.3 9717.6 9737.0 9832.7 9841.7 9634.4 9669.0 9723.5 9762.2 9768.9 9723.9 9748.3 9749.2 9806.9 9816.0 9676.8 9721.6 9727.0 9745.3 9765.5 9720.8 9751.8 9788.2 9794.5 9833.2 41470.4 41749.6 41798.8 41849.0 41858.4 3441.9 3512.3 3619.7 3636.3 4036.5 30589.4 31274.5 31644.0 31992.5 32202.2 3180.4 3275.9 3321.6 3379.7 3440.0 448832.0 471289.0 475121.0 476822.0 478097.0 63960.0 64002.0 64025.7 64172.0 64263.0 63909.4 65731.0 65924.0 66384.7 66567.7 16954.4 16960.2 17218.7 17235.7 17238.5 12890.5 13051.7 13113.7 13126.4 13155.0 17303.0 17913.2 18061.5 18149.6 18169.4 6386.6 6397.2 6412.7 6423.7 6490.1 887.5 940.3 984.5 1012.4 1039.8 27987.2 29852.3 31136.4 31396.0 31418.8 999.7 1059.7 1119.7 1155.7 1181.6 15656.3 16162.7 16196.6 16267.7 16556.4 646726.0 659759.0 660448.0 660908.0 661446.0 61053.4 61060.7 61104.4 61121.0 61190.4 1389.9 1421.4 1423.7 1425.5 1430.4 30728.7 30805.8 31287.1 31332.0 31428.5 43440.0 54319.0 58330.0 58366.1 61990.0 578987.0 583865.0 586604.0 587443.0 592421.0 27147.2 27606.6 27635.4 27638.2 27671.7 49112.5 49183.8 49225.0 49507.8 49719.8 40246.8 40887.2 41133.6 42053.6 42143.2 40382.8 40601.4 40845.2 41958.0 42009.7 38990.8 40046.2 40266.0 40335.4 40342.0 34707.0 35782.5 36089.3 36453.4 36864.6 26942.3 26957.6 27025.5 27073.9 27143.0 15:20:57 INFO - PROCESS | 1914 | 15:20:57 INFO - PROCESS | 1914 | [#2] jquery.html Cycles:180 Average:36302.85 Median:21995.95 stddev:104446.02 (474.8%) stddev-sans-first:104704.10 15:20:57 INFO - PROCESS | 1914 | Values: 337.0 385.6 394.7 403.7 414.2 19092.8 20242.9 21036.0 21179.0 21202.8 11848.8 12354.9 12373.9 12442.4 20492.7 5351.8 5624.0 5629.6 5649.5 5655.8 5492.9 5823.6 6146.0 6188.5 6213.2 5562.4 5988.2 6135.8 6188.7 6206.3 6770.5 6890.1 6925.7 6959.8 6979.2 5275.8 5522.9 5601.3 6158.2 6162.0 13521.7 13542.2 13745.9 18696.2 25252.7 2252.3 2259.0 2328.3 2575.3 2816.5 10631.5 12367.0 14642.0 15289.5 16217.2 1931.0 1978.4 2032.2 2048.9 2050.1 121280.8 122601.4 125858.0 127904.0 129840.0 11660.0 11825.6 11826.8 11904.2 12315.4 14889.5 15038.5 15215.9 15241.4 15273.5 8582.5 8756.9 8857.0 8987.9 8990.3 8680.5 8713.8 8763.6 8873.4 8898.2 4625.4 4638.7 4696.5 4762.6 4781.5 1984.5 2041.9 2059.0 2066.4 2085.4 857.4 912.9 938.4 983.3 994.4 20699.7 21564.0 21944.8 22047.1 22069.3 954.6 965.3 970.6 1011.0 1028.1 11029.8 11127.6 11998.3 12012.3 12329.3 615624.0 620395.0 620819.9 631578.0 634359.0 125886.7 128641.4 139550.0 139805.3 141042.5 1024.9 1039.7 1051.4 1052.9 1058.5 18608.0 19398.0 19712.0 19754.3 19775.0 56525.9 57533.7 58051.4 58898.4 59746.7 80366.0 81741.8 82664.0 83442.2 83620.6 7158.2 7245.5 7662.6 7857.1 8020.4 11675.6 13331.7 13499.5 13588.9 13595.3 9924.8 9967.8 11380.0 11533.8 11821.9 9804.5 11327.9 11443.9 11696.2 11777.0 11633.4 11664.7 11675.5 11754.9 11986.5 6599.0 7542.5 7874.6 7912.4 7918.7 11300.6 11486.7 11834.0 11884.1 11925.4 15:20:57 INFO - PROCESS | 1914 | 15:20:57 INFO - PROCESS | 1914 | [#3] mootools.html Cycles:180 Average:5535.53 Median:5335.07 stddev:2984.72 (55.9%) stddev-sans-first:2977.50 15:20:57 INFO - PROCESS | 1914 | Values: 1477.0 1515.8 1733.2 1866.5 1893.2 7715.8 7918.1 8140.8 8611.4 8612.3 8240.8 8439.7 8522.7 8537.6 8560.9 2394.5 2424.1 2439.8 2442.7 2446.3 2424.1 2455.9 2468.9 2474.6 2481.9 2404.0 2419.4 2425.1 2433.0 2442.5 2386.5 2395.1 2413.5 2416.0 2419.9 2414.9 2429.8 2438.2 2439.5 2444.8 8348.8 8498.2 8501.4 8507.3 8508.6 5204.0 5363.2 5371.7 5374.6 5375.4 3505.1 3582.1 3597.6 3610.7 3625.6 1053.5 1079.0 1093.2 1098.4 1107.1 10918.4 10940.7 11025.6 11030.3 11076.3 3841.5 4498.3 4672.4 4770.5 4848.7 9576.6 9611.7 9644.5 9664.4 9667.8 5269.8 5314.7 5355.5 5367.1 5378.2 4431.7 4464.8 4875.2 4910.7 4943.0 3408.6 3994.1 4094.6 4117.8 4127.8 1308.4 1309.4 1312.9 1313.1 1322.3 798.9 842.9 866.9 924.1 941.7 5826.7 5867.8 5892.2 5926.6 6088.3 938.3 947.6 955.0 961.6 1019.7 3520.5 3612.9 3670.2 3903.9 3930.0 9926.4 10136.2 10224.6 10248.1 10253.7 9967.7 9973.9 10125.4 10168.7 10216.7 6981.3 7624.6 7860.1 7966.5 7967.7 5552.9 5751.0 5756.3 5857.8 5895.6 9057.0 9158.8 9199.6 9465.1 9480.4 9421.3 9467.7 9495.4 9522.8 9571.4 6693.6 6912.7 7061.9 7242.2 7341.4 7816.6 8075.9 8085.0 8087.8 8090.6 6676.7 7074.8 7237.3 7250.6 7267.5 6979.1 7156.4 7172.4 7199.5 7295.3 6626.5 6754.3 6778.5 6835.1 6839.1 4820.7 4902.5 4918.3 4960.7 4986.7 4203.5 4912.9 4952.3 5034.1 5048.4 15:20:57 INFO - PROCESS | 1914 | 15:20:57 INFO - PROCESS | 1914 | [#4] prototype.html Cycles:180 Average:4609.04 Median:5201.05 stddev:1443.71 (27.8%) stddev-sans-first:1411.32 15:20:57 INFO - PROCESS | 1914 | Values: 315.1 315.5 321.5 327.5 327.8 5338.0 5495.5 5533.0 5614.8 5674.1 5282.4 5388.9 5458.3 5471.9 5533.3 4223.6 4231.5 4234.8 4274.6 4277.7 3501.1 3628.3 3663.6 3717.4 4285.3 3283.8 3905.3 4201.3 4211.9 4223.0 3694.7 4203.1 4217.9 4222.6 4231.9 3721.0 3731.8 3736.3 3740.6 3744.6 3986.8 4594.7 4795.3 4923.9 5350.2 4201.4 4754.3 4758.8 4765.0 4771.3 5755.1 5771.7 5790.5 5801.1 5829.0 3197.2 3685.6 3896.6 3968.6 3971.4 6030.3 6041.9 6054.9 6063.5 6106.8 3535.2 3605.0 4029.6 4061.8 4063.3 3294.6 3636.5 3818.2 3930.0 4013.4 4685.0 4739.9 4741.6 4754.6 4756.6 4885.5 5802.3 5847.5 5864.4 5880.7 1132.4 1179.6 1216.6 1273.2 1286.2 870.6 892.5 896.7 900.6 911.9 4629.6 5228.2 5231.8 5271.4 5304.3 5441.2 5669.5 5709.6 5739.9 5761.8 4409.6 4968.9 5209.4 5248.7 5323.4 2540.6 2669.0 2941.0 2956.6 2995.0 5478.6 5645.7 5822.3 5891.8 5900.4 4180.5 4970.1 5059.7 5588.4 5679.3 5004.3 5045.9 5563.3 5638.3 5841.0 4777.6 5047.5 5141.9 5626.8 5676.6 4470.7 5193.7 5999.6 6090.4 6111.3 5876.0 6055.3 6259.3 6328.7 6366.8 3802.5 4000.4 4093.6 4119.7 4147.6 5451.8 5789.0 5789.8 5963.0 6057.8 5320.3 5379.4 5679.6 5909.6 5945.5 4684.9 5208.4 5576.4 5899.3 5947.9 4852.5 5385.5 5751.5 5796.7 5831.5 5334.8 5909.8 5960.4 5991.0 6029.5 4498.7 5553.9 5815.2 5835.3 5855.0 15:20:57 INFO - PROCESS | 1914 | 15:20:57 INFO - PROCESS | 1914 | [#5] yui.html Cycles:180 Average:22025.62 Median:4094.89 stddev:32927.70 (804.1%) stddev-sans-first:32981.02 15:20:57 INFO - PROCESS | 1914 | Values: 668.8 741.2 790.3 809.3 829.8 14801.1 15231.9 15291.5 15343.7 15718.4 14170.5 15014.5 15546.5 15574.0 15997.2 691.3 709.4 867.3 867.5 896.2 868.1 891.3 892.7 893.3 893.5 906.4 915.5 943.7 949.8 953.5 918.5 932.0 943.9 949.3 953.6 674.6 675.7 676.9 708.4 710.9 15300.8 15456.8 15527.3 15634.2 15767.8 14249.2 14548.9 14591.7 14628.6 14629.9 11798.5 11847.2 11954.6 11962.8 11985.3 3559.9 3812.4 3862.6 3862.8 3876.4 109663.9 111212.0 111470.0 113096.4 113899.0 9985.3 10450.0 10662.2 10876.6 10960.3 21487.6 21497.9 21583.6 21784.7 21804.2 7553.4 7718.2 7757.9 7801.3 7841.9 4313.4 4406.5 4454.4 4548.1 4549.0 3393.3 3471.5 3504.7 3507.0 3515.9 627.2 636.4 650.9 657.2 708.1 586.6 589.8 617.0 633.2 641.2 7571.1 7830.6 8811.6 9128.9 9239.7 594.9 629.7 670.9 698.4 699.9 4422.8 4542.0 4599.4 4610.7 4661.8 100288.0 106555.0 112641.0 128236.0 132362.0 76521.6 78986.8 97562.5 102625.4 105228.0 90056.0 93087.0 94425.4 95242.0 98222.0 8497.5 9509.0 9889.3 9943.3 10011.7 43928.1 51816.7 54376.2 56661.0 59669.4 78745.0 80372.0 81996.0 82637.0 83788.2 12243.3 12362.7 12395.7 12563.9 12689.1 13791.9 13984.0 14112.4 14472.3 14601.1 12891.7 13426.5 14275.8 14320.9 14515.2 13073.9 13652.7 14273.9 14284.4 14293.8 13060.7 13395.5 13554.4 13742.7 13841.4 9360.9 11239.8 11360.1 11489.7 11844.8 10683.7 10735.0 10781.2 11446.0 11471.8 15:20:57 INFO - PROCESS | 1914 | -------- Summary: end -------- 15:20:57 INFO - PROCESS | 1914 | 15:20:58 INFO - TEST-INFO | 1914: exit 0 15:20:58 INFO - TEST-OK | dromaeo_css | took 1114327ms 15:20:58 INFO - TEST-START | kraken 15:20:58 INFO - operating with platform_type : mac_ 15:20:58 INFO - Initialising browser for kraken test... 15:20:58 INFO - TEST-INFO | started process 1920 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/tmp5zPpD2/profile http://localhost:49365/getInfo.html) 15:21:01 INFO - PROCESS | 1920 | 2017-01-23 15:21:01.125 plugin-container[1922:17396] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8543, name = 'com.apple.tsm.portname' 15:21:01 INFO - PROCESS | 1920 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:21:01 INFO - PROCESS | 1920 | 2017-01-23 15:21:01.126 plugin-container[1922:17396] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4607, name = 'com.apple.CFPasteboardClient' 15:21:01 INFO - PROCESS | 1920 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:21:01 INFO - PROCESS | 1920 | 2017-01-23 15:21:01.126 plugin-container[1922:17396] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:21:01 INFO - PROCESS | 1920 | __metrics Screen width/height:1600/1200 15:21:01 INFO - PROCESS | 1920 | colorDepth:24 15:21:01 INFO - PROCESS | 1920 | Browser inner width/height: 1024/689 15:21:01 INFO - PROCESS | 1920 | __metrics 15:21:01 ERROR - PROCESS | 1920 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3181: TypeError: this.tabs is undefined 15:21:02 INFO - TEST-INFO | 1920: exit 0 15:21:02 INFO - Browser initialized. 15:21:02 INFO - Running cycle 1/1 for kraken test... 15:21:02 INFO - TEST-INFO | started process 1923 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/T/tmp5zPpD2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:21:04 INFO - PROCESS | 1923 | 2017-01-23 15:21:04.504 plugin-container[1924:17520] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 15:21:04 INFO - PROCESS | 1923 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:21:04 INFO - PROCESS | 1923 | 2017-01-23 15:21:04.505 plugin-container[1924:17520] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461b, name = 'com.apple.CFPasteboardClient' 15:21:04 INFO - PROCESS | 1923 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:21:04 INFO - PROCESS | 1923 | 2017-01-23 15:21:04.505 plugin-container[1924:17520] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:21:14 INFO - PROCESS | 1923 | RSS: Main: 178565120 15:21:14 INFO - PROCESS | 1923 | 15:21:17 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:49365/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49365/tests/kraken/driver.html?testName=audio-beat-detection) 15:21:18 INFO - PROCESS | 1923 | RSS: Main: 190656512 15:21:18 INFO - PROCESS | 1923 | 15:21:20 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:49365/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49365/tests/kraken/driver.html?testName=audio-dft) 15:21:21 INFO - PROCESS | 1923 | RSS: Main: 198729728 15:21:21 INFO - PROCESS | 1923 | 15:21:24 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:49365/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49365/tests/kraken/driver.html?testName=audio-fft) 15:21:24 INFO - PROCESS | 1923 | RSS: Main: 202502144 15:21:24 INFO - PROCESS | 1923 | 15:21:26 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:49365/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49365/tests/kraken/driver.html?testName=audio-oscillator) 15:21:27 INFO - PROCESS | 1923 | RSS: Main: 202768384 15:21:27 INFO - PROCESS | 1923 | 15:21:29 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:49365/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49365/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:21:30 INFO - PROCESS | 1923 | RSS: Main: 204251136 15:21:30 INFO - PROCESS | 1923 | 15:21:32 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:49365/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49365/tests/kraken/driver.html?testName=imaging-darkroom) 15:21:33 INFO - PROCESS | 1923 | RSS: Main: 207953920 15:21:33 INFO - PROCESS | 1923 | 15:21:36 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:49365/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49365/tests/kraken/driver.html?testName=imaging-desaturate) 15:21:36 INFO - PROCESS | 1923 | RSS: Main: 208646144 15:21:36 INFO - PROCESS | 1923 | 15:21:39 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:49365/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49365/tests/kraken/driver.html?testName=json-parse-financial) 15:21:40 INFO - PROCESS | 1923 | RSS: Main: 207974400 15:21:40 INFO - PROCESS | 1923 | 15:21:42 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:49365/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49365/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:21:42 INFO - PROCESS | 1923 | RSS: Main: 207265792 15:21:42 INFO - PROCESS | 1923 | 15:21:44 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:49365/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49365/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:21:45 INFO - PROCESS | 1923 | RSS: Main: 206827520 15:21:45 INFO - PROCESS | 1923 | 15:21:47 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:49365/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49365/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:21:47 INFO - PROCESS | 1923 | RSS: Main: 206819328 15:21:47 INFO - PROCESS | 1923 | 15:21:50 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:49365/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49365/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:21:51 INFO - PROCESS | 1923 | RSS: Main: 206831616 15:21:51 INFO - PROCESS | 1923 | 15:21:54 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:49365/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49365/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:21:55 INFO - PROCESS | 1923 | RSS: Main: 207220736 15:21:55 INFO - PROCESS | 1923 | 15:21:56 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:49365/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49365/tests/kraken/driver.html?testName=ai-astar) 15:21:57 INFO - PROCESS | 1923 | RSS: Main: 209195008 15:21:57 INFO - PROCESS | 1923 | 15:21:57 INFO - PROCESS | 1923 | __start_tp_report 15:21:57 INFO - PROCESS | 1923 | _x_x_mozilla_page_load 15:21:57 INFO - PROCESS | 1923 | _x_x_mozilla_page_load_details 15:21:57 INFO - PROCESS | 1923 | |i|pagename|runs| 15:21:57 INFO - PROCESS | 1923 | |0;ai-astar;105;117;118;116;118;118;118;118;121;117 15:21:57 INFO - PROCESS | 1923 | |1;audio-beat-detection;141;109;135;173;108;112;108;110;135;107 15:21:57 INFO - PROCESS | 1923 | |2;audio-dft;192;195;193;197;196;194;193;196;196;197 15:21:57 INFO - PROCESS | 1923 | |3;audio-fft;81;61;58;85;61;58;59;58;76;59 15:21:57 INFO - PROCESS | 1923 | |4;audio-oscillator;83;95;107;72;69;70;83;61;71;105 15:21:57 INFO - PROCESS | 1923 | |5;imaging-gaussian-blur;89;86;88;85;89;86;89;91;124;85 15:21:57 INFO - PROCESS | 1923 | |6;imaging-darkroom;98;98;99;99;99;98;98;99;98;130 15:21:57 INFO - PROCESS | 1923 | |7;imaging-desaturate;86;91;83;88;89;134;81;141;141;92 15:21:57 INFO - PROCESS | 1923 | |8;json-parse-financial;55;56;56;59;59;58;58;63;79;87 15:21:57 INFO - PROCESS | 1923 | |9;json-stringify-tinderbox;66;72;103;103;76;70;80;70;70;71 15:21:57 INFO - PROCESS | 1923 | |10;stanford-crypto-aes;81;83;81;84;80;84;81;86;85;81 15:21:57 INFO - PROCESS | 1923 | |11;stanford-crypto-ccm;144;148;151;136;139;154;149;157;193;172 15:21:57 INFO - PROCESS | 1923 | |12;stanford-crypto-pbkdf2;165;222;221;231;166;160;163;167;224;163 15:21:57 INFO - PROCESS | 1923 | |13;stanford-crypto-sha256-iterative;68;67;67;68;65;66;65;66;65;67 15:21:57 INFO - PROCESS | 1923 | __end_tp_report 15:21:57 INFO - PROCESS | 1923 | __start_cc_report 15:21:57 INFO - PROCESS | 1923 | _x_x_mozilla_cycle_collect,912 15:21:57 INFO - PROCESS | 1923 | __end_cc_report 15:21:57 INFO - PROCESS | 1923 | __startTimestamp1485213717180__endTimestamp 15:21:57 INFO - PROCESS | 1923 | 15:21:57 INFO - PROCESS | 1923 | ------- Summary: start ------- 15:21:57 INFO - PROCESS | 1923 | Number of tests: 14 15:21:57 INFO - PROCESS | 1923 | 15:21:57 INFO - PROCESS | 1923 | [#0] ai-astar Cycles:10 Average:116.60 Median:118.00 stddev:4.27 (3.6%) stddev-sans-first:1.36 15:21:57 INFO - PROCESS | 1923 | Values: 105.0 117.0 118.0 116.0 118.0 118.0 118.0 118.0 121.0 117.0 15:21:57 INFO - PROCESS | 1923 | 15:21:57 INFO - PROCESS | 1923 | [#1] audio-beat-detection Cycles:10 Average:123.80 Median:123.50 stddev:21.85 (17.7%) stddev-sans-first:22.27 15:21:57 INFO - PROCESS | 1923 | Values: 141.0 109.0 135.0 173.0 108.0 112.0 108.0 110.0 135.0 107.0 15:21:57 INFO - PROCESS | 1923 | 15:21:57 INFO - PROCESS | 1923 | [#2] audio-dft Cycles:10 Average:194.90 Median:196.00 stddev:1.79 (0.9%) stddev-sans-first:1.56 15:21:57 INFO - PROCESS | 1923 | Values: 192.0 195.0 193.0 197.0 196.0 194.0 193.0 196.0 196.0 197.0 15:21:57 INFO - PROCESS | 1923 | 15:21:57 INFO - PROCESS | 1923 | [#3] audio-fft Cycles:10 Average:65.60 Median:61.00 stddev:10.67 (17.5%) stddev-sans-first:9.75 15:21:57 INFO - PROCESS | 1923 | Values: 81.0 61.0 58.0 85.0 61.0 58.0 59.0 58.0 76.0 59.0 15:21:57 INFO - PROCESS | 1923 | 15:21:57 INFO - PROCESS | 1923 | [#4] audio-oscillator Cycles:10 Average:81.60 Median:71.50 stddev:15.98 (22.4%) stddev-sans-first:16.94 15:21:57 INFO - PROCESS | 1923 | Values: 83.0 95.0 107.0 72.0 69.0 70.0 83.0 61.0 71.0 105.0 15:21:57 INFO - PROCESS | 1923 | 15:21:57 INFO - PROCESS | 1923 | [#5] imaging-gaussian-blur Cycles:10 Average:91.20 Median:88.50 stddev:11.70 (13.2%) stddev-sans-first:12.38 15:21:57 INFO - PROCESS | 1923 | Values: 89.0 86.0 88.0 85.0 89.0 86.0 89.0 91.0 124.0 85.0 15:21:57 INFO - PROCESS | 1923 | 15:21:57 INFO - PROCESS | 1923 | [#6] imaging-darkroom Cycles:10 Average:101.60 Median:98.50 stddev:9.99 (10.1%) stddev-sans-first:10.51 15:21:57 INFO - PROCESS | 1923 | Values: 98.0 98.0 99.0 99.0 99.0 98.0 98.0 99.0 98.0 130.0 15:21:57 INFO - PROCESS | 1923 | 15:21:57 INFO - PROCESS | 1923 | [#7] imaging-desaturate Cycles:10 Average:102.60 Median:87.00 stddev:25.18 (28.9%) stddev-sans-first:25.98 15:21:57 INFO - PROCESS | 1923 | Values: 86.0 91.0 83.0 88.0 89.0 134.0 81.0 141.0 141.0 92.0 15:21:57 INFO - PROCESS | 1923 | 15:21:57 INFO - PROCESS | 1923 | [#8] json-parse-financial Cycles:10 Average:63.00 Median:59.00 stddev:10.93 (18.5%) stddev-sans-first:11.21 15:21:57 INFO - PROCESS | 1923 | Values: 55.0 56.0 56.0 59.0 59.0 58.0 58.0 63.0 79.0 87.0 15:21:57 INFO - PROCESS | 1923 | 15:21:57 INFO - PROCESS | 1923 | [#9] json-stringify-tinderbox Cycles:10 Average:78.10 Median:70.50 stddev:13.66 (19.4%) stddev-sans-first:13.77 15:21:57 INFO - PROCESS | 1923 | Values: 66.0 72.0 103.0 103.0 76.0 70.0 80.0 70.0 70.0 71.0 15:21:57 INFO - PROCESS | 1923 | 15:21:57 INFO - PROCESS | 1923 | [#10] stanford-crypto-aes Cycles:10 Average:82.60 Median:83.50 stddev:2.07 (2.5%) stddev-sans-first:2.11 15:21:57 INFO - PROCESS | 1923 | Values: 81.0 83.0 81.0 84.0 80.0 84.0 81.0 86.0 85.0 81.0 15:21:57 INFO - PROCESS | 1923 | 15:21:57 INFO - PROCESS | 1923 | [#11] stanford-crypto-ccm Cycles:10 Average:154.30 Median:152.50 stddev:16.91 (11.1%) stddev-sans-first:17.52 15:21:57 INFO - PROCESS | 1923 | Values: 144.0 148.0 151.0 136.0 139.0 154.0 149.0 157.0 193.0 172.0 15:21:57 INFO - PROCESS | 1923 | 15:21:57 INFO - PROCESS | 1923 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:188.20 Median:194.00 stddev:31.41 (16.2%) stddev-sans-first:32.17 15:21:57 INFO - PROCESS | 1923 | Values: 165.0 222.0 221.0 231.0 166.0 160.0 163.0 167.0 224.0 163.0 15:21:57 INFO - PROCESS | 1923 | 15:21:57 INFO - PROCESS | 1923 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.40 Median:67.00 stddev:1.17 (1.8%) stddev-sans-first:1.09 15:21:57 INFO - PROCESS | 1923 | Values: 68.0 67.0 67.0 68.0 65.0 66.0 65.0 66.0 65.0 67.0 15:21:57 INFO - PROCESS | 1923 | -------- Summary: end -------- 15:21:57 INFO - PROCESS | 1923 | 15:21:58 INFO - TEST-INFO | 1923: exit 0 15:21:58 INFO - TEST-OK | kraken | took 59808ms 15:21:58 INFO - SUITE-END | took 1174s 15:21:58 INFO - Completed test suite (00:19:34) 15:21:58 INFO - javascript benchmark 15:21:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7445.606545867816, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3024.516077427612, 3485.442329227324, 3629.5100161478194, 3652.086978255437, 3684.299983003229, 6092.908606370908, 6110.266767987837, 6138.600990935597, 6157.199564056672, 6187.535934804885, 5072.442031376546, 6119.3268740438625, 6128.233970753656, 6173.41352571505, 6209.410106039919, 5433.26650902129, 5478.123500239963, 5486.177073438997, 5494.423085576017, 5602.0, 5426.75579598917, 5457.645253058558, 5557.861053473655, 5562.916556251639, 5585.162225666142, 5334.3598768147685, 5340.25236466895, 5405.864853378657, 5464.617476776627, 5477.370102438231, 5379.35447746273, 5403.78384864605, 5409.134538473864, 5414.512693857571, 5526.834194974157, 5407.756650950717, 5413.296271484682, 5449.455054494559, 5456.127019676833, 5472.315960504937, 6090.33006369299, 6112.786052488142, 6126.387361263883, 6185.195924529782, 6329.4619957303685, 6320.115183874262, 6365.4589359904, 6371.553991220617, 6399.77600783975, 6450.387213214737, 6513.478921686254, 6515.902261466082, 6532.771353002622, 6537.150170477855, 6551.05009773585, 6053.152558641794, 6123.84690382744, 6135.171422439398, 6166.075088736726, 6218.160548325964, 6668.666566671647, 6691.76578819739, 6705.530612857151, 6723.798286051427, 6731.259561448237, 6361.554691171574, 6369.490440764727, 6375.74497020114, 6378.043293506012, 6379.74481020754, 6182.350853160443, 6328.145700330489, 6378.968105159444, 6483.870322593521, 6523.575967562093, 5092.719900405513, 5288.2596436498925, 6340.936590634127, 6425.967870160619, 6428.0, 5646.632968857011, 6124.295705993868, 6401.07184458251, 6405.327440618668, 6434.678266086677, 5129.9230511542355, 5164.173732202905, 5165.966806638688, 5224.2947202127225, 5233.293505376725, 4646.86059418218, 4647.86056418308, 4687.086018226481, 4713.481517033124, 4758.571728544378, 5232.816851410183, 5366.8729566790635, 5534.225208470817, 6191.226096737908, 6201.751929922752, 6289.528285378524, 6361.745530178741, 6404.039394090831, 6538.1827271591055, 6558.704858281293, 6160.1375807387, 6208.192934918432, 6217.96891015542, 6229.875402491924, 6235.625862448268, 5713.2572765541045, 5742.224799652077, 5743.023685973395, 5747.712614369349, 5771.163181338682, 6522.804315870531, 6591.604503729792, 6672.83317917056, 6688.431483323875, 6717.664116794141, 6394.26465956421, 6420.454261387647, 6471.611703297817, 6482.935170648422, 6551.279359270381, 6737.494687898329, 6745.898811517733, 6751.189857217166, 6763.661816909036, 6766.255711871591, 6224.719887604978, 6378.330275321025, 6414.422701956846, 6430.228372595318, 6431.0031945048595, 6314.684265786784, 6402.807915762534, 6415.71129299192, 6466.062420948842, 6531.444827189742, 5022.824201152942, 6312.179416675803, 6330.8100756977365, 6337.207849018872, 6366.713497892513, 6288.025356069817, 6297.96851015742, 6307.873842523216, 6308.14839996613, 6351.71417286214, 5999.650078732425, 6022.819315420545, 6137.315698286874, 6193.628382296896, 6202.906956395748, 6283.905741413974, 6294.5279104068295, 6308.179936608211, 6308.558400912076, 6309.274433440213, 6187.721552530237, 6191.102290167991, 6201.348858369806, 6205.131281620486, 6208.130861679459], "value": 5936.707265181715, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [444.70204962675024, 530.3158924986767, 541.6316904504938, 542.0865841057818, 544.850166204294, 40044.19911601766, 41819.0, 41878.790606046925, 41920.790396047974, 41994.37008444876, 42404.363934541005, 42439.0, 43148.0, 43535.34696979548, 43672.12655746891, 9668.32321737477, 9717.562709678046, 9736.977617350183, 9832.655857045005, 9841.704748857546, 9634.421934683907, 9669.0, 9723.46520941348, 9762.219022478184, 9768.90231097687, 9723.854142187873, 9748.31761776676, 9749.220062395027, 9806.901930980706, 9815.950920245425, 9676.806463870718, 9721.611135554569, 9727.027297270251, 9745.269104817167, 9765.462899540522, 9720.805583888354, 9751.756206094833, 9788.216942644642, 9794.461304628243, 9833.164181044549, 41470.37794433086, 41749.582504174876, 41798.79100604508, 41849.0, 41858.37212441785, 3441.9311613767836, 3512.262424890776, 3619.7466177367596, 3636.3091012707764, 4036.4752582164133, 30589.388212235852, 31274.530882036786, 31644.0, 31992.520112198337, 32202.194945126324, 3180.3798259339437, 3275.918102047444, 3321.551590535271, 3379.695827375548, 3440.0, 448832.0, 471289.0, 475121.0, 476822.0, 478097.0, 63960.040599391046, 64002.0, 64025.67987160128, 64172.0, 64263.0, 63909.36090639127, 65731.0, 65924.01113983295, 66384.6680766593, 66567.66716166388, 16954.406595769087, 16960.236789344508, 17218.741718874226, 17235.74146387805, 17238.482845514656, 12890.548830791065, 13051.738965220642, 13113.67215819593, 13126.409311581001, 13155.0, 17303.0, 17913.19390627425, 18061.548461288574, 18149.637007260044, 18169.36407225741, 6386.616802991835, 6397.200349956255, 6412.67936603168, 6423.74305027803, 6490.091387205795, 887.4586502233632, 940.3078637598335, 984.5274268351232, 1012.4128005756644, 1039.8180318444392, 27987.160385188476, 29852.253693657833, 31136.377272454425, 31396.0, 31418.842905785325, 999.7003894936554, 1059.6979860739652, 1119.7424592343807, 1155.7226265696172, 1181.645506348092, 15656.295466704025, 16162.676746465006, 16196.595085122966, 16267.674646507003, 16556.42052528156, 646726.0, 659759.0, 660448.0, 660908.0, 661446.0, 61053.38946610566, 61060.69469652623, 61104.38895611076, 61121.0, 61190.38809611936, 1389.8679625435568, 1421.4172189402295, 1423.729491396652, 1425.4796999095256, 1430.4349781836117, 30728.69271307258, 30805.8459707709, 31287.061388158392, 31332.0, 31428.52857207098, 43440.0, 54319.0, 58330.0, 58366.12450813326, 61990.0, 578987.0, 583865.0, 586604.0, 587443.0, 592421.0, 27147.1855844325, 27606.585901211096, 27635.44729105447, 27638.170854874385, 27671.72328276772, 49112.508874910796, 49183.75408122937, 49225.0, 49507.75246123746, 49719.751401242764, 40246.79876600598, 40887.1822563553, 41133.58866411298, 42053.579464204966, 42143.157136857706, 40382.79808600938, 40601.39097913475, 40845.183096337314, 41958.0, 42009.73970780882, 38990.830275091794, 40046.199076017736, 40266.0, 40335.3949690749, 40342.0, 34707.0, 35782.46326305055, 36089.27821443504, 36453.384093486486, 36864.63135368612, 26942.32644183911, 26957.59563606508, 27025.45949080968, 27073.864630676722, 27143.04999325014], "value": 20548.65745285988, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [337.0411180192353, 385.60090306532743, 394.7256656623647, 403.6952101163621, 414.21506245664466, 19092.809071909276, 20242.89878550605, 21036.0, 21179.0, 21202.78797212029, 11848.822267665992, 12354.876451235485, 12373.938130309358, 12442.377881105955, 20492.69260961087, 5351.812686555972, 5624.0439125348585, 5629.60592758507, 5649.49154576088, 5655.773769049254, 5492.917606235909, 5823.563232757539, 6146.0, 6188.47397971173, 6213.161223234873, 5562.415946325638, 5988.2215312009375, 6135.815925522219, 6188.659623720693, 6206.286277078126, 6770.52606317558, 6890.104286442781, 6925.688344024531, 6959.791206263795, 6979.197392299849, 5275.788968441258, 5522.861928451781, 5601.29983752031, 6158.199434073586, 6162.0, 13521.729565408637, 13542.187468751907, 13745.862541374658, 18696.158672859754, 25252.747472525407, 2252.3468194223656, 2258.954820903573, 2328.2898715891647, 2575.2531765788085, 2816.464871674396, 10631.521581528772, 12367.0, 14642.0, 15289.464868729652, 16217.189140542925, 1930.951726206842, 1978.3867001229664, 2032.2175962254491, 2048.8873111978837, 2050.1184490669007, 121280.75517356939, 122601.38699306536, 125858.0, 127904.0, 129840.0, 11660.0, 11825.586104486301, 11826.822597661041, 11904.166708330507, 12315.4458049388, 14889.47886823956, 15038.473653422077, 15215.934884557975, 15241.390344386322, 15273.465428709942, 8582.54096803545, 8756.8686469703, 8856.955715221382, 8987.910120898707, 8990.28077753778, 8680.479171249744, 8713.782155446037, 8763.56182190888, 8873.378863479624, 8898.243649289754, 4625.444946606362, 4638.652101092432, 4696.5303469653445, 4762.618990480753, 4781.474037855833, 1984.4542750743371, 2041.8672786268844, 2058.989705051465, 2066.3697572240612, 2085.384811480617, 857.3655494933829, 912.9087091290818, 938.4134915677702, 983.306768728045, 994.4331730913455, 20699.689504657443, 21564.0, 21944.780552194592, 22047.11811527521, 22069.33791986243, 954.613381580461, 965.2615748952057, 970.5632465390603, 1010.9797804043878, 1028.0541901450683, 11029.83455248172, 11127.554897804159, 11998.280103193836, 12012.279263244232, 12329.273901653776, 615624.0, 620395.0, 620819.8959005267, 631578.0, 634359.0, 125886.74113258933, 128641.3567932173, 139550.0, 139805.31070791002, 141042.4739381503, 1024.8513965475113, 1039.6776999130293, 1051.4374809477047, 1052.894710528941, 1058.454895728685, 18607.97656128926, 19398.0, 19712.0, 19754.308599198957, 19775.011249437182, 56525.86948261094, 57533.71233143807, 58051.4194858046, 58898.411015891004, 59746.701266493386, 80366.0, 81741.77387339313, 82664.0, 83442.16557834344, 83620.58189709013, 7158.176809666955, 7245.492815502864, 7662.616869156632, 7857.135715071452, 8020.398470114881, 11675.649730508097, 13331.66670833237, 13499.527516536875, 13588.93205533966, 13595.32023398846, 9924.801503970024, 9967.800643986935, 11380.0, 11533.769324613628, 11821.881781182077, 9804.460754658277, 11327.943360283145, 11443.942780286045, 11696.181267311207, 11777.0, 11633.41832908368, 11664.6500604982, 11675.474603642686, 11754.941225293818, 11986.520539178684, 6599.0, 7542.509736867086, 7874.56689882062, 7912.36701063925, 7918.683252670059, 11300.604478843201, 11486.695159249748, 11834.0, 11884.108691847972, 11925.4037298133], "value": 9761.895402873544, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1477.0, 1515.7650564162552, 1733.1767410480024, 1866.5240363707253, 1893.1953919584173, 7715.768526944201, 7918.129005809371, 8140.837183256332, 8611.44025638334, 8612.267957223636, 8240.7939801505, 8439.70461033864, 8522.701705440311, 8537.615807288672, 8560.914390856089, 2394.5210957808463, 2424.103081859714, 2439.7926176275037, 2442.7312995570474, 2446.290575733044, 2424.1394305021727, 2455.938601534967, 2468.90124395024, 2474.5793215153376, 2481.913133040344, 2403.9519209615796, 2419.358869899478, 2425.0542288507495, 2432.963505547418, 2442.5481285962064, 2386.53462574798, 2395.137750409851, 2413.51729654069, 2415.973211385172, 2419.903203871843, 2414.9275521734376, 2429.829911906167, 2438.2319569335605, 2439.512097580491, 2444.755524447559, 8348.791280217982, 8498.235158835672, 8501.447405918596, 8507.319414446829, 8508.574571271412, 5203.973980130075, 5363.249145119687, 5371.650842695212, 5374.5700343972385, 5375.408705042481, 3505.1061979195397, 3582.0865679251883, 3597.5862775780597, 3610.656987586175, 3625.6374362563797, 1053.4785281285735, 1078.9892101079047, 1093.1637297467444, 1098.4288170151556, 1107.1364335818075, 10918.399488028155, 10940.726481838017, 11025.558977640885, 11030.338179709162, 11076.280041797258, 3841.4814000110186, 4498.302763071729, 4672.392588963413, 4770.522947705271, 4848.7090774553635, 9576.569054392568, 9611.663591774255, 9644.469554174588, 9664.371815831948, 9667.758306042406, 5269.815556455505, 5314.681119132864, 5355.464453554691, 5367.060764366221, 5378.2470454136455, 4431.711938723973, 4464.843730469418, 4875.219964805685, 4910.705357678551, 4943.0, 3408.642092580293, 3994.1412596291357, 4094.6314831665295, 4117.814698338582, 4127.814248358831, 1308.4112149532748, 1309.4238535044549, 1312.934353282332, 1313.1005261395783, 1322.312397553267, 798.944073914832, 842.8567143585606, 866.8569686001865, 924.089771574998, 941.7174847545709, 5826.650400975955, 5867.794627188028, 5892.234009578728, 5926.607247296968, 6088.299845517734, 938.3009657804843, 947.6020071569957, 954.9570269337896, 961.6153538584482, 1019.6635110413694, 3520.542329497149, 3612.9277414451562, 3670.1925576373155, 3903.906906066306, 3930.0174956260935, 9926.354786938826, 10136.239782016379, 10224.59101635941, 10248.077673009466, 10253.692389228336, 9967.651132210338, 9973.900260997443, 10125.443100629536, 10168.745781355525, 10216.693499195037, 6981.2669669684965, 7624.618769061524, 7860.135385107634, 7966.522008679498, 7967.721129760431, 5552.888942221052, 5751.0, 5756.251687280626, 5857.765689372377, 5895.587308888336, 9057.0, 9158.816823663356, 9199.586018629045, 9465.148136667733, 9480.383775054599, 9421.293402994666, 9467.66863159786, 9495.43027418357, 9522.761930951781, 9571.37786043905, 6693.564918280491, 6912.654367281716, 7061.894071589033, 7242.167150777517, 7341.4126869849215, 7816.57008864518, 8075.878861817195, 8084.959575202086, 8087.757367278991, 8090.6359213836695, 6676.732930682912, 7074.787756367317, 7237.312455316736, 7250.60121693312, 7267.454940879561, 6979.057827193467, 7156.3917067047805, 7172.426205903619, 7199.496035277481, 7295.270472952662, 6626.469882409492, 6754.324567543403, 6778.542613338156, 6835.07726456922, 6839.1451068616425, 4820.68665536739, 4902.534259245366, 4918.2868484068895, 4960.727160006234, 4986.675866068694, 4203.47456567929, 4912.8771780705765, 4952.331435256338, 5034.0686972910125, 5048.444671086251], "value": 4518.781107080213, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [315.0815373187161, 315.48103369956425, 321.5257495194588, 327.5054667452147, 327.8131465064914, 5338.0, 5495.505404513603, 5533.0, 5614.838287259404, 5674.120511320752, 5282.385219316434, 5388.946110538894, 5458.2904222451025, 5471.917921231185, 5533.335999680034, 4223.61987421132, 4231.4710661167355, 4234.814252009427, 4274.63211772233, 4277.657787377002, 3501.072215862798, 3628.328759179547, 3663.5970043295215, 3717.368047431943, 4285.250081235787, 3283.8029718216985, 3905.3360928642055, 4201.256478561393, 4211.894702632443, 4223.0, 3694.741368104234, 4203.096334288112, 4217.882261200784, 4222.641075508585, 4231.8730438086595, 3720.962790372089, 3731.7574357666663, 3736.346139425617, 3740.5698344690163, 3744.55065392152, 3986.8205930732897, 4594.74728889911, 4795.304680821261, 4923.926141107886, 5350.228089197107, 4201.424465825316, 4754.263089221176, 4758.762061896891, 4764.976175119103, 4771.308160316735, 5755.107958266475, 5771.653700777967, 5790.478856902857, 5801.129830525413, 5829.0, 3197.2486465680545, 3685.6130106338715, 3896.649301562873, 3968.6428221460205, 3971.364581666948, 6030.276366835964, 6041.939580604182, 6054.939450605526, 6063.545234107415, 6106.847328816815, 3535.2399234164263, 3605.0, 4029.576894426043, 4061.7766022868436, 4063.2686116499312, 3294.5552350432386, 3636.5272064813794, 3818.1790914953417, 3929.960700393017, 4013.3779264214095, 4684.953150468452, 4739.881502962385, 4741.596964258077, 4754.643401744883, 4756.6432517561325, 4885.5358740919555, 5802.33273173582, 5847.532197424196, 5864.384239654775, 5880.676562789002, 1132.4394424759632, 1179.5930404010603, 1216.6471723200173, 1273.2106094221642, 1286.2089814763854, 870.5516658920661, 892.4511425473287, 896.7221709064557, 900.5722281916169, 911.8874972533498, 4629.629629629621, 5228.215767634885, 5231.843044708665, 5271.420143784258, 5304.257403963448, 5441.211024401439, 5669.489745922887, 5709.571782116358, 5739.885202295931, 5761.793890459988, 4409.603135717801, 4968.925466118011, 5209.4009188943755, 5248.711320877388, 5323.361196656426, 2540.61890716394, 2668.9849163881536, 2941.0147600554055, 2956.63042119735, 2995.0, 5478.643888147258, 5645.745941432645, 5822.301323841166, 5891.793787217427, 5900.439458251545, 4180.512595005966, 4970.061675946384, 5059.696418214845, 5588.39548491229, 5679.261695979496, 5004.299398084271, 5045.949540504548, 5563.332400112013, 5638.295213098363, 5841.0, 4777.63837306366, 5047.510984259578, 5141.948580514147, 5626.831195064155, 5676.637606974379, 4470.664700147437, 5193.689237932432, 5999.5800293979, 6090.421409966046, 6111.327753947151, 5875.970620146872, 6055.304514735881, 6259.280182778856, 6328.702615963808, 6366.777162799465, 3802.467654528313, 4000.3599424092004, 4093.5906409358827, 4119.691023173334, 4147.647449966787, 5451.827857010761, 5788.971055144697, 5789.768409263583, 5963.0459126539545, 6057.7879774207695, 5320.255164276927, 5379.354477462573, 5679.586175875319, 5909.552159720896, 5945.524358051432, 4684.9313699526865, 5208.401033881152, 5576.386597474356, 5899.292084949833, 5947.858770842579, 4852.51474852526, 5385.515303622693, 5751.539876809761, 5796.681182535001, 5831.504322132666, 5334.839954801362, 5909.8227053188475, 5960.403959604005, 5990.9700451497465, 6029.517638588814, 4498.730076195438, 5553.916691249715, 5815.185873977731, 5835.328937172109, 5854.970725146347], "value": 4147.904233199514, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [668.7692746002631, 741.1624863903788, 790.2966359939653, 809.348474478045, 829.8049958259808, 14801.11193328401, 15231.923840380783, 15291.464798732046, 15343.69312613747, 15718.371265149395, 14170.50403235887, 15014.474493392734, 15546.53360399187, 15574.0, 15997.200139993012, 691.2603514239765, 709.4289097276691, 867.3451544084239, 867.4578388507167, 896.1665650944619, 868.0754995929346, 891.3359547137392, 892.6830975003845, 893.271983333581, 893.5041052216018, 906.4108329585756, 915.4919019943928, 943.7451887990238, 949.7957939043139, 953.461294368683, 918.4994178172911, 932.0213775535694, 943.89617142115, 949.2975198353238, 953.5661274120293, 674.6019848289533, 675.7128220506314, 676.8646270745821, 708.4438715608222, 710.9111361079865, 15300.846991530165, 15456.768148477782, 15527.301271442811, 15634.2182890855, 15767.763483547642, 14249.216293103875, 14548.927255363762, 14591.70816583673, 14628.561143165616, 14629.92685036568, 11798.469068891834, 11847.229930054518, 11954.58158964432, 11962.82055769164, 11985.28088314704, 3559.857605695769, 3812.3519001769764, 3862.5558060822805, 3862.8454861805494, 3876.3991581304945, 109663.90336096617, 111212.0, 111470.0, 113096.43451782688, 113899.0, 9985.30102892805, 10450.0, 10662.200334974752, 10876.61931832398, 10960.28758130719, 21487.57024859494, 21497.892510537346, 21583.56832863334, 21784.673229901564, 21804.23685171043, 7553.395728341719, 7718.151003389623, 7757.883631745528, 7801.29788319054, 7841.882371764428, 4313.375453153783, 4406.471223453206, 4454.354118652842, 4548.067646132535, 4548.953740639605, 3393.3043726036103, 3471.548698669207, 3504.667056629616, 3506.982465087658, 3515.876944306937, 627.1815281058172, 636.4494712074115, 650.9349065093547, 657.1555551116792, 708.0653537330674, 586.633354153654, 589.8024161905723, 616.9722362493699, 633.2116514938839, 641.2113100885937, 7571.141505153378, 7830.6084695764985, 8811.647534098563, 9128.90871091294, 9239.676611318571, 594.8750762339957, 629.6820105846518, 670.9261981182066, 698.448225901532, 699.9090118284693, 4422.756748378806, 4541.954580454153, 4599.356090147382, 4610.677252592353, 4661.766911654404, 100288.0, 106555.0, 112641.0, 128236.0, 132362.0, 76521.6173919138, 78986.81519777208, 97562.51218743861, 102625.40811071577, 105228.0, 90056.0, 93086.99999999865, 94425.44272350273, 95242.0, 98222.0, 8497.532635705096, 9508.952455237957, 9889.25830562725, 9943.30396872241, 10011.699649010541, 43928.12143757043, 51816.704582384235, 54376.18435723546, 56661.0, 59669.403305968124, 78745.0, 80372.0, 81996.0, 82637.0, 83788.16211837804, 12243.265404075784, 12362.690932726753, 12395.690107747378, 12563.87436125627, 12689.111762176559, 13791.931040344734, 13983.951203659562, 14112.435502579781, 14472.27638618086, 14601.12393256408, 12891.677708057374, 13426.530071447452, 14275.785863211853, 14320.928395357956, 14515.201663908587, 13073.869261307265, 13652.658683532594, 14273.929455290687, 14284.35720392564, 14293.785593216318, 13060.738785224052, 13395.464181432633, 13554.390052447467, 13742.725145496834, 13841.377138029015, 9360.859587106335, 11239.775204496027, 11360.1479889007, 11489.655310340702, 11844.76310473803, 10683.675304177597, 10735.0, 10781.245312828027, 11446.02708769764, 11471.827922581335], "value": 7632.041573418746, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1510.5000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 117.0, 118.0, 116.0, 118.0, 118.0, 118.0, 118.0, 121.0, 117.0], "value": 116.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 109.0, 135.0, 173.0, 108.0, 112.0, 108.0, 110.0, 135.0, 107.0], "value": 123.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 195.0, 193.0, 197.0, 196.0, 194.0, 193.0, 196.0, 196.0, 197.0], "value": 194.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 61.0, 58.0, 85.0, 61.0, 58.0, 59.0, 58.0, 76.0, 59.0], "value": 65.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 95.0, 107.0, 72.0, 69.0, 70.0, 83.0, 61.0, 71.0, 105.0], "value": 81.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 88.0, 85.0, 89.0, 86.0, 89.0, 91.0, 124.0, 85.0], "value": 91.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 98.0, 99.0, 99.0, 99.0, 98.0, 98.0, 99.0, 98.0, 130.0], "value": 101.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 91.0, 83.0, 88.0, 89.0, 134.0, 81.0, 141.0, 141.0, 92.0], "value": 102.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 56.0, 59.0, 59.0, 58.0, 58.0, 63.0, 79.0, 87.0], "value": 63.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 72.0, 103.0, 103.0, 76.0, 70.0, 80.0, 70.0, 70.0, 71.0], "value": 78.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 81.0, 84.0, 80.0, 84.0, 81.0, 86.0, 85.0, 81.0], "value": 82.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 148.0, 151.0, 136.0, 139.0, 154.0, 149.0, 157.0, 193.0, 172.0], "value": 154.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 222.0, 221.0, 231.0, 166.0, 160.0, 163.0, 167.0, 224.0, 163.0], "value": 188.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 67.0, 68.0, 65.0, 66.0, 65.0, 66.0, 65.0, 67.0], "value": 66.4, "unit": "score"}]}]} 15:21:58 INFO - Return code: 0 15:21:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:21:58 ERROR - # TBPL SUCCESS # 15:21:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:21:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:21:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:21:58 INFO - 2017-01-23 15:21:58.526 screenresolution[1928:17894] starting screenresolution argv=screenresolution get 15:21:58 INFO - 2017-01-23 15:21:58.530 screenresolution[1928:17894] Display 0: 1600x1200x32@60 15:21:58 INFO - 2017-01-23 15:21:58.536 screenresolution[1929:17897] starting screenresolution argv=screenresolution list 15:21:58 INFO - Available Modes on Display 0 15:21:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:21:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:21:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:21:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:21:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:21:58 INFO - Intel Iris: 15:21:58 INFO - Chipset Model: Intel Iris 15:21:58 INFO - Type: GPU 15:21:58 INFO - Bus: Built-In 15:21:58 INFO - VRAM (Dynamic, Max): 1536 MB 15:21:58 INFO - Vendor: Intel (0x8086) 15:21:58 INFO - Device ID: 0x0a2e 15:21:58 INFO - Revision ID: 0x0009 15:21:58 INFO - Displays: 15:21:58 INFO - Display: 15:21:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:21:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:21:58 INFO - Display Serial Number: 1600x1200 60 15:21:58 INFO - Main Display: Yes 15:21:58 INFO - Mirror: Off 15:21:58 INFO - Online: Yes 15:21:58 INFO - Rotation: Supported 15:21:58 INFO - Return code: 0 15:21:58 INFO - Running post-action listener: _resource_record_post_action 15:21:58 INFO - [mozharness: 2017-01-23 23:21:58.773294Z] Finished run-tests step (success) 15:21:58 INFO - Running post-run listener: _resource_record_post_run 15:21:58 INFO - Total resource usage - Wall time: 1196s; CPU: 54.0%; Read bytes: 4132864; Write bytes: 502343168; Read time: 31; Write time: 1758 15:21:58 INFO - TinderboxPrint: CPU usage
54.1% 15:21:58 INFO - TinderboxPrint: I/O read bytes / time
4,132,864 / 31 15:21:58 INFO - TinderboxPrint: I/O write bytes / time
502,343,168 / 1,758 15:21:58 INFO - TinderboxPrint: CPU idle
2,197.3 (45.9%) 15:21:58 INFO - TinderboxPrint: CPU system
963.4 (20.1%) 15:21:58 INFO - TinderboxPrint: CPU user
1,625.0 (34.0%) 15:21:58 INFO - TinderboxPrint: Swap in / out
196,100,096 / 0 15:21:58 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 182821376; Write bytes: 177124864; Read time: 16720; Write time: 492 15:21:58 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 4132864; Write bytes: 307679232; Read time: 31; Write time: 1213 15:21:59 INFO - Running post-run listener: _upload_blobber_files 15:21:59 INFO - Blob upload gear active. 15:21:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:21:59 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 15:21:59 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'] 15:21:59 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 15:21:59 INFO - (blobuploader) - INFO - Open directory for files ... 15:21:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:21:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:21:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:22:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:22:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:22:00 INFO - (blobuploader) - INFO - Done attempting. 15:22:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:22:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:22:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:22:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:22:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:22:01 INFO - (blobuploader) - INFO - Done attempting. 15:22:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:22:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:22:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:22:04 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:22:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:22:04 INFO - (blobuploader) - INFO - Done attempting. 15:22:04 INFO - (blobuploader) - INFO - Iteration through files over. 15:22:04 INFO - Return code: 0 15:22:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:22:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:22:04 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6731ba9fa0130d077e31b5b8399ab16f5da28d3aa5760eefde836011d0389b47b5342c5b241187d99f9d28a532fe2792063a1a575b7e58d59b3e7a4322af124d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b3c7fbe77d3ccd92480facbbd207855098cebabe230b638196f34595ab5fb2a65393911a67273ead80747ab8a3e2dc42b393d1097f219ddabf2fb00e2d7b0ec7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7f3c9fd8cf184315d25248968c8016ba175a6b33e07377abe03d4c5a03cf485464ba4cb5a5d3df3483bb8a8bd65b620831b0def9eaf8cbfd287335ca44f3e74e"} 15:22:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:22:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:22:04 INFO - Contents: 15:22:04 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/6731ba9fa0130d077e31b5b8399ab16f5da28d3aa5760eefde836011d0389b47b5342c5b241187d99f9d28a532fe2792063a1a575b7e58d59b3e7a4322af124d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b3c7fbe77d3ccd92480facbbd207855098cebabe230b638196f34595ab5fb2a65393911a67273ead80747ab8a3e2dc42b393d1097f219ddabf2fb00e2d7b0ec7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7f3c9fd8cf184315d25248968c8016ba175a6b33e07377abe03d4c5a03cf485464ba4cb5a5d3df3483bb8a8bd65b620831b0def9eaf8cbfd287335ca44f3e74e"} 15:22:04 INFO - Running post-run listener: copy_logs_to_upload_dir 15:22:04 INFO - Copying logs to upload dir... 15:22:04 INFO - mkdir: /builds/slave/test/build/upload/logs 15:22:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1256.691516 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-01-23 15:22:04.145551) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-23 15:22:04.149115) ========= 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.7a5Mahn9H7/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.e3vCdIwuUH/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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/6731ba9fa0130d077e31b5b8399ab16f5da28d3aa5760eefde836011d0389b47b5342c5b241187d99f9d28a532fe2792063a1a575b7e58d59b3e7a4322af124d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b3c7fbe77d3ccd92480facbbd207855098cebabe230b638196f34595ab5fb2a65393911a67273ead80747ab8a3e2dc42b393d1097f219ddabf2fb00e2d7b0ec7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7f3c9fd8cf184315d25248968c8016ba175a6b33e07377abe03d4c5a03cf485464ba4cb5a5d3df3483bb8a8bd65b620831b0def9eaf8cbfd287335ca44f3e74e"} build_url:https://queue.taskcluster.net/v1/task/HuXj3xkQTjGzOQ_DiIqp9w/artifacts/public/build/firefox-52.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011183 build_url: 'https://queue.taskcluster.net/v1/task/HuXj3xkQTjGzOQ_DiIqp9w/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/6731ba9fa0130d077e31b5b8399ab16f5da28d3aa5760eefde836011d0389b47b5342c5b241187d99f9d28a532fe2792063a1a575b7e58d59b3e7a4322af124d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b3c7fbe77d3ccd92480facbbd207855098cebabe230b638196f34595ab5fb2a65393911a67273ead80747ab8a3e2dc42b393d1097f219ddabf2fb00e2d7b0ec7", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/7f3c9fd8cf184315d25248968c8016ba175a6b33e07377abe03d4c5a03cf485464ba4cb5a5d3df3483bb8a8bd65b620831b0def9eaf8cbfd287335ca44f3e74e"}' ========= master_lag: 0.33 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-23 15:22:04.486026) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-23 15:22:04.486376) ========= 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.7a5Mahn9H7/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.e3vCdIwuUH/Listeners TMPDIR=/var/folders/f9/cg1m6_fx7ts4ntdpv3xn3tmm00000w/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.005381 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-23 15:22:04.513178) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-23 15:22:04.513496) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-23 15:22:04.513832) ========= ========= Total master_lag: 0.46 =========