builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0068 starttime: 1482682784.04 results: success (0) buildid: 20161225064336 builduid: 38d556b093174298ad26a5e6577ca784 revision: 0597e6a2070b596ff07b4f061049f1f9505864d7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-25 08:19:44.046890) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-25 08:19:44.047382) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-25 08:19:44.047665) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-25 08:19:44.073948) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-25 08:19:44.074313) ========= 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.sO5uo60ziu/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.y455Mm2SPA/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-25 08:19:44-- 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% 71.7M=0s 2016-12-25 08:19:44 (71.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.126367 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-25 08:19:44.225474) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-25 08:19:44.225803) ========= 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.sO5uo60ziu/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.y455Mm2SPA/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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.027581 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-25 08:19:44.315258) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-25 08:19:44.315609) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 0597e6a2070b596ff07b4f061049f1f9505864d7 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 0597e6a2070b596ff07b4f061049f1f9505864d7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.sO5uo60ziu/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.y455Mm2SPA/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-25 08:19:44,410 truncating revision to first 12 chars 2016-12-25 08:19:44,410 Setting DEBUG logging. 2016-12-25 08:19:44,410 attempt 1/10 2016-12-25 08:19:44,410 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/0597e6a2070b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-25 08:19:45,455 unpacking tar archive at: mozilla-aurora-0597e6a2070b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.317513 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-25 08:19:45.657137) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-25 08:19:45.657480) ========= script_repo_revision: 0597e6a2070b596ff07b4f061049f1f9505864d7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-25 08:19:45.657893) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-25 08:19:45.658184) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-25 08:19:45.678942) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-12-25 08:19:45.679361) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.sO5uo60ziu/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.y455Mm2SPA/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 08:19:45 INFO - MultiFileLogger online at 20161225 08:19:45 in /builds/slave/test 08:19:45 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 08:19:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:19:45 INFO - {'append_to_log': False, 08:19:45 INFO - 'base_work_dir': '/builds/slave/test', 08:19:45 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:19:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:19:45 INFO - 'branch': 'Mozilla-Aurora', 08:19:45 INFO - 'buildbot_json_path': 'buildprops.json', 08:19:45 INFO - 'config_files': ('talos/mac_config.py',), 08:19:45 INFO - 'default_actions': ('clobber', 08:19:45 INFO - 'read-buildbot-config', 08:19:45 INFO - 'download-and-extract', 08:19:45 INFO - 'populate-webroot', 08:19:45 INFO - 'create-virtualenv', 08:19:45 INFO - 'install', 08:19:45 INFO - 'run-tests'), 08:19:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:19:45 INFO - 'download_minidump_stackwalk': True, 08:19:45 INFO - 'download_symbols': 'ondemand', 08:19:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:19:45 INFO - 'tooltool.py': '/tools/tooltool.py', 08:19:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:19:45 INFO - '/tools/misc-python/virtualenv.py')}, 08:19:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:19:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:19:45 INFO - 'installer_path': 'installer.exe', 08:19:45 INFO - 'log_level': 'info', 08:19:45 INFO - 'log_name': 'talos', 08:19:45 INFO - 'log_to_console': True, 08:19:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:19:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:19:45 INFO - 'opt_config_files': (), 08:19:45 INFO - 'pip_index': False, 08:19:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:19:45 INFO - 'cmd': ('bash', 08:19:45 INFO - '-c', 08:19:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:19:45 INFO - 'enabled': True, 08:19:45 INFO - 'halt_on_failure': False, 08:19:45 INFO - 'name': 'check_screen_resolution'},), 08:19:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:19:45 INFO - 'cmd': ('bash', 08:19:45 INFO - '-c', 08:19:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:19:45 INFO - 'enabled': True, 08:19:45 INFO - 'halt_on_failure': False, 08:19:45 INFO - 'name': 'check_screen_resolution'},), 08:19:45 INFO - 'run_cmd_checks_enabled': True, 08:19:45 INFO - 'sps_profile': False, 08:19:45 INFO - 'sps_profile_interval': 0, 08:19:45 INFO - 'suite': 'dromaeojs-e10s', 08:19:45 INFO - 'system_bits': '32', 08:19:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:19:45 INFO - 'title': 't-yosemite-r7-0068', 08:19:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:19:45 INFO - 'use_talos_json': True, 08:19:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:19:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:19:45 INFO - 'work_dir': 'build'} 08:19:45 INFO - [mozharness: 2016-12-25 16:19:45.886176Z] Running clobber step. 08:19:45 INFO - Running pre-action listener: _resource_record_pre_action 08:19:45 INFO - Running main action method: clobber 08:19:45 INFO - rmtree: /builds/slave/test/build 08:19:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:19:48 INFO - Running post-action listener: _resource_record_post_action 08:19:48 INFO - [mozharness: 2016-12-25 16:19:48.458526Z] Finished clobber step (success) 08:19:48 INFO - [mozharness: 2016-12-25 16:19:48.458786Z] Running read-buildbot-config step. 08:19:48 INFO - Running pre-action listener: _resource_record_pre_action 08:19:48 INFO - Running main action method: read_buildbot_config 08:19:48 INFO - Using buildbot properties: 08:19:48 INFO - { 08:19:48 INFO - "project": "", 08:19:48 INFO - "product": "firefox", 08:19:48 INFO - "script_repo_revision": "production", 08:19:48 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 08:19:48 INFO - "repository": "", 08:19:48 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 08:19:48 INFO - "buildid": "20161225064336", 08:19:48 INFO - "pgo_build": "False", 08:19:48 INFO - "basedir": "/builds/slave/test", 08:19:48 INFO - "buildnumber": 19, 08:19:48 INFO - "slavename": "t-yosemite-r7-0068", 08:19:48 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 08:19:48 INFO - "platform": "yosemite_r7", 08:19:48 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:19:48 INFO - "branch": "mozilla-aurora", 08:19:48 INFO - "repo_path": "releases/mozilla-aurora", 08:19:48 INFO - "revision": "0597e6a2070b596ff07b4f061049f1f9505864d7", 08:19:48 INFO - "stage_platform": "macosx64", 08:19:48 INFO - "builduid": "38d556b093174298ad26a5e6577ca784", 08:19:48 INFO - "slavebuilddir": "test" 08:19:48 INFO - } 08:19:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/aqFHoS3LSXSTXJFWjySzjA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 08:19:48 INFO - Running post-action listener: _resource_record_post_action 08:19:48 INFO - [mozharness: 2016-12-25 16:19:48.464460Z] Finished read-buildbot-config step (success) 08:19:48 INFO - [mozharness: 2016-12-25 16:19:48.464613Z] Running download-and-extract step. 08:19:48 INFO - Running pre-action listener: _resource_record_pre_action 08:19:48 INFO - Running main action method: download_and_extract 08:19:48 INFO - mkdir: /builds/slave/test/build/tests 08:19:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:19:48 INFO - trying https://queue.taskcluster.net/v1/task/aqFHoS3LSXSTXJFWjySzjA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 08:19:48 INFO - Downloading https://queue.taskcluster.net/v1/task/aqFHoS3LSXSTXJFWjySzjA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:19:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aqFHoS3LSXSTXJFWjySzjA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 08:19:49 INFO - Downloaded 1199 bytes. 08:19:49 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:19:49 INFO - Using the following test package requirements: 08:19:49 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:19:49 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:19:49 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 08:19:49 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:19:49 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 08:19:49 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:19:49 INFO - u'jsshell-mac.zip'], 08:19:49 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:19:49 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 08:19:49 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:19:49 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:19:49 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 08:19:49 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:19:49 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 08:19:49 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:19:49 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 08:19:49 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:19:49 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 08:19:49 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:19:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aqFHoS3LSXSTXJFWjySzjA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:19:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aqFHoS3LSXSTXJFWjySzjA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:19:49 INFO - Fetch https://queue.taskcluster.net/v1/task/aqFHoS3LSXSTXJFWjySzjA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:19:50 INFO - Http code: 200 08:19:50 INFO - via: 1.1 76bce8bb4fbd102fc0b3aa2e41094b79.cloudfront.net (CloudFront) 08:19:50 INFO - x-amz-cf-id: q8aUPNOOUY3VpbZo-Qq-XL-Tmw1T3W-n4spZ1sRAXAX5ZfmfMOotxA== 08:19:50 INFO - x-amz-version-id: TaoSnGNQ4hA4zuLm7TC77BOdLI4zoVUT 08:19:50 INFO - Expected file size: 16271085 08:19:50 INFO - Obtained file size: 16271085 08:19:57 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 08:19:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aqFHoS3LSXSTXJFWjySzjA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:19:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aqFHoS3LSXSTXJFWjySzjA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:19:57 INFO - Fetch https://queue.taskcluster.net/v1/task/aqFHoS3LSXSTXJFWjySzjA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:19:57 INFO - Http code: 200 08:19:57 INFO - via: 1.1 b3bccc0e4c2cc0a33e7e8308d88cacdd.cloudfront.net (CloudFront) 08:19:57 INFO - x-amz-cf-id: EEBXmGyX8dJ-TkJYdrc7f8i74cflZ0snJVrR3pwRkGQ4Wc0i3vcfZA== 08:19:57 INFO - x-amz-version-id: TaoSnGNQ4hA4zuLm7TC77BOdLI4zoVUT 08:19:58 INFO - Expected file size: 16271085 08:19:58 INFO - Obtained file size: 16271085 08:20:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aqFHoS3LSXSTXJFWjySzjA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 08:20:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/aqFHoS3LSXSTXJFWjySzjA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:20:04 INFO - Fetch https://queue.taskcluster.net/v1/task/aqFHoS3LSXSTXJFWjySzjA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 08:20:05 INFO - Http code: 200 08:20:05 INFO - via: 1.1 d2bd759914e30b1d5aee2929535c55f9.cloudfront.net (CloudFront) 08:20:05 INFO - x-amz-cf-id: 89_6Cv28ZOKFPQ22byvKOKGZOUZJGkfwmbCXp2VM-eb_9vobKZhHKg== 08:20:05 INFO - x-amz-version-id: nJiJdst0J7Odrrx6k2F89k_vnX9Wcu8a 08:20:05 INFO - Expected file size: 14409038 08:20:05 INFO - Obtained file size: 14409038 08:20:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:06 INFO - trying https://queue.taskcluster.net/v1/task/aqFHoS3LSXSTXJFWjySzjA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:20:06 INFO - Downloading https://queue.taskcluster.net/v1/task/aqFHoS3LSXSTXJFWjySzjA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 08:20:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aqFHoS3LSXSTXJFWjySzjA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 08:20:08 INFO - Downloaded 109782496 bytes. 08:20:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aqFHoS3LSXSTXJFWjySzjA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:20:08 INFO - mkdir: /builds/slave/test/properties 08:20:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:20:08 INFO - Writing to file /builds/slave/test/properties/build_url 08:20:08 INFO - Contents: 08:20:08 INFO - build_url:https://queue.taskcluster.net/v1/task/aqFHoS3LSXSTXJFWjySzjA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:20:09 INFO - Running post-action listener: _resource_record_post_action 08:20:09 INFO - Running post-action listener: set_extra_try_arguments 08:20:09 INFO - [mozharness: 2016-12-25 16:20:09.619244Z] Finished download-and-extract step (success) 08:20:09 INFO - [mozharness: 2016-12-25 16:20:09.619365Z] Running populate-webroot step. 08:20:09 INFO - Running pre-action listener: _resource_record_pre_action 08:20:09 INFO - Running main action method: populate_webroot 08:20:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:20:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:20:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:20:09 INFO - '--tppagecycles', 08:20:09 INFO - '7'], 08:20:09 INFO - 'tests': ['tsvgm']}}, 08:20:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:20:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:20:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:20:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:20:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:20:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:20:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:20:09 INFO - 'talos_options': ['--disable-e10s'], 08:20:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:20:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:20:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:20:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:20:09 INFO - 'talos_options': ['--disable-e10s'], 08:20:09 INFO - 'tests': ['damp', 'tps']}, 08:20:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:20:09 INFO - 'tests': ['damp', 'tps']}, 08:20:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:20:09 INFO - 'tests': ['dromaeo_dom']}, 08:20:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:20:09 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:20:09 INFO - 'tests': ['basic_compositor_video']}, 08:20:09 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:20:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:20:09 INFO - 'tests': ['a11yr', 08:20:09 INFO - 'ts_paint', 08:20:09 INFO - 'tpaint', 08:20:09 INFO - 'sessionrestore', 08:20:09 INFO - 'sessionrestore_no_auto_restore', 08:20:09 INFO - 'tabpaint']}, 08:20:09 INFO - 'other-e10s': {'tests': ['a11yr', 08:20:09 INFO - 'ts_paint', 08:20:09 INFO - 'tpaint', 08:20:09 INFO - 'sessionrestore', 08:20:09 INFO - 'sessionrestore_no_auto_restore', 08:20:09 INFO - 'tabpaint']}, 08:20:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:20:09 INFO - 'ts_paint', 08:20:09 INFO - 'tpaint', 08:20:09 INFO - 'sessionrestore', 08:20:09 INFO - 'sessionrestore_no_auto_restore', 08:20:09 INFO - 'tabpaint']}, 08:20:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:20:09 INFO - 'ts_paint', 08:20:09 INFO - 'tpaint', 08:20:09 INFO - 'sessionrestore', 08:20:09 INFO - 'sessionrestore_no_auto_restore', 08:20:09 INFO - 'tabpaint']}, 08:20:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:20:09 INFO - 'tests': ['a11yr', 08:20:09 INFO - 'ts_paint', 08:20:09 INFO - 'tpaint', 08:20:09 INFO - 'sessionrestore', 08:20:09 INFO - 'sessionrestore_no_auto_restore', 08:20:09 INFO - 'tabpaint']}, 08:20:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:20:09 INFO - 'tests': ['a11yr', 08:20:09 INFO - 'ts_paint', 08:20:09 INFO - 'tpaint', 08:20:09 INFO - 'sessionrestore', 08:20:09 INFO - 'sessionrestore_no_auto_restore', 08:20:09 INFO - 'tabpaint']}, 08:20:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:20:09 INFO - 'tests': ['tsvgx', 08:20:09 INFO - 'tsvgr_opacity', 08:20:09 INFO - 'tart', 08:20:09 INFO - 'tscrollx', 08:20:09 INFO - 'cart']}, 08:20:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:20:09 INFO - 'tsvgr_opacity', 08:20:09 INFO - 'tart', 08:20:09 INFO - 'tscrollx', 08:20:09 INFO - 'cart']}, 08:20:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:20:09 INFO - 'talos_options': ['--disable-e10s'], 08:20:09 INFO - 'tests': ['tp5o']}, 08:20:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:20:09 INFO - 'tests': ['tp5o']}, 08:20:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:20:09 INFO - 'talos_options': ['--disable-e10s', 08:20:09 INFO - '--xperf_path', 08:20:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:20:09 INFO - 'tests': ['tp5n']}, 08:20:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:20:09 INFO - 'talos_options': ['--xperf_path', 08:20:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:20:09 INFO - 'tests': ['tp5n']}}, 08:20:09 INFO - 'talos.zip': {'path': '', 08:20:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:20:09 INFO - Running post-action listener: _resource_record_post_action 08:20:09 INFO - [mozharness: 2016-12-25 16:20:09.638030Z] Finished populate-webroot step (success) 08:20:09 INFO - [mozharness: 2016-12-25 16:20:09.638173Z] Running create-virtualenv step. 08:20:09 INFO - Running pre-action listener: _resource_record_pre_action 08:20:09 INFO - Running main action method: create_virtualenv 08:20:09 INFO - Creating virtualenv /builds/slave/test/build/venv 08:20:09 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:20:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:20:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:20:09 INFO - Using real prefix '/tools/python27' 08:20:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:20:10 INFO - Installing distribute.............................................................................................................................................................................................done. 08:20:13 INFO - Installing pip.................done. 08:20:13 INFO - Return code: 0 08:20:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:20:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:20:13 INFO - Reading from file tmpfile_stdout 08:20:13 INFO - Output received: 08:20:13 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:20:13 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:20:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10faf1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa67570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcaf94b9dc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f905d30>, '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 0x10f76bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f8fdb20>, '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.sO5uo60ziu/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.y455Mm2SPA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 08:20:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:20:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:20:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sO5uo60ziu/Render', 08:20:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:13 INFO - 'HOME': '/Users/cltbld', 08:20:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:13 INFO - 'LOGNAME': 'cltbld', 08:20:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:13 INFO - 'MOZ_NO_REMOTE': '1', 08:20:13 INFO - 'NO_EM_RESTART': '1', 08:20:13 INFO - 'PAGER': '/bin/cat', 08:20:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:13 INFO - 'PWD': '/builds/slave/test', 08:20:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:13 INFO - 'SHELL': '/bin/bash', 08:20:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.y455Mm2SPA/Listeners', 08:20:13 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 08:20:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:13 INFO - 'USER': 'cltbld', 08:20:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:13 INFO - 'XPC_FLAGS': '0x0', 08:20:13 INFO - 'XPC_SERVICE_NAME': '0', 08:20:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:13 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:20:13 INFO - Cleaning up... 08:20:13 INFO - Return code: 0 08:20:13 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:20:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10faf1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa67570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcaf94b9dc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f905d30>, '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 0x10f76bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f8fdb20>, '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.sO5uo60ziu/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.y455Mm2SPA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 08:20:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:20:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:20:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sO5uo60ziu/Render', 08:20:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:13 INFO - 'HOME': '/Users/cltbld', 08:20:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:13 INFO - 'LOGNAME': 'cltbld', 08:20:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:13 INFO - 'MOZ_NO_REMOTE': '1', 08:20:13 INFO - 'NO_EM_RESTART': '1', 08:20:13 INFO - 'PAGER': '/bin/cat', 08:20:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:13 INFO - 'PWD': '/builds/slave/test', 08:20:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:13 INFO - 'SHELL': '/bin/bash', 08:20:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.y455Mm2SPA/Listeners', 08:20:13 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 08:20:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:13 INFO - 'USER': 'cltbld', 08:20:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:13 INFO - 'XPC_FLAGS': '0x0', 08:20:13 INFO - 'XPC_SERVICE_NAME': '0', 08:20:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:14 INFO - Downloading/unpacking psutil>=3.1.1 08:20:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:20:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:20:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:20:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:20:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:20:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:20:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:20:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:20:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:20:17 INFO - Installing collected packages: psutil 08:20:17 INFO - Running setup.py install for psutil 08:20:17 INFO - building 'psutil._psutil_osx' extension 08:20:17 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 08:20:18 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 08:20:18 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 08:20:18 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 08:20:18 INFO - building 'psutil._psutil_posix' extension 08:20:18 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 08:20:18 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:20:18 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:20:18 INFO - ^ 08:20:18 INFO - 1 warning generated. 08:20:18 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 08:20:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:20:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:20:18 INFO - Successfully installed psutil 08:20:18 INFO - Cleaning up... 08:20:18 INFO - Return code: 0 08:20:18 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:20:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10faf1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa67570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcaf94b9dc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f905d30>, '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 0x10f76bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f8fdb20>, '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.sO5uo60ziu/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.y455Mm2SPA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 08:20:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:20:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:20:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sO5uo60ziu/Render', 08:20:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:18 INFO - 'HOME': '/Users/cltbld', 08:20:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:18 INFO - 'LOGNAME': 'cltbld', 08:20:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:18 INFO - 'MOZ_NO_REMOTE': '1', 08:20:18 INFO - 'NO_EM_RESTART': '1', 08:20:18 INFO - 'PAGER': '/bin/cat', 08:20:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:18 INFO - 'PWD': '/builds/slave/test', 08:20:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:18 INFO - 'SHELL': '/bin/bash', 08:20:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.y455Mm2SPA/Listeners', 08:20:18 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 08:20:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:18 INFO - 'USER': 'cltbld', 08:20:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:18 INFO - 'XPC_FLAGS': '0x0', 08:20:18 INFO - 'XPC_SERVICE_NAME': '0', 08:20:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:19 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:20:19 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 08:20:19 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 08:20:19 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 08:20:19 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 08:20:19 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 08:20:19 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 08:20:21 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:20:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:20:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:20:21 INFO - Installing collected packages: mozsystemmonitor 08:20:21 INFO - Running setup.py install for mozsystemmonitor 08:20:22 INFO - Successfully installed mozsystemmonitor 08:20:22 INFO - Cleaning up... 08:20:22 INFO - Return code: 0 08:20:22 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:20:22 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')]} 08:20:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:22 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')]} 08:20:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:22 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 0x10faf1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa67570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcaf94b9dc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f905d30>, '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 0x10f76bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f8fdb20>, '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.sO5uo60ziu/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.y455Mm2SPA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 08:20:22 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 08:20:22 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 08:20:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sO5uo60ziu/Render', 08:20:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:22 INFO - 'HOME': '/Users/cltbld', 08:20:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:22 INFO - 'LOGNAME': 'cltbld', 08:20:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:22 INFO - 'MOZ_NO_REMOTE': '1', 08:20:22 INFO - 'NO_EM_RESTART': '1', 08:20:22 INFO - 'PAGER': '/bin/cat', 08:20:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:22 INFO - 'PWD': '/builds/slave/test', 08:20:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:22 INFO - 'SHELL': '/bin/bash', 08:20:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.y455Mm2SPA/Listeners', 08:20:22 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 08:20:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:22 INFO - 'USER': 'cltbld', 08:20:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:22 INFO - 'XPC_FLAGS': '0x0', 08:20:22 INFO - 'XPC_SERVICE_NAME': '0', 08:20:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:22 INFO - Downloading/unpacking jsonschema==2.5.1 08:20:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:20:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:20:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:20:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:20:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:20:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:20:24 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:20:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:20:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:20:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:20:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:20:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:20:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:20:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:20:25 INFO - Downloading functools32-3.2.3-2.tar.gz 08:20:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:20:25 INFO - warning: no files found matching '*.txt' 08:20:25 INFO - no previously-included directories found matching 'build' 08:20:25 INFO - no previously-included directories found matching 'dist' 08:20:25 INFO - no previously-included directories found matching '.git*' 08:20:25 INFO - Installing collected packages: jsonschema, functools32 08:20:25 INFO - Running setup.py install for functools32 08:20:25 INFO - warning: no files found matching '*.txt' 08:20:25 INFO - no previously-included directories found matching 'build' 08:20:25 INFO - no previously-included directories found matching 'dist' 08:20:25 INFO - no previously-included directories found matching '.git*' 08:20:25 INFO - Successfully installed jsonschema functools32 08:20:25 INFO - Cleaning up... 08:20:25 INFO - Return code: 0 08:20:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:20:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10faf1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa67570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcaf94b9dc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f905d30>, '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 0x10f76bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f8fdb20>, '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.sO5uo60ziu/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.y455Mm2SPA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 08:20:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:20:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:20:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sO5uo60ziu/Render', 08:20:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:25 INFO - 'HOME': '/Users/cltbld', 08:20:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:25 INFO - 'LOGNAME': 'cltbld', 08:20:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:25 INFO - 'MOZ_NO_REMOTE': '1', 08:20:25 INFO - 'NO_EM_RESTART': '1', 08:20:25 INFO - 'PAGER': '/bin/cat', 08:20:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:25 INFO - 'PWD': '/builds/slave/test', 08:20:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:25 INFO - 'SHELL': '/bin/bash', 08:20:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.y455Mm2SPA/Listeners', 08:20:25 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 08:20:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:25 INFO - 'USER': 'cltbld', 08:20:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:25 INFO - 'XPC_FLAGS': '0x0', 08:20:25 INFO - 'XPC_SERVICE_NAME': '0', 08:20:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:20:26 INFO - Cleaning up... 08:20:26 INFO - Return code: 0 08:20:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:20: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')]} 08:20:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20: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')]} 08:20:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10faf1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa67570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcaf94b9dc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f905d30>, '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 0x10f76bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f8fdb20>, '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.sO5uo60ziu/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.y455Mm2SPA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 08:20:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:20:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:20:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sO5uo60ziu/Render', 08:20:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:26 INFO - 'HOME': '/Users/cltbld', 08:20:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:26 INFO - 'LOGNAME': 'cltbld', 08:20:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:26 INFO - 'MOZ_NO_REMOTE': '1', 08:20:26 INFO - 'NO_EM_RESTART': '1', 08:20:26 INFO - 'PAGER': '/bin/cat', 08:20:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:26 INFO - 'PWD': '/builds/slave/test', 08:20:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:26 INFO - 'SHELL': '/bin/bash', 08:20:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.y455Mm2SPA/Listeners', 08:20:26 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 08:20:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:26 INFO - 'USER': 'cltbld', 08:20:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:26 INFO - 'XPC_FLAGS': '0x0', 08:20:26 INFO - 'XPC_SERVICE_NAME': '0', 08:20:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:26 INFO - Downloading/unpacking blobuploader==1.2.4 08:20:26 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 08:20:26 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 08:20:26 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 08:20:26 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 08:20:26 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 08:20:26 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 08:20:28 INFO - Downloading blobuploader-1.2.4.tar.gz 08:20:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:20:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:20:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:20:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:20:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:20:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:20:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:20:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:20:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:20:29 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:20:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:20:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:20:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:20:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:20:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:20:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:20:30 INFO - Downloading docopt-0.6.1.tar.gz 08:20:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:20:30 INFO - Installing collected packages: blobuploader, requests, docopt 08:20:30 INFO - Running setup.py install for blobuploader 08:20:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:20:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:20:30 INFO - Running setup.py install for requests 08:20:30 INFO - Running setup.py install for docopt 08:20:31 INFO - Successfully installed blobuploader requests docopt 08:20:31 INFO - Cleaning up... 08:20:31 INFO - Return code: 0 08:20:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:20:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:31 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 0x10faf1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa67570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcaf94b9dc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f905d30>, '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 0x10f76bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f8fdb20>, '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.sO5uo60ziu/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.y455Mm2SPA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 08:20:31 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 08:20:31 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 08:20:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sO5uo60ziu/Render', 08:20:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:31 INFO - 'HOME': '/Users/cltbld', 08:20:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:31 INFO - 'LOGNAME': 'cltbld', 08:20:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:31 INFO - 'MOZ_NO_REMOTE': '1', 08:20:31 INFO - 'NO_EM_RESTART': '1', 08:20:31 INFO - 'PAGER': '/bin/cat', 08:20:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:31 INFO - 'PWD': '/builds/slave/test', 08:20:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:31 INFO - 'SHELL': '/bin/bash', 08:20:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.y455Mm2SPA/Listeners', 08:20:31 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 08:20:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:31 INFO - 'USER': 'cltbld', 08:20:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:31 INFO - 'XPC_FLAGS': '0x0', 08:20:31 INFO - 'XPC_SERVICE_NAME': '0', 08:20:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:20:31 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-DGV6ON-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:20:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:20:31 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-xn0oIL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:20:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:20:31 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-6T5Uf6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:20:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:20:31 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-opQ7uc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:20:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:20:31 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-K1X1Bg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:20:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:20:31 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-ZFWrIP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:20:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:20:32 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-v5YhOt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:20:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:20:32 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-Nr1NS7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:20:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:20:32 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-WQlv3J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:20:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:20:32 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-zERJ_A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:20:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:20:32 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-yfNKqL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:20:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:20:32 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-87pjvv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:20:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:20:32 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-XG5wVq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:20:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:20:32 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-EcmRnM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:20:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:20:32 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-GUt1KH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:20:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:20:33 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-Uv3icM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:20:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:20:33 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-Zk7yBl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:20:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:20:33 INFO - Running setup.py install for manifestparser 08:20:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:20:33 INFO - Running setup.py install for mozcrash 08:20:33 INFO - Running setup.py install for mozdebug 08:20:33 INFO - Running setup.py install for mozdevice 08:20:33 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:20:33 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:20:33 INFO - Running setup.py install for mozfile 08:20:33 INFO - Running setup.py install for mozhttpd 08:20:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:20:34 INFO - Running setup.py install for mozinfo 08:20:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:20:34 INFO - Running setup.py install for mozInstall 08:20:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:20:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:20:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:20:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:20:34 INFO - Running setup.py install for mozleak 08:20:34 INFO - Running setup.py install for mozlog 08:20:34 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:20:34 INFO - Running setup.py install for moznetwork 08:20:34 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:20:34 INFO - Running setup.py install for mozprocess 08:20:35 INFO - Running setup.py install for mozprofile 08:20:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:20:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:20:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:20:35 INFO - Running setup.py install for mozrunner 08:20:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:20:35 INFO - Running setup.py install for mozscreenshot 08:20:35 INFO - Running setup.py install for moztest 08:20:35 INFO - Running setup.py install for mozversion 08:20:35 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:20:35 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:20:35 INFO - Cleaning up... 08:20:35 INFO - Return code: 0 08:20:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:20:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10faf1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa67570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcaf94b9dc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f905d30>, '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 0x10f76bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f8fdb20>, '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.sO5uo60ziu/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.y455Mm2SPA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 08:20:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:20:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:20:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sO5uo60ziu/Render', 08:20:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:35 INFO - 'HOME': '/Users/cltbld', 08:20:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:35 INFO - 'LOGNAME': 'cltbld', 08:20:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:35 INFO - 'MOZ_NO_REMOTE': '1', 08:20:35 INFO - 'NO_EM_RESTART': '1', 08:20:35 INFO - 'PAGER': '/bin/cat', 08:20:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:35 INFO - 'PWD': '/builds/slave/test', 08:20:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:35 INFO - 'SHELL': '/bin/bash', 08:20:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.y455Mm2SPA/Listeners', 08:20:35 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 08:20:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:35 INFO - 'USER': 'cltbld', 08:20:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:35 INFO - 'XPC_FLAGS': '0x0', 08:20:35 INFO - 'XPC_SERVICE_NAME': '0', 08:20:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:20:36 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-WJQSlO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:20:36 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)) 08:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:20:36 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-D5Ip_c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:20:36 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)) 08:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:20:36 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-pI34L6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:20:36 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)) 08:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:20:36 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-1ZlpYS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:20:36 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)) 08:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:20:36 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-UCRbdI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:20:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:20:36 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-btmvv1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:20:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:20:36 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-XF_sPK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:20:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:20:36 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-CglKKb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:20:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:20:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:20:36 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-Yxla_y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:20:37 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)) 08:20:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:20:37 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-ETztXm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:20:37 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)) 08:20:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:20:37 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-ZaFGeY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:20:37 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)) 08:20:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:20:37 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-fRy8xb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:20:37 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)) 08:20:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:20:37 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-tdThl8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:20:37 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)) 08:20:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:20:37 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-W26Dfj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:20:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:20:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:20:37 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-Sf8h09-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:20:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:20:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:20:37 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-UlU0oB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:20:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:20:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:20:37 INFO - Running setup.py (path:/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/pip-kuJgER-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:20:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:20:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:20:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:20:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:20:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:20:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:20:37 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:20:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:20:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:20:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:20:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:20:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:20:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:20:40 INFO - Downloading blessings-1.6.tar.gz 08:20:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:20:40 INFO - Installing collected packages: blessings 08:20:40 INFO - Running setup.py install for blessings 08:20:40 INFO - Successfully installed blessings 08:20:40 INFO - Cleaning up... 08:20:40 INFO - Return code: 0 08:20:40 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:20:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:20:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:20:41 INFO - Reading from file tmpfile_stdout 08:20:41 INFO - Current package versions: 08:20:41 INFO - blessings == 1.6 08:20:41 INFO - blobuploader == 1.2.4 08:20:41 INFO - docopt == 0.6.1 08:20:41 INFO - functools32 == 3.2.3-2 08:20:41 INFO - jsonschema == 2.5.1 08:20:41 INFO - manifestparser == 1.1 08:20:41 INFO - mozInstall == 1.12 08:20:41 INFO - mozcrash == 1.0 08:20:41 INFO - mozdebug == 0.1 08:20:41 INFO - mozdevice == 0.48 08:20:41 INFO - mozfile == 1.2 08:20:41 INFO - mozhttpd == 0.7 08:20:41 INFO - mozinfo == 0.9 08:20:41 INFO - mozleak == 0.1 08:20:41 INFO - mozlog == 3.4 08:20:41 INFO - moznetwork == 0.27 08:20:41 INFO - mozprocess == 0.23 08:20:41 INFO - mozprofile == 0.28 08:20:41 INFO - mozrunner == 6.13 08:20:41 INFO - mozscreenshot == 0.1 08:20:41 INFO - mozsystemmonitor == 0.3 08:20:41 INFO - moztest == 0.7 08:20:41 INFO - mozversion == 1.4 08:20:41 INFO - psutil == 3.1.1 08:20:41 INFO - requests == 1.2.3 08:20:41 INFO - wsgiref == 0.1.2 08:20:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:20:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:20:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10faf1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa67570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcaf94b9dc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f905d30>, '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 0x10f76bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f8fdb20>, '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.sO5uo60ziu/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.y455Mm2SPA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 08:20:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:20:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:20:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sO5uo60ziu/Render', 08:20:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:41 INFO - 'HOME': '/Users/cltbld', 08:20:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:41 INFO - 'LOGNAME': 'cltbld', 08:20:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:41 INFO - 'MOZ_NO_REMOTE': '1', 08:20:41 INFO - 'NO_EM_RESTART': '1', 08:20:41 INFO - 'PAGER': '/bin/cat', 08:20:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:41 INFO - 'PWD': '/builds/slave/test', 08:20:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:41 INFO - 'SHELL': '/bin/bash', 08:20:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.y455Mm2SPA/Listeners', 08:20:41 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 08:20:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:41 INFO - 'USER': 'cltbld', 08:20:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:41 INFO - 'XPC_FLAGS': '0x0', 08:20:41 INFO - 'XPC_SERVICE_NAME': '0', 08:20:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:42 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)) 08:20:42 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)) 08:20:42 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)) 08:20:42 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)) 08:20:42 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)) 08:20:42 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)) 08:20:42 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)) 08:20:42 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)) 08:20:42 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)) 08:20:42 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)) 08:20:42 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)) 08:20:42 INFO - Cleaning up... 08:20:42 INFO - Return code: 0 08:20:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:20: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')]} 08:20:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:20:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20: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')]} 08:20:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:20:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:20:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10faf1490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fa67570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcaf94b9dc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f905d30>, '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 0x10f76bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f8fdb20>, '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.sO5uo60ziu/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.y455Mm2SPA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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 08:20:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:20: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 08:20:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sO5uo60ziu/Render', 08:20:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:20:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:20:42 INFO - 'HOME': '/Users/cltbld', 08:20:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:20:42 INFO - 'LOGNAME': 'cltbld', 08:20:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:20:42 INFO - 'MOZ_NO_REMOTE': '1', 08:20:42 INFO - 'NO_EM_RESTART': '1', 08:20:42 INFO - 'PAGER': '/bin/cat', 08:20:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:20:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:20:42 INFO - 'PWD': '/builds/slave/test', 08:20:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:20:42 INFO - 'SHELL': '/bin/bash', 08:20:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.y455Mm2SPA/Listeners', 08:20:42 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 08:20:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:20:42 INFO - 'USER': 'cltbld', 08:20:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:20:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:20:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:20:42 INFO - 'XPC_FLAGS': '0x0', 08:20:42 INFO - 'XPC_SERVICE_NAME': '0', 08:20:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:20:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:20:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:20:42 INFO - Cleaning up... 08:20:42 INFO - Return code: 0 08:20:42 INFO - Running post-action listener: _resource_record_post_action 08:20:42 INFO - Running post-action listener: _start_resource_monitoring 08:20:42 INFO - Starting resource monitoring. 08:20:42 INFO - [mozharness: 2016-12-25 16:20:42.758301Z] Finished create-virtualenv step (success) 08:20:42 INFO - [mozharness: 2016-12-25 16:20:42.759303Z] Running install step. 08:20:42 INFO - Running pre-action listener: _resource_record_pre_action 08:20:42 INFO - Running main action method: install 08:20:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:20:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:20:43 INFO - Reading from file tmpfile_stdout 08:20:43 INFO - Detecting whether we're running mozinstall >=1.0... 08:20:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:20:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:20:43 INFO - Reading from file tmpfile_stdout 08:20:43 INFO - Output received: 08:20:43 INFO - Usage: mozinstall [options] installer 08:20:43 INFO - Options: 08:20:43 INFO - -h, --help show this help message and exit 08:20:43 INFO - -d DEST, --destination=DEST 08:20:43 INFO - Directory to install application into. [default: 08:20:43 INFO - "/builds/slave/test"] 08:20:43 INFO - --app=APP Application being installed. [default: firefox] 08:20:43 INFO - mkdir: /builds/slave/test/build/application 08:20:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:20:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 08:21:13 INFO - Reading from file tmpfile_stdout 08:21:13 INFO - Output received: 08:21:13 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:21:13 INFO - Running post-action listener: _resource_record_post_action 08:21:13 INFO - [mozharness: 2016-12-25 16:21:13.725159Z] Finished install step (success) 08:21:13 INFO - [mozharness: 2016-12-25 16:21:13.725313Z] Running run-tests step. 08:21:13 INFO - Running pre-action listener: _resource_record_pre_action 08:21:13 INFO - Running main action method: run_tests 08:21:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:21:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:21:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:21:13 INFO - 2016-12-25 08:21:13.739 screenresolution[1629:7334] starting screenresolution argv=screenresolution get 08:21:13 INFO - 2016-12-25 08:21:13.742 screenresolution[1629:7334] Display 0: 1600x1200x32@60 08:21:13 INFO - 2016-12-25 08:21:13.748 screenresolution[1630:7336] starting screenresolution argv=screenresolution list 08:21:13 INFO - Available Modes on Display 0 08:21:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:21:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:21:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:21:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:21:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:21:13 INFO - Intel Iris: 08:21:13 INFO - Chipset Model: Intel Iris 08:21:13 INFO - Type: GPU 08:21:13 INFO - Bus: Built-In 08:21:13 INFO - VRAM (Dynamic, Max): 1536 MB 08:21:13 INFO - Vendor: Intel (0x8086) 08:21:13 INFO - Device ID: 0x0a2e 08:21:13 INFO - Revision ID: 0x0009 08:21:13 INFO - Displays: 08:21:13 INFO - Display: 08:21:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:21:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:21:13 INFO - Display Serial Number: 1600x1200 60 08:21:13 INFO - Main Display: Yes 08:21:13 INFO - Mirror: Off 08:21:13 INFO - Online: Yes 08:21:13 INFO - Rotation: Supported 08:21:13 INFO - Return code: 0 08:21:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:21:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:21:13 INFO - Python 2.7.3 08:21:13 INFO - Return code: 0 08:21:13 INFO - grabbing minidump binary from tooltool 08:21:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:21:13 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 0x10f905d30>, '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 0x10f76bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f8fdb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:21:13 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 08:21:13 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 08:21:13 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 08:21:14 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:21:14 INFO - Return code: 0 08:21:14 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:21:14 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:21:14 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:21:14 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:21:14 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:21:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aqFHoS3LSXSTXJFWjySzjA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0068', '--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 08:21:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/aqFHoS3LSXSTXJFWjySzjA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0068 --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 08:21:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sO5uo60ziu/Render', 08:21:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:21:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:21:14 INFO - 'HOME': '/Users/cltbld', 08:21:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:21:14 INFO - 'LOGNAME': 'cltbld', 08:21:14 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:21:14 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:21:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:21:14 INFO - 'MOZ_NO_REMOTE': '1', 08:21:14 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:21:14 INFO - 'NO_EM_RESTART': '1', 08:21:14 INFO - 'PAGER': '/bin/cat', 08:21:14 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:21:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:21:14 INFO - 'PWD': '/builds/slave/test', 08:21:14 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:21:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:21:14 INFO - 'SHELL': '/bin/bash', 08:21:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.y455Mm2SPA/Listeners', 08:21:14 INFO - 'TMPDIR': '/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/', 08:21:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:21:14 INFO - 'USER': 'cltbld', 08:21:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:21:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:21:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:21:14 INFO - 'XPC_FLAGS': '0x0', 08:21:14 INFO - 'XPC_SERVICE_NAME': '0', 08:21:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:21:14 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aqFHoS3LSXSTXJFWjySzjA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0068', '--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 08:21:14 INFO - mozversion application_buildid: 20161225064336 08:21:14 INFO - mozversion application_changeset: 0597e6a2070b596ff07b4f061049f1f9505864d7 08:21:14 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:21:14 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:21:14 INFO - mozversion application_name: Firefox 08:21:14 INFO - mozversion application_remotingname: firefox-dev 08:21:14 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:21:14 INFO - mozversion application_vendor: Mozilla 08:21:14 INFO - mozversion application_version: 52.0a2 08:21:14 INFO - mozversion platform_buildid: 20161225064336 08:21:14 INFO - mozversion platform_changeset: 0597e6a2070b596ff07b4f061049f1f9505864d7 08:21:14 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:21:14 INFO - mozversion platform_version: 52.0a2 08:21:14 INFO - using testdate: 1482682874 08:21:14 INFO - actual date: 1482682874 08:21:14 INFO - starting webserver on 'localhost:49244' 08:21:14 INFO - SUITE-START | Running 2 tests 08:21:14 INFO - TEST-START | dromaeo_css 08:21:14 INFO - operating with platform_type : mac_ 08:21:14 INFO - Initialising browser for dromaeo_css test... 08:21:14 INFO - TEST-INFO | started process 1640 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmp6r7I0W/profile http://localhost:49244/getInfo.html) 08:21:17 INFO - PROCESS | 1640 | 2016-12-25 08:21:17.627 plugin-container[1642:7513] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a3f, name = 'com.apple.tsm.portname' 08:21:17 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:21:17 INFO - PROCESS | 1640 | 2016-12-25 08:21:17.630 plugin-container[1642:7513] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 08:21:17 INFO - PROCESS | 1640 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:21:17 INFO - PROCESS | 1640 | 2016-12-25 08:21:17.630 plugin-container[1642:7513] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:21:17 INFO - PROCESS | 1640 | __metrics Screen width/height:1600/1200 08:21:17 INFO - PROCESS | 1640 | colorDepth:24 08:21:17 INFO - PROCESS | 1640 | Browser inner width/height: 1024/689 08:21:17 INFO - PROCESS | 1640 | __metrics 08:21:17 ERROR - PROCESS | 1640 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 08:21:18 INFO - TEST-INFO | 1640: exit 0 08:21:18 INFO - Browser initialized. 08:21:18 INFO - Running cycle 1/1 for dromaeo_css test... 08:21:18 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmp6r7I0W/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:21:21 INFO - PROCESS | 1643 | 2016-12-25 08:21:21.411 plugin-container[1644:7643] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843f, name = 'com.apple.tsm.portname' 08:21:21 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:21:21 INFO - PROCESS | 1643 | 2016-12-25 08:21:21.414 plugin-container[1644:7643] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4327, name = 'com.apple.CFPasteboardClient' 08:21:21 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:21:21 INFO - PROCESS | 1643 | 2016-12-25 08:21:21.414 plugin-container[1644:7643] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:21:30 INFO - PROCESS | 1643 | RSS: Main: 229257216 08:21:30 INFO - PROCESS | 1643 | 08:22:20 INFO - PROCESS | 1643 | 1482682940595 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 08:24:34 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49244/tests/dromaeo/cssquery-ext.html) 08:24:36 INFO - PROCESS | 1643 | RSS: Main: 232198144 08:24:36 INFO - PROCESS | 1643 | 08:27:39 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-ext.html (next: http://localhost:49244/tests/dromaeo/cssquery-jquery.html) 08:27:39 INFO - PROCESS | 1643 | RSS: Main: 243212288 08:27:39 INFO - PROCESS | 1643 | 08:30:41 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49244/tests/dromaeo/cssquery-mootools.html) 08:30:42 INFO - PROCESS | 1643 | RSS: Main: 246095872 08:30:42 INFO - PROCESS | 1643 | 08:33:44 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49244/tests/dromaeo/cssquery-prototype.html) 08:33:44 INFO - PROCESS | 1643 | RSS: Main: 245956608 08:33:44 INFO - PROCESS | 1643 | 08:36:47 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49244/tests/dromaeo/cssquery-yui.html) 08:36:48 INFO - PROCESS | 1643 | RSS: Main: 248860672 08:36:48 INFO - PROCESS | 1643 | 08:39:50 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-yui.html (next: http://localhost:49244/tests/dromaeo/cssquery-dojo.html) 08:39:50 INFO - PROCESS | 1643 | RSS: Main: 251559936 08:39:50 INFO - PROCESS | 1643 | 08:39:50 INFO - PROCESS | 1643 | __start_tp_report 08:39:50 INFO - PROCESS | 1643 | _x_x_mozilla_page_load 08:39:50 INFO - PROCESS | 1643 | _x_x_mozilla_page_load_details 08:39:50 INFO - PROCESS | 1643 | |i|pagename|runs| 08:39:50 INFO - PROCESS | 1643 | |0;dojo.html;2824.1104052223554;2882.812617179883;3206;3367.2255381262307;3375.7468189885763;4694.835680751179;5525.557955363572;5608.663480191191;5633.718314084294;5722.942770572293;4624.7456389898625;4874.8537543873745;5431.777849983751;5529.640573362729;5759.136129580565;5231.738413079341;5260.579153667697;5315.415304316522;5316.362036555619;5331.093714068598;5280;5303.965726683298;5319.867003324909;5335.359756829186;5374;4347.586979236968;4780.259059845729;4914.434839993393;5282.31329927109;5389.83830485086;5136.178211486182;5150.459201783796;5234;5290.418054014055;5315.548178404821;5166.173412254059;5216.7913283468615;5225.869353266161;5227.895442091137;5325.360956685184;5824.767009319622;5849.268841394825;5925.022371308729;5932.139839723216;5934;6136.396539415845;6156.938430615726;6211.565190436671;6271.655058971754;6308.716107775161;6241.438270555672;6253.906191407087;6255.499560035186;6263.217097862767;6267.1539342189135;4829.323894654751;5392.649477783932;5832;5880.647161170344;5886.205362276123;5259.815906443333;6156.846078848064;6183.3816618337805;6230.937690623126;6231.594946328474;6189.96905015481;6195.070739389038;6199.938000620026;6221.129041934133;6225.408586184396;6250.531210159256;6251.78118765841;6263.843403914939;6264.21697287839;6306.936930630635;6105.48103411215;6117.562372842379;6135.202044401884;6143.539234557426;6232.470240029557;6203.844903877439;6222.844428889314;6249.968750156311;6258.968705156536;6300.2754683212015;4631.397918270603;4858.73276969763;4907.9263811042865;4957.0085982803585;5008.449070602231;4409.162259170747;4417.1386579616665;4461.553844615577;4474.395956545824;4502.234620114588;5830;5867.14926335679;5877;5917.964356237641;5946.613470124428;5418.83743487696;6150.7539698411565;6167.013277875519;6179.752809887644;6187.566870319034;4638.1883170445035;4839.838438774739;5896.498797602252;5924.229850119457;5975.163477113208;5290.576753859682;5339.385970613352;5356.839294821161;5420.783168673288;5427.348718153768;6282.685865706696;6294.590851594632;6308.936910630835;6402.807915762534;6417.069524918954;6156.661383623944;6176.876462470816;6184.443400094013;6185.072239163981;6191.226096737908;6130.673316708229;6316.242050953915;6385.13800636908;6395;6422.935770642233;6093.9085913712215;6122.357152499102;6126.081087836682;6130.47890929276;6233.2208473940755;5498.367687715964;5944.9405505946115;6207.131001659681;6225.968870155802;6227.844303892258;6094.51243900478;6095.1771510845465;6152.507799376129;6169.074638804215;6192.071189321638;5633.183188437735;5894.823155305347;5896.204012458435;5999.160117583455;6002.129691194839;5299.205119232146;5360.597955153301;5797.101449275369;5800.361960184292;5833.241678581757;5958.195643588234;5958.612690175125;6031.517478601613;6065.423784740443;6125.84685382869;5991.940080599313;6003.8198854034445;6009.609375390585;6013;6051.788187413419 08:39:50 INFO - PROCESS | 1643 | |1;ext.html;458.3605869811615;487.1499233836953;498.48904872505676;504.91164046291897;511.764588289387;23233.303000909957;31013.379732405367;39943.800280998556;41025.7948710256;41212.58787412125;40480.39279410811;40827.79586102073;40874.182516349654;40995.77012689617;41079.17841643173;9188.862167067482;9277.489738064438;9311.394759340621;9355.4386736796;9388.295877809158;9245.44527328359;9320.067993200693;9338.019507951669;9355.158035776778;9390.5304734763;9327.86008209874;9339.43963362197;9363.157315841572;9402.106799854002;9409.341346105775;9302.674406395778;9348.57931393089;9356.625734970594;9357.953210233973;9408.482533460658;9226.723198304062;9264;9272.860907086399;9334.15992560666;9348.953255233748;38035.85892423204;38314.42528362049;38345.42481862774;38403.84788456351;38576.42135367972;3182.583750231148;3301.3727391795724;3331.4669652855428;3372.1232479555247;3688.0779805048737;28093.016744414053;28541;29954.70045299541;30027.549586756217;30362.78548858043;3166.5725127108008;3168.17627416871;3188.2029492626843;3220.72623826975;3276.0990727549874;447787;448586;449446;454343.72828135977;455162;58625.12062319068;58824.41175588275;58863;58864.70567647134;58955.70522147362;61399;62060;62084;62156.68921655363;62256;15510.922445387701;15562.688746225012;15780.368785248691;16009.519714408587;16028.519144425685;10497.842532362021;10566.630167944086;11704.35626040559;12425.254484730945;12471.31407772563;16037.839621603867;16330.591735206715;16388.26252818626;16416.261268242957;16429.260683269044;3179.459491886385;6705.966470167618;6970.372666460043;7014.684339204748;7040.612766297801;829.9585020748939;939.5818860606964;946.7301818981557;1005.2762011351815;1095.5727266366123;27985.58021629677;28812.135635930954;29500;29628.703712963026;29721.959731409297;870.3689824876914;975.1954637424153;991.375433476905;1001.4692213127055;1030.4538594544904;12622.431990560446;13151.605451836269;14762.48331308399;14762.63093422673;15137.167455789817;671622;672444;675151;676209;680003;55741.442585574434;55823.72088139615;56157.71921140368;56222.43777562172;56659.58351041257;1320.531211419932;1349.1163288046541;1354.8170996915487;1360.156702844243;1365.044468871774;27913.44173116567;28176.872925082767;28590.857045714638;29185.70814291915;29309.85345073261;48537.51462485471;48678.513214867395;48709;48716.512834871195;48839.75580122077;594218;596482;597313;597944;598390;25986.96052157968;26142.215733528024;26297.60553591736;26444.603330949667;26453.867730661223;42707.93230169271;44324.11351772882;44664.33003504885;45607;45670.31494527518;36756;36900;37140.44289335716;37416.2516749658;38327.42508862314;36941.26117477581;36979;37026.074348140435;37057.81471092627;37455.438168426954;36393.81803090968;36876.078098047765;37117;37414.62585374111;37530.6246937538;31959.520607190447;33739;33791.662083378855;33991.66008339885;34088.82955585206;24972.125975590767;25242.621360679972;25251.873740631912;25559.87220063962;25836.483270334113 08:39:50 INFO - PROCESS | 1643 | |2;jquery.html;369.8520591763296;371.1426604543504;385.4391859843926;392.85071672764343;402.7643828360409;14707.705845883076;14772.852271477283;18313.450596482093;19130;19359.612807743855;15432.84567154328;15996.440124595641;18676;19113.61772764544;19127.330543430984;5063.164577844651;5585.1063829787245;5802.53579713624;5887.234659494281;5898.351181370046;4588.082383523309;5716.657000579979;5807.82576522705;5830.883382332351;5836.5914385993;5761.683107429089;5777.45114214149;5802.970985145068;5812.3025236971625;5825.8834823303505;5536.335639723239;5735.08238681811;5756.280464941882;5763.769449222026;5765.769369225226;4603.194440972849;4777.259524773665;4853.053654537367;5059.595232381437;5083.720395378253;23437.7656223439;23573.88213058941;23957.520849583085;24170;24659.0136394544;2390.5696974544576;2468.0744720729726;2468.913677981682;2549;2617.659704238456;14886.553403397915;14887.70224595502;14914.105153690705;14919.104853708704;15257.771133433007;1548;1801.5406071451696;1828.8719789614731;1916.482549711584;1922.327185485069;103983.4800825991;117261.24108138299;119838;120161.39919300436;121236;10010.749731256632;10630.840537391945;10747;10835.079018283534;10990.945045274722;13259.535916242885;13286.601401957956;13290.800637990438;13293.401796919163;13308.800367994489;8028.237317454832;8309.792255193548;8335.958320208481;8443.324534037261;8460.323174146053;8369.414141010193;8391.909051823224;8422.53676047811;8519.531425771642;8577.614007369559;6267.467265282411;6446.7421303148285;6454.32229615893;6457.128287681103;6561.475081993429;1825.6257467219455;1863;1863.3943968210385;1867.738516607676;1868.785089714673;742.9182789893107;764.1899586438282;801.7795106345801;805.2712295372697;884.1688812516214;19835.801641983686;20923.79076209249;20958.847263400356;21174.47063823417;21241.256556020464;857.2541888556996;868.2272777228272;950.14486961735;955.0688079122939;1022.25375475903;7644.467350205435;9344.626214951326;9363.765905852271;10332.741681458023;11212.887871121347;515615;618669;636035;648335;649909;116784;116936;117227;120245;121300;995.7112437393075;1031.4791030529534;1053.3679792124817;1054.4991129213563;1058.8729352477599;17763.023033732752;18182.636347273245;18620.348287809862;18690.065496725383;18821.811781882006;50708.74645626748;52255.477445225064;52710.73644631906;53073;53260;72808.27191728014;79082;80028.41233651878;80620;82421.58789206255;6088.995315773025;6880.277570854988;7927;7987.600619969095;8157.306628936607;10033.397996120257;12671.30307833078;12706.36468176606;12730.23618582888;12877.484900603871;8904;10339.689809305733;10493.790124197321;10646.254762166574;10881.238313317992;10127.341722787995;10735.94632026835;10772.299800512941;10794.326879333716;10869.945650271698;10588.682339529827;11016.889831101907;11097.944510277397;11305.773884522428;11517.654470365902;7839.294463498312;8052.677892884219;8122.5126492410645;8315.625796839036;8365.33077353798;10291.94854025725;10772.946135269274;10842.403667798031;11226.831597525881;11250.493727782106 08:39:50 INFO - PROCESS | 1643 | |3;mootools.html;1431.3129697745082;1466.7433199190143;1475.0633347824141;1601.934713415578;1743.564108972756;7400.555966642005;7633.778595424733;7860.685572577097;8376.371772117085;8387.832243355137;7926.524408535477;8159.347252219823;8169.264766171043;8283;8294.419390642657;2001.2695366191344;2331.0559223514506;2334.509752951882;2334.556434277482;2340.2862127051244;1944.6013567218686;2272.0457407888725;2296.0471404367167;2346.3547524430746;2348.283773449097;2276.7609401012905;2278.2026290798253;2309.3418375762826;2318.107528601484;2341.672165896767;1873.381784011273;2081.4900349414415;2102.747670279576;2128.2125613523017;2142.410837019824;2313.456337760625;2338.5089131282452;2340.625499920022;2349.988250058756;2350.0952133428755;8215.301699355561;8387.119352467964;8424.53665048422;8433.03020152684;8453.492790432594;5059.671121377099;5179.559737422323;5214.983078299733;5237.973810130963;5238.266642670029;2772.36235665796;3251.2684645954473;3542.415501442259;3546.414841551141;3554.591222009476;872.7250915961515;1068.9737851662414;1074.049466222391;1078.6763970808727;1085.7177133945152;8478.915210847874;10606.628767993083;10688.572457101707;10751.193660475417;10837.67486975392;4284.400183974246;4356.738595684269;4470.262406702923;4476.888077798016;4483.125790470892;8449.746507604781;8588.699395521127;8602.569871506399;8611.181937715906;8618.267447267053;5086.3133476980875;5094.61790365724;5098.082345177903;5114.514121158484;5117.027764724765;4167.1239163183445;4473.172463094338;4771.117343291433;4772.809087636456;4816.8554943351755;3523.3129539740016;3560.3769340365334;3653.2693461307845;3661.5056967309074;3681.466187402865;1038.1539045677748;1076.7523469602036;1085.2837127495816;1087.6791346552786;1093.8085834978847;793.5516433215215;804.4047404920434;809.3848675007036;839.6809212499211;916.5783739479781;5389.299391079213;5426.8371948841595;5588.524975377139;5672.858178545569;5693.459121383462;423.53199714315235;432.1680764528333;439.96700247480925;443.96448284137193;458.191292368969;3795.791231482295;3798.4112462568346;3852.3836186210074;3856.5564960029396;3921.490206273223;9095.31785116119;9910.45492497905;10004.249681273934;10018.39889606626;10031.799364012679;9869.901300986898;9956.253281003954;9957.850632240523;9994.200463962865;10089.29374943747;7577.696892124363;7789.8831517528415;7825.295723384809;7842.411819113589;7852.646630901623;5440;5556.777728890961;5593.972030139823;5627.634203776742;5627.7900251446135;9126;9189.586468608795;9364.344495885223;9426.575804088696;9534.046595340411;9014.549272536216;9355.345125841126;9379.531023448937;9428;9440.48077355752;6564.671766411756;6757.560758550679;6877.140357455318;6993.370596646327;7014.508984371045;7669.118051423934;7838.255365740246;7903.920960790549;7991.600419979094;7994.600269986594;6947.687354068978;7092.042574252616;7128.251533588898;7143.107111611049;7148.320909513588;6223.190985171898;6793.252742198453;7046.154461464657;7056.435485161277;7077.752278670222;6534.934650653623;6640.468762498892;6696.799096027127;6698.83252918681;6700.396964273239;4852.102361063215;4865.416150062015;4882.218844984927;4916.557509824133;4947.084789313988;4261.957380426156;4840.34655321527;4842.564169224786;4853.878653033561;5080.822171223989 08:39:50 INFO - PROCESS | 1643 | |4;prototype.html;301.7224153778522;302.1223346179349;305.7279021670712;309.55733301379024;314.15492325644016;4427.490838553567;4557.749323787195;5042.193249080148;5111.897762044761;5385.838424847251;5197.480251974801;5312.2828418163535;5358.839234822962;5381.919271210935;5386.622936394454;4071.6742660587156;4094.9590504095027;4107.199096176247;4128.401381799637;4136.97931510342;3762.9623703762886;3916.667083297923;3945.2701250268647;4093.7953102344914;4101.179764047188;3354.4632858742607;4026.6577340926056;4054.9862534366407;4064.959350406488;4073.674106071522;3501.531420348661;3929.9017524561827;4048.1701251243426;4082.326416141333;4089.159878054887;3180.522921561761;3502.7372947029075;3559.4482855157494;3566.8751593694096;3604.197901049475;3776.6601005909324;4529.501754807002;5037.748321330424;5223.582113430916;5260.6580572263065;3849.4610754494392;4635.235186194274;4664.743439110847;4680.695754775963;4710.387649605529;4941.975290123526;5395.325584301962;5621.18492818539;5692.5161361284345;5720.427957204288;3244.329170477206;3562.5012498250267;3852.210296889131;3900.687944964396;3907.27715372657;5276.781163490268;5665.49010589049;6062.817385741129;6074.48366888815;6079.635221886657;3893.4549163117185;3967.444557761916;3988.7806170660892;3989.7805620691142;4008.118213992975;3745.6254374562873;3980.467520004819;4007.617372006653;4017.196560687874;4026.335654617014;4272.739541835166;4669.743164125938;4712.293156026555;4714.4106986626675;4718.976405118021;4963.585378167205;5706.4606250219085;5712.857428514314;5836.766529338779;5896.204012458349;1095.3373209208473;1148.1274231583889;1257.4530079415483;1257.5535684831937;1287.291989405823;747.8317378589774;797.795329053134;866.3718803867146;866.6490071521044;885.9424137431047;4428.446444194476;4793.243884766273;5115.112514489015;5150.381954165524;5237.292965449616;5515.207557543734;5559.833205003856;5570.916436253459;5571.9721401392735;5635.267415235993;4747.026859493795;4880.730460335961;5085.949140508622;5103.362079740033;5114.795408183631;2686.4284393629555;2764.557670772707;2854.557543580748;2904.201344630201;2973.8067025643263;5645.87082583485;5652.293463317085;5852.27869040898;5859;5885.2349194605285;5094.885622466709;5361.35663720356;5457.918131228034;5503.257060296889;5673;5111.846644600668;5889.970550147222;5890.587658863838;5896.882062358814;5916.49709774674;5331;5432.9185062223305;5646.3788983212635;5725.742341594555;5751.5398768099285;4811.807527698853;5578.30271216098;5879.676617786063;5930;5953.226080609493;5655.899472882127;6275.215598050244;6305.527085468517;6310;6332.113504109337;3766.0604788534474;3939.350007248772;3974.4237085623;3992.9401058984718;4008.8396464141106;5679.858003549944;5725.339651501018;5888.234529511136;5971.731272092679;6002.579819412774;4144.274751918462;4764.475907650087;5253.343332083489;5825.863636817907;5877.324107727666;4840.419149701917;5776.451237132465;5818.127280907728;5833.591648584558;5873.94126058751;4159.833606655699;4779.637803226064;4903.362562866804;5132.461091585479;5152.433232344364;5131.409887862795;5181.326427564392;5790.971045144747;5807.390224026416;5860.179574859438;5656.88686226265;5708.943845388616;5726.885462290647;5744.224529688606;5785.768569257182 08:39:50 INFO - PROCESS | 1643 | |5;yui.html;655.881941250575;663.6238212270611;701.9613921234336;752.9548227106374;766.3754040457027;13944.581662550127;14489.927550362247;14998.475053373135;15097.622559435993;15104.54686359411;13192.538261160862;13345.065845390802;13517.864821351783;13527.120737152078;13558.661033474167;770.9460337776344;793.3534169651715;795.6658203554492;798.2895223251311;798.480987358216;630.9747610095613;653.7450394346209;779.4193325972159;786.532013451998;803.1406395157184;840.9831803363929;844.7170197983683;847.6270441005969;848.6605357856845;852.4331319672441;745.4893398022372;841.0874201491389;843.1231519220034;843.3632607381419;844.7676888855552;616.5899676715024;624.9343818899041;625.7715933684218;626.3048016701504;630.8580569371901;13289.86710132896;13983.440662373492;13999;14034.087784293988;14075.085119467201;13150.006193484454;13307.933460332733;13464.86535134646;13496.730065398637;13529.797053044213;10730.570777168903;10769.407682577455;11109.611163609316;11177.608783692613;11284.153688473316;3515.455104458813;3661.5240018797654;3675.1730860556427;3688.0779805048737;3785.9621403785886;77336;79300.20699793044;79819;80571.59714201391;84794;10189.337693050076;10459.058684718411;10517.737056573495;10535.42055186972;10536.051755341903;16805.579860503585;20965.68551471729;21095.261665841623;21098.050587723283;21333.893330533247;6309.873802523924;7528.4353673473615;7679.308862202429;7704.6918123274445;7768;3585.9421470666216;4108.239975604522;4137.2552940470405;4227.640650544677;4329.004329004347;3291.6708329167373;3368.326334733063;3397.167693914957;3403.6085850127056;3408.16499957512;592.2478452365558;609.1228630771764;610.334735138692;626.9655168965751;629.0721186250336;582.0686900958433;602.9065494848305;618.088319728406;641.7240586547736;650.952753589957;6551.574147680385;7092.184398794101;7426.62866856655;9057.184853363096;9175.72472825803;448.3789950918002;573.2175580332815;619.3342157181048;625.343389441085;635.8569321902535;3177.3327601204005;3967.285888540033;4277.422547956048;4432.268675668543;4462.709923854939;50031;68966.65516672384;70536;103992.53116590745;105268;86198;89621;94784;96195.51902240443;96684;57726.71136644374;78316;80460.59769701114;81169;85352.57323713465;8095.069067057364;8507.40448168647;8524.062353141026;8628.22345988864;8834.425762325429;48385.75807120942;50832;51327;53001.20498192453;54093.45906540884;67347;72395.63802180956;72903.90644140236;75331.62334188513;76504.61747691449;10839.512221950188;10876.728081798019;10892.727681808019;10945.01494865466;11127.165462590176;11868;11874.76250475003;11898;11945.761084778429;11949.880501194877;10511.26421150512;10586.04725574702;10617.469126543796;10702.357858528514;10712.410817405118;9458.668946586837;11280.661580152608;11362.977332040156;11458.828117578076;11531.423428828693;11806.881931180578;12072.637820865388;12343.567975120828;12513.93743031279;12755;9455.669051583161;9875.40747555149;10492.268775651932;10511.789764204826;10704.30422022566;10610.89389106099;11176.944115279372;11179.161562882653;11194.66416007521;11534.94232528832 08:39:50 INFO - PROCESS | 1643 | __end_tp_report 08:39:50 INFO - PROCESS | 1643 | __start_cc_report 08:39:50 INFO - PROCESS | 1643 | _x_x_mozilla_cycle_collect,371 08:39:50 INFO - PROCESS | 1643 | __end_cc_report 08:39:50 INFO - PROCESS | 1643 | __startTimestamp1482683990867__endTimestamp 08:39:50 INFO - PROCESS | 1643 | 08:39:50 INFO - PROCESS | 1643 | ------- Summary: start ------- 08:39:50 INFO - PROCESS | 1643 | Number of tests: 6 08:39:50 INFO - PROCESS | 1643 | 08:39:50 INFO - PROCESS | 1643 | [#0] dojo.html Cycles:165 Average:5678.52 Median:5932.14 stddev:694.17 (11.7%) stddev-sans-first:659.19 08:39:50 INFO - PROCESS | 1643 | Values: 2824.1 2882.8 3206.0 3367.2 3375.7 4694.8 5525.6 5608.7 5633.7 5722.9 4624.7 4874.9 5431.8 5529.6 5759.1 5231.7 5260.6 5315.4 5316.4 5331.1 5280.0 5304.0 5319.9 5335.4 5374.0 4347.6 4780.3 4914.4 5282.3 5389.8 5136.2 5150.5 5234.0 5290.4 5315.5 5166.2 5216.8 5225.9 5227.9 5325.4 5824.8 5849.3 5925.0 5932.1 5934.0 6136.4 6156.9 6211.6 6271.7 6308.7 6241.4 6253.9 6255.5 6263.2 6267.2 4829.3 5392.6 5832.0 5880.6 5886.2 5259.8 6156.8 6183.4 6230.9 6231.6 6190.0 6195.1 6199.9 6221.1 6225.4 6250.5 6251.8 6263.8 6264.2 6306.9 6105.5 6117.6 6135.2 6143.5 6232.5 6203.8 6222.8 6250.0 6259.0 6300.3 4631.4 4858.7 4907.9 4957.0 5008.4 4409.2 4417.1 4461.6 4474.4 4502.2 5830.0 5867.1 5877.0 5918.0 5946.6 5418.8 6150.8 6167.0 6179.8 6187.6 4638.2 4839.8 5896.5 5924.2 5975.2 5290.6 5339.4 5356.8 5420.8 5427.3 6282.7 6294.6 6308.9 6402.8 6417.1 6156.7 6176.9 6184.4 6185.1 6191.2 6130.7 6316.2 6385.1 6395.0 6422.9 6093.9 6122.4 6126.1 6130.5 6233.2 5498.4 5944.9 6207.1 6226.0 6227.8 6094.5 6095.2 6152.5 6169.1 6192.1 5633.2 5894.8 5896.2 5999.2 6002.1 5299.2 5360.6 5797.1 5800.4 5833.2 5958.2 5958.6 6031.5 6065.4 6125.8 5991.9 6003.8 6009.6 6013.0 6051.8 08:39:50 INFO - PROCESS | 1643 | 08:39:50 INFO - PROCESS | 1643 | [#1] ext.html Cycles:180 Average:69789.10 Median:38490.13 stddev:155878.10 (405.0%) stddev-sans-first:156228.46 08:39:50 INFO - PROCESS | 1643 | Values: 458.4 487.1 498.5 504.9 511.8 23233.3 31013.4 39943.8 41025.8 41212.6 40480.4 40827.8 40874.2 40995.8 41079.2 9188.9 9277.5 9311.4 9355.4 9388.3 9245.4 9320.1 9338.0 9355.2 9390.5 9327.9 9339.4 9363.2 9402.1 9409.3 9302.7 9348.6 9356.6 9358.0 9408.5 9226.7 9264.0 9272.9 9334.2 9349.0 38035.9 38314.4 38345.4 38403.8 38576.4 3182.6 3301.4 3331.5 3372.1 3688.1 28093.0 28541.0 29954.7 30027.5 30362.8 3166.6 3168.2 3188.2 3220.7 3276.1 447787.0 448586.0 449446.0 454343.7 455162.0 58625.1 58824.4 58863.0 58864.7 58955.7 61399.0 62060.0 62084.0 62156.7 62256.0 15510.9 15562.7 15780.4 16009.5 16028.5 10497.8 10566.6 11704.4 12425.3 12471.3 16037.8 16330.6 16388.3 16416.3 16429.3 3179.5 6706.0 6970.4 7014.7 7040.6 830.0 939.6 946.7 1005.3 1095.6 27985.6 28812.1 29500.0 29628.7 29722.0 870.4 975.2 991.4 1001.5 1030.5 12622.4 13151.6 14762.5 14762.6 15137.2 671622.0 672444.0 675151.0 676209.0 680003.0 55741.4 55823.7 56157.7 56222.4 56659.6 1320.5 1349.1 1354.8 1360.2 1365.0 27913.4 28176.9 28590.9 29185.7 29309.9 48537.5 48678.5 48709.0 48716.5 48839.8 594218.0 596482.0 597313.0 597944.0 598390.0 25987.0 26142.2 26297.6 26444.6 26453.9 42707.9 44324.1 44664.3 45607.0 45670.3 36756.0 36900.0 37140.4 37416.3 38327.4 36941.3 36979.0 37026.1 37057.8 37455.4 36393.8 36876.1 37117.0 37414.6 37530.6 31959.5 33739.0 33791.7 33991.7 34088.8 24972.1 25242.6 25251.9 25559.9 25836.5 08:39:50 INFO - PROCESS | 1643 | 08:39:50 INFO - PROCESS | 1643 | [#2] jquery.html Cycles:180 Average:34929.65 Median:24414.51 stddev:102423.43 (419.5%) stddev-sans-first:102677.88 08:39:50 INFO - PROCESS | 1643 | Values: 369.9 371.1 385.4 392.9 402.8 14707.7 14772.9 18313.5 19130.0 19359.6 15432.8 15996.4 18676.0 19113.6 19127.3 5063.2 5585.1 5802.5 5887.2 5898.4 4588.1 5716.7 5807.8 5830.9 5836.6 5761.7 5777.5 5803.0 5812.3 5825.9 5536.3 5735.1 5756.3 5763.8 5765.8 4603.2 4777.3 4853.1 5059.6 5083.7 23437.8 23573.9 23957.5 24170.0 24659.0 2390.6 2468.1 2468.9 2549.0 2617.7 14886.6 14887.7 14914.1 14919.1 15257.8 1548.0 1801.5 1828.9 1916.5 1922.3 103983.5 117261.2 119838.0 120161.4 121236.0 10010.7 10630.8 10747.0 10835.1 10990.9 13259.5 13286.6 13290.8 13293.4 13308.8 8028.2 8309.8 8336.0 8443.3 8460.3 8369.4 8391.9 8422.5 8519.5 8577.6 6267.5 6446.7 6454.3 6457.1 6561.5 1825.6 1863.0 1863.4 1867.7 1868.8 742.9 764.2 801.8 805.3 884.2 19835.8 20923.8 20958.8 21174.5 21241.3 857.3 868.2 950.1 955.1 1022.3 7644.5 9344.6 9363.8 10332.7 11212.9 515615.0 618669.0 636035.0 648335.0 649909.0 116784.0 116936.0 117227.0 120245.0 121300.0 995.7 1031.5 1053.4 1054.5 1058.9 17763.0 18182.6 18620.3 18690.1 18821.8 50708.7 52255.5 52710.7 53073.0 53260.0 72808.3 79082.0 80028.4 80620.0 82421.6 6089.0 6880.3 7927.0 7987.6 8157.3 10033.4 12671.3 12706.4 12730.2 12877.5 8904.0 10339.7 10493.8 10646.3 10881.2 10127.3 10735.9 10772.3 10794.3 10869.9 10588.7 11016.9 11097.9 11305.8 11517.7 7839.3 8052.7 8122.5 8315.6 8365.3 10291.9 10772.9 10842.4 11226.8 11250.5 08:39:50 INFO - PROCESS | 1643 | 08:39:50 INFO - PROCESS | 1643 | [#3] mootools.html Cycles:180 Average:5330.15 Median:5106.30 stddev:2943.26 (57.6%) stddev-sans-first:2936.94 08:39:50 INFO - PROCESS | 1643 | Values: 1431.3 1466.7 1475.1 1601.9 1743.6 7400.6 7633.8 7860.7 8376.4 8387.8 7926.5 8159.3 8169.3 8283.0 8294.4 2001.3 2331.1 2334.5 2334.6 2340.3 1944.6 2272.0 2296.0 2346.4 2348.3 2276.8 2278.2 2309.3 2318.1 2341.7 1873.4 2081.5 2102.7 2128.2 2142.4 2313.5 2338.5 2340.6 2350.0 2350.1 8215.3 8387.1 8424.5 8433.0 8453.5 5059.7 5179.6 5215.0 5238.0 5238.3 2772.4 3251.3 3542.4 3546.4 3554.6 872.7 1069.0 1074.0 1078.7 1085.7 8478.9 10606.6 10688.6 10751.2 10837.7 4284.4 4356.7 4470.3 4476.9 4483.1 8449.7 8588.7 8602.6 8611.2 8618.3 5086.3 5094.6 5098.1 5114.5 5117.0 4167.1 4473.2 4771.1 4772.8 4816.9 3523.3 3560.4 3653.3 3661.5 3681.5 1038.2 1076.8 1085.3 1087.7 1093.8 793.6 804.4 809.4 839.7 916.6 5389.3 5426.8 5588.5 5672.9 5693.5 423.5 432.2 440.0 444.0 458.2 3795.8 3798.4 3852.4 3856.6 3921.5 9095.3 9910.5 10004.2 10018.4 10031.8 9869.9 9956.3 9957.9 9994.2 10089.3 7577.7 7789.9 7825.3 7842.4 7852.6 5440.0 5556.8 5594.0 5627.6 5627.8 9126.0 9189.6 9364.3 9426.6 9534.0 9014.5 9355.3 9379.5 9428.0 9440.5 6564.7 6757.6 6877.1 6993.4 7014.5 7669.1 7838.3 7903.9 7991.6 7994.6 6947.7 7092.0 7128.3 7143.1 7148.3 6223.2 6793.3 7046.2 7056.4 7077.8 6534.9 6640.5 6696.8 6698.8 6700.4 4852.1 4865.4 4882.2 4916.6 4947.1 4262.0 4840.3 4842.6 4853.9 5080.8 08:39:50 INFO - PROCESS | 1643 | 08:39:50 INFO - PROCESS | 1643 | [#4] prototype.html Cycles:180 Average:4549.34 Median:5099.12 stddev:1426.13 (28.0%) stddev-sans-first:1394.04 08:39:50 INFO - PROCESS | 1643 | Values: 301.7 302.1 305.7 309.6 314.2 4427.5 4557.7 5042.2 5111.9 5385.8 5197.5 5312.3 5358.8 5381.9 5386.6 4071.7 4095.0 4107.2 4128.4 4137.0 3763.0 3916.7 3945.3 4093.8 4101.2 3354.5 4026.7 4055.0 4065.0 4073.7 3501.5 3929.9 4048.2 4082.3 4089.2 3180.5 3502.7 3559.4 3566.9 3604.2 3776.7 4529.5 5037.7 5223.6 5260.7 3849.5 4635.2 4664.7 4680.7 4710.4 4942.0 5395.3 5621.2 5692.5 5720.4 3244.3 3562.5 3852.2 3900.7 3907.3 5276.8 5665.5 6062.8 6074.5 6079.6 3893.5 3967.4 3988.8 3989.8 4008.1 3745.6 3980.5 4007.6 4017.2 4026.3 4272.7 4669.7 4712.3 4714.4 4719.0 4963.6 5706.5 5712.9 5836.8 5896.2 1095.3 1148.1 1257.5 1257.6 1287.3 747.8 797.8 866.4 866.6 885.9 4428.4 4793.2 5115.1 5150.4 5237.3 5515.2 5559.8 5570.9 5572.0 5635.3 4747.0 4880.7 5085.9 5103.4 5114.8 2686.4 2764.6 2854.6 2904.2 2973.8 5645.9 5652.3 5852.3 5859.0 5885.2 5094.9 5361.4 5457.9 5503.3 5673.0 5111.8 5890.0 5890.6 5896.9 5916.5 5331.0 5432.9 5646.4 5725.7 5751.5 4811.8 5578.3 5879.7 5930.0 5953.2 5655.9 6275.2 6305.5 6310.0 6332.1 3766.1 3939.4 3974.4 3992.9 4008.8 5679.9 5725.3 5888.2 5971.7 6002.6 4144.3 4764.5 5253.3 5825.9 5877.3 4840.4 5776.5 5818.1 5833.6 5873.9 4159.8 4779.6 4903.4 5132.5 5152.4 5131.4 5181.3 5791.0 5807.4 5860.2 5656.9 5708.9 5726.9 5744.2 5785.8 08:39:50 INFO - PROCESS | 1643 | 08:39:50 INFO - PROCESS | 1643 | [#5] yui.html Cycles:180 Average:18733.91 Median:49208.38 stddev:26821.38 (54.5%) stddev-sans-first:26862.27 08:39:50 INFO - PROCESS | 1643 | Values: 655.9 663.6 702.0 753.0 766.4 13944.6 14489.9 14998.5 15097.6 15104.5 13192.5 13345.1 13517.9 13527.1 13558.7 770.9 793.4 795.7 798.3 798.5 631.0 653.7 779.4 786.5 803.1 841.0 844.7 847.6 848.7 852.4 745.5 841.1 843.1 843.4 844.8 616.6 624.9 625.8 626.3 630.9 13289.9 13983.4 13999.0 14034.1 14075.1 13150.0 13307.9 13464.9 13496.7 13529.8 10730.6 10769.4 11109.6 11177.6 11284.2 3515.5 3661.5 3675.2 3688.1 3786.0 77336.0 79300.2 79819.0 80571.6 84794.0 10189.3 10459.1 10517.7 10535.4 10536.1 16805.6 20965.7 21095.3 21098.1 21333.9 6309.9 7528.4 7679.3 7704.7 7768.0 3585.9 4108.2 4137.3 4227.6 4329.0 3291.7 3368.3 3397.2 3403.6 3408.2 592.2 609.1 610.3 627.0 629.1 582.1 602.9 618.1 641.7 651.0 6551.6 7092.2 7426.6 9057.2 9175.7 448.4 573.2 619.3 625.3 635.9 3177.3 3967.3 4277.4 4432.3 4462.7 50031.0 68966.7 70536.0 103992.5 105268.0 86198.0 89621.0 94784.0 96195.5 96684.0 57726.7 78316.0 80460.6 81169.0 85352.6 8095.1 8507.4 8524.1 8628.2 8834.4 48385.8 50832.0 51327.0 53001.2 54093.5 67347.0 72395.6 72903.9 75331.6 76504.6 10839.5 10876.7 10892.7 10945.0 11127.2 11868.0 11874.8 11898.0 11945.8 11949.9 10511.3 10586.0 10617.5 10702.4 10712.4 9458.7 11280.7 11363.0 11458.8 11531.4 11806.9 12072.6 12343.6 12513.9 12755.0 9455.7 9875.4 10492.3 10511.8 10704.3 10610.9 11176.9 11179.2 11194.7 11534.9 08:39:50 INFO - PROCESS | 1643 | -------- Summary: end -------- 08:39:50 INFO - PROCESS | 1643 | 08:39:51 INFO - TEST-INFO | 1643: exit 0 08:39:51 INFO - TEST-OK | dromaeo_css | took 1117211ms 08:39:51 INFO - TEST-START | kraken 08:39:51 INFO - operating with platform_type : mac_ 08:39:51 INFO - Initialising browser for kraken test... 08:39:51 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmp89NNFc/profile http://localhost:49244/getInfo.html) 08:39:54 INFO - PROCESS | 1656 | 2016-12-25 08:39:54.352 plugin-container[1657:10014] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x423b, name = 'com.apple.tsm.portname' 08:39:54 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:39:54 INFO - PROCESS | 1656 | 2016-12-25 08:39:54.355 plugin-container[1657:10014] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x423f, name = 'com.apple.CFPasteboardClient' 08:39:54 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:39:54 INFO - PROCESS | 1656 | 2016-12-25 08:39:54.355 plugin-container[1657:10014] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:39:54 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 08:39:54 INFO - PROCESS | 1656 | colorDepth:24 08:39:54 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 08:39:54 INFO - PROCESS | 1656 | __metrics 08:39:54 ERROR - PROCESS | 1656 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 08:39:55 INFO - TEST-INFO | 1656: exit 0 08:39:55 INFO - Browser initialized. 08:39:55 INFO - Running cycle 1/1 for kraken test... 08:39:55 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/tmp89NNFc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:39:58 INFO - PROCESS | 1658 | 2016-12-25 08:39:58.206 plugin-container[1659:10135] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8743, name = 'com.apple.tsm.portname' 08:39:58 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:39:58 INFO - PROCESS | 1658 | 2016-12-25 08:39:58.207 plugin-container[1659:10135] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x431f, name = 'com.apple.CFPasteboardClient' 08:39:58 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:39:58 INFO - PROCESS | 1658 | 2016-12-25 08:39:58.207 plugin-container[1659:10135] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:40:07 INFO - PROCESS | 1658 | RSS: Main: 229179392 08:40:07 INFO - PROCESS | 1658 | 08:40:10 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection) 08:40:11 INFO - PROCESS | 1658 | RSS: Main: 241070080 08:40:11 INFO - PROCESS | 1658 | 08:40:13 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-dft) 08:40:14 INFO - PROCESS | 1658 | RSS: Main: 248860672 08:40:14 INFO - PROCESS | 1658 | 08:40:17 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-fft) 08:40:18 INFO - PROCESS | 1658 | RSS: Main: 252579840 08:40:18 INFO - PROCESS | 1658 | 08:40:20 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator) 08:40:21 INFO - PROCESS | 1658 | RSS: Main: 253882368 08:40:21 INFO - PROCESS | 1658 | 08:40:22 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:40:23 INFO - PROCESS | 1658 | RSS: Main: 254308352 08:40:23 INFO - PROCESS | 1658 | 08:40:26 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom) 08:40:27 INFO - PROCESS | 1658 | RSS: Main: 258752512 08:40:27 INFO - PROCESS | 1658 | 08:40:30 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate) 08:40:30 INFO - PROCESS | 1658 | RSS: Main: 258523136 08:40:30 INFO - PROCESS | 1658 | 08:40:33 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial) 08:40:34 INFO - PROCESS | 1658 | RSS: Main: 258019328 08:40:34 INFO - PROCESS | 1658 | 08:40:35 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:40:36 INFO - PROCESS | 1658 | RSS: Main: 257097728 08:40:36 INFO - PROCESS | 1658 | 08:40:38 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:40:39 INFO - PROCESS | 1658 | RSS: Main: 257155072 08:40:39 INFO - PROCESS | 1658 | 08:40:41 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:40:41 INFO - PROCESS | 1658 | RSS: Main: 257081344 08:40:41 INFO - PROCESS | 1658 | 08:40:44 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:40:45 INFO - PROCESS | 1658 | RSS: Main: 257167360 08:40:45 INFO - PROCESS | 1658 | 08:40:48 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:40:49 INFO - PROCESS | 1658 | RSS: Main: 257445888 08:40:49 INFO - PROCESS | 1658 | 08:40:51 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49244/tests/kraken/driver.html?testName=ai-astar) 08:40:51 INFO - PROCESS | 1658 | RSS: Main: 259047424 08:40:51 INFO - PROCESS | 1658 | 08:40:51 INFO - PROCESS | 1658 | __start_tp_report 08:40:51 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 08:40:51 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 08:40:51 INFO - PROCESS | 1658 | |i|pagename|runs| 08:40:51 INFO - PROCESS | 1658 | |0;ai-astar;107;121;117;120;118;120;119;119;118;119 08:40:51 INFO - PROCESS | 1658 | |1;audio-beat-detection;145;112;115;143;115;96;95;117;173;146 08:40:51 INFO - PROCESS | 1658 | |2;audio-dft;196;196;184;200;198;217;165;235;196;282 08:40:51 INFO - PROCESS | 1658 | |3;audio-fft;82;58;64;105;54;78;57;66;70;70 08:40:51 INFO - PROCESS | 1658 | |4;audio-oscillator;87;71;90;71;68;70;87;71;70;89 08:40:51 INFO - PROCESS | 1658 | |5;imaging-gaussian-blur;101;116;89;75;134;131;89;104;87;86 08:40:51 INFO - PROCESS | 1658 | |6;imaging-darkroom;94;95;95;95;96;96;99;96;109;152 08:40:51 INFO - PROCESS | 1658 | |7;imaging-desaturate;109;81;133;91;83;88;106;140;137;88 08:40:51 INFO - PROCESS | 1658 | |8;json-parse-financial;57;58;58;58;63;61;64;61;61;61 08:40:51 INFO - PROCESS | 1658 | |9;json-stringify-tinderbox;71;75;68;71;82;76;71;73;73;74 08:40:51 INFO - PROCESS | 1658 | |10;stanford-crypto-aes;93;84;89;79;102;88;81;85;86;86 08:40:51 INFO - PROCESS | 1658 | |11;stanford-crypto-ccm;140;145;143;154;169;176;146;157;208;152 08:40:51 INFO - PROCESS | 1658 | |12;stanford-crypto-pbkdf2;153;183;206;212;199;235;226;169;170;169 08:40:51 INFO - PROCESS | 1658 | |13;stanford-crypto-sha256-iterative;67;70;67;64;59;93;68;69;68;68 08:40:51 INFO - PROCESS | 1658 | __end_tp_report 08:40:51 INFO - PROCESS | 1658 | __start_cc_report 08:40:51 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,989 08:40:51 INFO - PROCESS | 1658 | __end_cc_report 08:40:51 INFO - PROCESS | 1658 | __startTimestamp1482684051320__endTimestamp 08:40:51 INFO - PROCESS | 1658 | 08:40:51 INFO - PROCESS | 1658 | ------- Summary: start ------- 08:40:51 INFO - PROCESS | 1658 | Number of tests: 14 08:40:51 INFO - PROCESS | 1658 | 08:40:51 INFO - PROCESS | 1658 | [#0] ai-astar Cycles:10 Average:117.80 Median:119.00 stddev:3.97 (3.3%) stddev-sans-first:1.22 08:40:51 INFO - PROCESS | 1658 | Values: 107.0 121.0 117.0 120.0 118.0 120.0 119.0 119.0 118.0 119.0 08:40:51 INFO - PROCESS | 1658 | 08:40:51 INFO - PROCESS | 1658 | [#1] audio-beat-detection Cycles:10 Average:125.70 Median:145.50 stddev:25.03 (17.2%) stddev-sans-first:25.55 08:40:51 INFO - PROCESS | 1658 | Values: 145.0 112.0 115.0 143.0 115.0 96.0 95.0 117.0 173.0 146.0 08:40:51 INFO - PROCESS | 1658 | 08:40:51 INFO - PROCESS | 1658 | [#2] audio-dft Cycles:10 Average:206.90 Median:199.00 stddev:32.14 (16.1%) stddev-sans-first:33.84 08:40:51 INFO - PROCESS | 1658 | Values: 196.0 196.0 184.0 200.0 198.0 217.0 165.0 235.0 196.0 282.0 08:40:51 INFO - PROCESS | 1658 | 08:40:51 INFO - PROCESS | 1658 | [#3] audio-fft Cycles:10 Average:70.40 Median:68.00 stddev:15.10 (22.2%) stddev-sans-first:15.42 08:40:51 INFO - PROCESS | 1658 | Values: 82.0 58.0 64.0 105.0 54.0 78.0 57.0 66.0 70.0 70.0 08:40:51 INFO - PROCESS | 1658 | 08:40:51 INFO - PROCESS | 1658 | [#4] audio-oscillator Cycles:10 Average:77.40 Median:79.00 stddev:9.42 (11.9%) stddev-sans-first:9.33 08:40:51 INFO - PROCESS | 1658 | Values: 87.0 71.0 90.0 71.0 68.0 70.0 87.0 71.0 70.0 89.0 08:40:51 INFO - PROCESS | 1658 | 08:40:51 INFO - PROCESS | 1658 | [#5] imaging-gaussian-blur Cycles:10 Average:101.20 Median:80.50 stddev:20.02 (24.9%) stddev-sans-first:21.24 08:40:51 INFO - PROCESS | 1658 | Values: 101.0 116.0 89.0 75.0 134.0 131.0 89.0 104.0 87.0 86.0 08:40:51 INFO - PROCESS | 1658 | 08:40:51 INFO - PROCESS | 1658 | [#6] imaging-darkroom Cycles:10 Average:102.70 Median:95.50 stddev:17.86 (18.7%) stddev-sans-first:18.67 08:40:51 INFO - PROCESS | 1658 | Values: 94.0 95.0 95.0 95.0 96.0 96.0 99.0 96.0 109.0 152.0 08:40:51 INFO - PROCESS | 1658 | 08:40:51 INFO - PROCESS | 1658 | [#7] imaging-desaturate Cycles:10 Average:105.60 Median:82.00 stddev:23.29 (28.4%) stddev-sans-first:24.67 08:40:51 INFO - PROCESS | 1658 | Values: 109.0 81.0 133.0 91.0 83.0 88.0 106.0 140.0 137.0 88.0 08:40:51 INFO - PROCESS | 1658 | 08:40:51 INFO - PROCESS | 1658 | [#8] json-parse-financial Cycles:10 Average:60.20 Median:61.00 stddev:2.35 (3.8%) stddev-sans-first:2.19 08:40:51 INFO - PROCESS | 1658 | Values: 57.0 58.0 58.0 58.0 63.0 61.0 64.0 61.0 61.0 61.0 08:40:51 INFO - PROCESS | 1658 | 08:40:51 INFO - PROCESS | 1658 | [#9] json-stringify-tinderbox Cycles:10 Average:73.40 Median:73.50 stddev:3.81 (5.2%) stddev-sans-first:3.94 08:40:51 INFO - PROCESS | 1658 | Values: 71.0 75.0 68.0 71.0 82.0 76.0 71.0 73.0 73.0 74.0 08:40:51 INFO - PROCESS | 1658 | 08:40:51 INFO - PROCESS | 1658 | [#10] stanford-crypto-aes Cycles:10 Average:87.30 Median:86.00 stddev:6.50 (7.6%) stddev-sans-first:6.56 08:40:51 INFO - PROCESS | 1658 | Values: 93.0 84.0 89.0 79.0 102.0 88.0 81.0 85.0 86.0 86.0 08:40:51 INFO - PROCESS | 1658 | 08:40:51 INFO - PROCESS | 1658 | [#11] stanford-crypto-ccm Cycles:10 Average:159.00 Median:155.50 stddev:20.68 (13.3%) stddev-sans-first:20.76 08:40:51 INFO - PROCESS | 1658 | Values: 140.0 145.0 143.0 154.0 169.0 176.0 146.0 157.0 208.0 152.0 08:40:51 INFO - PROCESS | 1658 | 08:40:51 INFO - PROCESS | 1658 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.20 Median:202.50 stddev:27.47 (13.6%) stddev-sans-first:25.21 08:40:51 INFO - PROCESS | 1658 | Values: 153.0 183.0 206.0 212.0 199.0 235.0 226.0 169.0 170.0 169.0 08:40:51 INFO - PROCESS | 1658 | 08:40:51 INFO - PROCESS | 1658 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.30 Median:68.00 stddev:8.90 (13.1%) stddev-sans-first:9.40 08:40:51 INFO - PROCESS | 1658 | Values: 67.0 70.0 67.0 64.0 59.0 93.0 68.0 69.0 68.0 68.0 08:40:51 INFO - PROCESS | 1658 | -------- Summary: end -------- 08:40:51 INFO - PROCESS | 1658 | 08:40:52 INFO - TEST-INFO | 1658: exit 0 08:40:52 INFO - TEST-OK | kraken | took 60831ms 08:40:52 INFO - SUITE-END | took 1178s 08:40:52 INFO - Completed test suite (00:19:38) 08:40:52 INFO - javascript benchmark 08:40:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7028.4803033611015, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2824.1104052223554, 2882.812617179883, 3206.0, 3367.2255381262307, 3375.7468189885763, 4694.835680751179, 5525.557955363572, 5608.663480191191, 5633.718314084294, 5722.942770572293, 4624.7456389898625, 4874.8537543873745, 5431.777849983751, 5529.640573362729, 5759.136129580565, 5231.738413079341, 5260.579153667697, 5315.415304316522, 5316.362036555619, 5331.093714068598, 5280.0, 5303.965726683298, 5319.867003324909, 5335.359756829186, 5374.0, 4347.586979236968, 4780.259059845729, 4914.434839993393, 5282.31329927109, 5389.83830485086, 5136.178211486182, 5150.459201783796, 5234.0, 5290.418054014055, 5315.548178404821, 5166.173412254059, 5216.7913283468615, 5225.869353266161, 5227.895442091137, 5325.360956685184, 5824.767009319622, 5849.268841394825, 5925.022371308729, 5932.139839723216, 5934.0, 6136.396539415845, 6156.938430615726, 6211.565190436671, 6271.655058971754, 6308.716107775161, 6241.438270555672, 6253.906191407087, 6255.499560035186, 6263.217097862767, 6267.1539342189135, 4829.323894654751, 5392.649477783932, 5832.0, 5880.647161170344, 5886.205362276123, 5259.815906443333, 6156.846078848064, 6183.3816618337805, 6230.937690623126, 6231.594946328474, 6189.96905015481, 6195.070739389038, 6199.938000620026, 6221.129041934133, 6225.408586184396, 6250.531210159256, 6251.78118765841, 6263.843403914939, 6264.21697287839, 6306.936930630635, 6105.48103411215, 6117.562372842379, 6135.202044401884, 6143.539234557426, 6232.470240029557, 6203.844903877439, 6222.844428889314, 6249.968750156311, 6258.968705156536, 6300.2754683212015, 4631.397918270603, 4858.73276969763, 4907.9263811042865, 4957.0085982803585, 5008.449070602231, 4409.162259170747, 4417.1386579616665, 4461.553844615577, 4474.395956545824, 4502.234620114588, 5830.0, 5867.14926335679, 5877.0, 5917.964356237641, 5946.613470124428, 5418.83743487696, 6150.7539698411565, 6167.013277875519, 6179.752809887644, 6187.566870319034, 4638.1883170445035, 4839.838438774739, 5896.498797602252, 5924.229850119457, 5975.163477113208, 5290.576753859682, 5339.385970613352, 5356.839294821161, 5420.783168673288, 5427.348718153768, 6282.685865706696, 6294.590851594632, 6308.936910630835, 6402.807915762534, 6417.069524918954, 6156.661383623944, 6176.876462470816, 6184.443400094013, 6185.072239163981, 6191.226096737908, 6130.673316708229, 6316.242050953915, 6385.13800636908, 6395.0, 6422.935770642233, 6093.9085913712215, 6122.357152499102, 6126.081087836682, 6130.47890929276, 6233.2208473940755, 5498.367687715964, 5944.9405505946115, 6207.131001659681, 6225.968870155802, 6227.844303892258, 6094.51243900478, 6095.1771510845465, 6152.507799376129, 6169.074638804215, 6192.071189321638, 5633.183188437735, 5894.823155305347, 5896.204012458435, 5999.160117583455, 6002.129691194839, 5299.205119232146, 5360.597955153301, 5797.101449275369, 5800.361960184292, 5833.241678581757, 5958.195643588234, 5958.612690175125, 6031.517478601613, 6065.423784740443, 6125.84685382869, 5991.940080599313, 6003.8198854034445, 6009.609375390585, 6013.0, 6051.788187413419], "value": 5632.052381449866, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [458.3605869811615, 487.1499233836953, 498.48904872505676, 504.91164046291897, 511.764588289387, 23233.303000909957, 31013.379732405367, 39943.800280998556, 41025.7948710256, 41212.58787412125, 40480.39279410811, 40827.79586102073, 40874.182516349654, 40995.77012689617, 41079.17841643173, 9188.862167067482, 9277.489738064438, 9311.394759340621, 9355.4386736796, 9388.295877809158, 9245.44527328359, 9320.067993200693, 9338.019507951669, 9355.158035776778, 9390.5304734763, 9327.86008209874, 9339.43963362197, 9363.157315841572, 9402.106799854002, 9409.341346105775, 9302.674406395778, 9348.57931393089, 9356.625734970594, 9357.953210233973, 9408.482533460658, 9226.723198304062, 9264.0, 9272.860907086399, 9334.15992560666, 9348.953255233748, 38035.85892423204, 38314.42528362049, 38345.42481862774, 38403.84788456351, 38576.42135367972, 3182.583750231148, 3301.3727391795724, 3331.4669652855428, 3372.1232479555247, 3688.0779805048737, 28093.016744414053, 28541.0, 29954.70045299541, 30027.549586756217, 30362.78548858043, 3166.5725127108008, 3168.17627416871, 3188.2029492626843, 3220.72623826975, 3276.0990727549874, 447787.0, 448586.0, 449446.0, 454343.72828135977, 455162.0, 58625.12062319068, 58824.41175588275, 58863.0, 58864.70567647134, 58955.70522147362, 61399.0, 62060.0, 62084.0, 62156.68921655363, 62256.0, 15510.922445387701, 15562.688746225012, 15780.368785248691, 16009.519714408587, 16028.519144425685, 10497.842532362021, 10566.630167944086, 11704.35626040559, 12425.254484730945, 12471.31407772563, 16037.839621603867, 16330.591735206715, 16388.26252818626, 16416.261268242957, 16429.260683269044, 3179.459491886385, 6705.966470167618, 6970.372666460043, 7014.684339204748, 7040.612766297801, 829.9585020748939, 939.5818860606964, 946.7301818981557, 1005.2762011351815, 1095.5727266366123, 27985.58021629677, 28812.135635930954, 29500.0, 29628.703712963026, 29721.959731409297, 870.3689824876914, 975.1954637424153, 991.375433476905, 1001.4692213127055, 1030.4538594544904, 12622.431990560446, 13151.605451836269, 14762.48331308399, 14762.63093422673, 15137.167455789817, 671622.0, 672444.0, 675151.0, 676209.0, 680003.0, 55741.442585574434, 55823.72088139615, 56157.71921140368, 56222.43777562172, 56659.58351041257, 1320.531211419932, 1349.1163288046541, 1354.8170996915487, 1360.156702844243, 1365.044468871774, 27913.44173116567, 28176.872925082767, 28590.857045714638, 29185.70814291915, 29309.85345073261, 48537.51462485471, 48678.513214867395, 48709.0, 48716.512834871195, 48839.75580122077, 594218.0, 596482.0, 597313.0, 597944.0, 598390.0, 25986.96052157968, 26142.215733528024, 26297.60553591736, 26444.603330949667, 26453.867730661223, 42707.93230169271, 44324.11351772882, 44664.33003504885, 45607.0, 45670.31494527518, 36756.0, 36900.0, 37140.44289335716, 37416.2516749658, 38327.42508862314, 36941.26117477581, 36979.0, 37026.074348140435, 37057.81471092627, 37455.438168426954, 36393.81803090968, 36876.078098047765, 37117.0, 37414.62585374111, 37530.6246937538, 31959.520607190447, 33739.0, 33791.662083378855, 33991.66008339885, 34088.82955585206, 24972.125975590767, 25242.621360679972, 25251.873740631912, 25559.87220063962, 25836.483270334113], "value": 19190.405585924396, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.8520591763296, 371.1426604543504, 385.4391859843926, 392.85071672764343, 402.7643828360409, 14707.705845883076, 14772.852271477283, 18313.450596482093, 19130.0, 19359.612807743855, 15432.84567154328, 15996.440124595641, 18676.0, 19113.61772764544, 19127.330543430984, 5063.164577844651, 5585.1063829787245, 5802.53579713624, 5887.234659494281, 5898.351181370046, 4588.082383523309, 5716.657000579979, 5807.82576522705, 5830.883382332351, 5836.5914385993, 5761.683107429089, 5777.45114214149, 5802.970985145068, 5812.3025236971625, 5825.8834823303505, 5536.335639723239, 5735.08238681811, 5756.280464941882, 5763.769449222026, 5765.769369225226, 4603.194440972849, 4777.259524773665, 4853.053654537367, 5059.595232381437, 5083.720395378253, 23437.7656223439, 23573.88213058941, 23957.520849583085, 24170.0, 24659.0136394544, 2390.5696974544576, 2468.0744720729726, 2468.913677981682, 2549.0, 2617.659704238456, 14886.553403397915, 14887.70224595502, 14914.105153690705, 14919.104853708704, 15257.771133433007, 1548.0, 1801.5406071451696, 1828.8719789614731, 1916.482549711584, 1922.327185485069, 103983.4800825991, 117261.24108138299, 119838.0, 120161.39919300436, 121236.0, 10010.749731256632, 10630.840537391945, 10747.0, 10835.079018283534, 10990.945045274722, 13259.535916242885, 13286.601401957956, 13290.800637990438, 13293.401796919163, 13308.800367994489, 8028.237317454832, 8309.792255193548, 8335.958320208481, 8443.324534037261, 8460.323174146053, 8369.414141010193, 8391.909051823224, 8422.53676047811, 8519.531425771642, 8577.614007369559, 6267.467265282411, 6446.7421303148285, 6454.32229615893, 6457.128287681103, 6561.475081993429, 1825.6257467219455, 1863.0, 1863.3943968210385, 1867.738516607676, 1868.785089714673, 742.9182789893107, 764.1899586438282, 801.7795106345801, 805.2712295372697, 884.1688812516214, 19835.801641983686, 20923.79076209249, 20958.847263400356, 21174.47063823417, 21241.256556020464, 857.2541888556996, 868.2272777228272, 950.14486961735, 955.0688079122939, 1022.25375475903, 7644.467350205435, 9344.626214951326, 9363.765905852271, 10332.741681458023, 11212.887871121347, 515615.0, 618669.0, 636035.0, 648335.0, 649909.0, 116784.0, 116936.0, 117227.0, 120245.0, 121300.0, 995.7112437393075, 1031.4791030529534, 1053.3679792124817, 1054.4991129213563, 1058.8729352477599, 17763.023033732752, 18182.636347273245, 18620.348287809862, 18690.065496725383, 18821.811781882006, 50708.74645626748, 52255.477445225064, 52710.73644631906, 53073.0, 53260.0, 72808.27191728014, 79082.0, 80028.41233651878, 80620.0, 82421.58789206255, 6088.995315773025, 6880.277570854988, 7927.0, 7987.600619969095, 8157.306628936607, 10033.397996120257, 12671.30307833078, 12706.36468176606, 12730.23618582888, 12877.484900603871, 8904.0, 10339.689809305733, 10493.790124197321, 10646.254762166574, 10881.238313317992, 10127.341722787995, 10735.94632026835, 10772.299800512941, 10794.326879333716, 10869.945650271698, 10588.682339529827, 11016.889831101907, 11097.944510277397, 11305.773884522428, 11517.654470365902, 7839.294463498312, 8052.677892884219, 8122.5126492410645, 8315.625796839036, 8365.33077353798, 10291.94854025725, 10772.946135269274, 10842.403667798031, 11226.831597525881, 11250.493727782106], "value": 9407.91468366482, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1431.3129697745082, 1466.7433199190143, 1475.0633347824141, 1601.934713415578, 1743.564108972756, 7400.555966642005, 7633.778595424733, 7860.685572577097, 8376.371772117085, 8387.832243355137, 7926.524408535477, 8159.347252219823, 8169.264766171043, 8283.0, 8294.419390642657, 2001.2695366191344, 2331.0559223514506, 2334.509752951882, 2334.556434277482, 2340.2862127051244, 1944.6013567218686, 2272.0457407888725, 2296.0471404367167, 2346.3547524430746, 2348.283773449097, 2276.7609401012905, 2278.2026290798253, 2309.3418375762826, 2318.107528601484, 2341.672165896767, 1873.381784011273, 2081.4900349414415, 2102.747670279576, 2128.2125613523017, 2142.410837019824, 2313.456337760625, 2338.5089131282452, 2340.625499920022, 2349.988250058756, 2350.0952133428755, 8215.301699355561, 8387.119352467964, 8424.53665048422, 8433.03020152684, 8453.492790432594, 5059.671121377099, 5179.559737422323, 5214.983078299733, 5237.973810130963, 5238.266642670029, 2772.36235665796, 3251.2684645954473, 3542.415501442259, 3546.414841551141, 3554.591222009476, 872.7250915961515, 1068.9737851662414, 1074.049466222391, 1078.6763970808727, 1085.7177133945152, 8478.915210847874, 10606.628767993083, 10688.572457101707, 10751.193660475417, 10837.67486975392, 4284.400183974246, 4356.738595684269, 4470.262406702923, 4476.888077798016, 4483.125790470892, 8449.746507604781, 8588.699395521127, 8602.569871506399, 8611.181937715906, 8618.267447267053, 5086.3133476980875, 5094.61790365724, 5098.082345177903, 5114.514121158484, 5117.027764724765, 4167.1239163183445, 4473.172463094338, 4771.117343291433, 4772.809087636456, 4816.8554943351755, 3523.3129539740016, 3560.3769340365334, 3653.2693461307845, 3661.5056967309074, 3681.466187402865, 1038.1539045677748, 1076.7523469602036, 1085.2837127495816, 1087.6791346552786, 1093.8085834978847, 793.5516433215215, 804.4047404920434, 809.3848675007036, 839.6809212499211, 916.5783739479781, 5389.299391079213, 5426.8371948841595, 5588.524975377139, 5672.858178545569, 5693.459121383462, 423.53199714315235, 432.1680764528333, 439.96700247480925, 443.96448284137193, 458.191292368969, 3795.791231482295, 3798.4112462568346, 3852.3836186210074, 3856.5564960029396, 3921.490206273223, 9095.31785116119, 9910.45492497905, 10004.249681273934, 10018.39889606626, 10031.799364012679, 9869.901300986898, 9956.253281003954, 9957.850632240523, 9994.200463962865, 10089.29374943747, 7577.696892124363, 7789.8831517528415, 7825.295723384809, 7842.411819113589, 7852.646630901623, 5440.0, 5556.777728890961, 5593.972030139823, 5627.634203776742, 5627.7900251446135, 9126.0, 9189.586468608795, 9364.344495885223, 9426.575804088696, 9534.046595340411, 9014.549272536216, 9355.345125841126, 9379.531023448937, 9428.0, 9440.48077355752, 6564.671766411756, 6757.560758550679, 6877.140357455318, 6993.370596646327, 7014.508984371045, 7669.118051423934, 7838.255365740246, 7903.920960790549, 7991.600419979094, 7994.600269986594, 6947.687354068978, 7092.042574252616, 7128.251533588898, 7143.107111611049, 7148.320909513588, 6223.190985171898, 6793.252742198453, 7046.154461464657, 7056.435485161277, 7077.752278670222, 6534.934650653623, 6640.468762498892, 6696.799096027127, 6698.83252918681, 6700.396964273239, 4852.102361063215, 4865.416150062015, 4882.218844984927, 4916.557509824133, 4947.084789313988, 4261.957380426156, 4840.34655321527, 4842.564169224786, 4853.878653033561, 5080.822171223989], "value": 4230.553693240249, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.7224153778522, 302.1223346179349, 305.7279021670712, 309.55733301379024, 314.15492325644016, 4427.490838553567, 4557.749323787195, 5042.193249080148, 5111.897762044761, 5385.838424847251, 5197.480251974801, 5312.2828418163535, 5358.839234822962, 5381.919271210935, 5386.622936394454, 4071.6742660587156, 4094.9590504095027, 4107.199096176247, 4128.401381799637, 4136.97931510342, 3762.9623703762886, 3916.667083297923, 3945.2701250268647, 4093.7953102344914, 4101.179764047188, 3354.4632858742607, 4026.6577340926056, 4054.9862534366407, 4064.959350406488, 4073.674106071522, 3501.531420348661, 3929.9017524561827, 4048.1701251243426, 4082.326416141333, 4089.159878054887, 3180.522921561761, 3502.7372947029075, 3559.4482855157494, 3566.8751593694096, 3604.197901049475, 3776.6601005909324, 4529.501754807002, 5037.748321330424, 5223.582113430916, 5260.6580572263065, 3849.4610754494392, 4635.235186194274, 4664.743439110847, 4680.695754775963, 4710.387649605529, 4941.975290123526, 5395.325584301962, 5621.18492818539, 5692.5161361284345, 5720.427957204288, 3244.329170477206, 3562.5012498250267, 3852.210296889131, 3900.687944964396, 3907.27715372657, 5276.781163490268, 5665.49010589049, 6062.817385741129, 6074.48366888815, 6079.635221886657, 3893.4549163117185, 3967.444557761916, 3988.7806170660892, 3989.7805620691142, 4008.118213992975, 3745.6254374562873, 3980.467520004819, 4007.617372006653, 4017.196560687874, 4026.335654617014, 4272.739541835166, 4669.743164125938, 4712.293156026555, 4714.4106986626675, 4718.976405118021, 4963.585378167205, 5706.4606250219085, 5712.857428514314, 5836.766529338779, 5896.204012458349, 1095.3373209208473, 1148.1274231583889, 1257.4530079415483, 1257.5535684831937, 1287.291989405823, 747.8317378589774, 797.795329053134, 866.3718803867146, 866.6490071521044, 885.9424137431047, 4428.446444194476, 4793.243884766273, 5115.112514489015, 5150.381954165524, 5237.292965449616, 5515.207557543734, 5559.833205003856, 5570.916436253459, 5571.9721401392735, 5635.267415235993, 4747.026859493795, 4880.730460335961, 5085.949140508622, 5103.362079740033, 5114.795408183631, 2686.4284393629555, 2764.557670772707, 2854.557543580748, 2904.201344630201, 2973.8067025643263, 5645.87082583485, 5652.293463317085, 5852.27869040898, 5859.0, 5885.2349194605285, 5094.885622466709, 5361.35663720356, 5457.918131228034, 5503.257060296889, 5673.0, 5111.846644600668, 5889.970550147222, 5890.587658863838, 5896.882062358814, 5916.49709774674, 5331.0, 5432.9185062223305, 5646.3788983212635, 5725.742341594555, 5751.5398768099285, 4811.807527698853, 5578.30271216098, 5879.676617786063, 5930.0, 5953.226080609493, 5655.899472882127, 6275.215598050244, 6305.527085468517, 6310.0, 6332.113504109337, 3766.0604788534474, 3939.350007248772, 3974.4237085623, 3992.9401058984718, 4008.8396464141106, 5679.858003549944, 5725.339651501018, 5888.234529511136, 5971.731272092679, 6002.579819412774, 4144.274751918462, 4764.475907650087, 5253.343332083489, 5825.863636817907, 5877.324107727666, 4840.419149701917, 5776.451237132465, 5818.127280907728, 5833.591648584558, 5873.94126058751, 4159.833606655699, 4779.637803226064, 4903.362562866804, 5132.461091585479, 5152.433232344364, 5131.409887862795, 5181.326427564392, 5790.971045144747, 5807.390224026416, 5860.179574859438, 5656.88686226265, 5708.943845388616, 5726.885462290647, 5744.224529688606, 5785.768569257182], "value": 4086.5431778164675, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [655.881941250575, 663.6238212270611, 701.9613921234336, 752.9548227106374, 766.3754040457027, 13944.581662550127, 14489.927550362247, 14998.475053373135, 15097.622559435993, 15104.54686359411, 13192.538261160862, 13345.065845390802, 13517.864821351783, 13527.120737152078, 13558.661033474167, 770.9460337776344, 793.3534169651715, 795.6658203554492, 798.2895223251311, 798.480987358216, 630.9747610095613, 653.7450394346209, 779.4193325972159, 786.532013451998, 803.1406395157184, 840.9831803363929, 844.7170197983683, 847.6270441005969, 848.6605357856845, 852.4331319672441, 745.4893398022372, 841.0874201491389, 843.1231519220034, 843.3632607381419, 844.7676888855552, 616.5899676715024, 624.9343818899041, 625.7715933684218, 626.3048016701504, 630.8580569371901, 13289.86710132896, 13983.440662373492, 13999.0, 14034.087784293988, 14075.085119467201, 13150.006193484454, 13307.933460332733, 13464.86535134646, 13496.730065398637, 13529.797053044213, 10730.570777168903, 10769.407682577455, 11109.611163609316, 11177.608783692613, 11284.153688473316, 3515.455104458813, 3661.5240018797654, 3675.1730860556427, 3688.0779805048737, 3785.9621403785886, 77336.0, 79300.20699793044, 79819.0, 80571.59714201391, 84794.0, 10189.337693050076, 10459.058684718411, 10517.737056573495, 10535.42055186972, 10536.051755341903, 16805.579860503585, 20965.68551471729, 21095.261665841623, 21098.050587723283, 21333.893330533247, 6309.873802523924, 7528.4353673473615, 7679.308862202429, 7704.6918123274445, 7768.0, 3585.9421470666216, 4108.239975604522, 4137.2552940470405, 4227.640650544677, 4329.004329004347, 3291.6708329167373, 3368.326334733063, 3397.167693914957, 3403.6085850127056, 3408.16499957512, 592.2478452365558, 609.1228630771764, 610.334735138692, 626.9655168965751, 629.0721186250336, 582.0686900958433, 602.9065494848305, 618.088319728406, 641.7240586547736, 650.952753589957, 6551.574147680385, 7092.184398794101, 7426.62866856655, 9057.184853363096, 9175.72472825803, 448.3789950918002, 573.2175580332815, 619.3342157181048, 625.343389441085, 635.8569321902535, 3177.3327601204005, 3967.285888540033, 4277.422547956048, 4432.268675668543, 4462.709923854939, 50031.0, 68966.65516672384, 70536.0, 103992.53116590745, 105268.0, 86198.0, 89621.0, 94784.0, 96195.51902240443, 96684.0, 57726.71136644374, 78316.0, 80460.59769701114, 81169.0, 85352.57323713465, 8095.069067057364, 8507.40448168647, 8524.062353141026, 8628.22345988864, 8834.425762325429, 48385.75807120942, 50832.0, 51327.0, 53001.20498192453, 54093.45906540884, 67347.0, 72395.63802180956, 72903.90644140236, 75331.62334188513, 76504.61747691449, 10839.512221950188, 10876.728081798019, 10892.727681808019, 10945.01494865466, 11127.165462590176, 11868.0, 11874.76250475003, 11898.0, 11945.761084778429, 11949.880501194877, 10511.26421150512, 10586.04725574702, 10617.469126543796, 10702.357858528514, 10712.410817405118, 9458.668946586837, 11280.661580152608, 11362.977332040156, 11458.828117578076, 11531.423428828693, 11806.881931180578, 12072.637820865388, 12343.567975120828, 12513.93743031279, 12755.0, 9455.669051583161, 9875.40747555149, 10492.268775651932, 10511.789764204826, 10704.30422022566, 10610.89389106099, 11176.944115279372, 11179.161562882653, 11194.66416007521, 11534.94232528832], "value": 6856.834451245046, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1549.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 121.0, 117.0, 120.0, 118.0, 120.0, 119.0, 119.0, 118.0, 119.0], "value": 117.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 112.0, 115.0, 143.0, 115.0, 96.0, 95.0, 117.0, 173.0, 146.0], "value": 125.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 196.0, 184.0, 200.0, 198.0, 217.0, 165.0, 235.0, 196.0, 282.0], "value": 206.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 58.0, 64.0, 105.0, 54.0, 78.0, 57.0, 66.0, 70.0, 70.0], "value": 70.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 90.0, 71.0, 68.0, 70.0, 87.0, 71.0, 70.0, 89.0], "value": 77.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 116.0, 89.0, 75.0, 134.0, 131.0, 89.0, 104.0, 87.0, 86.0], "value": 101.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 96.0, 96.0, 99.0, 96.0, 109.0, 152.0], "value": 102.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 81.0, 133.0, 91.0, 83.0, 88.0, 106.0, 140.0, 137.0, 88.0], "value": 105.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 58.0, 58.0, 63.0, 61.0, 64.0, 61.0, 61.0, 61.0], "value": 60.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 75.0, 68.0, 71.0, 82.0, 76.0, 71.0, 73.0, 73.0, 74.0], "value": 73.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 84.0, 89.0, 79.0, 102.0, 88.0, 81.0, 85.0, 86.0, 86.0], "value": 87.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 145.0, 143.0, 154.0, 169.0, 176.0, 146.0, 157.0, 208.0, 152.0], "value": 159.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 183.0, 206.0, 212.0, 199.0, 235.0, 226.0, 169.0, 170.0, 169.0], "value": 192.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 67.0, 64.0, 59.0, 93.0, 68.0, 69.0, 68.0, 68.0], "value": 69.3, "unit": "score"}]}]} 08:40:52 INFO - Return code: 0 08:40:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:40:52 ERROR - # TBPL SUCCESS # 08:40:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:40:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:40:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:40:52 INFO - 2016-12-25 08:40:52.670 screenresolution[1663:10520] starting screenresolution argv=screenresolution get 08:40:52 INFO - 2016-12-25 08:40:52.673 screenresolution[1663:10520] Display 0: 1600x1200x32@60 08:40:52 INFO - 2016-12-25 08:40:52.679 screenresolution[1664:10523] starting screenresolution argv=screenresolution list 08:40:52 INFO - Available Modes on Display 0 08:40:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:40:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:40:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:40:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:40:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:40:52 INFO - Intel Iris: 08:40:52 INFO - Chipset Model: Intel Iris 08:40:52 INFO - Type: GPU 08:40:52 INFO - Bus: Built-In 08:40:52 INFO - VRAM (Dynamic, Max): 1536 MB 08:40:52 INFO - Vendor: Intel (0x8086) 08:40:52 INFO - Device ID: 0x0a2e 08:40:52 INFO - Revision ID: 0x0009 08:40:52 INFO - Displays: 08:40:52 INFO - Display: 08:40:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:40:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:40:52 INFO - Display Serial Number: 1600x1200 60 08:40:52 INFO - Main Display: Yes 08:40:52 INFO - Mirror: Off 08:40:52 INFO - Online: Yes 08:40:52 INFO - Rotation: Supported 08:40:52 INFO - Return code: 0 08:40:52 INFO - Running post-action listener: _resource_record_post_action 08:40:52 INFO - [mozharness: 2016-12-25 16:40:52.918345Z] Finished run-tests step (success) 08:40:52 INFO - Running post-run listener: _resource_record_post_run 08:40:52 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 39836160; Write bytes: 656079360; Read time: 421; Write time: 1874 08:40:52 INFO - TinderboxPrint: CPU usage
54.3% 08:40:52 INFO - TinderboxPrint: I/O read bytes / time
39,836,160 / 421 08:40:52 INFO - TinderboxPrint: I/O write bytes / time
656,079,360 / 1,874 08:40:52 INFO - TinderboxPrint: CPU idle
2,210.8 (45.7%) 08:40:52 INFO - TinderboxPrint: CPU system
978.0 (20.2%) 08:40:52 INFO - TinderboxPrint: CPU user
1,648.9 (34.1%) 08:40:52 INFO - TinderboxPrint: Swap in / out
388,866,048 / 0 08:40:52 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 334289408; Write bytes: 333916160; Read time: 25774; Write time: 559 08:40:52 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 38877696; Write bytes: 304468480; Read time: 376; Write time: 1261 08:40:53 INFO - Running post-run listener: _upload_blobber_files 08:40:53 INFO - Blob upload gear active. 08:40:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:40:53 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 08:40:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:40:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:40:53 INFO - (blobuploader) - INFO - Open directory for files ... 08:40:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:40:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:40:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:40:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:40:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:40:55 INFO - (blobuploader) - INFO - Done attempting. 08:40:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:40:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:40:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:40:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:40:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:40:56 INFO - (blobuploader) - INFO - Done attempting. 08:40:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:40:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:40:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:40:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:40:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:40:58 INFO - (blobuploader) - INFO - Done attempting. 08:40:58 INFO - (blobuploader) - INFO - Iteration through files over. 08:40:58 INFO - Return code: 0 08:40:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:40:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:40:58 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/473d14ef061e4e3ebeda9900ffefa1993b6e66181f20ef48ef34103aac0e7d6e22896c19cdf08b22d20b76f9fb017929346a7d272d3ccbbf6b06e8adfce6f14a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4522f8bb4227ac1eb9203408508491a88882b578d33ce37ed1e68859d74fdb7948cb1252dab17466dd32a58155902a861f0c80f21e94f0a42c38dc5d12443df6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e472ea3457cd141706e6f03b2d0090c4633fb92932648fff6f93af8797d40ae77305dd08eaab9b7ead2ee965fdec9087bba07cbc5c0e90e87227e6566a8d5392"} 08:40:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:40:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:40:58 INFO - Contents: 08:40:58 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/473d14ef061e4e3ebeda9900ffefa1993b6e66181f20ef48ef34103aac0e7d6e22896c19cdf08b22d20b76f9fb017929346a7d272d3ccbbf6b06e8adfce6f14a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4522f8bb4227ac1eb9203408508491a88882b578d33ce37ed1e68859d74fdb7948cb1252dab17466dd32a58155902a861f0c80f21e94f0a42c38dc5d12443df6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e472ea3457cd141706e6f03b2d0090c4633fb92932648fff6f93af8797d40ae77305dd08eaab9b7ead2ee965fdec9087bba07cbc5c0e90e87227e6566a8d5392"} 08:40:58 INFO - Running post-run listener: copy_logs_to_upload_dir 08:40:58 INFO - Copying logs to upload dir... 08:40:58 INFO - mkdir: /builds/slave/test/build/upload/logs 08:40:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.544017 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-12-25 08:40:58.263218) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-25 08:40:58.266487) ========= 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.sO5uo60ziu/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.y455Mm2SPA/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/473d14ef061e4e3ebeda9900ffefa1993b6e66181f20ef48ef34103aac0e7d6e22896c19cdf08b22d20b76f9fb017929346a7d272d3ccbbf6b06e8adfce6f14a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4522f8bb4227ac1eb9203408508491a88882b578d33ce37ed1e68859d74fdb7948cb1252dab17466dd32a58155902a861f0c80f21e94f0a42c38dc5d12443df6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e472ea3457cd141706e6f03b2d0090c4633fb92932648fff6f93af8797d40ae77305dd08eaab9b7ead2ee965fdec9087bba07cbc5c0e90e87227e6566a8d5392"} build_url:https://queue.taskcluster.net/v1/task/aqFHoS3LSXSTXJFWjySzjA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011020 build_url: 'https://queue.taskcluster.net/v1/task/aqFHoS3LSXSTXJFWjySzjA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/473d14ef061e4e3ebeda9900ffefa1993b6e66181f20ef48ef34103aac0e7d6e22896c19cdf08b22d20b76f9fb017929346a7d272d3ccbbf6b06e8adfce6f14a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/4522f8bb4227ac1eb9203408508491a88882b578d33ce37ed1e68859d74fdb7948cb1252dab17466dd32a58155902a861f0c80f21e94f0a42c38dc5d12443df6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e472ea3457cd141706e6f03b2d0090c4633fb92932648fff6f93af8797d40ae77305dd08eaab9b7ead2ee965fdec9087bba07cbc5c0e90e87227e6566a8d5392"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-25 08:40:58.300985) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-25 08:40:58.301368) ========= 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.sO5uo60ziu/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.y455Mm2SPA/Listeners TMPDIR=/var/folders/20/pddrbtw532x46dt2fvfy5szh00000w/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.004984 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-25 08:40:58.349920) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-25 08:40:58.350272) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-25 08:40:58.350596) ========= ========= Total master_lag: 0.22 =========