builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0375 starttime: 1482445511.07 results: success (0) buildid: 20161222121953 builduid: 4e4f87ca59264d839b87be033a0e1938 revision: 20654fa125a90d0ca839bbf15b81612b8667c43b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-22 14:25:11.067490) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-22 14:25:11.067978) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-22 14:25:11.068260) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-22 14:25:11.096488) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 14:25:11.096886) ========= 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.MF3YjJIByx/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.vmXpAFwLCe/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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-22 14:25:11-- 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% 155M=0s 2016-12-22 14:25:11 (155 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.184653 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 14:25:11.307923) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 14:25:11.308314) ========= 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.MF3YjJIByx/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.vmXpAFwLCe/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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.021605 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 14:25:11.357498) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-22 14:25:11.360886) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 20654fa125a90d0ca839bbf15b81612b8667c43b --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 20654fa125a90d0ca839bbf15b81612b8667c43b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.MF3YjJIByx/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.vmXpAFwLCe/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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-22 14:25:11,420 truncating revision to first 12 chars 2016-12-22 14:25:11,420 Setting DEBUG logging. 2016-12-22 14:25:11,420 attempt 1/10 2016-12-22 14:25:11,420 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/20654fa125a9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-22 14:25:12,474 unpacking tar archive at: mozilla-aurora-20654fa125a9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.293890 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-22 14:25:12.681828) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-22 14:25:12.682200) ========= script_repo_revision: 20654fa125a90d0ca839bbf15b81612b8667c43b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-22 14:25:12.682712) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-22 14:25:12.682993) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-22 14:25:12.702795) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-12-22 14:25:12.703215) ========= /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.MF3YjJIByx/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.vmXpAFwLCe/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 14:25:12 INFO - MultiFileLogger online at 20161222 14:25:12 in /builds/slave/test 14:25:12 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 14:25:12 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:25:12 INFO - {'append_to_log': False, 14:25:12 INFO - 'base_work_dir': '/builds/slave/test', 14:25:12 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 14:25:12 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:25:12 INFO - 'branch': 'Mozilla-Aurora', 14:25:12 INFO - 'buildbot_json_path': 'buildprops.json', 14:25:12 INFO - 'config_files': ('talos/mac_config.py',), 14:25:12 INFO - 'default_actions': ('clobber', 14:25:12 INFO - 'read-buildbot-config', 14:25:12 INFO - 'download-and-extract', 14:25:12 INFO - 'populate-webroot', 14:25:12 INFO - 'create-virtualenv', 14:25:12 INFO - 'install', 14:25:12 INFO - 'run-tests'), 14:25:12 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:25:12 INFO - 'download_minidump_stackwalk': True, 14:25:12 INFO - 'download_symbols': 'ondemand', 14:25:12 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:25:12 INFO - 'tooltool.py': '/tools/tooltool.py', 14:25:12 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:25:12 INFO - '/tools/misc-python/virtualenv.py')}, 14:25:12 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:25:12 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:25:12 INFO - 'installer_path': 'installer.exe', 14:25:12 INFO - 'log_level': 'info', 14:25:12 INFO - 'log_name': 'talos', 14:25:12 INFO - 'log_to_console': True, 14:25:12 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:25:12 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:25:12 INFO - 'opt_config_files': (), 14:25:12 INFO - 'pip_index': False, 14:25:12 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:25:12 INFO - 'cmd': ('bash', 14:25:12 INFO - '-c', 14:25:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:25:12 INFO - 'enabled': True, 14:25:12 INFO - 'halt_on_failure': False, 14:25:12 INFO - 'name': 'check_screen_resolution'},), 14:25:12 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:25:12 INFO - 'cmd': ('bash', 14:25:12 INFO - '-c', 14:25:12 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:25:12 INFO - 'enabled': True, 14:25:12 INFO - 'halt_on_failure': False, 14:25:12 INFO - 'name': 'check_screen_resolution'},), 14:25:12 INFO - 'run_cmd_checks_enabled': True, 14:25:12 INFO - 'sps_profile': False, 14:25:12 INFO - 'sps_profile_interval': 0, 14:25:12 INFO - 'suite': 'dromaeojs-e10s', 14:25:12 INFO - 'system_bits': '32', 14:25:12 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:25:12 INFO - 'title': 't-yosemite-r7-0375', 14:25:12 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:25:12 INFO - 'use_talos_json': True, 14:25:12 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:25:12 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:25:12 INFO - 'work_dir': 'build'} 14:25:12 INFO - [mozharness: 2016-12-22 22:25:12.880589Z] Running clobber step. 14:25:12 INFO - Running pre-action listener: _resource_record_pre_action 14:25:12 INFO - Running main action method: clobber 14:25:12 INFO - rmtree: /builds/slave/test/build 14:25:12 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:25:13 INFO - Running post-action listener: _resource_record_post_action 14:25:13 INFO - [mozharness: 2016-12-22 22:25:13.080226Z] Finished clobber step (success) 14:25:13 INFO - [mozharness: 2016-12-22 22:25:13.080375Z] Running read-buildbot-config step. 14:25:13 INFO - Running pre-action listener: _resource_record_pre_action 14:25:13 INFO - Running main action method: read_buildbot_config 14:25:13 INFO - Using buildbot properties: 14:25:13 INFO - { 14:25:13 INFO - "project": "", 14:25:13 INFO - "product": "firefox", 14:25:13 INFO - "script_repo_revision": "production", 14:25:13 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 14:25:13 INFO - "repository": "", 14:25:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 14:25:13 INFO - "buildid": "20161222121953", 14:25:13 INFO - "pgo_build": "False", 14:25:13 INFO - "basedir": "/builds/slave/test", 14:25:13 INFO - "buildnumber": 16, 14:25:13 INFO - "slavename": "t-yosemite-r7-0375", 14:25:13 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 14:25:13 INFO - "platform": "yosemite_r7", 14:25:13 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 14:25:13 INFO - "branch": "mozilla-aurora", 14:25:13 INFO - "repo_path": "releases/mozilla-aurora", 14:25:13 INFO - "revision": "20654fa125a90d0ca839bbf15b81612b8667c43b", 14:25:13 INFO - "stage_platform": "macosx64", 14:25:13 INFO - "builduid": "4e4f87ca59264d839b87be033a0e1938", 14:25:13 INFO - "slavebuilddir": "test" 14:25:13 INFO - } 14:25:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/MJVAiO3lS_6NDZJJWJABog/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 14:25:13 INFO - Running post-action listener: _resource_record_post_action 14:25:13 INFO - [mozharness: 2016-12-22 22:25:13.083655Z] Finished read-buildbot-config step (success) 14:25:13 INFO - [mozharness: 2016-12-22 22:25:13.083792Z] Running download-and-extract step. 14:25:13 INFO - Running pre-action listener: _resource_record_pre_action 14:25:13 INFO - Running main action method: download_and_extract 14:25:13 INFO - mkdir: /builds/slave/test/build/tests 14:25: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')]} 14:25:13 INFO - trying https://queue.taskcluster.net/v1/task/MJVAiO3lS_6NDZJJWJABog/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 14:25:13 INFO - Downloading https://queue.taskcluster.net/v1/task/MJVAiO3lS_6NDZJJWJABog/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 14:25:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MJVAiO3lS_6NDZJJWJABog/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 14:25:14 INFO - Downloaded 1199 bytes. 14:25:14 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 14:25:14 INFO - Using the following test package requirements: 14:25:14 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 14:25:14 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:25:14 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 14:25:14 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:25:14 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 14:25:14 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:25:14 INFO - u'jsshell-mac.zip'], 14:25:14 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:25:14 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 14:25:14 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 14:25:14 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:25:14 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 14:25:14 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:25:14 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 14:25:14 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:25:14 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 14:25:14 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:25:14 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 14:25:14 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 14:25:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MJVAiO3lS_6NDZJJWJABog/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 14:25:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MJVAiO3lS_6NDZJJWJABog/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 14:25:14 INFO - Fetch https://queue.taskcluster.net/v1/task/MJVAiO3lS_6NDZJJWJABog/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 14:25:14 INFO - Http code: 200 14:25:14 INFO - via: 1.1 441811a054e8d055b893175754efd0c3.cloudfront.net (CloudFront) 14:25:14 INFO - x-amz-cf-id: MNACk0gjXi2-S7kvTbLYchsLVlykgrp9m3FjhZ9FIs7ThDVbG6Qa9Q== 14:25:14 INFO - x-amz-version-id: MqMEl1.QtVWrFqtAfJKDe1TZkgFn37_x 14:25:15 INFO - Expected file size: 16271077 14:25:15 INFO - Obtained file size: 16271077 14:25:21 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 14:25:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MJVAiO3lS_6NDZJJWJABog/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 14:25:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MJVAiO3lS_6NDZJJWJABog/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 14:25:21 INFO - Fetch https://queue.taskcluster.net/v1/task/MJVAiO3lS_6NDZJJWJABog/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 14:25:22 INFO - Http code: 200 14:25:22 INFO - via: 1.1 24b0e5a3429d07ef12381da50e07f70f.cloudfront.net (CloudFront) 14:25:22 INFO - x-amz-cf-id: sg2PC5-y0FIYosqR6cM873EkSxd4vlkq8_dpwDgQcJIbtVJgni_cpw== 14:25:22 INFO - x-amz-version-id: MqMEl1.QtVWrFqtAfJKDe1TZkgFn37_x 14:25:22 INFO - Expected file size: 16271077 14:25:22 INFO - Obtained file size: 16271077 14:25:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/MJVAiO3lS_6NDZJJWJABog/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 14:25:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MJVAiO3lS_6NDZJJWJABog/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 14:25:28 INFO - Fetch https://queue.taskcluster.net/v1/task/MJVAiO3lS_6NDZJJWJABog/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 14:25:29 INFO - Http code: 200 14:25:29 INFO - via: 1.1 73fa5a1ee49cf827e596b502b5927eab.cloudfront.net (CloudFront) 14:25:29 INFO - x-amz-cf-id: sTBEcTu-cIot8rMpK_nf_WYTYE-rKTL53Lg0yagPcJY9bLWu4n5yPQ== 14:25:29 INFO - x-amz-version-id: RYEYGk2RHNLype8CVkmPvFB02hZutDyF 14:25:29 INFO - Expected file size: 14409038 14:25:29 INFO - Obtained file size: 14409038 14:25:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:25:30 INFO - trying https://queue.taskcluster.net/v1/task/MJVAiO3lS_6NDZJJWJABog/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 14:25:30 INFO - Downloading https://queue.taskcluster.net/v1/task/MJVAiO3lS_6NDZJJWJABog/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 14:25:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MJVAiO3lS_6NDZJJWJABog/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 14:25:33 INFO - Downloaded 109785141 bytes. 14:25:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MJVAiO3lS_6NDZJJWJABog/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 14:25:33 INFO - mkdir: /builds/slave/test/properties 14:25:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:25:33 INFO - Writing to file /builds/slave/test/properties/build_url 14:25:33 INFO - Contents: 14:25:33 INFO - build_url:https://queue.taskcluster.net/v1/task/MJVAiO3lS_6NDZJJWJABog/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 14:25:33 INFO - Running post-action listener: _resource_record_post_action 14:25:33 INFO - Running post-action listener: set_extra_try_arguments 14:25:33 INFO - [mozharness: 2016-12-22 22:25:33.861225Z] Finished download-and-extract step (success) 14:25:33 INFO - [mozharness: 2016-12-22 22:25:33.861348Z] Running populate-webroot step. 14:25:33 INFO - Running pre-action listener: _resource_record_pre_action 14:25:33 INFO - Running main action method: populate_webroot 14:25:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:25:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:25:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:25:33 INFO - '--tppagecycles', 14:25:33 INFO - '7'], 14:25:33 INFO - 'tests': ['tsvgm']}}, 14:25:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:25:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:25:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:25:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:25:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:25:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:25:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:25:33 INFO - 'talos_options': ['--disable-e10s'], 14:25:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:25:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:25:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:25:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:25:33 INFO - 'talos_options': ['--disable-e10s'], 14:25:33 INFO - 'tests': ['damp', 'tps']}, 14:25:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:25:33 INFO - 'tests': ['damp', 'tps']}, 14:25:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:25:33 INFO - 'tests': ['dromaeo_dom']}, 14:25:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:25:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:25:33 INFO - 'tests': ['basic_compositor_video']}, 14:25:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:25:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:25:33 INFO - 'tests': ['a11yr', 14:25:33 INFO - 'ts_paint', 14:25:33 INFO - 'tpaint', 14:25:33 INFO - 'sessionrestore', 14:25:33 INFO - 'sessionrestore_no_auto_restore', 14:25:33 INFO - 'tabpaint']}, 14:25:33 INFO - 'other-e10s': {'tests': ['a11yr', 14:25:33 INFO - 'ts_paint', 14:25:33 INFO - 'tpaint', 14:25:33 INFO - 'sessionrestore', 14:25:33 INFO - 'sessionrestore_no_auto_restore', 14:25:33 INFO - 'tabpaint']}, 14:25:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:25:33 INFO - 'ts_paint', 14:25:33 INFO - 'tpaint', 14:25:33 INFO - 'sessionrestore', 14:25:33 INFO - 'sessionrestore_no_auto_restore', 14:25:33 INFO - 'tabpaint']}, 14:25:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:25:33 INFO - 'ts_paint', 14:25:33 INFO - 'tpaint', 14:25:33 INFO - 'sessionrestore', 14:25:33 INFO - 'sessionrestore_no_auto_restore', 14:25:33 INFO - 'tabpaint']}, 14:25:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:25:33 INFO - 'tests': ['a11yr', 14:25:33 INFO - 'ts_paint', 14:25:33 INFO - 'tpaint', 14:25:33 INFO - 'sessionrestore', 14:25:33 INFO - 'sessionrestore_no_auto_restore', 14:25:33 INFO - 'tabpaint']}, 14:25:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:25:33 INFO - 'tests': ['a11yr', 14:25:33 INFO - 'ts_paint', 14:25:33 INFO - 'tpaint', 14:25:33 INFO - 'sessionrestore', 14:25:33 INFO - 'sessionrestore_no_auto_restore', 14:25:33 INFO - 'tabpaint']}, 14:25:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:25:33 INFO - 'tests': ['tsvgx', 14:25:33 INFO - 'tsvgr_opacity', 14:25:33 INFO - 'tart', 14:25:33 INFO - 'tscrollx', 14:25:33 INFO - 'cart']}, 14:25:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:25:33 INFO - 'tsvgr_opacity', 14:25:33 INFO - 'tart', 14:25:33 INFO - 'tscrollx', 14:25:33 INFO - 'cart']}, 14:25:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:25:33 INFO - 'talos_options': ['--disable-e10s'], 14:25:33 INFO - 'tests': ['tp5o']}, 14:25:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:25:33 INFO - 'tests': ['tp5o']}, 14:25:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:25:33 INFO - 'talos_options': ['--disable-e10s', 14:25:33 INFO - '--xperf_path', 14:25:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:25:33 INFO - 'tests': ['tp5n']}, 14:25:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:25:33 INFO - 'talos_options': ['--xperf_path', 14:25:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:25:33 INFO - 'tests': ['tp5n']}}, 14:25:33 INFO - 'talos.zip': {'path': '', 14:25:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:25:33 INFO - Running post-action listener: _resource_record_post_action 14:25:33 INFO - [mozharness: 2016-12-22 22:25:33.881349Z] Finished populate-webroot step (success) 14:25:33 INFO - [mozharness: 2016-12-22 22:25:33.881502Z] Running create-virtualenv step. 14:25:33 INFO - Running pre-action listener: _resource_record_pre_action 14:25:33 INFO - Running main action method: create_virtualenv 14:25:33 INFO - Creating virtualenv /builds/slave/test/build/venv 14:25:33 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 14:25:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:25:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:25:33 INFO - Using real prefix '/tools/python27' 14:25:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:25:34 INFO - Installing distribute.............................................................................................................................................................................................done. 14:25:37 INFO - Installing pip.................done. 14:25:37 INFO - Return code: 0 14:25:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:25:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:25:37 INFO - Reading from file tmpfile_stdout 14:25:37 INFO - Output received: 14:25:37 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) 14:25:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:25:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:25:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:25:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:25:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:25:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:25:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:25:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104fed490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f63570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdab2dc1d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e01d30>, '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 0x104c67db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104df9b20>, '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.MF3YjJIByx/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.vmXpAFwLCe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 14:25:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:25:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:25:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MF3YjJIByx/Render', 14:25:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:25:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:25:37 INFO - 'HOME': '/Users/cltbld', 14:25:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:25:37 INFO - 'LOGNAME': 'cltbld', 14:25:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:25:37 INFO - 'MOZ_NO_REMOTE': '1', 14:25:37 INFO - 'NO_EM_RESTART': '1', 14:25:37 INFO - 'PAGER': '/bin/cat', 14:25:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:25:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:25:37 INFO - 'PWD': '/builds/slave/test', 14:25:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:25:37 INFO - 'SHELL': '/bin/bash', 14:25:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vmXpAFwLCe/Listeners', 14:25:37 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 14:25:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:25:37 INFO - 'USER': 'cltbld', 14:25:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:25:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:25:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:25:37 INFO - 'XPC_FLAGS': '0x0', 14:25:37 INFO - 'XPC_SERVICE_NAME': '0', 14:25:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:25:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:25:38 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 14:25:38 INFO - Cleaning up... 14:25:38 INFO - Return code: 0 14:25:38 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:25:38 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')]} 14:25:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:25:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:25:38 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')]} 14:25:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:25:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:25:38 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 0x104fed490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f63570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdab2dc1d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e01d30>, '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 0x104c67db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104df9b20>, '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.MF3YjJIByx/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.vmXpAFwLCe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 14:25:38 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 14:25:38 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 14:25:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MF3YjJIByx/Render', 14:25:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:25:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:25:38 INFO - 'HOME': '/Users/cltbld', 14:25:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:25:38 INFO - 'LOGNAME': 'cltbld', 14:25:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:25:38 INFO - 'MOZ_NO_REMOTE': '1', 14:25:38 INFO - 'NO_EM_RESTART': '1', 14:25:38 INFO - 'PAGER': '/bin/cat', 14:25:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:25:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:25:38 INFO - 'PWD': '/builds/slave/test', 14:25:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:25:38 INFO - 'SHELL': '/bin/bash', 14:25:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vmXpAFwLCe/Listeners', 14:25:38 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 14:25:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:25:38 INFO - 'USER': 'cltbld', 14:25:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:25:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:25:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:25:38 INFO - 'XPC_FLAGS': '0x0', 14:25:38 INFO - 'XPC_SERVICE_NAME': '0', 14:25:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:25:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:25:38 INFO - Downloading/unpacking psutil>=3.1.1 14:25:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:25:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:25:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:25:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:25:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:25:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:25:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:25:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:25:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:25:42 INFO - Installing collected packages: psutil 14:25:42 INFO - Running setup.py install for psutil 14:25:42 INFO - building 'psutil._psutil_osx' extension 14:25:42 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 14:25:42 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 14:25:42 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 14:25:42 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 14:25:42 INFO - building 'psutil._psutil_posix' extension 14:25:42 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 14:25:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:25:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:25:42 INFO - ^ 14:25:42 INFO - 1 warning generated. 14:25:42 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 14:25:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:25:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:25:42 INFO - Successfully installed psutil 14:25:42 INFO - Cleaning up... 14:25:42 INFO - Return code: 0 14:25:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:25: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')]} 14:25:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:25:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:25: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')]} 14:25:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:25:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:25: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104fed490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f63570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdab2dc1d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e01d30>, '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 0x104c67db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104df9b20>, '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.MF3YjJIByx/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.vmXpAFwLCe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 14:25: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:25: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 mozsystemmonitor==0.3 14:25:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MF3YjJIByx/Render', 14:25:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:25:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:25:42 INFO - 'HOME': '/Users/cltbld', 14:25:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:25:42 INFO - 'LOGNAME': 'cltbld', 14:25:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:25:42 INFO - 'MOZ_NO_REMOTE': '1', 14:25:42 INFO - 'NO_EM_RESTART': '1', 14:25:42 INFO - 'PAGER': '/bin/cat', 14:25:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:25:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:25:42 INFO - 'PWD': '/builds/slave/test', 14:25:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:25:42 INFO - 'SHELL': '/bin/bash', 14:25:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vmXpAFwLCe/Listeners', 14:25:42 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 14:25:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:25:42 INFO - 'USER': 'cltbld', 14:25:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:25:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:25:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:25:42 INFO - 'XPC_FLAGS': '0x0', 14:25:42 INFO - 'XPC_SERVICE_NAME': '0', 14:25:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:25:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:25:43 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:25:43 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 14:25:43 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 14:25:43 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 14:25:43 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 14:25:43 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 14:25:43 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 14:25:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:25:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:25:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:25:45 INFO - Installing collected packages: mozsystemmonitor 14:25:45 INFO - Running setup.py install for mozsystemmonitor 14:25:45 INFO - Successfully installed mozsystemmonitor 14:25:45 INFO - Cleaning up... 14:25:45 INFO - Return code: 0 14:25:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:25:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:25:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:25:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:25:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:25:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:25:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:25:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x104fed490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f63570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdab2dc1d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e01d30>, '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 0x104c67db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104df9b20>, '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.MF3YjJIByx/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.vmXpAFwLCe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 14:25:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:25:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:25:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MF3YjJIByx/Render', 14:25:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:25:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:25:46 INFO - 'HOME': '/Users/cltbld', 14:25:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:25:46 INFO - 'LOGNAME': 'cltbld', 14:25:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:25:46 INFO - 'MOZ_NO_REMOTE': '1', 14:25:46 INFO - 'NO_EM_RESTART': '1', 14:25:46 INFO - 'PAGER': '/bin/cat', 14:25:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:25:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:25:46 INFO - 'PWD': '/builds/slave/test', 14:25:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:25:46 INFO - 'SHELL': '/bin/bash', 14:25:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vmXpAFwLCe/Listeners', 14:25:46 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 14:25:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:25:46 INFO - 'USER': 'cltbld', 14:25:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:25:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:25:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:25:46 INFO - 'XPC_FLAGS': '0x0', 14:25:46 INFO - 'XPC_SERVICE_NAME': '0', 14:25:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:25:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:25:46 INFO - Downloading/unpacking jsonschema==2.5.1 14:25:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:25:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:25:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:25:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:25:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:25:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:25:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:25:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:25:48 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 14:25:48 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 14:25:48 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 14:25:48 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 14:25:48 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 14:25:48 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 14:25:49 INFO - Downloading functools32-3.2.3-2.tar.gz 14:25:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:25:49 INFO - warning: no files found matching '*.txt' 14:25:49 INFO - no previously-included directories found matching 'build' 14:25:49 INFO - no previously-included directories found matching 'dist' 14:25:49 INFO - no previously-included directories found matching '.git*' 14:25:49 INFO - Installing collected packages: jsonschema, functools32 14:25:49 INFO - Running setup.py install for functools32 14:25:49 INFO - warning: no files found matching '*.txt' 14:25:49 INFO - no previously-included directories found matching 'build' 14:25:49 INFO - no previously-included directories found matching 'dist' 14:25:49 INFO - no previously-included directories found matching '.git*' 14:25:49 INFO - Successfully installed jsonschema functools32 14:25:49 INFO - Cleaning up... 14:25:49 INFO - Return code: 0 14:25:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:25:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:25:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:25:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:25:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:25:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:25:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:25:49 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 0x104fed490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f63570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdab2dc1d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e01d30>, '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 0x104c67db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104df9b20>, '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.MF3YjJIByx/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.vmXpAFwLCe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 14:25:49 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 14:25:49 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 14:25:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MF3YjJIByx/Render', 14:25:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:25:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:25:49 INFO - 'HOME': '/Users/cltbld', 14:25:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:25:49 INFO - 'LOGNAME': 'cltbld', 14:25:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:25:49 INFO - 'MOZ_NO_REMOTE': '1', 14:25:49 INFO - 'NO_EM_RESTART': '1', 14:25:49 INFO - 'PAGER': '/bin/cat', 14:25:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:25:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:25:49 INFO - 'PWD': '/builds/slave/test', 14:25:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:25:49 INFO - 'SHELL': '/bin/bash', 14:25:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vmXpAFwLCe/Listeners', 14:25:49 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 14:25:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:25:49 INFO - 'USER': 'cltbld', 14:25:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:25:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:25:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:25:49 INFO - 'XPC_FLAGS': '0x0', 14:25:49 INFO - 'XPC_SERVICE_NAME': '0', 14:25:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:25:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:25:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:25:50 INFO - Cleaning up... 14:25:50 INFO - Return code: 0 14:25:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:25:50 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')]} 14:25:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:25:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:25:50 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')]} 14:25:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:25:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:25:50 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 0x104fed490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f63570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdab2dc1d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e01d30>, '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 0x104c67db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104df9b20>, '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.MF3YjJIByx/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.vmXpAFwLCe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 14:25:50 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 14:25:50 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 14:25:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MF3YjJIByx/Render', 14:25:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:25:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:25:50 INFO - 'HOME': '/Users/cltbld', 14:25:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:25:50 INFO - 'LOGNAME': 'cltbld', 14:25:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:25:50 INFO - 'MOZ_NO_REMOTE': '1', 14:25:50 INFO - 'NO_EM_RESTART': '1', 14:25:50 INFO - 'PAGER': '/bin/cat', 14:25:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:25:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:25:50 INFO - 'PWD': '/builds/slave/test', 14:25:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:25:50 INFO - 'SHELL': '/bin/bash', 14:25:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vmXpAFwLCe/Listeners', 14:25:50 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 14:25:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:25:50 INFO - 'USER': 'cltbld', 14:25:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:25:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:25:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:25:50 INFO - 'XPC_FLAGS': '0x0', 14:25:50 INFO - 'XPC_SERVICE_NAME': '0', 14:25:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:25:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:25:50 INFO - Downloading/unpacking blobuploader==1.2.4 14:25:50 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 14:25:50 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 14:25:50 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 14:25:50 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 14:25:50 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 14:25:50 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 14:25:53 INFO - Downloading blobuploader-1.2.4.tar.gz 14:25:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:25:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:25:53 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 14:25:53 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 14:25:53 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 14:25:53 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 14:25:53 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 14:25:53 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 14:25:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:25:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:25:53 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 14:25:53 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 14:25:53 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 14:25:53 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 14:25:53 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 14:25:53 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 14:25:54 INFO - Downloading docopt-0.6.1.tar.gz 14:25:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:25:54 INFO - Installing collected packages: blobuploader, requests, docopt 14:25:54 INFO - Running setup.py install for blobuploader 14:25:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:25:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:25:54 INFO - Running setup.py install for requests 14:25:55 INFO - Running setup.py install for docopt 14:25:55 INFO - Successfully installed blobuploader requests docopt 14:25:55 INFO - Cleaning up... 14:25:55 INFO - Return code: 0 14:25:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:25:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:25:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:25:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:25:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:25:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:25:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:25:55 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 0x104fed490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f63570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdab2dc1d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e01d30>, '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 0x104c67db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104df9b20>, '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.MF3YjJIByx/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.vmXpAFwLCe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 14:25:55 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 14:25:55 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 14:25:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MF3YjJIByx/Render', 14:25:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:25:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:25:55 INFO - 'HOME': '/Users/cltbld', 14:25:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:25:55 INFO - 'LOGNAME': 'cltbld', 14:25:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:25:55 INFO - 'MOZ_NO_REMOTE': '1', 14:25:55 INFO - 'NO_EM_RESTART': '1', 14:25:55 INFO - 'PAGER': '/bin/cat', 14:25:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:25:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:25:55 INFO - 'PWD': '/builds/slave/test', 14:25:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:25:55 INFO - 'SHELL': '/bin/bash', 14:25:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vmXpAFwLCe/Listeners', 14:25:55 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 14:25:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:25:55 INFO - 'USER': 'cltbld', 14:25:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:25:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:25:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:25:55 INFO - 'XPC_FLAGS': '0x0', 14:25:55 INFO - 'XPC_SERVICE_NAME': '0', 14:25:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:25:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:25:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:25:55 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-3VZZpA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:25:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:25:55 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-z4bOFL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:25:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:25:55 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-_XiaFT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:25:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:25:55 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-P9oLbA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:25:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:25:55 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-p2zazw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:25:56 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-ARV9g5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:25:56 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-3JwzMb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:25:56 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-gcoRwc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:25:56 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-HuHzNO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:25:56 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-hgnNJX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:25:56 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-zcOD7Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:25:56 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-_LrzlR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:25:56 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-qPGvK3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:25:56 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-IBqSNy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:25:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:25:56 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-btq6KY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:25:57 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-QDBKgR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:25:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:25:57 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-c6LRA6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:25:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:25:57 INFO - Running setup.py install for manifestparser 14:25:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:25:57 INFO - Running setup.py install for mozcrash 14:25:57 INFO - Running setup.py install for mozdebug 14:25:57 INFO - Running setup.py install for mozdevice 14:25:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:25:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:25:57 INFO - Running setup.py install for mozfile 14:25:58 INFO - Running setup.py install for mozhttpd 14:25:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:25:58 INFO - Running setup.py install for mozinfo 14:25:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:25:58 INFO - Running setup.py install for mozInstall 14:25:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:25:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:25:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:25:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:25:58 INFO - Running setup.py install for mozleak 14:25:58 INFO - Running setup.py install for mozlog 14:25:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:25:58 INFO - Running setup.py install for moznetwork 14:25:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:25:58 INFO - Running setup.py install for mozprocess 14:25:59 INFO - Running setup.py install for mozprofile 14:25:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:25:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:25:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:25:59 INFO - Running setup.py install for mozrunner 14:25:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:25:59 INFO - Running setup.py install for mozscreenshot 14:25:59 INFO - Running setup.py install for moztest 14:25:59 INFO - Running setup.py install for mozversion 14:25:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:25:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:25:59 INFO - Cleaning up... 14:25:59 INFO - Return code: 0 14:25:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:25:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:25:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:25:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:25:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:25:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:25:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:25:59 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 0x104fed490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f63570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdab2dc1d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e01d30>, '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 0x104c67db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104df9b20>, '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.MF3YjJIByx/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.vmXpAFwLCe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 14:25:59 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 14:25:59 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 14:25:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MF3YjJIByx/Render', 14:25:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:25:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:25:59 INFO - 'HOME': '/Users/cltbld', 14:25:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:25:59 INFO - 'LOGNAME': 'cltbld', 14:25:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:25:59 INFO - 'MOZ_NO_REMOTE': '1', 14:25:59 INFO - 'NO_EM_RESTART': '1', 14:25:59 INFO - 'PAGER': '/bin/cat', 14:25:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:25:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:25:59 INFO - 'PWD': '/builds/slave/test', 14:25:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:25:59 INFO - 'SHELL': '/bin/bash', 14:25:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vmXpAFwLCe/Listeners', 14:25:59 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 14:25:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:25:59 INFO - 'USER': 'cltbld', 14:25:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:25:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:25:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:25:59 INFO - 'XPC_FLAGS': '0x0', 14:25:59 INFO - 'XPC_SERVICE_NAME': '0', 14:25:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:26:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:26:00 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-EU8jjh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:26:00 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)) 14:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:26:00 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-I3ugA1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:26:00 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)) 14:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:26:00 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-7lQzeq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:26:00 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)) 14:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:26:00 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-tsUgzP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:26:00 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)) 14:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:26:00 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-3rpTzC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:26:00 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)) 14:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:26:00 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-8vTtpM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:26:00 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)) 14:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:26:00 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-hw8cMa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:26:00 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)) 14:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:26:00 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-QJ2bpC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:26:00 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)) 14:26:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:26:00 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-rBuJ1p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:26:01 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)) 14:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:26:01 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-Dp9sPr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:26:01 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)) 14:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:26:01 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-U6zGaI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:26:01 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)) 14:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:26:01 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-Q9N3YB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:26:01 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)) 14:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:26:01 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-3YEANr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:26:01 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)) 14:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:26:01 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-Eg0c74-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:26:01 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)) 14:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:26:01 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-Ua8LZM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:26:01 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)) 14:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:26:01 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-ce73Ne-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:26:01 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)) 14:26:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:26:01 INFO - Running setup.py (path:/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/pip-UOa4SP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:26:01 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)) 14:26:01 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)) 14:26:01 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)) 14:26:01 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)) 14:26:01 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)) 14:26:01 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)) 14:26:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:26:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:26:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:26:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:26:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:26:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:26:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:26:04 INFO - Downloading blessings-1.6.tar.gz 14:26:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:26:04 INFO - Installing collected packages: blessings 14:26:04 INFO - Running setup.py install for blessings 14:26:04 INFO - Successfully installed blessings 14:26:04 INFO - Cleaning up... 14:26:04 INFO - Return code: 0 14:26:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:26:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:26:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:26:05 INFO - Reading from file tmpfile_stdout 14:26:05 INFO - Current package versions: 14:26:05 INFO - blessings == 1.6 14:26:05 INFO - blobuploader == 1.2.4 14:26:05 INFO - docopt == 0.6.1 14:26:05 INFO - functools32 == 3.2.3-2 14:26:05 INFO - jsonschema == 2.5.1 14:26:05 INFO - manifestparser == 1.1 14:26:05 INFO - mozInstall == 1.12 14:26:05 INFO - mozcrash == 1.0 14:26:05 INFO - mozdebug == 0.1 14:26:05 INFO - mozdevice == 0.48 14:26:05 INFO - mozfile == 1.2 14:26:05 INFO - mozhttpd == 0.7 14:26:05 INFO - mozinfo == 0.9 14:26:05 INFO - mozleak == 0.1 14:26:05 INFO - mozlog == 3.4 14:26:05 INFO - moznetwork == 0.27 14:26:05 INFO - mozprocess == 0.23 14:26:05 INFO - mozprofile == 0.28 14:26:05 INFO - mozrunner == 6.13 14:26:05 INFO - mozscreenshot == 0.1 14:26:05 INFO - mozsystemmonitor == 0.3 14:26:05 INFO - moztest == 0.7 14:26:05 INFO - mozversion == 1.4 14:26:05 INFO - psutil == 3.1.1 14:26:05 INFO - requests == 1.2.3 14:26:05 INFO - wsgiref == 0.1.2 14:26:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:26:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:26:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:26:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26: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')]} 14:26:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:26:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:06 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 0x104fed490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f63570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdab2dc1d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e01d30>, '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 0x104c67db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104df9b20>, '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.MF3YjJIByx/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.vmXpAFwLCe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 14:26:06 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 14:26:06 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 14:26:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MF3YjJIByx/Render', 14:26:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:26:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:26:06 INFO - 'HOME': '/Users/cltbld', 14:26:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:26:06 INFO - 'LOGNAME': 'cltbld', 14:26:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:26:06 INFO - 'MOZ_NO_REMOTE': '1', 14:26:06 INFO - 'NO_EM_RESTART': '1', 14:26:06 INFO - 'PAGER': '/bin/cat', 14:26:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:26:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:26:06 INFO - 'PWD': '/builds/slave/test', 14:26:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:26:06 INFO - 'SHELL': '/bin/bash', 14:26:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vmXpAFwLCe/Listeners', 14:26:06 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 14:26:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:26:06 INFO - 'USER': 'cltbld', 14:26:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:26:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:26:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:26:06 INFO - 'XPC_FLAGS': '0x0', 14:26:06 INFO - 'XPC_SERVICE_NAME': '0', 14:26:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:26:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:26:06 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)) 14:26:06 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)) 14:26:06 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)) 14:26:06 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)) 14:26:06 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)) 14:26:06 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)) 14:26:06 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)) 14:26:06 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)) 14:26:06 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)) 14:26:06 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)) 14:26:06 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)) 14:26:06 INFO - Cleaning up... 14:26:06 INFO - Return code: 0 14:26:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:26: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')]} 14:26:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:26:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26: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')]} 14:26:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:26:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x104fed490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104f63570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdab2dc1d60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104e01d30>, '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 0x104c67db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104df9b20>, '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.MF3YjJIByx/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.vmXpAFwLCe/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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 14:26:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:26:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:26:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MF3YjJIByx/Render', 14:26:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:26:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:26:06 INFO - 'HOME': '/Users/cltbld', 14:26:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:26:06 INFO - 'LOGNAME': 'cltbld', 14:26:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:26:06 INFO - 'MOZ_NO_REMOTE': '1', 14:26:06 INFO - 'NO_EM_RESTART': '1', 14:26:06 INFO - 'PAGER': '/bin/cat', 14:26:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:26:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:26:06 INFO - 'PWD': '/builds/slave/test', 14:26:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:26:06 INFO - 'SHELL': '/bin/bash', 14:26:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vmXpAFwLCe/Listeners', 14:26:06 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 14:26:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:26:06 INFO - 'USER': 'cltbld', 14:26:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:26:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:26:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:26:06 INFO - 'XPC_FLAGS': '0x0', 14:26:06 INFO - 'XPC_SERVICE_NAME': '0', 14:26:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:26:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:26:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:26:07 INFO - Cleaning up... 14:26:07 INFO - Return code: 0 14:26:07 INFO - Running post-action listener: _resource_record_post_action 14:26:07 INFO - Running post-action listener: _start_resource_monitoring 14:26:07 INFO - Starting resource monitoring. 14:26:07 INFO - [mozharness: 2016-12-22 22:26:07.278740Z] Finished create-virtualenv step (success) 14:26:07 INFO - [mozharness: 2016-12-22 22:26:07.279737Z] Running install step. 14:26:07 INFO - Running pre-action listener: _resource_record_pre_action 14:26:07 INFO - Running main action method: install 14:26:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:26:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:26:07 INFO - Reading from file tmpfile_stdout 14:26:07 INFO - Detecting whether we're running mozinstall >=1.0... 14:26:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:26:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:26:07 INFO - Reading from file tmpfile_stdout 14:26:07 INFO - Output received: 14:26:07 INFO - Usage: mozinstall [options] installer 14:26:07 INFO - Options: 14:26:07 INFO - -h, --help show this help message and exit 14:26:07 INFO - -d DEST, --destination=DEST 14:26:07 INFO - Directory to install application into. [default: 14:26:07 INFO - "/builds/slave/test"] 14:26:07 INFO - --app=APP Application being installed. [default: firefox] 14:26:07 INFO - mkdir: /builds/slave/test/build/application 14:26:07 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'] 14:26:07 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 14:26:38 INFO - Reading from file tmpfile_stdout 14:26:38 INFO - Output received: 14:26:38 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 14:26:38 INFO - Running post-action listener: _resource_record_post_action 14:26:38 INFO - [mozharness: 2016-12-22 22:26:38.923797Z] Finished install step (success) 14:26:38 INFO - [mozharness: 2016-12-22 22:26:38.923948Z] Running run-tests step. 14:26:38 INFO - Running pre-action listener: _resource_record_pre_action 14:26:38 INFO - Running main action method: run_tests 14:26:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:26:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:26:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:26:38 INFO - 2016-12-22 14:26:38.938 screenresolution[2147:14352] starting screenresolution argv=screenresolution get 14:26:38 INFO - 2016-12-22 14:26:38.941 screenresolution[2147:14352] Display 0: 1600x1200x32@60 14:26:38 INFO - 2016-12-22 14:26:38.947 screenresolution[2148:14355] starting screenresolution argv=screenresolution list 14:26:38 INFO - Available Modes on Display 0 14:26:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:26:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:26:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:26:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:26:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:26:39 INFO - Intel Iris: 14:26:39 INFO - Chipset Model: Intel Iris 14:26:39 INFO - Type: GPU 14:26:39 INFO - Bus: Built-In 14:26:39 INFO - VRAM (Dynamic, Max): 1536 MB 14:26:39 INFO - Vendor: Intel (0x8086) 14:26:39 INFO - Device ID: 0x0a2e 14:26:39 INFO - Revision ID: 0x0009 14:26:39 INFO - Displays: 14:26:39 INFO - Display: 14:26:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:26:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:26:39 INFO - Display Serial Number: 1600x1200 60 14:26:39 INFO - Main Display: Yes 14:26:39 INFO - Mirror: Off 14:26:39 INFO - Online: Yes 14:26:39 INFO - Rotation: Supported 14:26:39 INFO - Return code: 0 14:26:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:26:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:26:39 INFO - Python 2.7.3 14:26:39 INFO - Return code: 0 14:26:39 INFO - grabbing minidump binary from tooltool 14:26:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:26:39 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 0x104e01d30>, '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 0x104c67db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104df9b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:26:39 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 14:26:39 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 14:26:39 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 14:26:39 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:26:39 INFO - Return code: 0 14:26:39 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:26:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:26:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:26:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:26:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:26:39 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/MJVAiO3lS_6NDZJJWJABog/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0375', '--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 14:26:39 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/MJVAiO3lS_6NDZJJWJABog/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0375 --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 14:26:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MF3YjJIByx/Render', 14:26:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:26:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:26:39 INFO - 'HOME': '/Users/cltbld', 14:26:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:26:39 INFO - 'LOGNAME': 'cltbld', 14:26:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:26:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:26:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:26:39 INFO - 'MOZ_NO_REMOTE': '1', 14:26:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:26:39 INFO - 'NO_EM_RESTART': '1', 14:26:39 INFO - 'PAGER': '/bin/cat', 14:26:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:26:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:26:39 INFO - 'PWD': '/builds/slave/test', 14:26:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:26:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:26:39 INFO - 'SHELL': '/bin/bash', 14:26:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vmXpAFwLCe/Listeners', 14:26:39 INFO - 'TMPDIR': '/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/', 14:26:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:26:39 INFO - 'USER': 'cltbld', 14:26:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:26:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:26:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:26:39 INFO - 'XPC_FLAGS': '0x0', 14:26:39 INFO - 'XPC_SERVICE_NAME': '0', 14:26:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:26:39 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/MJVAiO3lS_6NDZJJWJABog/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0375', '--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 14:26:39 INFO - mozversion application_buildid: 20161222121953 14:26:39 INFO - mozversion application_changeset: 20654fa125a90d0ca839bbf15b81612b8667c43b 14:26:39 INFO - mozversion application_display_name: FirefoxDeveloperEdition 14:26:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:26:39 INFO - mozversion application_name: Firefox 14:26:39 INFO - mozversion application_remotingname: firefox-dev 14:26:39 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:26:39 INFO - mozversion application_vendor: Mozilla 14:26:39 INFO - mozversion application_version: 52.0a2 14:26:39 INFO - mozversion platform_buildid: 20161222121953 14:26:39 INFO - mozversion platform_changeset: 20654fa125a90d0ca839bbf15b81612b8667c43b 14:26:39 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:26:39 INFO - mozversion platform_version: 52.0a2 14:26:39 INFO - using testdate: 1482445599 14:26:39 INFO - actual date: 1482445599 14:26:39 INFO - starting webserver on 'localhost:51388' 14:26:39 INFO - SUITE-START | Running 2 tests 14:26:39 INFO - TEST-START | dromaeo_css 14:26:39 INFO - operating with platform_type : mac_ 14:26:39 INFO - Initialising browser for dromaeo_css test... 14:26:39 INFO - TEST-INFO | started process 2158 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/tmp49ehwV/profile http://localhost:51388/getInfo.html) 14:26:42 INFO - PROCESS | 2158 | 2016-12-22 14:26:42.538 plugin-container[2160:14523] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 14:26:42 INFO - PROCESS | 2158 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:26:42 INFO - PROCESS | 2158 | 2016-12-22 14:26:42.540 plugin-container[2160:14523] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920f, name = 'com.apple.CFPasteboardClient' 14:26:42 INFO - PROCESS | 2158 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:26:42 INFO - PROCESS | 2158 | 2016-12-22 14:26:42.540 plugin-container[2160:14523] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:26:42 INFO - PROCESS | 2158 | __metrics Screen width/height:1600/1200 14:26:42 INFO - PROCESS | 2158 | colorDepth:24 14:26:42 INFO - PROCESS | 2158 | Browser inner width/height: 1024/689 14:26:42 INFO - PROCESS | 2158 | __metrics 14:26:42 ERROR - PROCESS | 2158 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 14:26:43 INFO - TEST-INFO | 2158: exit 0 14:26:43 INFO - Browser initialized. 14:26:43 INFO - Running cycle 1/1 for dromaeo_css test... 14:26:43 INFO - TEST-INFO | started process 2161 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/tmp49ehwV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:26:45 INFO - PROCESS | 2161 | 2016-12-22 14:26:45.971 plugin-container[2162:14654] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x423f, name = 'com.apple.tsm.portname' 14:26:45 INFO - PROCESS | 2161 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:26:45 INFO - PROCESS | 2161 | 2016-12-22 14:26:45.972 plugin-container[2162:14654] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c13, name = 'com.apple.CFPasteboardClient' 14:26:45 INFO - PROCESS | 2161 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:26:45 INFO - PROCESS | 2161 | 2016-12-22 14:26:45.972 plugin-container[2162:14654] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:26:55 INFO - PROCESS | 2161 | RSS: Main: 229466112 14:26:55 INFO - PROCESS | 2161 | 14:27:45 INFO - PROCESS | 2161 | 1482445665293 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] 14:29:59 INFO - PROCESS | 2161 | Cycle 1(1): loaded http://localhost:51388/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51388/tests/dromaeo/cssquery-ext.html) 14:30:01 INFO - PROCESS | 2161 | RSS: Main: 233164800 14:30:01 INFO - PROCESS | 2161 | 14:33:03 INFO - PROCESS | 2161 | Cycle 1(1): loaded http://localhost:51388/tests/dromaeo/cssquery-ext.html (next: http://localhost:51388/tests/dromaeo/cssquery-jquery.html) 14:33:04 INFO - PROCESS | 2161 | RSS: Main: 244011008 14:33:04 INFO - PROCESS | 2161 | 14:36:05 INFO - PROCESS | 2161 | Cycle 1(1): loaded http://localhost:51388/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51388/tests/dromaeo/cssquery-mootools.html) 14:36:06 INFO - PROCESS | 2161 | RSS: Main: 246784000 14:36:06 INFO - PROCESS | 2161 | 14:39:08 INFO - PROCESS | 2161 | Cycle 1(1): loaded http://localhost:51388/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51388/tests/dromaeo/cssquery-prototype.html) 14:39:09 INFO - PROCESS | 2161 | RSS: Main: 245968896 14:39:09 INFO - PROCESS | 2161 | 14:42:11 INFO - PROCESS | 2161 | Cycle 1(1): loaded http://localhost:51388/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51388/tests/dromaeo/cssquery-yui.html) 14:42:12 INFO - PROCESS | 2161 | RSS: Main: 249315328 14:42:12 INFO - PROCESS | 2161 | 14:45:15 INFO - PROCESS | 2161 | Cycle 1(1): loaded http://localhost:51388/tests/dromaeo/cssquery-yui.html (next: http://localhost:51388/tests/dromaeo/cssquery-dojo.html) 14:45:15 INFO - PROCESS | 2161 | RSS: Main: 251142144 14:45:15 INFO - PROCESS | 2161 | 14:45:15 INFO - PROCESS | 2161 | __start_tp_report 14:45:15 INFO - PROCESS | 2161 | _x_x_mozilla_page_load 14:45:15 INFO - PROCESS | 2161 | _x_x_mozilla_page_load_details 14:45:15 INFO - PROCESS | 2161 | |i|pagename|runs| 14:45:15 INFO - PROCESS | 2161 | |0;dojo.html;3322.9003129906096;3338.332333533294;3346.7322614190866;3348.5981682198135;3349.933001339975;4780.282957556368;5384.626920135365;5629.831105066844;5655.57583181261;5679.488846003859;4790.784414701338;5930.229070220866;5931.584789064767;6016.729247183876;6039.667818269993;4239.59723826236;5321.4412486688825;5357.598180136486;5377.731113444332;5425.543026824312;4640.814367425299;5359.651622644536;5436.3476382833915;5438.401775804658;5440.809571664992;5293.788248470057;5320.813771517997;5423.132298832187;5446.836594902159;5478.671279723229;5276.472352764731;5356.866078348034;5360.383555891083;5377.865553361158;5415.864603384907;5311.707856067915;5373;5376.274202982587;5424.593155513352;5427.62006659534;5995.7901473448655;6000.099985002241;6014.78948236814;6049.1833602463985;6052.636841789463;6088.117223002684;6131.65193447331;6153.569250152491;6204;6281.528885333572;4937.407511098691;6234.968825155891;6250.093736408196;6251.874962500725;6281.371862813728;5948.13752005961;5976.910346344808;5990.610610310316;6044.969775151184;6064.545159113084;6466.482681385478;6504.3495650435525;6513.1532900722605;6516.348365163541;6531.510136739668;5860.794872179454;6161.229846269216;6175.6912154393;6176.135341052256;6186.288576813634;6238.937610623926;6289.654069026156;6311.684415779193;6315;6339.2709838368255;6227.532935029801;6255.624662520263;6266.749330026839;6272.717727702265;6281.560290779602;6162.661053642093;6183.536234782319;6230.532710046765;6264.561480696399;6268.780592679234;5025.924611130836;5038.193888977746;5043.495650435;5049.596032317406;5062.139436295838;4449.888752781207;4501.977490112594;4502.414686090788;4504.09918016398;4516.36770852081;4742.644301677388;5714.771409143671;5992.370801065913;6010.879782404328;6047.516198704181;6258.499320054385;6315.863144633918;6339.841503962346;6341.8097457076365;6344.809655710336;5865.090910908816;5883.294004719375;6069.666168360783;6076.574639775174;6097.603655762361;5391.676499410048;5395.244665746799;5405.378381486101;5447.428020057916;5493.23094766733;6429.324920883427;6452.741890324334;6459.3863582960485;6471.288158302677;6499.837504062247;6156.415140561639;6266;6273.780417685359;6287.2140982377205;6297.653629050263;4822.204336284614;5401.027814993339;5573.634459557435;6023.674791545853;6332.683365831598;5725.599208055396;6241.96879015602;6256.186695729526;6259.12372267892;6264.154339164154;4982.202847544376;5903.144044113494;6167.198264225622;6239.387946705194;6296.748130074746;6116.816495505142;6146.293176284785;6152.477039451696;6174.506039516918;6266.780662676784;6213.37866213375;6273.62358258506;6278.121063051268;6280.5289603279025;6296.244450665949;5234.319538459976;6115.388461153849;6168.383161683975;6187.071939209155;6198.411150940653;6107.9083813741945;6130.417610327012;6133.365267548864;6138.938610613836;6184.350643182401;5206.505381988705;6121.510279177744;6184.659843708459;6184.721687523982;6212.689365531615 14:45:15 INFO - PROCESS | 2161 | |1;ext.html;461.6053274450344;463.87939135824683;473.60690626779757;517.3248910172224;541.2746919128367;38655.80672096639;40552;40726.7963660182;40784;40894.77315680527;39551.20897582047;42601.7219483416;42648;42679.78660106695;42740;9325.95337023314;9481.194098501635;9504.667336643219;9525.42847429153;9537.046295370477;9380.530973451334;9429.057094290585;9457.054294570522;9509.619615215383;9530.38052526587;9471;9489.240860731159;9495.477748723817;9524.80950380992;9525.28560357976;9456.101670341306;9460.716178514656;9515.524223788783;9532.714018579454;9580.760480987961;9410.153086222204;9460.763480912963;9485.573149208301;9504.334696571243;9527.857082143773;40201;40575.797121014504;40610.98472538181;40662.39006414906;40672.77981660555;3192.6647701991187;3350.5141754445713;3351.681590248947;3357;3385.7968521888765;28449.57325640117;29472.968446104496;30621.540676889865;30628.08115756531;31055.689443105504;3098.2873938994157;3126.3122113135073;3159.7788154829173;3309.4870295104297;3330.4837750148768;488436;496228;496693;497908;499836.5008174936;59257;59354.703226483594;59978.400215998154;59993.70003150043;60111.69944150251;62249.37750622526;62339;62551.748965020444;62673.37326626766;62814.74370512564;12743.745125097632;13090.803637945439;13409.865901340861;16254.75617865733;16371.754423683655;12601.684957876127;12628.81056784149;12651.304178270284;12686.619401417973;12706.618801435972;14940.178290194144;16590.83409165893;16594.917025414794;16622.50132496027;16623.750643740354;6258.249010118815;6283.59156654816;6414.198225221848;6424.71088801005;6449.838754031187;801.9679212831538;843.304273973974;848.2789628815458;946.7396465971913;956.4596003258137;24899.751002489742;28067;30272.697273026988;30324.84837575842;30422.695773041985;909.413428338731;980.1766516126346;1009.4347165587294;1068.1775033224374;1171.3616079236836;12119.515219391302;12310.569130080405;15398.384064637514;15569.299381527859;15719.528414147366;678320.6083969548;684122;684496;685112;686015;58444.70777646085;58619.4138058614;58971;59023.40976590265;59251.11123333153;1333.3999700134784;1356.0575400096839;1367.4871923028863;1369.0963963783854;1371.9931400342934;29283.707162928098;29613.85193074021;30346.241343965696;30372.544411834282;30938.0718578434;50295.49704503055;51082.23376649279;51109.977800444525;51212.487875122264;51342.716432088004;595242;599893;603754;604465;605278;22484.66273005939;26567.468650627266;26616;26811.32971675724;27126.321841954108;46675.53324466848;47038;47409;47664.761676191396;47876.7606161967;37725.245495090494;39082.413763792996;39370.2125957485;39989.000274993356;40303.79088627346;38206.044848879;39099.609003909594;39141.02147446336;40073.1985360297;40172;27758.44483110367;32672;35854.82072589621;36957.63042369542;37945.051373715876;28176.43647127087;31325.843370783;32489.51265731063;34478.620855165515;34637.614495419904;18829.717554236424;19270.518237044187;23749.81250937481;25675.229743107735;26126.477470450103 14:45:15 INFO - PROCESS | 2161 | |2;jquery.html;369.85760482214346;371.4280008786469;380.0460843283358;380.9238152369526;394.6527056190552;15972.520824375259;16270.83729162708;16780.66438671226;19311.841289522603;19350;18746.62506749864;19263.229470821152;19411.514712132204;19845.603087938267;20082.096305666244;5519.39286678466;5747.2241247431475;5789.160571717099;5810.738516766734;5825.737841797109;3880.9029774255728;6304.27500837405;6404.135441715379;6446.258680251784;6460.935390646104;4919.557239848413;5324.908393779266;6397.52018598607;6409.743610255584;6449.774257900975;6260.092286618437;6293.118963345135;6308.716107775115;6358.7774427895265;6379.489640828746;5265.420803711589;5335.893282134336;5352.732363381854;5531.751071201806;5547.583931205136;23254;23888.761112389002;24071.518569628686;24511.87744061286;24762.885670144886;2211.8562293450873;2282.2468585366787;2442.303943376147;2459.5818710819203;2856.243095579673;15026.248687565687;15186.696266074727;15533.223338833013;15942.760858587131;16009.839901601068;1748.1346733367066;1798.1368942907347;1806.7651205343352;1840.1627259596985;1861.7021276595815;113675;131108;131739.34130329383;139157.3042134783;139360.99999999898;11270.323780573191;11507.309561426173;11761.176717629683;11781.646550603497;11931.343776092399;14695.559133225806;14714.264286785618;14808.03747756414;14818.555443336718;14839.332230049673;8226.42415030954;8445.535495547803;8477.321814254845;8572.271356934605;8608.483490990435;8336.8332633347;8341.458130213383;8375.20435558621;8498.575071246412;8525.31797456202;4453;4565.383673204141;4566.383538222364;4594.310853371954;4676.205045142334;1697.270173825267;1939.6508628446734;1943.0770384067455;1943.2129987355145;1957.2562426278207;364.7811313212052;387.9612038796154;399.7441637352097;410.6201763368872;764.4916130773084;11665.941670291595;11714.121440891968;11769.17615766888;11796.646100616996;12027.579034733742;406.63606072564886;416.91870085333676;432.6646848692226;444.5554445554446;461.28500823723095;5992.250968628921;6011.909821352683;6096.268447786294;6113;6290;611956;612013;614682;628019;631348;124752;125056;130097;131507.34246328706;139395.30302348424;930.7533503621546;960.3229723045097;978.5694294510392;979.4955597867104;983.0366241083779;18726.782759120615;19183.52041198981;19258.71111933294;19291.80708192956;19294.517637058623;50316.74841625769;50864.23703644522;53384.73307633437;53902;55039.89920201654;77847.221527784;78700.60649696714;78901;84864;86995.56502217449;8168.959155204186;8265.297449716842;8329.6668133274;8521.872171917303;8562.785930351542;11964.880351196376;12879.871201287868;13062.869371306166;13073.477060917457;13270.469181232642;5371.758270877742;5436.6194366393975;9461.952690236505;10175.7456063599;11764.000059994996;8530.872036919327;11081.722956926142;11199.816035872927;11413.429328533706;11701.88298117008;8970.41692289999;8993.235574976201;10493.790124197321;10640.361578305326;11729.237599556001;8238.258556729923;8335.791605209919;8484.660613575388;8593.69922052725;8612.655493780429;9960.701178964642;11238.83141752858;11471.197016209118;11475.942620287178;11705.824412633647 14:45:15 INFO - PROCESS | 2161 | |3;mootools.html;1268.2770820632238;1408.7112142010883;1435.4545272796336;1768.8761786674938;1779.644071185763;5983.431574000463;5989.4010598940085;7530;8550.486970781749;8569.400141990063;7447.925520744791;7777.611119444034;7979.960100199505;8275.54484503352;8358.540280284582;2306.792388685018;2345.870977096259;2346.272655476799;2352.0121548949483;2362.0551779288335;2007.6687346587796;2337.672725818387;2366.3847399676124;2399.892004859777;2406.4104294447884;1953.238237087537;2075.408508575056;2254.2448279826276;2311.90752369905;2340.2979106268135;2317;2317.2237300504353;2328;2344.800691941187;2345.0385342009695;2309.445733024079;2364.99487717721;2373.1456675596773;2381.345130089222;2384.3085505203644;8439.873401898976;8470.280026197719;8488.066312705598;8489.108643592395;8490.108538603481;5066.594672426234;5226.686398816083;5227.47725227478;5247.842564723064;5258.2638430619745;3289.96710032899;3449.896503104911;3508;3596.478510615946;3596.478510615972;1024.3137098144261;1060.984085238722;1090.7872964771873;1107.3909349857547;1107.7507560798836;10329;10336.586536538529;10359.378437293786;10436.060754532129;10462.47687615616;4467.463904331501;4508.887277818015;4587.334836448763;4606.815727370935;4769.451513075971;9334;9392.765180870532;9426.62293508252;9454.57454414553;9459.952700236454;5210.374755029345;5210.97394513025;5219.765110570033;5219.973900130475;5226.189940559219;4298.46269216348;4312.762798046075;4708.529147085264;4783.115123702057;4792.137415265216;3175.380800743879;3816.904577385532;3927.430522574211;3940.133170702441;3941.758346120963;1271.9427625756864;1273.6497463197509;1280.065552146938;1285.575759999198;1290.4709069281737;715.6922523315025;745.1803016681607;884.5179377239419;896.5382827843666;915.2952226785338;4564.84023059198;5635.323761148688;5645.633033852787;5670.7731690732735;5740.54075673945;901.6123067081087;904.8868891388577;912.3476714149415;924.1636319131197;985.0543478260806;3762.078290818712;3792.7534710243744;3799.9240015199543;3903.863364782219;3922.627350401707;8216.507009579444;10185.388876667275;10257.17942564593;10257.58969641221;10266.948665256627;8832.955835220782;10170.288079834489;10178.541965611565;10205.795884082278;10345.327553709136;7451.3293803557945;7667.501612395176;7937.9283796686705;7953.602319883983;7985.281324680806;5720.971395142998;5749.453801888814;5753.453421924911;5920.763169473345;5946.821595351973;9154.67958621445;9291.62833486653;9325.813483730422;9350.345475816628;9443.244540436886;9475.857862131936;9559.426434413957;9564.76088097803;9634.651148839028;9643.614255429984;6520.673966301571;6573.3426657333885;6902.309769023058;7022.508424410242;7139.750108746169;7749.961250193713;7807.375409967302;7835.764927052198;7842.098158711821;7850.214978502104;6928.549644273106;7052.259512751088;7064.752733654503;7078.433725302067;7174.174969878531;6876.484263680344;6938.653067346713;6955.826104347432;6977;6981.402858338783;6486.254080780771;6495.220573531206;6562.5406221565945;6562.671866406565;6582.243042050225;4785.282207668877;4812.398450193726;4821.421429428491;4868.440129385166;4872.317875497362;4748.240281554935;4879.926801098058;4916.2625606157935;4942.8764280893265;4971.980743947554 14:45:15 INFO - PROCESS | 2161 | |4;prototype.html;250.62906897791268;294.8466797265422;305.859304719829;313.2153954344367;319.41865804236284;5296.894062118756;5418.972905135479;5451.91822122668;5571.749271282787;5581.488330251388;5248.370195576526;5360.892782144355;5392.137258038714;5425.565954723623;5453.3728621208575;3746.438034294864;3941.7634941903434;4191.434156388887;4202.558731333212;4224.5353011168745;3001.6097907272024;3431.8455669494806;4207.410962465257;4220.873373798776;4228.97885510572;4096.29285496626;4106.79466026699;4110.445089912869;4129.758139860211;4207.832966022501;3598.9100272493397;3968.206358728266;4097.815598298069;4130.586941305875;4156.293430116887;3052.4810782167083;3582.9641703582893;3603.56757189139;3605.369060414417;3609.8014609196484;4964.379452568429;5306.628536002481;5328.866778330534;5354.3574771027725;5464.852380999995;4712.6465515086165;4760.452547956995;4767.475577686451;4792.30511575823;4796.448408433005;4584.5873871351405;5533.972330138364;5733.455321744427;5794.68129252891;5809.24479817621;3537.982310088433;3675.6767563677054;3823.388257878754;3932.3315036443587;3946.3291240488898;4948.653594248404;6123.132444604394;6134.2332208473945;6148;6150;3439.1230236289584;3978.701597380208;3984.442178095069;4001.8199181036925;4005.898377946088;3533.3286675531567;3883.427211979153;4016.6786657067364;4017.1119573800265;4022.8390864365683;4492.9775351123035;4552.93006143556;4705.482396936334;4711.081339138835;4713.2458806590785;5057.898842023139;5408.783648654088;5652.247803181027;5872.29532456108;5964.343922168558;1093.5188517052472;1178.192937837567;1195.8505186851644;1264.4057293072242;1290.8382455789676;832.4672209785742;855.8360629543815;877.5875338590852;888.6178943054549;891.22463456793;5083.923741143886;5098.439171691111;5113.8721531961255;5139.969711963829;5142.5628821549835;5065.696058236444;5309.840704778863;5449.3188351456065;5558.666480011212;5589.524890384281;4589.862304130881;4884.584810291094;4895.951040489621;5078.314427552307;5139.588832893434;2606.292878164805;2681.6647919010124;2700.879135158887;2787.108125399893;2924.5905573219766;5328.893422131536;5658.217661436634;5731.140328950607;5756.740946657411;5777.966567552069;5430.913817236489;5526.198701188386;5675.262215911905;5720.628159169641;5750.281214848144;5220.347456567929;5293.655912365684;5720.914186287292;5773.769049237983;5779.099108102015;4333.783310834383;5460.563154947674;5539.030669632879;5596.188552659923;5601.075822489243;4859.829905953275;5244.449332819999;5452.891851644683;5457.481539253765;5484.369297530835;5130.820421285237;5132.820351287687;5731.656100633975;5781;5806.4193580641595;3477.904460095062;3480.8433620487644;3558.6797188252144;3761.6990640748127;3778.414345776409;5489.313835770528;5492.80775172883;5529.22590837275;5583.134614134816;5638.872225554987;5659.660420374791;5666.801661941812;5791.913121303268;5810.796622118375;5830.329512106162;5263.473472773175;5292.894142117213;5299.966506531189;5838.854028649148;5845.941540584539;4308.6337661297885;4993.300937868774;5020.774065167003;5575.972120139536;5672.893785711912;5121.436641969306;5781.508571771446;5797.942020579909;5821.7089145543405;5847.573257098256;4790.664653474223;5603.411641777555;5685.692290773062;5785.768569257351;5790.581307579501 14:45:15 INFO - PROCESS | 2161 | |5;yui.html;720.3336913355146;734.2143906020557;734.5702763883124;743.1416713695681;794;14054.859451405482;14638.853611463883;14699.412023519046;14768.409263629455;14855;13063.542776002841;13200.207987520731;13288.601341959733;13297.13568618039;13318;803.7428023032631;805.8710606302993;830.3440282177103;834.253343257784;840.2647683277132;808.2846680687604;825.108882406999;827.664795757719;828.1552816127546;831.1854382704953;827.3257295304338;855.2217482091298;859.4542465534399;860.8708693695933;861.5692153923038;848.2789628815519;854.6111519258753;856.4775486952954;857.2070834478083;857.8027053777635;621.1025068775621;630.9148264984215;631.1605564599072;632.9493640508748;633.8542135308904;13650.044496885223;13722.519711810139;13739.450421983109;13756.518521851787;13982.370793314223;12688.873111268862;13039.86960130396;13121.147125436912;13270.734585308337;13343.466261349535;9504.192143667797;11349.262297950607;11350.71623209418;11703.765924681544;12140.575079872251;3121.672224416426;3615.349237137314;3620.239749652576;3626.818659067036;3627.981860090683;85220.57389713074;86400;86861.13138868594;88554.5572272141;89124.66313005245;9756.731624888718;10042.497875106215;10092.192624590016;10198.337108088099;10314.535845886952;19154.23383064662;19256;19987.400377988684;20115.899420502803;20120.19519219215;7258.891116633255;7263.237360077116;7489.85020299591;7603.65783539742;7618.428617853683;4081.7741160859305;4243.42713733642;4247.129338485603;4248.299030659907;4295.8281668733525;3254.8047117173046;3395.4397524408196;3441.7246620270316;3499.8425070871363;3512.3677738006895;582.4786815799836;587.6033677267827;589.3840936221659;596.6599038548072;601.2544444888395;577.5408550202599;644.9161608990895;648.8896887529131;653.5882394091692;689.975850845228;7622.390208783284;8870.911290887008;8930.464172149692;8946.597403116875;9240.029796871366;567.9403662615448;575.5168032118191;661.6063442251852;665.9367360100782;667.5827607745159;3645.9635403645625;4402.537733537933;4404.713693609905;4457.687961842705;4478.641708663299;64735;65895.67052164709;68912;94578.0542194583;101852.49073754584;60772;73003.63498182475;76100.61949690327;76202.61898690471;89011;67912.98130528047;69790.65104674445;76219.61890190624;76226.85659715108;80100.5994970033;9023.774405639911;9372.859407108763;9381.812363752822;9398.906010940076;9482.478463684563;40186.79906600448;40295.79852100721;48828.51171488382;49927.500724992286;52712.73643631757;63211.05183422344;69173.65413172902;69996;70643;71190.64404677943;11832.230904991493;11912.451381320372;12134.817977730165;12335.69160770952;12338.691532711755;12120.939395302967;13351.198928064347;13559.59321220365;13919.930400347934;13920.164790112625;12106.455209515769;12729.236245825281;12871.22772633645;12892.548760793326;13233.470661173831;12240.510379584717;12520.937395312965;13008.804867927178;13576.524821631196;13597.232359793163;11970.22193557416;12513.374331283581;12539.749205015665;12624.305663188245;12924.966002719571;10124.549213648956;10147.49262536885;10255.38467691911;10478.318909270873;11166.497507612014;8980.506072166094;10730.89269107299;10862.076678745481;11088.778224435628;11147.888521114684 14:45:15 INFO - PROCESS | 2161 | __end_tp_report 14:45:15 INFO - PROCESS | 2161 | __start_cc_report 14:45:15 INFO - PROCESS | 2161 | _x_x_mozilla_cycle_collect,363 14:45:15 INFO - PROCESS | 2161 | __end_cc_report 14:45:15 INFO - PROCESS | 2161 | __startTimestamp1482446715465__endTimestamp 14:45:15 INFO - PROCESS | 2161 | 14:45:15 INFO - PROCESS | 2161 | ------- Summary: start ------- 14:45:15 INFO - PROCESS | 2161 | Number of tests: 6 14:45:15 INFO - PROCESS | 2161 | 14:45:15 INFO - PROCESS | 2161 | [#0] dojo.html Cycles:165 Average:5775.26 Median:6064.55 stddev:678.00 (11.2%) stddev-sans-first:652.22 14:45:15 INFO - PROCESS | 2161 | Values: 3322.9 3338.3 3346.7 3348.6 3349.9 4780.3 5384.6 5629.8 5655.6 5679.5 4790.8 5930.2 5931.6 6016.7 6039.7 4239.6 5321.4 5357.6 5377.7 5425.5 4640.8 5359.7 5436.3 5438.4 5440.8 5293.8 5320.8 5423.1 5446.8 5478.7 5276.5 5356.9 5360.4 5377.9 5415.9 5311.7 5373.0 5376.3 5424.6 5427.6 5995.8 6000.1 6014.8 6049.2 6052.6 6088.1 6131.7 6153.6 6204.0 6281.5 4937.4 6235.0 6250.1 6251.9 6281.4 5948.1 5976.9 5990.6 6045.0 6064.5 6466.5 6504.3 6513.2 6516.3 6531.5 5860.8 6161.2 6175.7 6176.1 6186.3 6238.9 6289.7 6311.7 6315.0 6339.3 6227.5 6255.6 6266.7 6272.7 6281.6 6162.7 6183.5 6230.5 6264.6 6268.8 5025.9 5038.2 5043.5 5049.6 5062.1 4449.9 4502.0 4502.4 4504.1 4516.4 4742.6 5714.8 5992.4 6010.9 6047.5 6258.5 6315.9 6339.8 6341.8 6344.8 5865.1 5883.3 6069.7 6076.6 6097.6 5391.7 5395.2 5405.4 5447.4 5493.2 6429.3 6452.7 6459.4 6471.3 6499.8 6156.4 6266.0 6273.8 6287.2 6297.7 4822.2 5401.0 5573.6 6023.7 6332.7 5725.6 6242.0 6256.2 6259.1 6264.2 4982.2 5903.1 6167.2 6239.4 6296.7 6116.8 6146.3 6152.5 6174.5 6266.8 6213.4 6273.6 6278.1 6280.5 6296.2 5234.3 6115.4 6168.4 6187.1 6198.4 6107.9 6130.4 6133.4 6138.9 6184.4 5206.5 6121.5 6184.7 6184.7 6212.7 14:45:15 INFO - PROCESS | 2161 | 14:45:15 INFO - PROCESS | 2161 | [#1] ext.html Cycles:180 Average:71884.57 Median:40699.79 stddev:160359.15 (394.0%) stddev-sans-first:160719.33 14:45:15 INFO - PROCESS | 2161 | Values: 461.6 463.9 473.6 517.3 541.3 38655.8 40552.0 40726.8 40784.0 40894.8 39551.2 42601.7 42648.0 42679.8 42740.0 9326.0 9481.2 9504.7 9525.4 9537.0 9380.5 9429.1 9457.1 9509.6 9530.4 9471.0 9489.2 9495.5 9524.8 9525.3 9456.1 9460.7 9515.5 9532.7 9580.8 9410.2 9460.8 9485.6 9504.3 9527.9 40201.0 40575.8 40611.0 40662.4 40672.8 3192.7 3350.5 3351.7 3357.0 3385.8 28449.6 29473.0 30621.5 30628.1 31055.7 3098.3 3126.3 3159.8 3309.5 3330.5 488436.0 496228.0 496693.0 497908.0 499836.5 59257.0 59354.7 59978.4 59993.7 60111.7 62249.4 62339.0 62551.7 62673.4 62814.7 12743.7 13090.8 13409.9 16254.8 16371.8 12601.7 12628.8 12651.3 12686.6 12706.6 14940.2 16590.8 16594.9 16622.5 16623.8 6258.2 6283.6 6414.2 6424.7 6449.8 802.0 843.3 848.3 946.7 956.5 24899.8 28067.0 30272.7 30324.8 30422.7 909.4 980.2 1009.4 1068.2 1171.4 12119.5 12310.6 15398.4 15569.3 15719.5 678320.6 684122.0 684496.0 685112.0 686015.0 58444.7 58619.4 58971.0 59023.4 59251.1 1333.4 1356.1 1367.5 1369.1 1372.0 29283.7 29613.9 30346.2 30372.5 30938.1 50295.5 51082.2 51110.0 51212.5 51342.7 595242.0 599893.0 603754.0 604465.0 605278.0 22484.7 26567.5 26616.0 26811.3 27126.3 46675.5 47038.0 47409.0 47664.8 47876.8 37725.2 39082.4 39370.2 39989.0 40303.8 38206.0 39099.6 39141.0 40073.2 40172.0 27758.4 32672.0 35854.8 36957.6 37945.1 28176.4 31325.8 32489.5 34478.6 34637.6 18829.7 19270.5 23749.8 25675.2 26126.5 14:45:15 INFO - PROCESS | 2161 | 14:45:15 INFO - PROCESS | 2161 | [#2] jquery.html Cycles:180 Average:35678.00 Median:397.20 stddev:103742.42 (26118.5%) stddev-sans-first:103999.57 14:45:15 INFO - PROCESS | 2161 | Values: 369.9 371.4 380.0 380.9 394.7 15972.5 16270.8 16780.7 19311.8 19350.0 18746.6 19263.2 19411.5 19845.6 20082.1 5519.4 5747.2 5789.2 5810.7 5825.7 3880.9 6304.3 6404.1 6446.3 6460.9 4919.6 5324.9 6397.5 6409.7 6449.8 6260.1 6293.1 6308.7 6358.8 6379.5 5265.4 5335.9 5352.7 5531.8 5547.6 23254.0 23888.8 24071.5 24511.9 24762.9 2211.9 2282.2 2442.3 2459.6 2856.2 15026.2 15186.7 15533.2 15942.8 16009.8 1748.1 1798.1 1806.8 1840.2 1861.7 113675.0 131108.0 131739.3 139157.3 139361.0 11270.3 11507.3 11761.2 11781.6 11931.3 14695.6 14714.3 14808.0 14818.6 14839.3 8226.4 8445.5 8477.3 8572.3 8608.5 8336.8 8341.5 8375.2 8498.6 8525.3 4453.0 4565.4 4566.4 4594.3 4676.2 1697.3 1939.7 1943.1 1943.2 1957.3 364.8 388.0 399.7 410.6 764.5 11665.9 11714.1 11769.2 11796.6 12027.6 406.6 416.9 432.7 444.6 461.3 5992.3 6011.9 6096.3 6113.0 6290.0 611956.0 612013.0 614682.0 628019.0 631348.0 124752.0 125056.0 130097.0 131507.3 139395.3 930.8 960.3 978.6 979.5 983.0 18726.8 19183.5 19258.7 19291.8 19294.5 50316.7 50864.2 53384.7 53902.0 55039.9 77847.2 78700.6 78901.0 84864.0 86995.6 8169.0 8265.3 8329.7 8521.9 8562.8 11964.9 12879.9 13062.9 13073.5 13270.5 5371.8 5436.6 9462.0 10175.7 11764.0 8530.9 11081.7 11199.8 11413.4 11701.9 8970.4 8993.2 10493.8 10640.4 11729.2 8238.3 8335.8 8484.7 8593.7 8612.7 9960.7 11238.8 11471.2 11475.9 11705.8 14:45:15 INFO - PROCESS | 2161 | 14:45:15 INFO - PROCESS | 2161 | [#3] mootools.html Cycles:180 Average:5390.88 Median:5210.67 stddev:2929.77 (56.2%) stddev-sans-first:2921.61 14:45:15 INFO - PROCESS | 2161 | Values: 1268.3 1408.7 1435.5 1768.9 1779.6 5983.4 5989.4 7530.0 8550.5 8569.4 7447.9 7777.6 7980.0 8275.5 8358.5 2306.8 2345.9 2346.3 2352.0 2362.1 2007.7 2337.7 2366.4 2399.9 2406.4 1953.2 2075.4 2254.2 2311.9 2340.3 2317.0 2317.2 2328.0 2344.8 2345.0 2309.4 2365.0 2373.1 2381.3 2384.3 8439.9 8470.3 8488.1 8489.1 8490.1 5066.6 5226.7 5227.5 5247.8 5258.3 3290.0 3449.9 3508.0 3596.5 3596.5 1024.3 1061.0 1090.8 1107.4 1107.8 10329.0 10336.6 10359.4 10436.1 10462.5 4467.5 4508.9 4587.3 4606.8 4769.5 9334.0 9392.8 9426.6 9454.6 9460.0 5210.4 5211.0 5219.8 5220.0 5226.2 4298.5 4312.8 4708.5 4783.1 4792.1 3175.4 3816.9 3927.4 3940.1 3941.8 1271.9 1273.6 1280.1 1285.6 1290.5 715.7 745.2 884.5 896.5 915.3 4564.8 5635.3 5645.6 5670.8 5740.5 901.6 904.9 912.3 924.2 985.1 3762.1 3792.8 3799.9 3903.9 3922.6 8216.5 10185.4 10257.2 10257.6 10266.9 8833.0 10170.3 10178.5 10205.8 10345.3 7451.3 7667.5 7937.9 7953.6 7985.3 5721.0 5749.5 5753.5 5920.8 5946.8 9154.7 9291.6 9325.8 9350.3 9443.2 9475.9 9559.4 9564.8 9634.7 9643.6 6520.7 6573.3 6902.3 7022.5 7139.8 7750.0 7807.4 7835.8 7842.1 7850.2 6928.5 7052.3 7064.8 7078.4 7174.2 6876.5 6938.7 6955.8 6977.0 6981.4 6486.3 6495.2 6562.5 6562.7 6582.2 4785.3 4812.4 4821.4 4868.4 4872.3 4748.2 4879.9 4916.3 4942.9 4972.0 14:45:15 INFO - PROCESS | 2161 | 14:45:15 INFO - PROCESS | 2161 | [#4] prototype.html Cycles:180 Average:4549.63 Median:5136.20 stddev:1413.23 (27.5%) stddev-sans-first:1379.87 14:45:15 INFO - PROCESS | 2161 | Values: 250.6 294.8 305.9 313.2 319.4 5296.9 5419.0 5451.9 5571.7 5581.5 5248.4 5360.9 5392.1 5425.6 5453.4 3746.4 3941.8 4191.4 4202.6 4224.5 3001.6 3431.8 4207.4 4220.9 4229.0 4096.3 4106.8 4110.4 4129.8 4207.8 3598.9 3968.2 4097.8 4130.6 4156.3 3052.5 3583.0 3603.6 3605.4 3609.8 4964.4 5306.6 5328.9 5354.4 5464.9 4712.6 4760.5 4767.5 4792.3 4796.4 4584.6 5534.0 5733.5 5794.7 5809.2 3538.0 3675.7 3823.4 3932.3 3946.3 4948.7 6123.1 6134.2 6148.0 6150.0 3439.1 3978.7 3984.4 4001.8 4005.9 3533.3 3883.4 4016.7 4017.1 4022.8 4493.0 4552.9 4705.5 4711.1 4713.2 5057.9 5408.8 5652.2 5872.3 5964.3 1093.5 1178.2 1195.9 1264.4 1290.8 832.5 855.8 877.6 888.6 891.2 5083.9 5098.4 5113.9 5140.0 5142.6 5065.7 5309.8 5449.3 5558.7 5589.5 4589.9 4884.6 4896.0 5078.3 5139.6 2606.3 2681.7 2700.9 2787.1 2924.6 5328.9 5658.2 5731.1 5756.7 5778.0 5430.9 5526.2 5675.3 5720.6 5750.3 5220.3 5293.7 5720.9 5773.8 5779.1 4333.8 5460.6 5539.0 5596.2 5601.1 4859.8 5244.4 5452.9 5457.5 5484.4 5130.8 5132.8 5731.7 5781.0 5806.4 3477.9 3480.8 3558.7 3761.7 3778.4 5489.3 5492.8 5529.2 5583.1 5638.9 5659.7 5666.8 5791.9 5810.8 5830.3 5263.5 5292.9 5300.0 5838.9 5845.9 4308.6 4993.3 5020.8 5576.0 5672.9 5121.4 5781.5 5797.9 5821.7 5847.6 4790.7 5603.4 5685.7 5785.8 5790.6 14:45:15 INFO - PROCESS | 2161 | 14:45:15 INFO - PROCESS | 2161 | [#5] yui.html Cycles:180 Average:18298.17 Median:49378.01 stddev:25294.14 (51.2%) stddev-sans-first:25330.66 14:45:15 INFO - PROCESS | 2161 | Values: 720.3 734.2 734.6 743.1 794.0 14054.9 14638.9 14699.4 14768.4 14855.0 13063.5 13200.2 13288.6 13297.1 13318.0 803.7 805.9 830.3 834.3 840.3 808.3 825.1 827.7 828.2 831.2 827.3 855.2 859.5 860.9 861.6 848.3 854.6 856.5 857.2 857.8 621.1 630.9 631.2 632.9 633.9 13650.0 13722.5 13739.5 13756.5 13982.4 12688.9 13039.9 13121.1 13270.7 13343.5 9504.2 11349.3 11350.7 11703.8 12140.6 3121.7 3615.3 3620.2 3626.8 3628.0 85220.6 86400.0 86861.1 88554.6 89124.7 9756.7 10042.5 10092.2 10198.3 10314.5 19154.2 19256.0 19987.4 20115.9 20120.2 7258.9 7263.2 7489.9 7603.7 7618.4 4081.8 4243.4 4247.1 4248.3 4295.8 3254.8 3395.4 3441.7 3499.8 3512.4 582.5 587.6 589.4 596.7 601.3 577.5 644.9 648.9 653.6 690.0 7622.4 8870.9 8930.5 8946.6 9240.0 567.9 575.5 661.6 665.9 667.6 3646.0 4402.5 4404.7 4457.7 4478.6 64735.0 65895.7 68912.0 94578.1 101852.5 60772.0 73003.6 76100.6 76202.6 89011.0 67913.0 69790.7 76219.6 76226.9 80100.6 9023.8 9372.9 9381.8 9398.9 9482.5 40186.8 40295.8 48828.5 49927.5 52712.7 63211.1 69173.7 69996.0 70643.0 71190.6 11832.2 11912.5 12134.8 12335.7 12338.7 12120.9 13351.2 13559.6 13919.9 13920.2 12106.5 12729.2 12871.2 12892.5 13233.5 12240.5 12520.9 13008.8 13576.5 13597.2 11970.2 12513.4 12539.7 12624.3 12925.0 10124.5 10147.5 10255.4 10478.3 11166.5 8980.5 10730.9 10862.1 11088.8 11147.9 14:45:15 INFO - PROCESS | 2161 | -------- Summary: end -------- 14:45:15 INFO - PROCESS | 2161 | 14:45:16 INFO - TEST-INFO | 2161: exit 0 14:45:16 INFO - TEST-OK | dromaeo_css | took 1116610ms 14:45:16 INFO - TEST-START | kraken 14:45:16 INFO - operating with platform_type : mac_ 14:45:16 INFO - Initialising browser for kraken test... 14:45:16 INFO - TEST-INFO | started process 2167 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/tmptPoUEa/profile http://localhost:51388/getInfo.html) 14:45:19 INFO - PROCESS | 2167 | 2016-12-22 14:45:19.357 plugin-container[2168:16938] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863b, name = 'com.apple.tsm.portname' 14:45:19 INFO - PROCESS | 2167 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:45:19 INFO - PROCESS | 2167 | 2016-12-22 14:45:19.360 plugin-container[2168:16938] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4523, name = 'com.apple.CFPasteboardClient' 14:45:19 INFO - PROCESS | 2167 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:45:19 INFO - PROCESS | 2167 | 2016-12-22 14:45:19.360 plugin-container[2168:16938] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:45:19 INFO - PROCESS | 2167 | __metrics Screen width/height:1600/1200 14:45:19 INFO - PROCESS | 2167 | colorDepth:24 14:45:19 INFO - PROCESS | 2167 | Browser inner width/height: 1024/689 14:45:19 INFO - PROCESS | 2167 | __metrics 14:45:19 ERROR - PROCESS | 2167 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 14:45:20 INFO - TEST-INFO | 2167: exit 0 14:45:20 INFO - Browser initialized. 14:45:20 INFO - Running cycle 1/1 for kraken test... 14:45:20 INFO - TEST-INFO | started process 2169 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/T/tmptPoUEa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:45:23 INFO - PROCESS | 2169 | 2016-12-22 14:45:23.109 plugin-container[2170:17064] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x852f, name = 'com.apple.tsm.portname' 14:45:23 INFO - PROCESS | 2169 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:45:23 INFO - PROCESS | 2169 | 2016-12-22 14:45:23.110 plugin-container[2170:17064] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x431b, name = 'com.apple.CFPasteboardClient' 14:45:23 INFO - PROCESS | 2169 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:45:23 INFO - PROCESS | 2169 | 2016-12-22 14:45:23.110 plugin-container[2170:17064] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:45:32 INFO - PROCESS | 2169 | RSS: Main: 229031936 14:45:32 INFO - PROCESS | 2169 | 14:45:35 INFO - PROCESS | 2169 | Cycle 1(1): loaded http://localhost:51388/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51388/tests/kraken/driver.html?testName=audio-beat-detection) 14:45:36 INFO - PROCESS | 2169 | RSS: Main: 241152000 14:45:36 INFO - PROCESS | 2169 | 14:45:38 INFO - PROCESS | 2169 | Cycle 1(1): loaded http://localhost:51388/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51388/tests/kraken/driver.html?testName=audio-dft) 14:45:39 INFO - PROCESS | 2169 | RSS: Main: 248786944 14:45:39 INFO - PROCESS | 2169 | 14:45:42 INFO - PROCESS | 2169 | Cycle 1(1): loaded http://localhost:51388/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51388/tests/kraken/driver.html?testName=audio-fft) 14:45:43 INFO - PROCESS | 2169 | RSS: Main: 252452864 14:45:43 INFO - PROCESS | 2169 | 14:45:45 INFO - PROCESS | 2169 | Cycle 1(1): loaded http://localhost:51388/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51388/tests/kraken/driver.html?testName=audio-oscillator) 14:45:46 INFO - PROCESS | 2169 | RSS: Main: 252903424 14:45:46 INFO - PROCESS | 2169 | 14:45:48 INFO - PROCESS | 2169 | Cycle 1(1): loaded http://localhost:51388/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51388/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:45:48 INFO - PROCESS | 2169 | RSS: Main: 252993536 14:45:48 INFO - PROCESS | 2169 | 14:45:51 INFO - PROCESS | 2169 | Cycle 1(1): loaded http://localhost:51388/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51388/tests/kraken/driver.html?testName=imaging-darkroom) 14:45:52 INFO - PROCESS | 2169 | RSS: Main: 258482176 14:45:52 INFO - PROCESS | 2169 | 14:45:54 INFO - PROCESS | 2169 | Cycle 1(1): loaded http://localhost:51388/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51388/tests/kraken/driver.html?testName=imaging-desaturate) 14:45:55 INFO - PROCESS | 2169 | RSS: Main: 258965504 14:45:55 INFO - PROCESS | 2169 | 14:45:58 INFO - PROCESS | 2169 | Cycle 1(1): loaded http://localhost:51388/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51388/tests/kraken/driver.html?testName=json-parse-financial) 14:45:59 INFO - PROCESS | 2169 | RSS: Main: 258547712 14:45:59 INFO - PROCESS | 2169 | 14:46:00 INFO - PROCESS | 2169 | Cycle 1(1): loaded http://localhost:51388/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51388/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:46:01 INFO - PROCESS | 2169 | RSS: Main: 257048576 14:46:01 INFO - PROCESS | 2169 | 14:46:03 INFO - PROCESS | 2169 | Cycle 1(1): loaded http://localhost:51388/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51388/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:46:03 INFO - PROCESS | 2169 | RSS: Main: 257019904 14:46:03 INFO - PROCESS | 2169 | 14:46:06 INFO - PROCESS | 2169 | Cycle 1(1): loaded http://localhost:51388/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51388/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:46:06 INFO - PROCESS | 2169 | RSS: Main: 256901120 14:46:06 INFO - PROCESS | 2169 | 14:46:09 INFO - PROCESS | 2169 | Cycle 1(1): loaded http://localhost:51388/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51388/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:46:10 INFO - PROCESS | 2169 | RSS: Main: 256970752 14:46:10 INFO - PROCESS | 2169 | 14:46:13 INFO - PROCESS | 2169 | Cycle 1(1): loaded http://localhost:51388/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51388/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:46:14 INFO - PROCESS | 2169 | RSS: Main: 257359872 14:46:14 INFO - PROCESS | 2169 | 14:46:15 INFO - PROCESS | 2169 | Cycle 1(1): loaded http://localhost:51388/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51388/tests/kraken/driver.html?testName=ai-astar) 14:46:16 INFO - PROCESS | 2169 | RSS: Main: 258883584 14:46:16 INFO - PROCESS | 2169 | 14:46:16 INFO - PROCESS | 2169 | __start_tp_report 14:46:16 INFO - PROCESS | 2169 | _x_x_mozilla_page_load 14:46:16 INFO - PROCESS | 2169 | _x_x_mozilla_page_load_details 14:46:16 INFO - PROCESS | 2169 | |i|pagename|runs| 14:46:16 INFO - PROCESS | 2169 | |0;ai-astar;115;120;120;120;119;117;120;122;136;119 14:46:16 INFO - PROCESS | 2169 | |1;audio-beat-detection;143;111;114;149;116;142;115;117;138;109 14:46:16 INFO - PROCESS | 2169 | |2;audio-dft;286;250;199;195;200;198;193;251;196;202 14:46:16 INFO - PROCESS | 2169 | |3;audio-fft;86;57;58;83;59;62;57;57;75;61 14:46:16 INFO - PROCESS | 2169 | |4;audio-oscillator;90;71;92;72;70;66;106;85;57;87 14:46:16 INFO - PROCESS | 2169 | |5;imaging-gaussian-blur;90;87;90;89;90;87;91;87;93;131 14:46:16 INFO - PROCESS | 2169 | |6;imaging-darkroom;123;95;96;95;95;95;95;95;96;109 14:46:16 INFO - PROCESS | 2169 | |7;imaging-desaturate;87;92;87;86;89;96;132;92;95;91 14:46:16 INFO - PROCESS | 2169 | |8;json-parse-financial;54;53;50;60;72;54;57;60;52;57 14:46:16 INFO - PROCESS | 2169 | |9;json-stringify-tinderbox;97;107;117;102;80;70;70;72;73;73 14:46:16 INFO - PROCESS | 2169 | |10;stanford-crypto-aes;98;103;102;86;85;84;89;83;82;84 14:46:16 INFO - PROCESS | 2169 | |11;stanford-crypto-ccm;158;158;139;152;168;160;139;164;267;184 14:46:16 INFO - PROCESS | 2169 | |12;stanford-crypto-pbkdf2;166;194;232;164;167;165;164;208;234;166 14:46:16 INFO - PROCESS | 2169 | |13;stanford-crypto-sha256-iterative;66;70;67;67;69;66;69;66;68;69 14:46:16 INFO - PROCESS | 2169 | __end_tp_report 14:46:16 INFO - PROCESS | 2169 | __start_cc_report 14:46:16 INFO - PROCESS | 2169 | _x_x_mozilla_cycle_collect,959 14:46:16 INFO - PROCESS | 2169 | __end_cc_report 14:46:16 INFO - PROCESS | 2169 | __startTimestamp1482446776201__endTimestamp 14:46:16 INFO - PROCESS | 2169 | 14:46:16 INFO - PROCESS | 2169 | ------- Summary: start ------- 14:46:16 INFO - PROCESS | 2169 | Number of tests: 14 14:46:16 INFO - PROCESS | 2169 | 14:46:16 INFO - PROCESS | 2169 | [#0] ai-astar Cycles:10 Average:120.80 Median:120.00 stddev:5.67 (4.7%) stddev-sans-first:5.61 14:46:16 INFO - PROCESS | 2169 | Values: 115.0 120.0 120.0 120.0 119.0 117.0 120.0 122.0 136.0 119.0 14:46:16 INFO - PROCESS | 2169 | 14:46:16 INFO - PROCESS | 2169 | [#1] audio-beat-detection Cycles:10 Average:125.40 Median:127.50 stddev:15.54 (12.2%) stddev-sans-first:15.13 14:46:16 INFO - PROCESS | 2169 | Values: 143.0 111.0 114.0 149.0 116.0 142.0 115.0 117.0 138.0 109.0 14:46:16 INFO - PROCESS | 2169 | 14:46:16 INFO - PROCESS | 2169 | [#2] audio-dft Cycles:10 Average:217.00 Median:201.00 stddev:32.84 (16.3%) stddev-sans-first:23.49 14:46:16 INFO - PROCESS | 2169 | Values: 286.0 250.0 199.0 195.0 200.0 198.0 193.0 251.0 196.0 202.0 14:46:16 INFO - PROCESS | 2169 | 14:46:16 INFO - PROCESS | 2169 | [#3] audio-fft Cycles:10 Average:65.50 Median:61.50 stddev:11.37 (18.5%) stddev-sans-first:9.34 14:46:16 INFO - PROCESS | 2169 | Values: 86.0 57.0 58.0 83.0 59.0 62.0 57.0 57.0 75.0 61.0 14:46:16 INFO - PROCESS | 2169 | 14:46:16 INFO - PROCESS | 2169 | [#4] audio-oscillator Cycles:10 Average:79.60 Median:78.50 stddev:14.77 (18.8%) stddev-sans-first:15.17 14:46:16 INFO - PROCESS | 2169 | Values: 90.0 71.0 92.0 72.0 70.0 66.0 106.0 85.0 57.0 87.0 14:46:16 INFO - PROCESS | 2169 | 14:46:16 INFO - PROCESS | 2169 | [#5] imaging-gaussian-blur Cycles:10 Average:93.50 Median:90.00 stddev:13.32 (14.8%) stddev-sans-first:14.07 14:46:16 INFO - PROCESS | 2169 | Values: 90.0 87.0 90.0 89.0 90.0 87.0 91.0 87.0 93.0 131.0 14:46:16 INFO - PROCESS | 2169 | 14:46:16 INFO - PROCESS | 2169 | [#6] imaging-darkroom Cycles:10 Average:99.40 Median:95.00 stddev:9.36 (9.9%) stddev-sans-first:4.60 14:46:16 INFO - PROCESS | 2169 | Values: 123.0 95.0 96.0 95.0 95.0 95.0 95.0 95.0 96.0 109.0 14:46:16 INFO - PROCESS | 2169 | 14:46:16 INFO - PROCESS | 2169 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:91.50 stddev:13.53 (14.8%) stddev-sans-first:14.06 14:46:16 INFO - PROCESS | 2169 | Values: 87.0 92.0 87.0 86.0 89.0 96.0 132.0 92.0 95.0 91.0 14:46:16 INFO - PROCESS | 2169 | 14:46:16 INFO - PROCESS | 2169 | [#8] json-parse-financial Cycles:10 Average:56.90 Median:57.00 stddev:6.24 (11.0%) stddev-sans-first:6.53 14:46:16 INFO - PROCESS | 2169 | Values: 54.0 53.0 50.0 60.0 72.0 54.0 57.0 60.0 52.0 57.0 14:46:16 INFO - PROCESS | 2169 | 14:46:16 INFO - PROCESS | 2169 | [#9] json-stringify-tinderbox Cycles:10 Average:86.10 Median:72.50 stddev:17.83 (24.6%) stddev-sans-first:18.47 14:46:16 INFO - PROCESS | 2169 | Values: 97.0 107.0 117.0 102.0 80.0 70.0 70.0 72.0 73.0 73.0 14:46:16 INFO - PROCESS | 2169 | 14:46:16 INFO - PROCESS | 2169 | [#10] stanford-crypto-aes Cycles:10 Average:89.60 Median:84.50 stddev:8.18 (9.7%) stddev-sans-first:8.09 14:46:16 INFO - PROCESS | 2169 | Values: 98.0 103.0 102.0 86.0 85.0 84.0 89.0 83.0 82.0 84.0 14:46:16 INFO - PROCESS | 2169 | 14:46:16 INFO - PROCESS | 2169 | [#11] stanford-crypto-ccm Cycles:10 Average:168.90 Median:162.00 stddev:36.92 (22.8%) stddev-sans-first:38.95 14:46:16 INFO - PROCESS | 2169 | Values: 158.0 158.0 139.0 152.0 168.0 160.0 139.0 164.0 267.0 184.0 14:46:16 INFO - PROCESS | 2169 | 14:46:16 INFO - PROCESS | 2169 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.00 Median:180.50 stddev:28.94 (16.0%) stddev-sans-first:29.78 14:46:16 INFO - PROCESS | 2169 | Values: 166.0 194.0 232.0 164.0 167.0 165.0 164.0 208.0 234.0 166.0 14:46:16 INFO - PROCESS | 2169 | 14:46:16 INFO - PROCESS | 2169 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.70 Median:68.50 stddev:1.49 (2.2%) stddev-sans-first:1.45 14:46:16 INFO - PROCESS | 2169 | Values: 66.0 70.0 67.0 67.0 69.0 66.0 69.0 66.0 68.0 69.0 14:46:16 INFO - PROCESS | 2169 | -------- Summary: end -------- 14:46:16 INFO - PROCESS | 2169 | 14:46:17 INFO - TEST-INFO | 2169: exit 0 14:46:17 INFO - TEST-OK | kraken | took 61085ms 14:46:17 INFO - SUITE-END | took 1177s 14:46:17 INFO - Completed test suite (00:19:37) 14:46:17 INFO - javascript benchmark 14:46:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7072.8230498879875, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3322.9003129906096, 3338.332333533294, 3346.7322614190866, 3348.5981682198135, 3349.933001339975, 4780.282957556368, 5384.626920135365, 5629.831105066844, 5655.57583181261, 5679.488846003859, 4790.784414701338, 5930.229070220866, 5931.584789064767, 6016.729247183876, 6039.667818269993, 4239.59723826236, 5321.4412486688825, 5357.598180136486, 5377.731113444332, 5425.543026824312, 4640.814367425299, 5359.651622644536, 5436.3476382833915, 5438.401775804658, 5440.809571664992, 5293.788248470057, 5320.813771517997, 5423.132298832187, 5446.836594902159, 5478.671279723229, 5276.472352764731, 5356.866078348034, 5360.383555891083, 5377.865553361158, 5415.864603384907, 5311.707856067915, 5373.0, 5376.274202982587, 5424.593155513352, 5427.62006659534, 5995.7901473448655, 6000.099985002241, 6014.78948236814, 6049.1833602463985, 6052.636841789463, 6088.117223002684, 6131.65193447331, 6153.569250152491, 6204.0, 6281.528885333572, 4937.407511098691, 6234.968825155891, 6250.093736408196, 6251.874962500725, 6281.371862813728, 5948.13752005961, 5976.910346344808, 5990.610610310316, 6044.969775151184, 6064.545159113084, 6466.482681385478, 6504.3495650435525, 6513.1532900722605, 6516.348365163541, 6531.510136739668, 5860.794872179454, 6161.229846269216, 6175.6912154393, 6176.135341052256, 6186.288576813634, 6238.937610623926, 6289.654069026156, 6311.684415779193, 6315.0, 6339.2709838368255, 6227.532935029801, 6255.624662520263, 6266.749330026839, 6272.717727702265, 6281.560290779602, 6162.661053642093, 6183.536234782319, 6230.532710046765, 6264.561480696399, 6268.780592679234, 5025.924611130836, 5038.193888977746, 5043.495650435, 5049.596032317406, 5062.139436295838, 4449.888752781207, 4501.977490112594, 4502.414686090788, 4504.09918016398, 4516.36770852081, 4742.644301677388, 5714.771409143671, 5992.370801065913, 6010.879782404328, 6047.516198704181, 6258.499320054385, 6315.863144633918, 6339.841503962346, 6341.8097457076365, 6344.809655710336, 5865.090910908816, 5883.294004719375, 6069.666168360783, 6076.574639775174, 6097.603655762361, 5391.676499410048, 5395.244665746799, 5405.378381486101, 5447.428020057916, 5493.23094766733, 6429.324920883427, 6452.741890324334, 6459.3863582960485, 6471.288158302677, 6499.837504062247, 6156.415140561639, 6266.0, 6273.780417685359, 6287.2140982377205, 6297.653629050263, 4822.204336284614, 5401.027814993339, 5573.634459557435, 6023.674791545853, 6332.683365831598, 5725.599208055396, 6241.96879015602, 6256.186695729526, 6259.12372267892, 6264.154339164154, 4982.202847544376, 5903.144044113494, 6167.198264225622, 6239.387946705194, 6296.748130074746, 6116.816495505142, 6146.293176284785, 6152.477039451696, 6174.506039516918, 6266.780662676784, 6213.37866213375, 6273.62358258506, 6278.121063051268, 6280.5289603279025, 6296.244450665949, 5234.319538459976, 6115.388461153849, 6168.383161683975, 6187.071939209155, 6198.411150940653, 6107.9083813741945, 6130.417610327012, 6133.365267548864, 6138.938610613836, 6184.350643182401, 5206.505381988705, 6121.510279177744, 6184.659843708459, 6184.721687523982, 6212.689365531615], "value": 5734.217430031264, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [461.6053274450344, 463.87939135824683, 473.60690626779757, 517.3248910172224, 541.2746919128367, 38655.80672096639, 40552.0, 40726.7963660182, 40784.0, 40894.77315680527, 39551.20897582047, 42601.7219483416, 42648.0, 42679.78660106695, 42740.0, 9325.95337023314, 9481.194098501635, 9504.667336643219, 9525.42847429153, 9537.046295370477, 9380.530973451334, 9429.057094290585, 9457.054294570522, 9509.619615215383, 9530.38052526587, 9471.0, 9489.240860731159, 9495.477748723817, 9524.80950380992, 9525.28560357976, 9456.101670341306, 9460.716178514656, 9515.524223788783, 9532.714018579454, 9580.760480987961, 9410.153086222204, 9460.763480912963, 9485.573149208301, 9504.334696571243, 9527.857082143773, 40201.0, 40575.797121014504, 40610.98472538181, 40662.39006414906, 40672.77981660555, 3192.6647701991187, 3350.5141754445713, 3351.681590248947, 3357.0, 3385.7968521888765, 28449.57325640117, 29472.968446104496, 30621.540676889865, 30628.08115756531, 31055.689443105504, 3098.2873938994157, 3126.3122113135073, 3159.7788154829173, 3309.4870295104297, 3330.4837750148768, 488436.0, 496228.0, 496693.0, 497908.0, 499836.5008174936, 59257.0, 59354.703226483594, 59978.400215998154, 59993.70003150043, 60111.69944150251, 62249.37750622526, 62339.0, 62551.748965020444, 62673.37326626766, 62814.74370512564, 12743.745125097632, 13090.803637945439, 13409.865901340861, 16254.75617865733, 16371.754423683655, 12601.684957876127, 12628.81056784149, 12651.304178270284, 12686.619401417973, 12706.618801435972, 14940.178290194144, 16590.83409165893, 16594.917025414794, 16622.50132496027, 16623.750643740354, 6258.249010118815, 6283.59156654816, 6414.198225221848, 6424.71088801005, 6449.838754031187, 801.9679212831538, 843.304273973974, 848.2789628815458, 946.7396465971913, 956.4596003258137, 24899.751002489742, 28067.0, 30272.697273026988, 30324.84837575842, 30422.695773041985, 909.413428338731, 980.1766516126346, 1009.4347165587294, 1068.1775033224374, 1171.3616079236836, 12119.515219391302, 12310.569130080405, 15398.384064637514, 15569.299381527859, 15719.528414147366, 678320.6083969548, 684122.0, 684496.0, 685112.0, 686015.0, 58444.70777646085, 58619.4138058614, 58971.0, 59023.40976590265, 59251.11123333153, 1333.3999700134784, 1356.0575400096839, 1367.4871923028863, 1369.0963963783854, 1371.9931400342934, 29283.707162928098, 29613.85193074021, 30346.241343965696, 30372.544411834282, 30938.0718578434, 50295.49704503055, 51082.23376649279, 51109.977800444525, 51212.487875122264, 51342.716432088004, 595242.0, 599893.0, 603754.0, 604465.0, 605278.0, 22484.66273005939, 26567.468650627266, 26616.0, 26811.32971675724, 27126.321841954108, 46675.53324466848, 47038.0, 47409.0, 47664.761676191396, 47876.7606161967, 37725.245495090494, 39082.413763792996, 39370.2125957485, 39989.000274993356, 40303.79088627346, 38206.044848879, 39099.609003909594, 39141.02147446336, 40073.1985360297, 40172.0, 27758.44483110367, 32672.0, 35854.82072589621, 36957.63042369542, 37945.051373715876, 28176.43647127087, 31325.843370783, 32489.51265731063, 34478.620855165515, 34637.614495419904, 18829.717554236424, 19270.518237044187, 23749.81250937481, 25675.229743107735, 26126.477470450103], "value": 19466.18255820642, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [369.85760482214346, 371.4280008786469, 380.0460843283358, 380.9238152369526, 394.6527056190552, 15972.520824375259, 16270.83729162708, 16780.66438671226, 19311.841289522603, 19350.0, 18746.62506749864, 19263.229470821152, 19411.514712132204, 19845.603087938267, 20082.096305666244, 5519.39286678466, 5747.2241247431475, 5789.160571717099, 5810.738516766734, 5825.737841797109, 3880.9029774255728, 6304.27500837405, 6404.135441715379, 6446.258680251784, 6460.935390646104, 4919.557239848413, 5324.908393779266, 6397.52018598607, 6409.743610255584, 6449.774257900975, 6260.092286618437, 6293.118963345135, 6308.716107775115, 6358.7774427895265, 6379.489640828746, 5265.420803711589, 5335.893282134336, 5352.732363381854, 5531.751071201806, 5547.583931205136, 23254.0, 23888.761112389002, 24071.518569628686, 24511.87744061286, 24762.885670144886, 2211.8562293450873, 2282.2468585366787, 2442.303943376147, 2459.5818710819203, 2856.243095579673, 15026.248687565687, 15186.696266074727, 15533.223338833013, 15942.760858587131, 16009.839901601068, 1748.1346733367066, 1798.1368942907347, 1806.7651205343352, 1840.1627259596985, 1861.7021276595815, 113675.0, 131108.0, 131739.34130329383, 139157.3042134783, 139360.99999999898, 11270.323780573191, 11507.309561426173, 11761.176717629683, 11781.646550603497, 11931.343776092399, 14695.559133225806, 14714.264286785618, 14808.03747756414, 14818.555443336718, 14839.332230049673, 8226.42415030954, 8445.535495547803, 8477.321814254845, 8572.271356934605, 8608.483490990435, 8336.8332633347, 8341.458130213383, 8375.20435558621, 8498.575071246412, 8525.31797456202, 4453.0, 4565.383673204141, 4566.383538222364, 4594.310853371954, 4676.205045142334, 1697.270173825267, 1939.6508628446734, 1943.0770384067455, 1943.2129987355145, 1957.2562426278207, 364.7811313212052, 387.9612038796154, 399.7441637352097, 410.6201763368872, 764.4916130773084, 11665.941670291595, 11714.121440891968, 11769.17615766888, 11796.646100616996, 12027.579034733742, 406.63606072564886, 416.91870085333676, 432.6646848692226, 444.5554445554446, 461.28500823723095, 5992.250968628921, 6011.909821352683, 6096.268447786294, 6113.0, 6290.0, 611956.0, 612013.0, 614682.0, 628019.0, 631348.0, 124752.0, 125056.0, 130097.0, 131507.34246328706, 139395.30302348424, 930.7533503621546, 960.3229723045097, 978.5694294510392, 979.4955597867104, 983.0366241083779, 18726.782759120615, 19183.52041198981, 19258.71111933294, 19291.80708192956, 19294.517637058623, 50316.74841625769, 50864.23703644522, 53384.73307633437, 53902.0, 55039.89920201654, 77847.221527784, 78700.60649696714, 78901.0, 84864.0, 86995.56502217449, 8168.959155204186, 8265.297449716842, 8329.6668133274, 8521.872171917303, 8562.785930351542, 11964.880351196376, 12879.871201287868, 13062.869371306166, 13073.477060917457, 13270.469181232642, 5371.758270877742, 5436.6194366393975, 9461.952690236505, 10175.7456063599, 11764.000059994996, 8530.872036919327, 11081.722956926142, 11199.816035872927, 11413.429328533706, 11701.88298117008, 8970.41692289999, 8993.235574976201, 10493.790124197321, 10640.361578305326, 11729.237599556001, 8238.258556729923, 8335.791605209919, 8484.660613575388, 8593.69922052725, 8612.655493780429, 9960.701178964642, 11238.83141752858, 11471.197016209118, 11475.942620287178, 11705.824412633647], "value": 8944.07737214237, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1268.2770820632238, 1408.7112142010883, 1435.4545272796336, 1768.8761786674938, 1779.644071185763, 5983.431574000463, 5989.4010598940085, 7530.0, 8550.486970781749, 8569.400141990063, 7447.925520744791, 7777.611119444034, 7979.960100199505, 8275.54484503352, 8358.540280284582, 2306.792388685018, 2345.870977096259, 2346.272655476799, 2352.0121548949483, 2362.0551779288335, 2007.6687346587796, 2337.672725818387, 2366.3847399676124, 2399.892004859777, 2406.4104294447884, 1953.238237087537, 2075.408508575056, 2254.2448279826276, 2311.90752369905, 2340.2979106268135, 2317.0, 2317.2237300504353, 2328.0, 2344.800691941187, 2345.0385342009695, 2309.445733024079, 2364.99487717721, 2373.1456675596773, 2381.345130089222, 2384.3085505203644, 8439.873401898976, 8470.280026197719, 8488.066312705598, 8489.108643592395, 8490.108538603481, 5066.594672426234, 5226.686398816083, 5227.47725227478, 5247.842564723064, 5258.2638430619745, 3289.96710032899, 3449.896503104911, 3508.0, 3596.478510615946, 3596.478510615972, 1024.3137098144261, 1060.984085238722, 1090.7872964771873, 1107.3909349857547, 1107.7507560798836, 10329.0, 10336.586536538529, 10359.378437293786, 10436.060754532129, 10462.47687615616, 4467.463904331501, 4508.887277818015, 4587.334836448763, 4606.815727370935, 4769.451513075971, 9334.0, 9392.765180870532, 9426.62293508252, 9454.57454414553, 9459.952700236454, 5210.374755029345, 5210.97394513025, 5219.765110570033, 5219.973900130475, 5226.189940559219, 4298.46269216348, 4312.762798046075, 4708.529147085264, 4783.115123702057, 4792.137415265216, 3175.380800743879, 3816.904577385532, 3927.430522574211, 3940.133170702441, 3941.758346120963, 1271.9427625756864, 1273.6497463197509, 1280.065552146938, 1285.575759999198, 1290.4709069281737, 715.6922523315025, 745.1803016681607, 884.5179377239419, 896.5382827843666, 915.2952226785338, 4564.84023059198, 5635.323761148688, 5645.633033852787, 5670.7731690732735, 5740.54075673945, 901.6123067081087, 904.8868891388577, 912.3476714149415, 924.1636319131197, 985.0543478260806, 3762.078290818712, 3792.7534710243744, 3799.9240015199543, 3903.863364782219, 3922.627350401707, 8216.507009579444, 10185.388876667275, 10257.17942564593, 10257.58969641221, 10266.948665256627, 8832.955835220782, 10170.288079834489, 10178.541965611565, 10205.795884082278, 10345.327553709136, 7451.3293803557945, 7667.501612395176, 7937.9283796686705, 7953.602319883983, 7985.281324680806, 5720.971395142998, 5749.453801888814, 5753.453421924911, 5920.763169473345, 5946.821595351973, 9154.67958621445, 9291.62833486653, 9325.813483730422, 9350.345475816628, 9443.244540436886, 9475.857862131936, 9559.426434413957, 9564.76088097803, 9634.651148839028, 9643.614255429984, 6520.673966301571, 6573.3426657333885, 6902.309769023058, 7022.508424410242, 7139.750108746169, 7749.961250193713, 7807.375409967302, 7835.764927052198, 7842.098158711821, 7850.214978502104, 6928.549644273106, 7052.259512751088, 7064.752733654503, 7078.433725302067, 7174.174969878531, 6876.484263680344, 6938.653067346713, 6955.826104347432, 6977.0, 6981.402858338783, 6486.254080780771, 6495.220573531206, 6562.5406221565945, 6562.671866406565, 6582.243042050225, 4785.282207668877, 4812.398450193726, 4821.421429428491, 4868.440129385166, 4872.317875497362, 4748.240281554935, 4879.926801098058, 4916.2625606157935, 4942.8764280893265, 4971.980743947554], "value": 4384.568955767811, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [250.62906897791268, 294.8466797265422, 305.859304719829, 313.2153954344367, 319.41865804236284, 5296.894062118756, 5418.972905135479, 5451.91822122668, 5571.749271282787, 5581.488330251388, 5248.370195576526, 5360.892782144355, 5392.137258038714, 5425.565954723623, 5453.3728621208575, 3746.438034294864, 3941.7634941903434, 4191.434156388887, 4202.558731333212, 4224.5353011168745, 3001.6097907272024, 3431.8455669494806, 4207.410962465257, 4220.873373798776, 4228.97885510572, 4096.29285496626, 4106.79466026699, 4110.445089912869, 4129.758139860211, 4207.832966022501, 3598.9100272493397, 3968.206358728266, 4097.815598298069, 4130.586941305875, 4156.293430116887, 3052.4810782167083, 3582.9641703582893, 3603.56757189139, 3605.369060414417, 3609.8014609196484, 4964.379452568429, 5306.628536002481, 5328.866778330534, 5354.3574771027725, 5464.852380999995, 4712.6465515086165, 4760.452547956995, 4767.475577686451, 4792.30511575823, 4796.448408433005, 4584.5873871351405, 5533.972330138364, 5733.455321744427, 5794.68129252891, 5809.24479817621, 3537.982310088433, 3675.6767563677054, 3823.388257878754, 3932.3315036443587, 3946.3291240488898, 4948.653594248404, 6123.132444604394, 6134.2332208473945, 6148.0, 6150.0, 3439.1230236289584, 3978.701597380208, 3984.442178095069, 4001.8199181036925, 4005.898377946088, 3533.3286675531567, 3883.427211979153, 4016.6786657067364, 4017.1119573800265, 4022.8390864365683, 4492.9775351123035, 4552.93006143556, 4705.482396936334, 4711.081339138835, 4713.2458806590785, 5057.898842023139, 5408.783648654088, 5652.247803181027, 5872.29532456108, 5964.343922168558, 1093.5188517052472, 1178.192937837567, 1195.8505186851644, 1264.4057293072242, 1290.8382455789676, 832.4672209785742, 855.8360629543815, 877.5875338590852, 888.6178943054549, 891.22463456793, 5083.923741143886, 5098.439171691111, 5113.8721531961255, 5139.969711963829, 5142.5628821549835, 5065.696058236444, 5309.840704778863, 5449.3188351456065, 5558.666480011212, 5589.524890384281, 4589.862304130881, 4884.584810291094, 4895.951040489621, 5078.314427552307, 5139.588832893434, 2606.292878164805, 2681.6647919010124, 2700.879135158887, 2787.108125399893, 2924.5905573219766, 5328.893422131536, 5658.217661436634, 5731.140328950607, 5756.740946657411, 5777.966567552069, 5430.913817236489, 5526.198701188386, 5675.262215911905, 5720.628159169641, 5750.281214848144, 5220.347456567929, 5293.655912365684, 5720.914186287292, 5773.769049237983, 5779.099108102015, 4333.783310834383, 5460.563154947674, 5539.030669632879, 5596.188552659923, 5601.075822489243, 4859.829905953275, 5244.449332819999, 5452.891851644683, 5457.481539253765, 5484.369297530835, 5130.820421285237, 5132.820351287687, 5731.656100633975, 5781.0, 5806.4193580641595, 3477.904460095062, 3480.8433620487644, 3558.6797188252144, 3761.6990640748127, 3778.414345776409, 5489.313835770528, 5492.80775172883, 5529.22590837275, 5583.134614134816, 5638.872225554987, 5659.660420374791, 5666.801661941812, 5791.913121303268, 5810.796622118375, 5830.329512106162, 5263.473472773175, 5292.894142117213, 5299.966506531189, 5838.854028649148, 5845.941540584539, 4308.6337661297885, 4993.300937868774, 5020.774065167003, 5575.972120139536, 5672.893785711912, 5121.436641969306, 5781.508571771446, 5797.942020579909, 5821.7089145543405, 5847.573257098256, 4790.664653474223, 5603.411641777555, 5685.692290773062, 5785.768569257351, 5790.581307579501], "value": 4087.0750971433636, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [720.3336913355146, 734.2143906020557, 734.5702763883124, 743.1416713695681, 794.0, 14054.859451405482, 14638.853611463883, 14699.412023519046, 14768.409263629455, 14855.0, 13063.542776002841, 13200.207987520731, 13288.601341959733, 13297.13568618039, 13318.0, 803.7428023032631, 805.8710606302993, 830.3440282177103, 834.253343257784, 840.2647683277132, 808.2846680687604, 825.108882406999, 827.664795757719, 828.1552816127546, 831.1854382704953, 827.3257295304338, 855.2217482091298, 859.4542465534399, 860.8708693695933, 861.5692153923038, 848.2789628815519, 854.6111519258753, 856.4775486952954, 857.2070834478083, 857.8027053777635, 621.1025068775621, 630.9148264984215, 631.1605564599072, 632.9493640508748, 633.8542135308904, 13650.044496885223, 13722.519711810139, 13739.450421983109, 13756.518521851787, 13982.370793314223, 12688.873111268862, 13039.86960130396, 13121.147125436912, 13270.734585308337, 13343.466261349535, 9504.192143667797, 11349.262297950607, 11350.71623209418, 11703.765924681544, 12140.575079872251, 3121.672224416426, 3615.349237137314, 3620.239749652576, 3626.818659067036, 3627.981860090683, 85220.57389713074, 86400.0, 86861.13138868594, 88554.5572272141, 89124.66313005245, 9756.731624888718, 10042.497875106215, 10092.192624590016, 10198.337108088099, 10314.535845886952, 19154.23383064662, 19256.0, 19987.400377988684, 20115.899420502803, 20120.19519219215, 7258.891116633255, 7263.237360077116, 7489.85020299591, 7603.65783539742, 7618.428617853683, 4081.7741160859305, 4243.42713733642, 4247.129338485603, 4248.299030659907, 4295.8281668733525, 3254.8047117173046, 3395.4397524408196, 3441.7246620270316, 3499.8425070871363, 3512.3677738006895, 582.4786815799836, 587.6033677267827, 589.3840936221659, 596.6599038548072, 601.2544444888395, 577.5408550202599, 644.9161608990895, 648.8896887529131, 653.5882394091692, 689.975850845228, 7622.390208783284, 8870.911290887008, 8930.464172149692, 8946.597403116875, 9240.029796871366, 567.9403662615448, 575.5168032118191, 661.6063442251852, 665.9367360100782, 667.5827607745159, 3645.9635403645625, 4402.537733537933, 4404.713693609905, 4457.687961842705, 4478.641708663299, 64735.0, 65895.67052164709, 68912.0, 94578.0542194583, 101852.49073754584, 60772.0, 73003.63498182475, 76100.61949690327, 76202.61898690471, 89011.0, 67912.98130528047, 69790.65104674445, 76219.61890190624, 76226.85659715108, 80100.5994970033, 9023.774405639911, 9372.859407108763, 9381.812363752822, 9398.906010940076, 9482.478463684563, 40186.79906600448, 40295.79852100721, 48828.51171488382, 49927.500724992286, 52712.73643631757, 63211.05183422344, 69173.65413172902, 69996.0, 70643.0, 71190.64404677943, 11832.230904991493, 11912.451381320372, 12134.817977730165, 12335.69160770952, 12338.691532711755, 12120.939395302967, 13351.198928064347, 13559.59321220365, 13919.930400347934, 13920.164790112625, 12106.455209515769, 12729.236245825281, 12871.22772633645, 12892.548760793326, 13233.470661173831, 12240.510379584717, 12520.937395312965, 13008.804867927178, 13576.524821631196, 13597.232359793163, 11970.22193557416, 12513.374331283581, 12539.749205015665, 12624.305663188245, 12924.966002719571, 10124.549213648956, 10147.49262536885, 10255.38467691911, 10478.318909270873, 11166.497507612014, 8980.506072166094, 10730.89269107299, 10862.076678745481, 11088.778224435628, 11147.888521114684], "value": 6996.505551085244, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1551.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 120.0, 120.0, 120.0, 119.0, 117.0, 120.0, 122.0, 136.0, 119.0], "value": 120.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 111.0, 114.0, 149.0, 116.0, 142.0, 115.0, 117.0, 138.0, 109.0], "value": 125.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [286.0, 250.0, 199.0, 195.0, 200.0, 198.0, 193.0, 251.0, 196.0, 202.0], "value": 217.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 57.0, 58.0, 83.0, 59.0, 62.0, 57.0, 57.0, 75.0, 61.0], "value": 65.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 71.0, 92.0, 72.0, 70.0, 66.0, 106.0, 85.0, 57.0, 87.0], "value": 79.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 90.0, 89.0, 90.0, 87.0, 91.0, 87.0, 93.0, 131.0], "value": 93.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 95.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 109.0], "value": 99.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 92.0, 87.0, 86.0, 89.0, 96.0, 132.0, 92.0, 95.0, 91.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 50.0, 60.0, 72.0, 54.0, 57.0, 60.0, 52.0, 57.0], "value": 56.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 107.0, 117.0, 102.0, 80.0, 70.0, 70.0, 72.0, 73.0, 73.0], "value": 86.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 103.0, 102.0, 86.0, 85.0, 84.0, 89.0, 83.0, 82.0, 84.0], "value": 89.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 158.0, 139.0, 152.0, 168.0, 160.0, 139.0, 164.0, 267.0, 184.0], "value": 168.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 194.0, 232.0, 164.0, 167.0, 165.0, 164.0, 208.0, 234.0, 166.0], "value": 186.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 70.0, 67.0, 67.0, 69.0, 66.0, 69.0, 66.0, 68.0, 69.0], "value": 67.7, "unit": "score"}]}]} 14:46:17 INFO - Return code: 0 14:46:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:46:17 ERROR - # TBPL SUCCESS # 14:46:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:46:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:46:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:46:17 INFO - 2016-12-22 14:46:17.557 screenresolution[2174:17449] starting screenresolution argv=screenresolution get 14:46:17 INFO - 2016-12-22 14:46:17.560 screenresolution[2174:17449] Display 0: 1600x1200x32@60 14:46:17 INFO - 2016-12-22 14:46:17.567 screenresolution[2175:17452] starting screenresolution argv=screenresolution list 14:46:17 INFO - Available Modes on Display 0 14:46:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:46:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:46:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:46:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:46:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:46:17 INFO - Intel Iris: 14:46:17 INFO - Chipset Model: Intel Iris 14:46:17 INFO - Type: GPU 14:46:17 INFO - Bus: Built-In 14:46:17 INFO - VRAM (Dynamic, Max): 1536 MB 14:46:17 INFO - Vendor: Intel (0x8086) 14:46:17 INFO - Device ID: 0x0a2e 14:46:17 INFO - Revision ID: 0x0009 14:46:17 INFO - Displays: 14:46:17 INFO - Display: 14:46:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:46:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:46:17 INFO - Display Serial Number: 1600x1200 60 14:46:17 INFO - Main Display: Yes 14:46:17 INFO - Mirror: Off 14:46:17 INFO - Online: Yes 14:46:17 INFO - Rotation: Supported 14:46:17 INFO - Return code: 0 14:46:17 INFO - Running post-action listener: _resource_record_post_action 14:46:17 INFO - [mozharness: 2016-12-22 22:46:17.806397Z] Finished run-tests step (success) 14:46:17 INFO - Running post-run listener: _resource_record_post_run 14:46:17 INFO - Total resource usage - Wall time: 1210s; CPU: 55.0%; Read bytes: 1480192; Write bytes: 661079552; Read time: 15; Write time: 1951 14:46:17 INFO - TinderboxPrint: CPU usage
54.6% 14:46:17 INFO - TinderboxPrint: I/O read bytes / time
1,480,192 / 15 14:46:17 INFO - TinderboxPrint: I/O write bytes / time
661,079,552 / 1,951 14:46:17 INFO - TinderboxPrint: CPU idle
2,199.2 (45.4%) 14:46:17 INFO - TinderboxPrint: CPU system
982.6 (20.3%) 14:46:17 INFO - TinderboxPrint: CPU user
1,659.9 (34.3%) 14:46:17 INFO - TinderboxPrint: Swap in / out
350,023,680 / 0 14:46:17 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 342415872; Write bytes: 337307136; Read time: 26860; Write time: 708 14:46:17 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 1480192; Write bytes: 308359168; Read time: 15; Write time: 1190 14:46:18 INFO - Running post-run listener: _upload_blobber_files 14:46:18 INFO - Blob upload gear active. 14:46:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:46:18 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 14:46:18 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'] 14:46:18 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 14:46:18 INFO - (blobuploader) - INFO - Open directory for files ... 14:46:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:46:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:46:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:46:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:46:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:46:19 INFO - (blobuploader) - INFO - Done attempting. 14:46:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:46:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:46:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:46:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:46:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:46:20 INFO - (blobuploader) - INFO - Done attempting. 14:46:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:46:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:46:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:46:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:46:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:46:22 INFO - (blobuploader) - INFO - Done attempting. 14:46:22 INFO - (blobuploader) - INFO - Iteration through files over. 14:46:22 INFO - Return code: 0 14:46:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:46:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:46:22 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9bc990935c8adb2468c50850c665cca274af35a237445bfe4db6d6180d63c938f50395d4f7364b61ca7882d6e9b9c8c28c8ae5e5e3c7d47d6d08b609ab592af5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6365e8244e071340fd4adee0281a036d729211caccca9c0bd47263a5155b06916f4709ce49dde389eca044e38d205a8b0133f50408b8372dc56e1aab351b986e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ac24c3989b6841cb731708310d48c6bf5f42a66805923ea66c68a420c91f33ce6305e31bde4ef08f613b88b4c23fd706b972e992a86d7b59db2b4dd221297c10"} 14:46:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:46:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:46:22 INFO - Contents: 14:46:22 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9bc990935c8adb2468c50850c665cca274af35a237445bfe4db6d6180d63c938f50395d4f7364b61ca7882d6e9b9c8c28c8ae5e5e3c7d47d6d08b609ab592af5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6365e8244e071340fd4adee0281a036d729211caccca9c0bd47263a5155b06916f4709ce49dde389eca044e38d205a8b0133f50408b8372dc56e1aab351b986e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ac24c3989b6841cb731708310d48c6bf5f42a66805923ea66c68a420c91f33ce6305e31bde4ef08f613b88b4c23fd706b972e992a86d7b59db2b4dd221297c10"} 14:46:22 INFO - Running post-run listener: copy_logs_to_upload_dir 14:46:22 INFO - Copying logs to upload dir... 14:46:22 INFO - mkdir: /builds/slave/test/build/upload/logs 14:46:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1269.984625 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-12-22 14:46:22.740804) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-22 14:46:22.745092) ========= 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.MF3YjJIByx/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.vmXpAFwLCe/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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/9bc990935c8adb2468c50850c665cca274af35a237445bfe4db6d6180d63c938f50395d4f7364b61ca7882d6e9b9c8c28c8ae5e5e3c7d47d6d08b609ab592af5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6365e8244e071340fd4adee0281a036d729211caccca9c0bd47263a5155b06916f4709ce49dde389eca044e38d205a8b0133f50408b8372dc56e1aab351b986e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ac24c3989b6841cb731708310d48c6bf5f42a66805923ea66c68a420c91f33ce6305e31bde4ef08f613b88b4c23fd706b972e992a86d7b59db2b4dd221297c10"} build_url:https://queue.taskcluster.net/v1/task/MJVAiO3lS_6NDZJJWJABog/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011274 build_url: 'https://queue.taskcluster.net/v1/task/MJVAiO3lS_6NDZJJWJABog/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/9bc990935c8adb2468c50850c665cca274af35a237445bfe4db6d6180d63c938f50395d4f7364b61ca7882d6e9b9c8c28c8ae5e5e3c7d47d6d08b609ab592af5", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6365e8244e071340fd4adee0281a036d729211caccca9c0bd47263a5155b06916f4709ce49dde389eca044e38d205a8b0133f50408b8372dc56e1aab351b986e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ac24c3989b6841cb731708310d48c6bf5f42a66805923ea66c68a420c91f33ce6305e31bde4ef08f613b88b4c23fd706b972e992a86d7b59db2b4dd221297c10"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-22 14:46:22.782949) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 14:46:22.783292) ========= 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.MF3YjJIByx/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.vmXpAFwLCe/Listeners TMPDIR=/var/folders/kq/03dzpmjx5y95z5300b8pt38m00000w/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.005271 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 14:46:22.831701) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-22 14:46:22.832040) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-22 14:46:22.832359) ========= ========= Total master_lag: 0.20 =========