builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0328 starttime: 1480555471.6 results: success (0) buildid: 20161130141744 builduid: 15d4596afa324fb8bdbd79dbdd1c81cf revision: 3fa3896980f6726c3897ce1645a839996d5d5e8b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-30 17:24:31.602727) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-30 17:24:31.603181) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-30 17:24:31.603469) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-30 17:24:31.629865) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 17:24:31.630271) ========= 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.nCJmDy8FT2/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.wJxczN5Hwd/Listeners TMPDIR=/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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-11-30 17:24:31-- 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% 18.8M=0.001s 2016-11-30 17:24:31 (18.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.126004 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 17:24:31.782523) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 17:24:31.783058) ========= 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.nCJmDy8FT2/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.wJxczN5Hwd/Listeners TMPDIR=/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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.026921 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 17:24:31.834855) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-30 17:24:31.835462) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3fa3896980f6726c3897ce1645a839996d5d5e8b --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 3fa3896980f6726c3897ce1645a839996d5d5e8b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.nCJmDy8FT2/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.wJxczN5Hwd/Listeners TMPDIR=/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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-11-30 17:24:31,936 truncating revision to first 12 chars 2016-11-30 17:24:31,936 Setting DEBUG logging. 2016-11-30 17:24:31,936 attempt 1/10 2016-11-30 17:24:31,936 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/3fa3896980f6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-30 17:24:32,925 unpacking tar archive at: mozilla-aurora-3fa3896980f6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.267902 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-30 17:24:33.127833) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-30 17:24:33.128185) ========= script_repo_revision: 3fa3896980f6726c3897ce1645a839996d5d5e8b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-30 17:24:33.128589) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-30 17:24:33.128853) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-30 17:24:33.148861) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-30 17:24:33.149270) ========= /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.nCJmDy8FT2/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.wJxczN5Hwd/Listeners TMPDIR=/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 17:24:33 INFO - MultiFileLogger online at 20161130 17:24:33 in /builds/slave/test 17:24:33 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 17:24:33 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:24:33 INFO - {'append_to_log': False, 17:24:33 INFO - 'base_work_dir': '/builds/slave/test', 17:24:33 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 17:24:33 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:24:33 INFO - 'branch': 'Mozilla-Aurora', 17:24:33 INFO - 'buildbot_json_path': 'buildprops.json', 17:24:33 INFO - 'config_files': ('talos/mac_config.py',), 17:24:33 INFO - 'default_actions': ('clobber', 17:24:33 INFO - 'read-buildbot-config', 17:24:33 INFO - 'download-and-extract', 17:24:33 INFO - 'populate-webroot', 17:24:33 INFO - 'create-virtualenv', 17:24:33 INFO - 'install', 17:24:33 INFO - 'run-tests'), 17:24:33 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:24:33 INFO - 'download_minidump_stackwalk': True, 17:24:33 INFO - 'download_symbols': 'ondemand', 17:24:33 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:24:33 INFO - 'tooltool.py': '/tools/tooltool.py', 17:24:33 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:24:33 INFO - '/tools/misc-python/virtualenv.py')}, 17:24:33 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:24:33 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:24:33 INFO - 'installer_path': 'installer.exe', 17:24:33 INFO - 'log_level': 'info', 17:24:33 INFO - 'log_name': 'talos', 17:24:33 INFO - 'log_to_console': True, 17:24:33 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:24:33 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:24:33 INFO - 'opt_config_files': (), 17:24:33 INFO - 'pip_index': False, 17:24:33 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:24:33 INFO - 'cmd': ('bash', 17:24:33 INFO - '-c', 17:24:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:24:33 INFO - 'enabled': True, 17:24:33 INFO - 'halt_on_failure': False, 17:24:33 INFO - 'name': 'check_screen_resolution'},), 17:24:33 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:24:33 INFO - 'cmd': ('bash', 17:24:33 INFO - '-c', 17:24:33 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:24:33 INFO - 'enabled': True, 17:24:33 INFO - 'halt_on_failure': False, 17:24:33 INFO - 'name': 'check_screen_resolution'},), 17:24:33 INFO - 'run_cmd_checks_enabled': True, 17:24:33 INFO - 'sps_profile': False, 17:24:33 INFO - 'sps_profile_interval': 0, 17:24:33 INFO - 'suite': 'dromaeojs-e10s', 17:24:33 INFO - 'system_bits': '32', 17:24:33 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:24:33 INFO - 'title': 't-yosemite-r7-0328', 17:24:33 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:24:33 INFO - 'use_talos_json': True, 17:24:33 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:24:33 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:24:33 INFO - 'work_dir': 'build'} 17:24:33 INFO - [mozharness: 2016-12-01 01:24:33.356023Z] Running clobber step. 17:24:33 INFO - Running pre-action listener: _resource_record_pre_action 17:24:33 INFO - Running main action method: clobber 17:24:33 INFO - rmtree: /builds/slave/test/build 17:24:33 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:24:35 INFO - Running post-action listener: _resource_record_post_action 17:24:35 INFO - [mozharness: 2016-12-01 01:24:35.408978Z] Finished clobber step (success) 17:24:35 INFO - [mozharness: 2016-12-01 01:24:35.409102Z] Running read-buildbot-config step. 17:24:35 INFO - Running pre-action listener: _resource_record_pre_action 17:24:35 INFO - Running main action method: read_buildbot_config 17:24:35 INFO - Using buildbot properties: 17:24:35 INFO - { 17:24:35 INFO - "project": "", 17:24:35 INFO - "product": "firefox", 17:24:35 INFO - "script_repo_revision": "production", 17:24:35 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 17:24:35 INFO - "repository": "", 17:24:35 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 17:24:35 INFO - "buildid": "20161130141744", 17:24:35 INFO - "pgo_build": "False", 17:24:35 INFO - "basedir": "/builds/slave/test", 17:24:35 INFO - "buildnumber": 471, 17:24:35 INFO - "slavename": "t-yosemite-r7-0328", 17:24:35 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 17:24:35 INFO - "platform": "yosemite_r7", 17:24:35 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 17:24:35 INFO - "branch": "mozilla-aurora", 17:24:35 INFO - "repo_path": "releases/mozilla-aurora", 17:24:35 INFO - "revision": "3fa3896980f6726c3897ce1645a839996d5d5e8b", 17:24:35 INFO - "stage_platform": "macosx64", 17:24:35 INFO - "builduid": "15d4596afa324fb8bdbd79dbdd1c81cf", 17:24:35 INFO - "slavebuilddir": "test" 17:24:35 INFO - } 17:24:35 INFO - Found installer url https://queue.taskcluster.net/v1/task/Xb7NEB8-SR6ucPQnPkRDSA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 17:24:35 INFO - Running post-action listener: _resource_record_post_action 17:24:35 INFO - [mozharness: 2016-12-01 01:24:35.412418Z] Finished read-buildbot-config step (success) 17:24:35 INFO - [mozharness: 2016-12-01 01:24:35.412508Z] Running download-and-extract step. 17:24:35 INFO - Running pre-action listener: _resource_record_pre_action 17:24:35 INFO - Running main action method: download_and_extract 17:24:35 INFO - mkdir: /builds/slave/test/build/tests 17:24:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:24:35 INFO - trying https://queue.taskcluster.net/v1/task/Xb7NEB8-SR6ucPQnPkRDSA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 17:24:35 INFO - Downloading https://queue.taskcluster.net/v1/task/Xb7NEB8-SR6ucPQnPkRDSA/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 17:24:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Xb7NEB8-SR6ucPQnPkRDSA/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 17:24:36 INFO - Downloaded 1199 bytes. 17:24:36 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 17:24:36 INFO - Using the following test package requirements: 17:24:36 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 17:24:36 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 17:24:36 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 17:24:36 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 17:24:36 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 17:24:36 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 17:24:36 INFO - u'jsshell-mac.zip'], 17:24:36 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 17:24:36 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 17:24:36 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 17:24:36 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 17:24:36 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 17:24:36 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 17:24:36 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 17:24:36 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 17:24:36 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 17:24:36 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 17:24:36 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 17:24:36 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 17:24:36 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Xb7NEB8-SR6ucPQnPkRDSA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 17:24:36 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Xb7NEB8-SR6ucPQnPkRDSA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 17:24:36 INFO - Fetch https://queue.taskcluster.net/v1/task/Xb7NEB8-SR6ucPQnPkRDSA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 17:24:36 INFO - Http code: 200 17:24:36 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront) 17:24:36 INFO - x-amz-cf-id: GM-m4-mr2dExG2wBLCcfDSgEjEn_TUfEB6AjCVyyEvLglkt46_m8kw== 17:24:36 INFO - x-amz-version-id: mStQbsv2ce.oUxf5KqhhTjryVkWf9H9w 17:24:37 INFO - Expected file size: 16285165 17:24:37 INFO - Obtained file size: 16285165 17:24:43 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 17:24:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Xb7NEB8-SR6ucPQnPkRDSA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 17:24:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Xb7NEB8-SR6ucPQnPkRDSA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 17:24:43 INFO - Fetch https://queue.taskcluster.net/v1/task/Xb7NEB8-SR6ucPQnPkRDSA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 17:24:44 INFO - Http code: 200 17:24:44 INFO - via: 1.1 6cd88b9fd84ad5daa3b3867fb8c5c91a.cloudfront.net (CloudFront) 17:24:44 INFO - x-amz-cf-id: 5HNCaX5vDw8Ss0bwsmYVf7KukFqrhTXstBHFmIp39FAyWolv9hG4IQ== 17:24:44 INFO - x-amz-version-id: mStQbsv2ce.oUxf5KqhhTjryVkWf9H9w 17:24:44 INFO - Expected file size: 16285165 17:24:44 INFO - Obtained file size: 16285165 17:24:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Xb7NEB8-SR6ucPQnPkRDSA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 17:24:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Xb7NEB8-SR6ucPQnPkRDSA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 17:24:50 INFO - Fetch https://queue.taskcluster.net/v1/task/Xb7NEB8-SR6ucPQnPkRDSA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 17:24:51 INFO - Http code: 200 17:24:51 INFO - via: 1.1 455cf6ccbccc261c46d02110de1c0237.cloudfront.net (CloudFront) 17:24:51 INFO - x-amz-cf-id: LzHlSXNtrg6R0R-7ESWQhdks15LqsjD_Og2cALxv-l8IfX3gXKzLwA== 17:24:51 INFO - x-amz-version-id: lfZInHrhkJvAQzOAoNt0RN86foAyAZCi 17:24:51 INFO - Expected file size: 14409038 17:24:51 INFO - Obtained file size: 14409038 17:24:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:24:52 INFO - trying https://queue.taskcluster.net/v1/task/Xb7NEB8-SR6ucPQnPkRDSA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 17:24:52 INFO - Downloading https://queue.taskcluster.net/v1/task/Xb7NEB8-SR6ucPQnPkRDSA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 17:24:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Xb7NEB8-SR6ucPQnPkRDSA/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 17:24:54 INFO - Downloaded 109765617 bytes. 17:24:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Xb7NEB8-SR6ucPQnPkRDSA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 17:24:54 INFO - mkdir: /builds/slave/test/properties 17:24:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:24:54 INFO - Writing to file /builds/slave/test/properties/build_url 17:24:54 INFO - Contents: 17:24:54 INFO - build_url:https://queue.taskcluster.net/v1/task/Xb7NEB8-SR6ucPQnPkRDSA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 17:24:55 INFO - Running post-action listener: _resource_record_post_action 17:24:55 INFO - Running post-action listener: set_extra_try_arguments 17:24:55 INFO - [mozharness: 2016-12-01 01:24:55.580112Z] Finished download-and-extract step (success) 17:24:55 INFO - [mozharness: 2016-12-01 01:24:55.580227Z] Running populate-webroot step. 17:24:55 INFO - Running pre-action listener: _resource_record_pre_action 17:24:55 INFO - Running main action method: populate_webroot 17:24:55 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:24:55 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:24:55 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:24:55 INFO - '--tppagecycles', 17:24:55 INFO - '7'], 17:24:55 INFO - 'tests': ['tsvgm']}}, 17:24:55 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:24:55 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:24:55 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:24:55 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:24:55 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:24:55 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:24:55 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:24:55 INFO - 'talos_options': ['--disable-e10s'], 17:24:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:24:55 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:24:55 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:24:55 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:24:55 INFO - 'talos_options': ['--disable-e10s'], 17:24:55 INFO - 'tests': ['damp', 'tps']}, 17:24:55 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:24:55 INFO - 'tests': ['damp', 'tps']}, 17:24:55 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:24:55 INFO - 'tests': ['dromaeo_dom']}, 17:24:55 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:24:55 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:24:55 INFO - 'tests': ['basic_compositor_video']}, 17:24:55 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:24:55 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:24:55 INFO - 'tests': ['a11yr', 17:24:55 INFO - 'ts_paint', 17:24:55 INFO - 'tpaint', 17:24:55 INFO - 'sessionrestore', 17:24:55 INFO - 'sessionrestore_no_auto_restore', 17:24:55 INFO - 'tabpaint']}, 17:24:55 INFO - 'other-e10s': {'tests': ['a11yr', 17:24:55 INFO - 'ts_paint', 17:24:55 INFO - 'tpaint', 17:24:55 INFO - 'sessionrestore', 17:24:55 INFO - 'sessionrestore_no_auto_restore', 17:24:55 INFO - 'tabpaint']}, 17:24:55 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:24:55 INFO - 'ts_paint', 17:24:55 INFO - 'tpaint', 17:24:55 INFO - 'sessionrestore', 17:24:55 INFO - 'sessionrestore_no_auto_restore', 17:24:55 INFO - 'tabpaint']}, 17:24:55 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:24:55 INFO - 'ts_paint', 17:24:55 INFO - 'tpaint', 17:24:55 INFO - 'sessionrestore', 17:24:55 INFO - 'sessionrestore_no_auto_restore', 17:24:55 INFO - 'tabpaint']}, 17:24:55 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:24:55 INFO - 'tests': ['a11yr', 17:24:55 INFO - 'ts_paint', 17:24:55 INFO - 'tpaint', 17:24:55 INFO - 'sessionrestore', 17:24:55 INFO - 'sessionrestore_no_auto_restore', 17:24:55 INFO - 'tabpaint']}, 17:24:55 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:24:55 INFO - 'tests': ['a11yr', 17:24:55 INFO - 'ts_paint', 17:24:55 INFO - 'tpaint', 17:24:55 INFO - 'sessionrestore', 17:24:55 INFO - 'sessionrestore_no_auto_restore', 17:24:55 INFO - 'tabpaint']}, 17:24:55 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:24:55 INFO - 'tests': ['tsvgx', 17:24:55 INFO - 'tsvgr_opacity', 17:24:55 INFO - 'tart', 17:24:55 INFO - 'tscrollx', 17:24:55 INFO - 'cart']}, 17:24:55 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:24:55 INFO - 'tsvgr_opacity', 17:24:55 INFO - 'tart', 17:24:55 INFO - 'tscrollx', 17:24:55 INFO - 'cart']}, 17:24:55 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:24:55 INFO - 'talos_options': ['--disable-e10s'], 17:24:55 INFO - 'tests': ['tp5o']}, 17:24:55 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:24:55 INFO - 'tests': ['tp5o']}, 17:24:55 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:24:55 INFO - 'talos_options': ['--disable-e10s', 17:24:55 INFO - '--xperf_path', 17:24:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:24:55 INFO - 'tests': ['tp5n']}, 17:24:55 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:24:55 INFO - 'talos_options': ['--xperf_path', 17:24:55 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:24:55 INFO - 'tests': ['tp5n']}}, 17:24:55 INFO - 'talos.zip': {'path': '', 17:24:55 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:24:55 INFO - Running post-action listener: _resource_record_post_action 17:24:55 INFO - [mozharness: 2016-12-01 01:24:55.598842Z] Finished populate-webroot step (success) 17:24:55 INFO - [mozharness: 2016-12-01 01:24:55.598964Z] Running create-virtualenv step. 17:24:55 INFO - Running pre-action listener: _resource_record_pre_action 17:24:55 INFO - Running main action method: create_virtualenv 17:24:55 INFO - Creating virtualenv /builds/slave/test/build/venv 17:24:55 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 17:24:55 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:24:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:24:55 INFO - Using real prefix '/tools/python27' 17:24:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:24:56 INFO - Installing distribute.............................................................................................................................................................................................done. 17:24:59 INFO - Installing pip.................done. 17:24:59 INFO - Return code: 0 17:24:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 17:24:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 17:24:59 INFO - Reading from file tmpfile_stdout 17:24:59 INFO - Output received: 17:24:59 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) 17:24:59 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:24: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')]} 17:24:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:24:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:24: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')]} 17:24:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:24:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:24:59 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 0x10e8bb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e831570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb320591e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6cfd30>, '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 0x10e535db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e6c7b20>, '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.nCJmDy8FT2/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.wJxczN5Hwd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 17:24:59 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 17:24:59 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 17:24:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nCJmDy8FT2/Render', 17:24:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:24:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:24:59 INFO - 'HOME': '/Users/cltbld', 17:24:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:24:59 INFO - 'LOGNAME': 'cltbld', 17:24:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:24:59 INFO - 'MOZ_NO_REMOTE': '1', 17:24:59 INFO - 'NO_EM_RESTART': '1', 17:24:59 INFO - 'PAGER': '/bin/cat', 17:24:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:24:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:24:59 INFO - 'PWD': '/builds/slave/test', 17:24:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:24:59 INFO - 'SHELL': '/bin/bash', 17:24:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wJxczN5Hwd/Listeners', 17:24:59 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 17:24:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:24:59 INFO - 'USER': 'cltbld', 17:24:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:24:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:24:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:24:59 INFO - 'XPC_FLAGS': '0x0', 17:24:59 INFO - 'XPC_SERVICE_NAME': '0', 17:24:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:24:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:00 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 17:25:00 INFO - Cleaning up... 17:25:00 INFO - Return code: 0 17:25:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:25:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:25:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:25:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:00 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 0x10e8bb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e831570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb320591e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6cfd30>, '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 0x10e535db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e6c7b20>, '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.nCJmDy8FT2/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.wJxczN5Hwd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 17:25:00 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 17:25:00 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 17:25:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nCJmDy8FT2/Render', 17:25:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:00 INFO - 'HOME': '/Users/cltbld', 17:25:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:00 INFO - 'LOGNAME': 'cltbld', 17:25:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:00 INFO - 'MOZ_NO_REMOTE': '1', 17:25:00 INFO - 'NO_EM_RESTART': '1', 17:25:00 INFO - 'PAGER': '/bin/cat', 17:25:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:00 INFO - 'PWD': '/builds/slave/test', 17:25:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:00 INFO - 'SHELL': '/bin/bash', 17:25:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wJxczN5Hwd/Listeners', 17:25:00 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 17:25:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:00 INFO - 'USER': 'cltbld', 17:25:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:00 INFO - 'XPC_FLAGS': '0x0', 17:25:00 INFO - 'XPC_SERVICE_NAME': '0', 17:25:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:00 INFO - Downloading/unpacking psutil>=3.1.1 17:25:00 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 17:25:00 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 17:25:00 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 17:25:00 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 17:25:00 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 17:25:00 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 17:25:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:25:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:25:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:25:03 INFO - Installing collected packages: psutil 17:25:03 INFO - Running setup.py install for psutil 17:25:03 INFO - building 'psutil._psutil_osx' extension 17:25:03 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 17:25:04 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 17:25:04 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 17:25:04 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 17:25:04 INFO - building 'psutil._psutil_posix' extension 17:25:04 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 17:25:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:25:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:25:04 INFO - ^ 17:25:04 INFO - 1 warning generated. 17:25:04 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 17:25:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:25:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:25:04 INFO - Successfully installed psutil 17:25:04 INFO - Cleaning up... 17:25:04 INFO - Return code: 0 17:25:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:25:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:25:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:25:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:04 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 0x10e8bb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e831570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb320591e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6cfd30>, '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 0x10e535db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e6c7b20>, '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.nCJmDy8FT2/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.wJxczN5Hwd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 17:25:04 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 17:25:04 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 17:25:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nCJmDy8FT2/Render', 17:25:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:04 INFO - 'HOME': '/Users/cltbld', 17:25:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:04 INFO - 'LOGNAME': 'cltbld', 17:25:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:04 INFO - 'MOZ_NO_REMOTE': '1', 17:25:04 INFO - 'NO_EM_RESTART': '1', 17:25:04 INFO - 'PAGER': '/bin/cat', 17:25:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:04 INFO - 'PWD': '/builds/slave/test', 17:25:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:04 INFO - 'SHELL': '/bin/bash', 17:25:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wJxczN5Hwd/Listeners', 17:25:04 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 17:25:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:04 INFO - 'USER': 'cltbld', 17:25:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:04 INFO - 'XPC_FLAGS': '0x0', 17:25:04 INFO - 'XPC_SERVICE_NAME': '0', 17:25:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:25:05 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 17:25:05 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 17:25:05 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 17:25:05 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 17:25:05 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 17:25:05 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 17:25:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:25:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:25:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:25:07 INFO - Installing collected packages: mozsystemmonitor 17:25:07 INFO - Running setup.py install for mozsystemmonitor 17:25:07 INFO - Successfully installed mozsystemmonitor 17:25:07 INFO - Cleaning up... 17:25:08 INFO - Return code: 0 17:25:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:25:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:25:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:25:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:08 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 0x10e8bb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e831570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb320591e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6cfd30>, '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 0x10e535db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e6c7b20>, '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.nCJmDy8FT2/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.wJxczN5Hwd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 17:25:08 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 17:25:08 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 17:25:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nCJmDy8FT2/Render', 17:25:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:08 INFO - 'HOME': '/Users/cltbld', 17:25:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:08 INFO - 'LOGNAME': 'cltbld', 17:25:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:08 INFO - 'MOZ_NO_REMOTE': '1', 17:25:08 INFO - 'NO_EM_RESTART': '1', 17:25:08 INFO - 'PAGER': '/bin/cat', 17:25:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:08 INFO - 'PWD': '/builds/slave/test', 17:25:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:08 INFO - 'SHELL': '/bin/bash', 17:25:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wJxczN5Hwd/Listeners', 17:25:08 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 17:25:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:08 INFO - 'USER': 'cltbld', 17:25:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:08 INFO - 'XPC_FLAGS': '0x0', 17:25:08 INFO - 'XPC_SERVICE_NAME': '0', 17:25:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:08 INFO - Downloading/unpacking jsonschema==2.5.1 17:25:08 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 17:25:08 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 17:25:08 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 17:25:08 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 17:25:08 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 17:25:08 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 17:25:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:25:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:25:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:25:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:25:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:11 INFO - Downloading functools32-3.2.3-2.tar.gz 17:25:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:25:11 INFO - warning: no files found matching '*.txt' 17:25:11 INFO - no previously-included directories found matching 'build' 17:25:11 INFO - no previously-included directories found matching 'dist' 17:25:11 INFO - no previously-included directories found matching '.git*' 17:25:11 INFO - Installing collected packages: jsonschema, functools32 17:25:11 INFO - Running setup.py install for functools32 17:25:11 INFO - warning: no files found matching '*.txt' 17:25:11 INFO - no previously-included directories found matching 'build' 17:25:11 INFO - no previously-included directories found matching 'dist' 17:25:11 INFO - no previously-included directories found matching '.git*' 17:25:11 INFO - Successfully installed jsonschema functools32 17:25:11 INFO - Cleaning up... 17:25:11 INFO - Return code: 0 17:25:11 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:25:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:25:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:25:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e8bb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e831570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb320591e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6cfd30>, '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 0x10e535db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e6c7b20>, '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.nCJmDy8FT2/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.wJxczN5Hwd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 17:25:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:25:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 17:25:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nCJmDy8FT2/Render', 17:25:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:11 INFO - 'HOME': '/Users/cltbld', 17:25:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:11 INFO - 'LOGNAME': 'cltbld', 17:25:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:11 INFO - 'MOZ_NO_REMOTE': '1', 17:25:11 INFO - 'NO_EM_RESTART': '1', 17:25:11 INFO - 'PAGER': '/bin/cat', 17:25:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:11 INFO - 'PWD': '/builds/slave/test', 17:25:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:11 INFO - 'SHELL': '/bin/bash', 17:25:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wJxczN5Hwd/Listeners', 17:25:11 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 17:25:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:11 INFO - 'USER': 'cltbld', 17:25:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:11 INFO - 'XPC_FLAGS': '0x0', 17:25:11 INFO - 'XPC_SERVICE_NAME': '0', 17:25:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:11 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:25:11 INFO - Cleaning up... 17:25:11 INFO - Return code: 0 17:25:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:25:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:25:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:25:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e8bb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e831570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb320591e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6cfd30>, '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 0x10e535db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e6c7b20>, '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.nCJmDy8FT2/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.wJxczN5Hwd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 17:25:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:25:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:25:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nCJmDy8FT2/Render', 17:25:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:11 INFO - 'HOME': '/Users/cltbld', 17:25:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:11 INFO - 'LOGNAME': 'cltbld', 17:25:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:11 INFO - 'MOZ_NO_REMOTE': '1', 17:25:11 INFO - 'NO_EM_RESTART': '1', 17:25:11 INFO - 'PAGER': '/bin/cat', 17:25:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:11 INFO - 'PWD': '/builds/slave/test', 17:25:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:11 INFO - 'SHELL': '/bin/bash', 17:25:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wJxczN5Hwd/Listeners', 17:25:11 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 17:25:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:11 INFO - 'USER': 'cltbld', 17:25:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:11 INFO - 'XPC_FLAGS': '0x0', 17:25:11 INFO - 'XPC_SERVICE_NAME': '0', 17:25:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:12 INFO - Downloading/unpacking blobuploader==1.2.4 17:25:12 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 17:25:12 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 17:25:12 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 17:25:12 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 17:25:12 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 17:25:12 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 17:25:14 INFO - Downloading blobuploader-1.2.4.tar.gz 17:25:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:25:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:25:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:25:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:25:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:25:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:25:15 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 17:25:15 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 17:25:15 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 17:25:15 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 17:25:15 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 17:25:15 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 17:25:15 INFO - Downloading docopt-0.6.1.tar.gz 17:25:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:25:16 INFO - Installing collected packages: blobuploader, requests, docopt 17:25:16 INFO - Running setup.py install for blobuploader 17:25:16 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:25:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:25:16 INFO - Running setup.py install for requests 17:25:16 INFO - Running setup.py install for docopt 17:25:16 INFO - Successfully installed blobuploader requests docopt 17:25:16 INFO - Cleaning up... 17:25:16 INFO - Return code: 0 17:25:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:25:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:25:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:25:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e8bb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e831570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb320591e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6cfd30>, '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 0x10e535db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e6c7b20>, '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.nCJmDy8FT2/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.wJxczN5Hwd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 17:25:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:25:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:25:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nCJmDy8FT2/Render', 17:25:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:16 INFO - 'HOME': '/Users/cltbld', 17:25:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:16 INFO - 'LOGNAME': 'cltbld', 17:25:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:16 INFO - 'MOZ_NO_REMOTE': '1', 17:25:16 INFO - 'NO_EM_RESTART': '1', 17:25:16 INFO - 'PAGER': '/bin/cat', 17:25:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:16 INFO - 'PWD': '/builds/slave/test', 17:25:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:16 INFO - 'SHELL': '/bin/bash', 17:25:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wJxczN5Hwd/Listeners', 17:25:16 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 17:25:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:16 INFO - 'USER': 'cltbld', 17:25:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:16 INFO - 'XPC_FLAGS': '0x0', 17:25:16 INFO - 'XPC_SERVICE_NAME': '0', 17:25:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:25:17 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-J4foEM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:25:17 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-XjzfPJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:25:17 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-Y4HZkC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:25:17 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-fCXV3u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:25:17 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-NYlnxs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:25:17 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-3GhiwC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:25:17 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-_XqdZv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:25:17 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-DQb97G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:25:17 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-pGDhWc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:25:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:25:18 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-dyGO4X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:25:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:25:18 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-8MeI5u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:25:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:25:18 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-PSEV7_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:25:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:25:18 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-rE2i2G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:25:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:25:18 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-O216SM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:25:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:25:18 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-R1ctoD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:25:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:25:18 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-b4vbdf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:25:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:25:18 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-yxzRf0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:25:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:25:18 INFO - Running setup.py install for manifestparser 17:25:19 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:25:19 INFO - Running setup.py install for mozcrash 17:25:19 INFO - Running setup.py install for mozdebug 17:25:19 INFO - Running setup.py install for mozdevice 17:25:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:25:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:25:19 INFO - Running setup.py install for mozfile 17:25:19 INFO - Running setup.py install for mozhttpd 17:25:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:25:19 INFO - Running setup.py install for mozinfo 17:25:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:25:19 INFO - Running setup.py install for mozInstall 17:25:20 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:25:20 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:25:20 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:25:20 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:25:20 INFO - Running setup.py install for mozleak 17:25:20 INFO - Running setup.py install for mozlog 17:25:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:25:20 INFO - Running setup.py install for moznetwork 17:25:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:25:20 INFO - Running setup.py install for mozprocess 17:25:20 INFO - Running setup.py install for mozprofile 17:25:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:25:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:25:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:25:20 INFO - Running setup.py install for mozrunner 17:25:21 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:25:21 INFO - Running setup.py install for mozscreenshot 17:25:21 INFO - Running setup.py install for moztest 17:25:21 INFO - Running setup.py install for mozversion 17:25:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:25:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:25:21 INFO - Cleaning up... 17:25:21 INFO - Return code: 0 17:25:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:25:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:25:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:25:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e8bb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e831570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb320591e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6cfd30>, '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 0x10e535db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e6c7b20>, '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.nCJmDy8FT2/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.wJxczN5Hwd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 17:25:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 17:25:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 17:25:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nCJmDy8FT2/Render', 17:25:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:21 INFO - 'HOME': '/Users/cltbld', 17:25:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:21 INFO - 'LOGNAME': 'cltbld', 17:25:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:21 INFO - 'MOZ_NO_REMOTE': '1', 17:25:21 INFO - 'NO_EM_RESTART': '1', 17:25:21 INFO - 'PAGER': '/bin/cat', 17:25:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:21 INFO - 'PWD': '/builds/slave/test', 17:25:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:21 INFO - 'SHELL': '/bin/bash', 17:25:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wJxczN5Hwd/Listeners', 17:25:21 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 17:25:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:21 INFO - 'USER': 'cltbld', 17:25:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:21 INFO - 'XPC_FLAGS': '0x0', 17:25:21 INFO - 'XPC_SERVICE_NAME': '0', 17:25:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:25:21 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-tykgzi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:25:21 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 17:25:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:25:21 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-usP4Ns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:25:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:25:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:25:21 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-G7hbch-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:25:22 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)) 17:25:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:25:22 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-24NEGo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:25:22 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)) 17:25:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:25:22 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-QCBuio-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:25:22 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)) 17:25:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:25:22 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-2PfCoP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:25:22 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)) 17:25:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:25:22 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-9pFzOs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:25:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 17:25:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:25:22 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-JENNBB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:25:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 17:25:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:25:22 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-vbYOvo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:25:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 17:25:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:25:22 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-vPha8z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:25:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:25:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:25:22 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-pm_6iR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:25:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 17:25:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:25:22 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-tvd85n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:25:23 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)) 17:25:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:25:23 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-MY6cQv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:25:23 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)) 17:25:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:25:23 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-obXQSw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:25:23 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)) 17:25:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:25:23 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-pcdIpN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:25:23 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)) 17:25:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:25:23 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-5G5VTZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:25:23 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)) 17:25:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:25:23 INFO - Running setup.py (path:/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/pip-wBQkId-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:25:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 17:25:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:25:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 17:25:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 17:25:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:25:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 17:25:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:25:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:25:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:25:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:25:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:25:27 INFO - Downloading blessings-1.6.tar.gz 17:25:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:25:27 INFO - Installing collected packages: blessings 17:25:27 INFO - Running setup.py install for blessings 17:25:27 INFO - Successfully installed blessings 17:25:27 INFO - Cleaning up... 17:25:27 INFO - Return code: 0 17:25:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:25:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:25:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:25:27 INFO - Reading from file tmpfile_stdout 17:25:27 INFO - Current package versions: 17:25:27 INFO - blessings == 1.6 17:25:27 INFO - blobuploader == 1.2.4 17:25:27 INFO - docopt == 0.6.1 17:25:27 INFO - functools32 == 3.2.3-2 17:25:27 INFO - jsonschema == 2.5.1 17:25:27 INFO - manifestparser == 1.1 17:25:27 INFO - mozInstall == 1.12 17:25:27 INFO - mozcrash == 1.0 17:25:27 INFO - mozdebug == 0.1 17:25:27 INFO - mozdevice == 0.48 17:25:27 INFO - mozfile == 1.2 17:25:27 INFO - mozhttpd == 0.7 17:25:27 INFO - mozinfo == 0.9 17:25:27 INFO - mozleak == 0.1 17:25:27 INFO - mozlog == 3.4 17:25:27 INFO - moznetwork == 0.27 17:25:27 INFO - mozprocess == 0.23 17:25:27 INFO - mozprofile == 0.28 17:25:27 INFO - mozrunner == 6.13 17:25:27 INFO - mozscreenshot == 0.1 17:25:27 INFO - mozsystemmonitor == 0.3 17:25:27 INFO - moztest == 0.7 17:25:27 INFO - mozversion == 1.4 17:25:27 INFO - psutil == 3.1.1 17:25:27 INFO - requests == 1.2.3 17:25:27 INFO - wsgiref == 0.1.2 17:25:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:25:27 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')]} 17:25:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:28 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')]} 17:25:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:28 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 0x10e8bb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e831570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb320591e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6cfd30>, '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 0x10e535db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e6c7b20>, '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.nCJmDy8FT2/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.wJxczN5Hwd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 17:25:28 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 17:25:28 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 17:25:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nCJmDy8FT2/Render', 17:25:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:28 INFO - 'HOME': '/Users/cltbld', 17:25:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:28 INFO - 'LOGNAME': 'cltbld', 17:25:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:28 INFO - 'MOZ_NO_REMOTE': '1', 17:25:28 INFO - 'NO_EM_RESTART': '1', 17:25:28 INFO - 'PAGER': '/bin/cat', 17:25:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:28 INFO - 'PWD': '/builds/slave/test', 17:25:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:28 INFO - 'SHELL': '/bin/bash', 17:25:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wJxczN5Hwd/Listeners', 17:25:28 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 17:25:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:28 INFO - 'USER': 'cltbld', 17:25:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:28 INFO - 'XPC_FLAGS': '0x0', 17:25:28 INFO - 'XPC_SERVICE_NAME': '0', 17:25:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:29 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)) 17:25:29 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)) 17:25:29 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)) 17:25:29 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)) 17:25:29 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)) 17:25:29 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)) 17:25:29 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)) 17:25:29 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)) 17:25:29 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)) 17:25:29 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)) 17:25:29 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)) 17:25:29 INFO - Cleaning up... 17:25:29 INFO - Return code: 0 17:25:29 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:25:29 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')]} 17:25:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:25:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:29 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')]} 17:25:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:25:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:25:29 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 0x10e8bb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e831570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb320591e40>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e6cfd30>, '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 0x10e535db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e6c7b20>, '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.nCJmDy8FT2/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.wJxczN5Hwd/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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 17:25:29 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 17:25:29 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 17:25:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nCJmDy8FT2/Render', 17:25:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:25:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:25:29 INFO - 'HOME': '/Users/cltbld', 17:25:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:25:29 INFO - 'LOGNAME': 'cltbld', 17:25:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:25:29 INFO - 'MOZ_NO_REMOTE': '1', 17:25:29 INFO - 'NO_EM_RESTART': '1', 17:25:29 INFO - 'PAGER': '/bin/cat', 17:25:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:25:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:25:29 INFO - 'PWD': '/builds/slave/test', 17:25:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:25:29 INFO - 'SHELL': '/bin/bash', 17:25:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wJxczN5Hwd/Listeners', 17:25:29 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 17:25:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:25:29 INFO - 'USER': 'cltbld', 17:25:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:25:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:25:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:25:29 INFO - 'XPC_FLAGS': '0x0', 17:25:29 INFO - 'XPC_SERVICE_NAME': '0', 17:25:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:25:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:25:29 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:25:29 INFO - Cleaning up... 17:25:29 INFO - Return code: 0 17:25:29 INFO - Running post-action listener: _resource_record_post_action 17:25:29 INFO - Running post-action listener: _start_resource_monitoring 17:25:29 INFO - Starting resource monitoring. 17:25:29 INFO - [mozharness: 2016-12-01 01:25:29.698972Z] Finished create-virtualenv step (success) 17:25:29 INFO - [mozharness: 2016-12-01 01:25:29.699850Z] Running install step. 17:25:29 INFO - Running pre-action listener: _resource_record_pre_action 17:25:29 INFO - Running main action method: install 17:25:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:25:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:25:29 INFO - Reading from file tmpfile_stdout 17:25:29 INFO - Detecting whether we're running mozinstall >=1.0... 17:25:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:25:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:25:30 INFO - Reading from file tmpfile_stdout 17:25:30 INFO - Output received: 17:25:30 INFO - Usage: mozinstall [options] installer 17:25:30 INFO - Options: 17:25:30 INFO - -h, --help show this help message and exit 17:25:30 INFO - -d DEST, --destination=DEST 17:25:30 INFO - Directory to install application into. [default: 17:25:30 INFO - "/builds/slave/test"] 17:25:30 INFO - --app=APP Application being installed. [default: firefox] 17:25:30 INFO - mkdir: /builds/slave/test/build/application 17:25:30 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'] 17:25:30 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 17:26:01 INFO - Reading from file tmpfile_stdout 17:26:01 INFO - Output received: 17:26:01 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 17:26:01 INFO - Running post-action listener: _resource_record_post_action 17:26:01 INFO - [mozharness: 2016-12-01 01:26:01.134567Z] Finished install step (success) 17:26:01 INFO - [mozharness: 2016-12-01 01:26:01.134706Z] Running run-tests step. 17:26:01 INFO - Running pre-action listener: _resource_record_pre_action 17:26:01 INFO - Running main action method: run_tests 17:26:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:26:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:26:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:26:01 INFO - 2016-11-30 17:26:01.149 screenresolution[1619:7255] starting screenresolution argv=screenresolution get 17:26:01 INFO - 2016-11-30 17:26:01.151 screenresolution[1619:7255] Display 0: 1600x1200x32@60 17:26:01 INFO - 2016-11-30 17:26:01.157 screenresolution[1620:7257] starting screenresolution argv=screenresolution list 17:26:01 INFO - Available Modes on Display 0 17:26:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:26:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:26:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:26:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:26:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:26:01 INFO - Intel Iris: 17:26:01 INFO - Chipset Model: Intel Iris 17:26:01 INFO - Type: GPU 17:26:01 INFO - Bus: Built-In 17:26:01 INFO - VRAM (Dynamic, Max): 1536 MB 17:26:01 INFO - Vendor: Intel (0x8086) 17:26:01 INFO - Device ID: 0x0a2e 17:26:01 INFO - Revision ID: 0x0009 17:26:01 INFO - Displays: 17:26:01 INFO - Display: 17:26:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:26:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:26:01 INFO - Display Serial Number: 1600x1200 60 17:26:01 INFO - Main Display: Yes 17:26:01 INFO - Mirror: Off 17:26:01 INFO - Online: Yes 17:26:01 INFO - Rotation: Supported 17:26:01 INFO - Return code: 0 17:26:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:26:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:26:01 INFO - Python 2.7.3 17:26:01 INFO - Return code: 0 17:26:01 INFO - grabbing minidump binary from tooltool 17:26:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:26:01 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 0x10e6cfd30>, '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 0x10e535db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e6c7b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:26:01 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 17:26:01 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 17:26:01 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 17:26:01 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:26:01 INFO - Return code: 0 17:26:01 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:26:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:26:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:26:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:26:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:26:01 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/Xb7NEB8-SR6ucPQnPkRDSA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0328', '--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 17:26:01 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/Xb7NEB8-SR6ucPQnPkRDSA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0328 --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 17:26:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.nCJmDy8FT2/Render', 17:26:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:26:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:26:01 INFO - 'HOME': '/Users/cltbld', 17:26:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:26:01 INFO - 'LOGNAME': 'cltbld', 17:26:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:26:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:26:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:26:01 INFO - 'MOZ_NO_REMOTE': '1', 17:26:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:26:01 INFO - 'NO_EM_RESTART': '1', 17:26:01 INFO - 'PAGER': '/bin/cat', 17:26:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:26:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:26:01 INFO - 'PWD': '/builds/slave/test', 17:26:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:26:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:26:01 INFO - 'SHELL': '/bin/bash', 17:26:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.wJxczN5Hwd/Listeners', 17:26:01 INFO - 'TMPDIR': '/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/', 17:26:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:26:01 INFO - 'USER': 'cltbld', 17:26:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:26:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:26:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:26:01 INFO - 'XPC_FLAGS': '0x0', 17:26:01 INFO - 'XPC_SERVICE_NAME': '0', 17:26:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:26:01 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/Xb7NEB8-SR6ucPQnPkRDSA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0328', '--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 17:26:01 INFO - mozversion application_buildid: 20161130141744 17:26:01 INFO - mozversion application_changeset: 3fa3896980f6726c3897ce1645a839996d5d5e8b 17:26:01 INFO - mozversion application_display_name: FirefoxDeveloperEdition 17:26:01 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:26:01 INFO - mozversion application_name: Firefox 17:26:01 INFO - mozversion application_remotingname: firefox-dev 17:26:01 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:26:01 INFO - mozversion application_vendor: Mozilla 17:26:01 INFO - mozversion application_version: 52.0a2 17:26:01 INFO - mozversion platform_buildid: 20161130141744 17:26:01 INFO - mozversion platform_changeset: 3fa3896980f6726c3897ce1645a839996d5d5e8b 17:26:01 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 17:26:01 INFO - mozversion platform_version: 52.0a2 17:26:01 INFO - using testdate: 1480555561 17:26:01 INFO - actual date: 1480555561 17:26:01 INFO - starting webserver on 'localhost:49235' 17:26:01 INFO - SUITE-START | Running 2 tests 17:26:01 INFO - TEST-START | dromaeo_css 17:26:01 INFO - operating with platform_type : mac_ 17:26:01 INFO - Initialising browser for dromaeo_css test... 17:26:01 INFO - TEST-INFO | started process 1630 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/tmpVbz7v1/profile http://localhost:49235/getInfo.html) 17:26:04 INFO - PROCESS | 1630 | 2016-11-30 17:26:04.756 plugin-container[1632:7436] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3b, name = 'com.apple.tsm.portname' 17:26:04 INFO - PROCESS | 1630 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:26:04 INFO - PROCESS | 1630 | 2016-11-30 17:26:04.758 plugin-container[1632:7436] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9413, name = 'com.apple.CFPasteboardClient' 17:26:04 INFO - PROCESS | 1630 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:26:04 INFO - PROCESS | 1630 | 2016-11-30 17:26:04.758 plugin-container[1632:7436] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:26:04 INFO - PROCESS | 1630 | __metrics Screen width/height:1600/1200 17:26:04 INFO - PROCESS | 1630 | colorDepth:24 17:26:04 INFO - PROCESS | 1630 | Browser inner width/height: 1024/689 17:26:04 INFO - PROCESS | 1630 | __metrics 17:26:04 ERROR - PROCESS | 1630 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 17:26:05 INFO - TEST-INFO | 1630: exit 0 17:26:05 INFO - Browser initialized. 17:26:05 INFO - Running cycle 1/1 for dromaeo_css test... 17:26:05 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/tmpVbz7v1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:26:08 INFO - PROCESS | 1633 | 2016-11-30 17:26:08.313 plugin-container[1634:7568] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 17:26:08 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:26:08 INFO - PROCESS | 1633 | 2016-11-30 17:26:08.313 plugin-container[1634:7568] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 17:26:08 INFO - PROCESS | 1633 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:26:08 INFO - PROCESS | 1633 | 2016-11-30 17:26:08.313 plugin-container[1634:7568] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:26:17 INFO - PROCESS | 1633 | RSS: Main: 229867520 17:26:17 INFO - PROCESS | 1633 | 17:27:07 INFO - PROCESS | 1633 | 1480555627637 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] 17:29:22 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49235/tests/dromaeo/cssquery-ext.html) 17:29:24 INFO - PROCESS | 1633 | RSS: Main: 233967616 17:29:24 INFO - PROCESS | 1633 | 17:32:26 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-ext.html (next: http://localhost:49235/tests/dromaeo/cssquery-jquery.html) 17:32:26 INFO - PROCESS | 1633 | RSS: Main: 244424704 17:32:26 INFO - PROCESS | 1633 | 17:35:28 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49235/tests/dromaeo/cssquery-mootools.html) 17:35:29 INFO - PROCESS | 1633 | RSS: Main: 247259136 17:35:29 INFO - PROCESS | 1633 | 17:38:31 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49235/tests/dromaeo/cssquery-prototype.html) 17:38:31 INFO - PROCESS | 1633 | RSS: Main: 246472704 17:38:31 INFO - PROCESS | 1633 | 17:41:34 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49235/tests/dromaeo/cssquery-yui.html) 17:41:35 INFO - PROCESS | 1633 | RSS: Main: 249958400 17:41:35 INFO - PROCESS | 1633 | 17:44:38 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-yui.html (next: http://localhost:49235/tests/dromaeo/cssquery-dojo.html) 17:44:38 INFO - PROCESS | 1633 | RSS: Main: 251326464 17:44:38 INFO - PROCESS | 1633 | 17:44:38 INFO - PROCESS | 1633 | __start_tp_report 17:44:38 INFO - PROCESS | 1633 | _x_x_mozilla_page_load 17:44:38 INFO - PROCESS | 1633 | _x_x_mozilla_page_load_details 17:44:38 INFO - PROCESS | 1633 | |i|pagename|runs| 17:44:38 INFO - PROCESS | 1633 | |0;dojo.html;3341.498775183722;3385.187554986804;3408.64209258028;3420.5382273393116;3424.452087665974;5057.848264552064;5279.973600132003;5324.281222035024;5615.971920140399;5774.07614781635;5151.407588127367;5386.434424385432;5428.185772134182;5529.783447641513;5955.106733989904;4855.951440485603;5305.0450918834595;5307.867303317409;5349.117395629716;5398.2982212312345;5088;5316.308879845615;5355.7857685692525;5359.919601205966;5371.194320851864;5228.137357336054;5233.031889100528;5376.489233522829;5383.219433182187;5406.351237851443;4445.266531022377;5267.3679158500845;5292.126799078128;5337.226102215176;5339.9733001335135;5189.7405129743365;5321.813736519203;5329.040772660919;5374.4894235048;5381.139017757179;5880.088586269135;5956.463918247378;5992.730327135289;5995.820125396245;6011.789587364421;6111.54163437744;6132.263729246501;6175.845603859894;6187.3503282155625;6229.09678096678;5252.527272545451;6146.7541298348015;6178.876422471525;6194.039923811816;6211.782587609412;5972.432618901197;5975.984082705951;5993.400659933972;6008.038713805858;6025.939740602582;6297.2128483939505;6308.495320374359;6360.84097897547;6367.331430199855;6415.518836087312;6113.020255720364;6126.23422072241;6143.880361275353;6145.385461453909;6147.231596050494;6215.192025036807;6225.62646241227;6236.126942228092;6249.343818899041;6260.937390626126;6112.174856394418;6144.924638188389;6167.259928808572;6170.413810687977;6181.412765787243;6121.326654068049;6146.692665366803;6162.691865406712;6166.290876549254;6178.104174894615;4937.753112344369;4989.55094041538;5035.974820125876;5060.51925067118;5061.5950723942;4450.799714012877;4496.010877606921;4498.752568608693;4517.457905051415;4520.2315606347;5775.6823374714795;5820.505257053113;5912.438318359749;6024.277086749618;6079.574429789961;6116.418940200674;6129.601575897553;6141.170941922873;6143.911070236141;6149.2313460817395;5166.380034395821;5868;6050.878982420415;6088.939110608926;6111.572189946661;5405.13517837152;5416.997855396686;5430;5485.122380419193;5495.0383682855345;4948.62885283598;6376.139221205078;6390.265119511316;6402.519811014193;6421.646809425526;6128.105930682507;6132.661461321697;6142.253225532332;6184.938150618436;6198.690065496617;6383.297837237993;6396.392342727433;6413.16628838248;6413.230412350361;6416.101745755691;5188.896222075613;5324.307839980777;6042.728077236623;6177;6220.129181914445;4816.927746083882;5612.765191657829;6085.178500902499;6149.323574406902;6192.59748116371;5319.627626066138;6117.265928088658;6189.164462797465;6192.225971753531;6200.7519699211525;5438.45615438453;6007.098935159761;6197.659128747962;6216.751329946752;6248.281447633399;5074.82238121664;5904.52763778888;5928.970355148197;6017.638941663514;6034.487068599043;6125.296497837281;6135.049067394561;6144.354842741448;6178.795134948642;6209.627422354673;6034.487068599043;6082.9695851520455;6087.995480745627;6106.771609723077;6124.816255512342 17:44:38 INFO - PROCESS | 1633 | |1;ext.html;441.67316186022344;495.5762822786516;507.132802907029;522.0576858769919;549.8377978496349;32272.193195170134;37430.81284593577;39622.00944976377;39645.801770991144;40125;40858.38712419309;41335.79332103335;41356;41441.37837932426;41498.96252593687;7630.236976302381;9110.772230694236;9441.29190310726;9481.857772133424;9491.762705932339;9367.484788336638;9430.575624096899;9465.337426380156;9481.47851868147;9513.571889265;9475.620975160986;9505.857412138823;9527.952360238189;9528.475933823636;9548.570314335837;9437.339386242931;9460.148586627167;9470.763230919281;9486.241100711926;9496.667616633455;9383.577739001761;9400.858987115198;9473.28950328728;9496;9503.667371642028;39460.210795783925;39729.40405893914;39914.40128398076;39945.60054399477;39999.600003999876;3165.6042994625673;3398.3543126805826;3492.6158122606494;3699.5560532736245;3728;28764.856175719196;30024.8498757507;30608.387832243232;31151.84424077888;31371.215719606964;2879.928001799951;3216.4853623420377;3236.5630639863552;3281.4585593377064;3297.884574039897;503354;504487;505120;507423;507873.46063269814;61480.38519614836;61624;61875;61963.38036619666;62037.069443958375;64180.35819641744;64390;64918.02622960659;64979.02531462032;64981.025284620766;16254.674906501803;16305.429309974093;16479.423220187477;16479.588010299838;16525.008499490068;12602.117851750472;12618.305993170463;12652.936735316265;12691.428885700165;12702.87297127017;16940.576485587713;17765.111744412985;17984;18019.18913648889;18034.188461519265;6447.6131432114225;6460.289368169498;6490.545661803629;6522.119513865568;6573.290944354407;797.5972134072256;836.2306677856386;902.9864552031725;917.6696389299847;923.463834313296;28426.715732842407;30307.393852123274;30522.38955220883;31013.68986310108;31107.53338699876;959.7168835193495;974.0518962075848;1031.8658574385431;1034.7361422837128;1153.2734377342222;13726.86273137256;15261.6184595386;15564.832637552003;15912.88609797303;16073.115978621054;693334;696975;698714;699564;701700;51749.37754357159;57987.71006144942;58720.11919821206;59209;59359.70320148458;1336.0580790542645;1351.959441216765;1370.766969615168;1371.9519816806364;1375.5460697969645;29479;30062.24844378908;30238.09285721432;30324.5451318226;30371.088867334016;42008;52916;52961.73519132534;53065.469345307596;53089.46910530845;625599.7440025724;630024;630276;630399;631321;26748.59877101884;27292;27349.45301094007;27590.448191036467;27625.72374276312;46583.76708116438;46987.29519057149;47189.764051179525;47635.761821190674;47965.04069918652;39373.80313098416;39475;39511.2097758049;39673;40395.79802100971;38938.80530597329;38970.4154437678;39425.60574394334;40150;40292.3956140664;37715.43426848545;38261.808690956364;38414.84755457341;38564.035899102746;38766.61233387743;33107.50338744969;34583.27083645858;34895.82552087223;34899.302013960085;34909;25728.228153155436;25904.22287331383;25922;25983.350416239744;26355.868220658773 17:44:38 INFO - PROCESS | 1633 | |2;jquery.html;376.2343630711501;376.555664316107;381.47356647825995;392.40942381715513;404.02428136051435;14781.113133212015;15022.399104035852;17691.29234830607;20047.799522004774;20296.883671398067;15788.368465261376;19082.809171908277;19090.140943657534;19706.507337316576;19825.702614460795;5700.429957004298;5726.3700992890745;5930.288365396159;5987.22166118404;6005.12925625784;5756.222909907172;5806.680632565207;5920.467157955804;5954.196183515213;5961.642301461926;4436.97781511092;5990.520758339344;5992.8501787455225;6045.516358691294;6058.394160583928;5774.16274640179;5939.4357536034;5994.310654274749;6008.699565021753;6029.608075475102;5708.942910570924;5792;5803.622764520335;5810.738516766755;5821.214136091616;12764.978801695843;12788.040896932767;12822.80765788514;13150.408231629599;13260.408750949851;2129.4782778219283;2194.308792730285;2206.7119991212976;2278.1798552521077;2488.8880000399845;9803.068708472754;10139;10369.533370998248;12803.359832008362;14213.289335533182;1450.840407555168;1833.175071217944;1948.6492431362349;1956.7260583518319;1989.801019898013;127133;129535.05697414547;131999.68000320066;132600.67399326077;134798;8043.477173983672;9166.129217724305;9878.407295562145;9881.752956176153;9982.750431239132;10623.787524249627;12287.815682764765;12355.38223088842;12416.56542021043;12421.254724716546;7818.8827167592535;7936.16670249627;7980.002499687539;7987.920120798718;8048.678052877819;7831.373490120891;8261.752147435587;8282.50304981703;8317.84525170196;8422.368322375847;3598.3163198992106;3924.4309575111447;3928.8035598219976;3951.664108550748;3985.8405663773706;1698.787651543557;1811.791643960935;1855.554666879939;1915.6647586672277;1975.0815870620231;831.7546323834483;863.9352048596255;864.3862857371337;952.5475399185332;985.4186030242191;19247.51881202981;20670.276540321018;20727.27454539114;20812.959352032336;20874.165033398494;885.6811547842772;920.3925409229876;921.5115988526092;937.4000639590668;994.105305225303;9432.386894851812;10789.946050269698;10862.72843178911;10867.45662716861;10901.727456813644;217728;218820;287229;579581;583301;124655.75344246441;126704;127406;127783;129144;874.208840998897;983.6753871222622;992.3797626483448;1033.405791669793;1034.3690348887174;18094.81905180984;18223.544411389823;18232.635347292715;18256;18260.452186434428;35907.820460898576;36913.63086369102;52974;53381.665458362295;55511.44488555063;58708.706456467444;73244.26755732375;73781;74343;75976.62011689907;7012.1935977363255;7936.682532698627;7993.800154995939;8064.758057258292;8229.300509456763;10703.929607039234;12321.691957701129;12964.870351296744;13002.024848136238;13775.931120344334;8509.234168924826;9761.609535618496;10835.808061113114;10955.34267943926;10984.395858227874;10968;11025.228234023542;11128.833067503832;11155.776884462428;11223.66329010131;11218.270812397168;11259.831102533632;11317.547298108313;11614.593489227836;11636.243644163102;6529.314421985813;7266.600336981517;7325.340719335285;7493.737719179802;7597.772066838003;9290.72127836166;10793.40636265013;11157.44212789341;11183.049440797624;11253.88746112561 17:44:38 INFO - PROCESS | 1633 | |3;mootools.html;1372.2932689664822;1455.5560554031017;1623.5616383576435;1743.2242652019847;1768.5755418699516;7397.667104980275;7688.769336919888;7893.250141236581;8343.791405214874;8349.874751878719;7878.290953814156;8250.58747062646;8252.133525979762;8254.628541715621;8262.256396924276;2288.39357570244;2305.827062970284;2308.3190458814606;2317.177402022283;2319.130326127702;2240.350298413458;2266.1048885690143;2282.9200977965775;2288.2677543186187;2289.954200915981;2291.9312420627407;2301.8158547316257;2311.075569772088;2328.4528135888054;2328.8951997160084;2300.217925905192;2310.7573704761094;2326.8138548916045;2332.38191879153;2333.6266197408418;2342.1802369170823;2358.2099996501192;2359.4927090675415;2362;2363.586372384843;8158.592070396457;8316.37627177964;8331.458455200393;8340.37447191463;8370.497770133812;4985.5513003829465;5147.665401748912;5154.123798954187;5155.71643559604;5158.587313014949;3265.6081270247487;3425.657434256579;3516.1561225306;3600.945985810215;3622.275544891006;1012.190247801763;1035.7415739876012;1080.2762149359949;1097.4841824342545;1099.4063205868822;10512.21158413122;10610.893891061069;10612;10655.84016239757;10671.733206669818;4487.88780280489;4585.28928016158;4668.3697700810635;4676.579107880307;4746.976265118721;9288.953555232181;9318.229536388064;9318.534073296309;9341.532923353805;9398.389104708172;5190.039842629126;5202.635815492879;5207.427183009866;5211.088059589557;5215.374155101412;4161.944682261995;4621.930671039937;4750.097481478508;4802.399700037496;4807.807687692523;3832.3485007548784;3844.231153769257;3919.980400097981;3924.47019652349;3948.9605103949166;1263.620913725897;1271.9491220351083;1277.2719549856492;1285.3483273992874;1286.5175559165314;772.5248971882364;873.7073080517971;898.5462341517492;906.3746015249457;915.1534830281962;5516.062269414209;5545.889082218333;5557.610967232255;5784.305883293948;5809.8547536311935;959.9376040557341;973.2116985241977;979.9314048016713;1006.7986402719486;1032.9845052324222;3674.6876515495946;3730.309892669865;3755.3615885299564;3761.7931013794505;3781.489498917666;9559.235261179088;9848.261380396356;9871.753206169904;9887.703368898945;9955.701328960144;9883;9977.60089596408;10009.499525023719;10009.849852252222;10053.994600540034;7635.541867487968;7671.53970761756;7768.41736869737;7800.141984381714;7805.102413222438;5271.472852714698;5355.651882627617;5467.289252397322;5495.752691128829;5526.91709624364;7461.440391970733;9191.632334706537;9243.907560924305;9320.114589114024;9343.906560934303;9176.908230917605;9350.95324523373;9499.240060795259;9512.476813775309;9520.619175232912;6187.412195841388;6245.313015568375;6450.129232553545;6641.468682505291;6711.161104861892;7732.497387669783;7828.138904720362;7874.448788584973;7883.921160788319;7901.486403383762;7014.193367762773;7092.255313192247;7096.2548932363425;7131.0729605151;7182.533135346186;5571.944280557304;6641.634710090992;6773.762918297836;6961.095057642474;6998;5614.747336369955;6518.087467754613;6576.145101136822;6616.933830661825;6622.602643841385;4697.0840686065885;4763.857084287477;4786.521347865186;4800.807967681254;4864.805407783649;4320.654347652243;4686.8125274988615;4729.148753224315;4745.2882067690125;4925.9014819704125 17:44:38 INFO - PROCESS | 1633 | |4;prototype.html;303.4265238698859;310.29253302470374;310.61328645835937;312.4812810734182;316.57894999650483;5490.725463726812;5509.5041446269925;5520.861978450531;5557.972210138949;5565.749541270642;4826.517348265181;5143.382794064708;5163.347728726809;5382.300300960869;5391.032273220276;3633.6911362534215;3685.539307586552;3817.675497582709;4030.9596904031023;4156.480439945007;3394.4738565522384;3628.328759179547;4139.668826493888;4156.485326377543;4215.178040282146;3665.9083522911874;4146.523149837777;4152.418661387408;4154.397612346208;4155.898686847988;3796.9430458543143;3812.6759225465867;4054.8580799671868;4184.4351012613215;4198.916021679549;3179.5071763876635;3525.841337139809;3552.946705799415;3558.3417067842274;3563.826066099677;4248.723832950848;4689.343491911135;5341.973290133564;5348.51863332298;5385.138377859563;4380.233459144637;4436.844710435119;4598.724076555417;4679.274712419547;4732.242841145435;5012.27322038306;5733.340665823442;5740.82777516671;5753.597248192628;5799.9130013049835;3553.928921421583;3802.239552089593;3881.4954055972544;3887.708421868343;3923.6468717815246;5398.433164517748;5805.970970145122;5873.530117590582;5970.164177015221;6058.697065146725;3447.3967055765143;3859.459675645412;3905.2970465316516;3976.960230397659;3995.6015394611654;3580.5703315602295;3850.6726928211415;3988.481497405319;3999.7200195986584;4027.4160246764054;3923.7841918694176;4713.835015774432;4719.625467790477;4738.881527961828;4743.786529606176;5331.333583302087;5425.83722488326;5815.505682017076;5873.71219010125;5876.676782776903;1175.5767923547517;1241.4661695470854;1245.9875401245872;1318.3803612302202;1323.9007074469453;822.4119754375531;846.551327796269;876.2332958661171;891.8795962545103;895.3329769321897;4730.645201609893;4777.140114779373;5290.270359396616;5319.468053194727;5603.767171222325;5271;5974.581779275496;6042;6047.78832740852;6054.697265136813;4190.51809041964;4882.633802464829;5454.5181942216195;5473.863153421196;5644.943550564524;2726.5364887969095;2911.8835246590324;2984.4627966966154;3002.609660744089;3029.9395211675737;5494.862628434321;5887.674368624369;5911.793087241926;5912.556558258148;5939.613925094942;5226.170840205898;5375.75809088592;5643.5203007743985;5788.986927287708;5862.912056319158;5644.8306550803545;5884.558658100659;5904.261967254093;5904.852378690395;5905.438983296666;5358.784729162471;5693.28833895763;5760.769569217352;5782.653040817564;5929.318128415288;4527.977360113178;5496.862578435571;5529.894021195857;5658.886822263614;5679.602427830012;5282.524572788468;5339.225812257278;5357.678539287655;5499.312585926759;5957.553183511341;3445.08705193119;3556.2531868307947;3753.498600559798;3754.6245375463327;3786.280606684721;4903.847595815052;5250.081235783799;5673.992783824579;5722.570807189561;5725.398833122629;4935.654504184673;5413.756380962787;5496.422875598165;5526.861828454321;5559.718267645672;4543;4930;4945;5396.379416367168;5417.322834645669;4579.748113853637;4663.720176789404;5366.812161574326;5370.248165256789;5382.623216374816;4730.124926888399;4985.825496107619;5611.466910643482;5619.555493360402;5779.942200577941;4754.646450007269;4828.155072862165;5488.286522752017;5613.915791263216;5643.645525074035 17:44:38 INFO - PROCESS | 1633 | |5;yui.html;685.1127789512581;714.2357677285305;736.4955005821013;739.652363389207;744.2260049548463;14079.788803167949;14814.333354999024;14827.851721482783;14859.405623775036;14883.255837208137;13416.932915335434;13434.865651343483;13475;13487.865121348808;13571.389287482063;640.5100098424709;661.8312330355776;795.5505139596139;797.86436305828;824.0893812337371;808.6280311056921;816.073756286613;816.5304949653943;824.418784756745;828.2876726015621;867.4795122926225;869.4870026684255;872.1845074854999;872.9694460693877;873.1792115411524;856.7815207122206;859.6045818923303;863.1713554987189;867.3451544084176;867.6182479708908;641.4515589171285;646.5474168082314;646.7186773753432;647.0294558162756;652.0935899100255;13822.308884555732;13920.930395347958;14083.507077252247;14178.503752368617;14443.350049247703;13732.65668358289;13788.172709637454;13972.301384930814;14000.089994150349;14133.78799318011;11260.21178517504;11296.943515282372;11507.597234096851;11579.826302605468;11590.594329198437;3662.853485860562;3687.8340474678703;3694.5751238607636;3694.9261014779822;3759.605241449663;81190;81990.9999999994;84738.57630711807;84826.57586712026;85930.28139437239;8876.334274929406;9517.90482095184;10528.842067368996;10717;10789.892101079045;17464.38874639407;21009.264675736275;21036;21064.15743370248;21382.037808298664;7326.816829579303;7693.192214817475;7910.7231246906085;7936.206379362133;7998.48009879356;4192.91614167721;4319.956800431955;4328.523862375137;4331.956680433218;4403.559644035635;3313.784604000732;3439.8280085995602;3447.7241820654285;3450.7929524228125;3548.6451354864826;673.6766352150995;676.2426082787309;687.1719577908608;691.9238883722786;692.8302565871394;580.9448102430263;584.514852672281;645.1516256123183;650.9283978762332;685.9965700171467;8093.231143041402;8584.8712269316;9133.086691330815;9326.160645541933;9342.813143737087;525.6504424557703;570.6433479075578;598.2432223237608;654.1430725749284;668.7358493395081;4339.717918335298;4381.233284175257;4561.292999585004;4564.246899261651;4757.238841785298;75620;106485;112001.87998120078;116804.41597791956;122933.15600266003;99728.00271997332;104504.47747761212;104811;106156.93843061625;107176;75285.3650122254;89229.66155507673;89827.55086224657;91409.54295228481;92519.61220581697;8170.754877353689;8227.71203007892;8723.694670686495;8738.781530461789;8861.33539984491;58933.705331473066;59498.405015951015;60244.69877650759;60525;60611.39388606058;80939;82400;82767.17232827835;85129.14870851212;87048;10853.78292434131;11231.21381503287;11420.972112509913;11427.942860285646;11908.940455297668;12243.081768867192;12451.249458327055;12477.75044499123;12508.437120329425;13025.413856376295;11465.541378345106;11504.94247528757;11610.477528511068;11879.703007424538;11954.402279885797;11422.885771142182;12111.515539378326;12183.390830458618;12410.255384676948;12436.00511959023;10183.287169898036;11851.05191584654;11894.286342819458;12286.262824230218;12339.876601233873;9628.934471406686;9753.268504862022;10234.692959211236;10340.53467593945;10374.325668831501;9580.568874400808;10191.592336306465;10235.437050962268;10248.538815753458;10278.126359259546 17:44:38 INFO - PROCESS | 1633 | __end_tp_report 17:44:38 INFO - PROCESS | 1633 | __start_cc_report 17:44:38 INFO - PROCESS | 1633 | _x_x_mozilla_cycle_collect,378 17:44:38 INFO - PROCESS | 1633 | __end_cc_report 17:44:38 INFO - PROCESS | 1633 | __startTimestamp1480556678446__endTimestamp 17:44:38 INFO - PROCESS | 1633 | 17:44:38 INFO - PROCESS | 1633 | ------- Summary: start ------- 17:44:38 INFO - PROCESS | 1633 | Number of tests: 6 17:44:38 INFO - PROCESS | 1633 | 17:44:38 INFO - PROCESS | 1633 | [#0] dojo.html Cycles:165 Average:5725.75 Median:6024.28 stddev:644.23 (10.7%) stddev-sans-first:618.46 17:44:38 INFO - PROCESS | 1633 | Values: 3341.5 3385.2 3408.6 3420.5 3424.5 5057.8 5280.0 5324.3 5616.0 5774.1 5151.4 5386.4 5428.2 5529.8 5955.1 4856.0 5305.0 5307.9 5349.1 5398.3 5088.0 5316.3 5355.8 5359.9 5371.2 5228.1 5233.0 5376.5 5383.2 5406.4 4445.3 5267.4 5292.1 5337.2 5340.0 5189.7 5321.8 5329.0 5374.5 5381.1 5880.1 5956.5 5992.7 5995.8 6011.8 6111.5 6132.3 6175.8 6187.4 6229.1 5252.5 6146.8 6178.9 6194.0 6211.8 5972.4 5976.0 5993.4 6008.0 6025.9 6297.2 6308.5 6360.8 6367.3 6415.5 6113.0 6126.2 6143.9 6145.4 6147.2 6215.2 6225.6 6236.1 6249.3 6260.9 6112.2 6144.9 6167.3 6170.4 6181.4 6121.3 6146.7 6162.7 6166.3 6178.1 4937.8 4989.6 5036.0 5060.5 5061.6 4450.8 4496.0 4498.8 4517.5 4520.2 5775.7 5820.5 5912.4 6024.3 6079.6 6116.4 6129.6 6141.2 6143.9 6149.2 5166.4 5868.0 6050.9 6088.9 6111.6 5405.1 5417.0 5430.0 5485.1 5495.0 4948.6 6376.1 6390.3 6402.5 6421.6 6128.1 6132.7 6142.3 6184.9 6198.7 6383.3 6396.4 6413.2 6413.2 6416.1 5188.9 5324.3 6042.7 6177.0 6220.1 4816.9 5612.8 6085.2 6149.3 6192.6 5319.6 6117.3 6189.2 6192.2 6200.8 5438.5 6007.1 6197.7 6216.8 6248.3 5074.8 5904.5 5929.0 6017.6 6034.5 6125.3 6135.0 6144.4 6178.8 6209.6 6034.5 6083.0 6088.0 6106.8 6124.8 17:44:38 INFO - PROCESS | 1633 | 17:44:38 INFO - PROCESS | 1633 | [#1] ext.html Cycles:180 Average:73784.45 Median:40221.20 stddev:165081.99 (410.4%) stddev-sans-first:165453.24 17:44:38 INFO - PROCESS | 1633 | Values: 441.7 495.6 507.1 522.1 549.8 32272.2 37430.8 39622.0 39645.8 40125.0 40858.4 41335.8 41356.0 41441.4 41499.0 7630.2 9110.8 9441.3 9481.9 9491.8 9367.5 9430.6 9465.3 9481.5 9513.6 9475.6 9505.9 9528.0 9528.5 9548.6 9437.3 9460.1 9470.8 9486.2 9496.7 9383.6 9400.9 9473.3 9496.0 9503.7 39460.2 39729.4 39914.4 39945.6 39999.6 3165.6 3398.4 3492.6 3699.6 3728.0 28764.9 30024.8 30608.4 31151.8 31371.2 2879.9 3216.5 3236.6 3281.5 3297.9 503354.0 504487.0 505120.0 507423.0 507873.5 61480.4 61624.0 61875.0 61963.4 62037.1 64180.4 64390.0 64918.0 64979.0 64981.0 16254.7 16305.4 16479.4 16479.6 16525.0 12602.1 12618.3 12652.9 12691.4 12702.9 16940.6 17765.1 17984.0 18019.2 18034.2 6447.6 6460.3 6490.5 6522.1 6573.3 797.6 836.2 903.0 917.7 923.5 28426.7 30307.4 30522.4 31013.7 31107.5 959.7 974.1 1031.9 1034.7 1153.3 13726.9 15261.6 15564.8 15912.9 16073.1 693334.0 696975.0 698714.0 699564.0 701700.0 51749.4 57987.7 58720.1 59209.0 59359.7 1336.1 1352.0 1370.8 1372.0 1375.5 29479.0 30062.2 30238.1 30324.5 30371.1 42008.0 52916.0 52961.7 53065.5 53089.5 625599.7 630024.0 630276.0 630399.0 631321.0 26748.6 27292.0 27349.5 27590.4 27625.7 46583.8 46987.3 47189.8 47635.8 47965.0 39373.8 39475.0 39511.2 39673.0 40395.8 38938.8 38970.4 39425.6 40150.0 40292.4 37715.4 38261.8 38414.8 38564.0 38766.6 33107.5 34583.3 34895.8 34899.3 34909.0 25728.2 25904.2 25922.0 25983.4 26355.9 17:44:38 INFO - PROCESS | 1633 | 17:44:38 INFO - PROCESS | 1633 | [#2] jquery.html Cycles:180 Average:28185.46 Median:19753.07 stddev:72101.86 (365.0%) stddev-sans-first:72273.89 17:44:38 INFO - PROCESS | 1633 | Values: 376.2 376.6 381.5 392.4 404.0 14781.1 15022.4 17691.3 20047.8 20296.9 15788.4 19082.8 19090.1 19706.5 19825.7 5700.4 5726.4 5930.3 5987.2 6005.1 5756.2 5806.7 5920.5 5954.2 5961.6 4437.0 5990.5 5992.9 6045.5 6058.4 5774.2 5939.4 5994.3 6008.7 6029.6 5708.9 5792.0 5803.6 5810.7 5821.2 12765.0 12788.0 12822.8 13150.4 13260.4 2129.5 2194.3 2206.7 2278.2 2488.9 9803.1 10139.0 10369.5 12803.4 14213.3 1450.8 1833.2 1948.6 1956.7 1989.8 127133.0 129535.1 131999.7 132600.7 134798.0 8043.5 9166.1 9878.4 9881.8 9982.8 10623.8 12287.8 12355.4 12416.6 12421.3 7818.9 7936.2 7980.0 7987.9 8048.7 7831.4 8261.8 8282.5 8317.8 8422.4 3598.3 3924.4 3928.8 3951.7 3985.8 1698.8 1811.8 1855.6 1915.7 1975.1 831.8 863.9 864.4 952.5 985.4 19247.5 20670.3 20727.3 20813.0 20874.2 885.7 920.4 921.5 937.4 994.1 9432.4 10789.9 10862.7 10867.5 10901.7 217728.0 218820.0 287229.0 579581.0 583301.0 124655.8 126704.0 127406.0 127783.0 129144.0 874.2 983.7 992.4 1033.4 1034.4 18094.8 18223.5 18232.6 18256.0 18260.5 35907.8 36913.6 52974.0 53381.7 55511.4 58708.7 73244.3 73781.0 74343.0 75976.6 7012.2 7936.7 7993.8 8064.8 8229.3 10703.9 12321.7 12964.9 13002.0 13775.9 8509.2 9761.6 10835.8 10955.3 10984.4 10968.0 11025.2 11128.8 11155.8 11223.7 11218.3 11259.8 11317.5 11614.6 11636.2 6529.3 7266.6 7325.3 7493.7 7597.8 9290.7 10793.4 11157.4 11183.0 11253.9 17:44:38 INFO - PROCESS | 1633 | 17:44:38 INFO - PROCESS | 1633 | [#3] mootools.html Cycles:180 Average:5367.15 Median:5196.34 stddev:2905.57 (55.9%) stddev-sans-first:2898.21 17:44:38 INFO - PROCESS | 1633 | Values: 1372.3 1455.6 1623.6 1743.2 1768.6 7397.7 7688.8 7893.3 8343.8 8349.9 7878.3 8250.6 8252.1 8254.6 8262.3 2288.4 2305.8 2308.3 2317.2 2319.1 2240.4 2266.1 2282.9 2288.3 2290.0 2291.9 2301.8 2311.1 2328.5 2328.9 2300.2 2310.8 2326.8 2332.4 2333.6 2342.2 2358.2 2359.5 2362.0 2363.6 8158.6 8316.4 8331.5 8340.4 8370.5 4985.6 5147.7 5154.1 5155.7 5158.6 3265.6 3425.7 3516.2 3600.9 3622.3 1012.2 1035.7 1080.3 1097.5 1099.4 10512.2 10610.9 10612.0 10655.8 10671.7 4487.9 4585.3 4668.4 4676.6 4747.0 9289.0 9318.2 9318.5 9341.5 9398.4 5190.0 5202.6 5207.4 5211.1 5215.4 4161.9 4621.9 4750.1 4802.4 4807.8 3832.3 3844.2 3920.0 3924.5 3949.0 1263.6 1271.9 1277.3 1285.3 1286.5 772.5 873.7 898.5 906.4 915.2 5516.1 5545.9 5557.6 5784.3 5809.9 959.9 973.2 979.9 1006.8 1033.0 3674.7 3730.3 3755.4 3761.8 3781.5 9559.2 9848.3 9871.8 9887.7 9955.7 9883.0 9977.6 10009.5 10009.8 10054.0 7635.5 7671.5 7768.4 7800.1 7805.1 5271.5 5355.7 5467.3 5495.8 5526.9 7461.4 9191.6 9243.9 9320.1 9343.9 9176.9 9351.0 9499.2 9512.5 9520.6 6187.4 6245.3 6450.1 6641.5 6711.2 7732.5 7828.1 7874.4 7883.9 7901.5 7014.2 7092.3 7096.3 7131.1 7182.5 5571.9 6641.6 6773.8 6961.1 6998.0 5614.7 6518.1 6576.1 6616.9 6622.6 4697.1 4763.9 4786.5 4800.8 4864.8 4320.7 4686.8 4729.1 4745.3 4925.9 17:44:38 INFO - PROCESS | 1633 | 17:44:38 INFO - PROCESS | 1633 | [#4] prototype.html Cycles:180 Average:4538.03 Median:5153.37 stddev:1406.53 (27.3%) stddev-sans-first:1374.09 17:44:38 INFO - PROCESS | 1633 | Values: 303.4 310.3 310.6 312.5 316.6 5490.7 5509.5 5520.9 5558.0 5565.7 4826.5 5143.4 5163.3 5382.3 5391.0 3633.7 3685.5 3817.7 4031.0 4156.5 3394.5 3628.3 4139.7 4156.5 4215.2 3665.9 4146.5 4152.4 4154.4 4155.9 3796.9 3812.7 4054.9 4184.4 4198.9 3179.5 3525.8 3552.9 3558.3 3563.8 4248.7 4689.3 5342.0 5348.5 5385.1 4380.2 4436.8 4598.7 4679.3 4732.2 5012.3 5733.3 5740.8 5753.6 5799.9 3553.9 3802.2 3881.5 3887.7 3923.6 5398.4 5806.0 5873.5 5970.2 6058.7 3447.4 3859.5 3905.3 3977.0 3995.6 3580.6 3850.7 3988.5 3999.7 4027.4 3923.8 4713.8 4719.6 4738.9 4743.8 5331.3 5425.8 5815.5 5873.7 5876.7 1175.6 1241.5 1246.0 1318.4 1323.9 822.4 846.6 876.2 891.9 895.3 4730.6 4777.1 5290.3 5319.5 5603.8 5271.0 5974.6 6042.0 6047.8 6054.7 4190.5 4882.6 5454.5 5473.9 5644.9 2726.5 2911.9 2984.5 3002.6 3029.9 5494.9 5887.7 5911.8 5912.6 5939.6 5226.2 5375.8 5643.5 5789.0 5862.9 5644.8 5884.6 5904.3 5904.9 5905.4 5358.8 5693.3 5760.8 5782.7 5929.3 4528.0 5496.9 5529.9 5658.9 5679.6 5282.5 5339.2 5357.7 5499.3 5957.6 3445.1 3556.3 3753.5 3754.6 3786.3 4903.8 5250.1 5674.0 5722.6 5725.4 4935.7 5413.8 5496.4 5526.9 5559.7 4543.0 4930.0 4945.0 5396.4 5417.3 4579.7 4663.7 5366.8 5370.2 5382.6 4730.1 4985.8 5611.5 5619.6 5779.9 4754.6 4828.2 5488.3 5613.9 5643.6 17:44:38 INFO - PROCESS | 1633 | 17:44:38 INFO - PROCESS | 1633 | [#5] yui.html Cycles:180 Average:20881.30 Median:4562.77 stddev:31208.62 (684.0%) stddev-sans-first:31259.32 17:44:38 INFO - PROCESS | 1633 | Values: 685.1 714.2 736.5 739.7 744.2 14079.8 14814.3 14827.9 14859.4 14883.3 13416.9 13434.9 13475.0 13487.9 13571.4 640.5 661.8 795.6 797.9 824.1 808.6 816.1 816.5 824.4 828.3 867.5 869.5 872.2 873.0 873.2 856.8 859.6 863.2 867.3 867.6 641.5 646.5 646.7 647.0 652.1 13822.3 13920.9 14083.5 14178.5 14443.4 13732.7 13788.2 13972.3 14000.1 14133.8 11260.2 11296.9 11507.6 11579.8 11590.6 3662.9 3687.8 3694.6 3694.9 3759.6 81190.0 81991.0 84738.6 84826.6 85930.3 8876.3 9517.9 10528.8 10717.0 10789.9 17464.4 21009.3 21036.0 21064.2 21382.0 7326.8 7693.2 7910.7 7936.2 7998.5 4192.9 4320.0 4328.5 4332.0 4403.6 3313.8 3439.8 3447.7 3450.8 3548.6 673.7 676.2 687.2 691.9 692.8 580.9 584.5 645.2 650.9 686.0 8093.2 8584.9 9133.1 9326.2 9342.8 525.7 570.6 598.2 654.1 668.7 4339.7 4381.2 4561.3 4564.2 4757.2 75620.0 106485.0 112001.9 116804.4 122933.2 99728.0 104504.5 104811.0 106156.9 107176.0 75285.4 89229.7 89827.6 91409.5 92519.6 8170.8 8227.7 8723.7 8738.8 8861.3 58933.7 59498.4 60244.7 60525.0 60611.4 80939.0 82400.0 82767.2 85129.1 87048.0 10853.8 11231.2 11421.0 11427.9 11908.9 12243.1 12451.2 12477.8 12508.4 13025.4 11465.5 11504.9 11610.5 11879.7 11954.4 11422.9 12111.5 12183.4 12410.3 12436.0 10183.3 11851.1 11894.3 12286.3 12339.9 9628.9 9753.3 10234.7 10340.5 10374.3 9580.6 10191.6 10235.4 10248.5 10278.1 17:44:38 INFO - PROCESS | 1633 | -------- Summary: end -------- 17:44:38 INFO - PROCESS | 1633 | 17:44:39 INFO - TEST-INFO | 1633: exit 0 17:44:39 INFO - TEST-OK | dromaeo_css | took 1117510ms 17:44:39 INFO - TEST-START | kraken 17:44:39 INFO - operating with platform_type : mac_ 17:44:39 INFO - Initialising browser for kraken test... 17:44:39 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/tmp_Ihhjb/profile http://localhost:49235/getInfo.html) 17:44:42 INFO - PROCESS | 1645 | 2016-11-30 17:44:42.121 plugin-container[1646:9935] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 17:44:42 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:44:42 INFO - PROCESS | 1645 | 2016-11-30 17:44:42.124 plugin-container[1646:9935] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x460b, name = 'com.apple.CFPasteboardClient' 17:44:42 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:44:42 INFO - PROCESS | 1645 | 2016-11-30 17:44:42.124 plugin-container[1646:9935] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:44:42 INFO - PROCESS | 1645 | __metrics Screen width/height:1600/1200 17:44:42 INFO - PROCESS | 1645 | colorDepth:24 17:44:42 INFO - PROCESS | 1645 | Browser inner width/height: 1024/689 17:44:42 INFO - PROCESS | 1645 | __metrics 17:44:42 ERROR - PROCESS | 1645 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 17:44:43 INFO - TEST-INFO | 1645: exit 0 17:44:43 INFO - Browser initialized. 17:44:43 INFO - Running cycle 1/1 for kraken test... 17:44:43 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/T/tmp_Ihhjb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:44:45 INFO - PROCESS | 1647 | 2016-11-30 17:44:45.813 plugin-container[1648:10057] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444f, name = 'com.apple.tsm.portname' 17:44:45 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:44:45 INFO - PROCESS | 1647 | 2016-11-30 17:44:45.813 plugin-container[1648:10057] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 17:44:45 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 17:44:45 INFO - PROCESS | 1647 | 2016-11-30 17:44:45.813 plugin-container[1648:10057] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 17:44:55 INFO - PROCESS | 1647 | RSS: Main: 229269504 17:44:55 INFO - PROCESS | 1647 | 17:44:58 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection) 17:44:59 INFO - PROCESS | 1647 | RSS: Main: 241344512 17:44:59 INFO - PROCESS | 1647 | 17:45:01 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-dft) 17:45:02 INFO - PROCESS | 1647 | RSS: Main: 248881152 17:45:02 INFO - PROCESS | 1647 | 17:45:05 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-fft) 17:45:06 INFO - PROCESS | 1647 | RSS: Main: 252649472 17:45:06 INFO - PROCESS | 1647 | 17:45:08 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator) 17:45:08 INFO - PROCESS | 1647 | RSS: Main: 254091264 17:45:08 INFO - PROCESS | 1647 | 17:45:10 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:45:11 INFO - PROCESS | 1647 | RSS: Main: 254275584 17:45:11 INFO - PROCESS | 1647 | 17:45:14 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom) 17:45:14 INFO - PROCESS | 1647 | RSS: Main: 258453504 17:45:14 INFO - PROCESS | 1647 | 17:45:17 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate) 17:45:18 INFO - PROCESS | 1647 | RSS: Main: 258781184 17:45:18 INFO - PROCESS | 1647 | 17:45:21 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial) 17:45:21 INFO - PROCESS | 1647 | RSS: Main: 258265088 17:45:21 INFO - PROCESS | 1647 | 17:45:23 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:45:24 INFO - PROCESS | 1647 | RSS: Main: 257077248 17:45:24 INFO - PROCESS | 1647 | 17:45:26 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:45:26 INFO - PROCESS | 1647 | RSS: Main: 257130496 17:45:26 INFO - PROCESS | 1647 | 17:45:28 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:45:29 INFO - PROCESS | 1647 | RSS: Main: 257114112 17:45:29 INFO - PROCESS | 1647 | 17:45:32 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:45:33 INFO - PROCESS | 1647 | RSS: Main: 257179648 17:45:33 INFO - PROCESS | 1647 | 17:45:36 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:45:36 INFO - PROCESS | 1647 | RSS: Main: 257523712 17:45:36 INFO - PROCESS | 1647 | 17:45:38 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49235/tests/kraken/driver.html?testName=ai-astar) 17:45:38 INFO - PROCESS | 1647 | RSS: Main: 259018752 17:45:38 INFO - PROCESS | 1647 | 17:45:38 INFO - PROCESS | 1647 | __start_tp_report 17:45:38 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 17:45:38 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 17:45:38 INFO - PROCESS | 1647 | |i|pagename|runs| 17:45:38 INFO - PROCESS | 1647 | |0;ai-astar;116;120;118;117;118;118;117;117;119;117 17:45:38 INFO - PROCESS | 1647 | |1;audio-beat-detection;148;118;114;163;146;123;117;120;149;120 17:45:38 INFO - PROCESS | 1647 | |2;audio-dft;196;196;210;196;200;199;196;194;199;199 17:45:38 INFO - PROCESS | 1647 | |3;audio-fft;84;60;60;88;62;62;52;71;109;81 17:45:38 INFO - PROCESS | 1647 | |4;audio-oscillator;85;72;87;70;58;77;120;56;98;119 17:45:38 INFO - PROCESS | 1647 | |5;imaging-gaussian-blur;89;87;89;86;91;86;90;87;88;85 17:45:38 INFO - PROCESS | 1647 | |6;imaging-darkroom;143;97;117;121;97;98;95;95;96;150 17:45:38 INFO - PROCESS | 1647 | |7;imaging-desaturate;89;104;133;110;93;88;90;93;87;105 17:45:38 INFO - PROCESS | 1647 | |8;json-parse-financial;58;56;56;61;60;60;59;58;59;59 17:45:38 INFO - PROCESS | 1647 | |9;json-stringify-tinderbox;70;73;77;86;81;76;69;69;70;94 17:45:38 INFO - PROCESS | 1647 | |10;stanford-crypto-aes;88;100;87;84;88;91;86;88;85;87 17:45:38 INFO - PROCESS | 1647 | |11;stanford-crypto-ccm;150;157;160;161;155;154;155;163;200;164 17:45:38 INFO - PROCESS | 1647 | |12;stanford-crypto-pbkdf2;173;173;171;176;170;189;224;172;174;174 17:45:38 INFO - PROCESS | 1647 | |13;stanford-crypto-sha256-iterative;71;68;70;72;72;67;69;71;67;70 17:45:38 INFO - PROCESS | 1647 | __end_tp_report 17:45:38 INFO - PROCESS | 1647 | __start_cc_report 17:45:38 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,974 17:45:38 INFO - PROCESS | 1647 | __end_cc_report 17:45:38 INFO - PROCESS | 1647 | __startTimestamp1480556738946__endTimestamp 17:45:38 INFO - PROCESS | 1647 | 17:45:38 INFO - PROCESS | 1647 | ------- Summary: start ------- 17:45:38 INFO - PROCESS | 1647 | Number of tests: 14 17:45:38 INFO - PROCESS | 1647 | 17:45:38 INFO - PROCESS | 1647 | [#0] ai-astar Cycles:10 Average:117.70 Median:118.00 stddev:1.16 (1.0%) stddev-sans-first:1.05 17:45:38 INFO - PROCESS | 1647 | Values: 116.0 120.0 118.0 117.0 118.0 118.0 117.0 117.0 119.0 117.0 17:45:38 INFO - PROCESS | 1647 | 17:45:38 INFO - PROCESS | 1647 | [#1] audio-beat-detection Cycles:10 Average:131.80 Median:134.50 stddev:17.69 (13.2%) stddev-sans-first:17.76 17:45:38 INFO - PROCESS | 1647 | Values: 148.0 118.0 114.0 163.0 146.0 123.0 117.0 120.0 149.0 120.0 17:45:38 INFO - PROCESS | 1647 | 17:45:38 INFO - PROCESS | 1647 | [#2] audio-dft Cycles:10 Average:198.50 Median:199.00 stddev:4.48 (2.3%) stddev-sans-first:4.66 17:45:38 INFO - PROCESS | 1647 | Values: 196.0 196.0 210.0 196.0 200.0 199.0 196.0 194.0 199.0 199.0 17:45:38 INFO - PROCESS | 1647 | 17:45:38 INFO - PROCESS | 1647 | [#3] audio-fft Cycles:10 Average:72.90 Median:66.50 stddev:17.42 (26.2%) stddev-sans-first:18.01 17:45:38 INFO - PROCESS | 1647 | Values: 84.0 60.0 60.0 88.0 62.0 62.0 52.0 71.0 109.0 81.0 17:45:38 INFO - PROCESS | 1647 | 17:45:38 INFO - PROCESS | 1647 | [#4] audio-oscillator Cycles:10 Average:84.20 Median:74.50 stddev:22.55 (30.3%) stddev-sans-first:23.91 17:45:38 INFO - PROCESS | 1647 | Values: 85.0 72.0 87.0 70.0 58.0 77.0 120.0 56.0 98.0 119.0 17:45:38 INFO - PROCESS | 1647 | 17:45:38 INFO - PROCESS | 1647 | [#5] imaging-gaussian-blur Cycles:10 Average:87.80 Median:88.50 stddev:1.93 (2.2%) stddev-sans-first:2.00 17:45:38 INFO - PROCESS | 1647 | Values: 89.0 87.0 89.0 86.0 91.0 86.0 90.0 87.0 88.0 85.0 17:45:38 INFO - PROCESS | 1647 | 17:45:38 INFO - PROCESS | 1647 | [#6] imaging-darkroom Cycles:10 Average:110.90 Median:95.50 stddev:21.03 (22.0%) stddev-sans-first:18.82 17:45:38 INFO - PROCESS | 1647 | Values: 143.0 97.0 117.0 121.0 97.0 98.0 95.0 95.0 96.0 150.0 17:45:38 INFO - PROCESS | 1647 | 17:45:38 INFO - PROCESS | 1647 | [#7] imaging-desaturate Cycles:10 Average:99.20 Median:88.50 stddev:14.36 (16.2%) stddev-sans-first:14.75 17:45:38 INFO - PROCESS | 1647 | Values: 89.0 104.0 133.0 110.0 93.0 88.0 90.0 93.0 87.0 105.0 17:45:38 INFO - PROCESS | 1647 | 17:45:38 INFO - PROCESS | 1647 | [#8] json-parse-financial Cycles:10 Average:58.60 Median:59.00 stddev:1.65 (2.8%) stddev-sans-first:1.73 17:45:38 INFO - PROCESS | 1647 | Values: 58.0 56.0 56.0 61.0 60.0 60.0 59.0 58.0 59.0 59.0 17:45:38 INFO - PROCESS | 1647 | 17:45:38 INFO - PROCESS | 1647 | [#9] json-stringify-tinderbox Cycles:10 Average:76.50 Median:76.50 stddev:8.34 (10.9%) stddev-sans-first:8.51 17:45:38 INFO - PROCESS | 1647 | Values: 70.0 73.0 77.0 86.0 81.0 76.0 69.0 69.0 70.0 94.0 17:45:38 INFO - PROCESS | 1647 | 17:45:38 INFO - PROCESS | 1647 | [#10] stanford-crypto-aes Cycles:10 Average:88.40 Median:87.50 stddev:4.50 (5.1%) stddev-sans-first:4.77 17:45:38 INFO - PROCESS | 1647 | Values: 88.0 100.0 87.0 84.0 88.0 91.0 86.0 88.0 85.0 87.0 17:45:38 INFO - PROCESS | 1647 | 17:45:38 INFO - PROCESS | 1647 | [#11] stanford-crypto-ccm Cycles:10 Average:161.90 Median:160.50 stddev:14.08 (8.8%) stddev-sans-first:14.26 17:45:38 INFO - PROCESS | 1647 | Values: 150.0 157.0 160.0 161.0 155.0 154.0 155.0 163.0 200.0 164.0 17:45:38 INFO - PROCESS | 1647 | 17:45:38 INFO - PROCESS | 1647 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.60 Median:174.00 stddev:16.49 (9.5%) stddev-sans-first:17.31 17:45:38 INFO - PROCESS | 1647 | Values: 173.0 173.0 171.0 176.0 170.0 189.0 224.0 172.0 174.0 174.0 17:45:38 INFO - PROCESS | 1647 | 17:45:38 INFO - PROCESS | 1647 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.70 Median:70.50 stddev:1.89 (2.7%) stddev-sans-first:1.94 17:45:38 INFO - PROCESS | 1647 | Values: 71.0 68.0 70.0 72.0 72.0 67.0 69.0 71.0 67.0 70.0 17:45:38 INFO - PROCESS | 1647 | -------- Summary: end -------- 17:45:38 INFO - PROCESS | 1647 | 17:45:39 INFO - TEST-INFO | 1647: exit 0 17:45:39 INFO - TEST-OK | kraken | took 60752ms 17:45:39 INFO - SUITE-END | took 1178s 17:45:40 INFO - Completed test suite (00:19:38) 17:45:40 INFO - javascript benchmark 17:45:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7125.762893853365, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3341.498775183722, 3385.187554986804, 3408.64209258028, 3420.5382273393116, 3424.452087665974, 5057.848264552064, 5279.973600132003, 5324.281222035024, 5615.971920140399, 5774.07614781635, 5151.407588127367, 5386.434424385432, 5428.185772134182, 5529.783447641513, 5955.106733989904, 4855.951440485603, 5305.0450918834595, 5307.867303317409, 5349.117395629716, 5398.2982212312345, 5088.0, 5316.308879845615, 5355.7857685692525, 5359.919601205966, 5371.194320851864, 5228.137357336054, 5233.031889100528, 5376.489233522829, 5383.219433182187, 5406.351237851443, 4445.266531022377, 5267.3679158500845, 5292.126799078128, 5337.226102215176, 5339.9733001335135, 5189.7405129743365, 5321.813736519203, 5329.040772660919, 5374.4894235048, 5381.139017757179, 5880.088586269135, 5956.463918247378, 5992.730327135289, 5995.820125396245, 6011.789587364421, 6111.54163437744, 6132.263729246501, 6175.845603859894, 6187.3503282155625, 6229.09678096678, 5252.527272545451, 6146.7541298348015, 6178.876422471525, 6194.039923811816, 6211.782587609412, 5972.432618901197, 5975.984082705951, 5993.400659933972, 6008.038713805858, 6025.939740602582, 6297.2128483939505, 6308.495320374359, 6360.84097897547, 6367.331430199855, 6415.518836087312, 6113.020255720364, 6126.23422072241, 6143.880361275353, 6145.385461453909, 6147.231596050494, 6215.192025036807, 6225.62646241227, 6236.126942228092, 6249.343818899041, 6260.937390626126, 6112.174856394418, 6144.924638188389, 6167.259928808572, 6170.413810687977, 6181.412765787243, 6121.326654068049, 6146.692665366803, 6162.691865406712, 6166.290876549254, 6178.104174894615, 4937.753112344369, 4989.55094041538, 5035.974820125876, 5060.51925067118, 5061.5950723942, 4450.799714012877, 4496.010877606921, 4498.752568608693, 4517.457905051415, 4520.2315606347, 5775.6823374714795, 5820.505257053113, 5912.438318359749, 6024.277086749618, 6079.574429789961, 6116.418940200674, 6129.601575897553, 6141.170941922873, 6143.911070236141, 6149.2313460817395, 5166.380034395821, 5868.0, 6050.878982420415, 6088.939110608926, 6111.572189946661, 5405.13517837152, 5416.997855396686, 5430.0, 5485.122380419193, 5495.0383682855345, 4948.62885283598, 6376.139221205078, 6390.265119511316, 6402.519811014193, 6421.646809425526, 6128.105930682507, 6132.661461321697, 6142.253225532332, 6184.938150618436, 6198.690065496617, 6383.297837237993, 6396.392342727433, 6413.16628838248, 6413.230412350361, 6416.101745755691, 5188.896222075613, 5324.307839980777, 6042.728077236623, 6177.0, 6220.129181914445, 4816.927746083882, 5612.765191657829, 6085.178500902499, 6149.323574406902, 6192.59748116371, 5319.627626066138, 6117.265928088658, 6189.164462797465, 6192.225971753531, 6200.7519699211525, 5438.45615438453, 6007.098935159761, 6197.659128747962, 6216.751329946752, 6248.281447633399, 5074.82238121664, 5904.52763778888, 5928.970355148197, 6017.638941663514, 6034.487068599043, 6125.296497837281, 6135.049067394561, 6144.354842741448, 6178.795134948642, 6209.627422354673, 6034.487068599043, 6082.9695851520455, 6087.995480745627, 6106.771609723077, 6124.816255512342], "value": 5688.2393933912745, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [441.67316186022344, 495.5762822786516, 507.132802907029, 522.0576858769919, 549.8377978496349, 32272.193195170134, 37430.81284593577, 39622.00944976377, 39645.801770991144, 40125.0, 40858.38712419309, 41335.79332103335, 41356.0, 41441.37837932426, 41498.96252593687, 7630.236976302381, 9110.772230694236, 9441.29190310726, 9481.857772133424, 9491.762705932339, 9367.484788336638, 9430.575624096899, 9465.337426380156, 9481.47851868147, 9513.571889265, 9475.620975160986, 9505.857412138823, 9527.952360238189, 9528.475933823636, 9548.570314335837, 9437.339386242931, 9460.148586627167, 9470.763230919281, 9486.241100711926, 9496.667616633455, 9383.577739001761, 9400.858987115198, 9473.28950328728, 9496.0, 9503.667371642028, 39460.210795783925, 39729.40405893914, 39914.40128398076, 39945.60054399477, 39999.600003999876, 3165.6042994625673, 3398.3543126805826, 3492.6158122606494, 3699.5560532736245, 3728.0, 28764.856175719196, 30024.8498757507, 30608.387832243232, 31151.84424077888, 31371.215719606964, 2879.928001799951, 3216.4853623420377, 3236.5630639863552, 3281.4585593377064, 3297.884574039897, 503354.0, 504487.0, 505120.0, 507423.0, 507873.46063269814, 61480.38519614836, 61624.0, 61875.0, 61963.38036619666, 62037.069443958375, 64180.35819641744, 64390.0, 64918.02622960659, 64979.02531462032, 64981.025284620766, 16254.674906501803, 16305.429309974093, 16479.423220187477, 16479.588010299838, 16525.008499490068, 12602.117851750472, 12618.305993170463, 12652.936735316265, 12691.428885700165, 12702.87297127017, 16940.576485587713, 17765.111744412985, 17984.0, 18019.18913648889, 18034.188461519265, 6447.6131432114225, 6460.289368169498, 6490.545661803629, 6522.119513865568, 6573.290944354407, 797.5972134072256, 836.2306677856386, 902.9864552031725, 917.6696389299847, 923.463834313296, 28426.715732842407, 30307.393852123274, 30522.38955220883, 31013.68986310108, 31107.53338699876, 959.7168835193495, 974.0518962075848, 1031.8658574385431, 1034.7361422837128, 1153.2734377342222, 13726.86273137256, 15261.6184595386, 15564.832637552003, 15912.88609797303, 16073.115978621054, 693334.0, 696975.0, 698714.0, 699564.0, 701700.0, 51749.37754357159, 57987.71006144942, 58720.11919821206, 59209.0, 59359.70320148458, 1336.0580790542645, 1351.959441216765, 1370.766969615168, 1371.9519816806364, 1375.5460697969645, 29479.0, 30062.24844378908, 30238.09285721432, 30324.5451318226, 30371.088867334016, 42008.0, 52916.0, 52961.73519132534, 53065.469345307596, 53089.46910530845, 625599.7440025724, 630024.0, 630276.0, 630399.0, 631321.0, 26748.59877101884, 27292.0, 27349.45301094007, 27590.448191036467, 27625.72374276312, 46583.76708116438, 46987.29519057149, 47189.764051179525, 47635.761821190674, 47965.04069918652, 39373.80313098416, 39475.0, 39511.2097758049, 39673.0, 40395.79802100971, 38938.80530597329, 38970.4154437678, 39425.60574394334, 40150.0, 40292.3956140664, 37715.43426848545, 38261.808690956364, 38414.84755457341, 38564.035899102746, 38766.61233387743, 33107.50338744969, 34583.27083645858, 34895.82552087223, 34899.302013960085, 34909.0, 25728.228153155436, 25904.22287331383, 25922.0, 25983.350416239744, 26355.868220658773], "value": 19898.77049602055, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [376.2343630711501, 376.555664316107, 381.47356647825995, 392.40942381715513, 404.02428136051435, 14781.113133212015, 15022.399104035852, 17691.29234830607, 20047.799522004774, 20296.883671398067, 15788.368465261376, 19082.809171908277, 19090.140943657534, 19706.507337316576, 19825.702614460795, 5700.429957004298, 5726.3700992890745, 5930.288365396159, 5987.22166118404, 6005.12925625784, 5756.222909907172, 5806.680632565207, 5920.467157955804, 5954.196183515213, 5961.642301461926, 4436.97781511092, 5990.520758339344, 5992.8501787455225, 6045.516358691294, 6058.394160583928, 5774.16274640179, 5939.4357536034, 5994.310654274749, 6008.699565021753, 6029.608075475102, 5708.942910570924, 5792.0, 5803.622764520335, 5810.738516766755, 5821.214136091616, 12764.978801695843, 12788.040896932767, 12822.80765788514, 13150.408231629599, 13260.408750949851, 2129.4782778219283, 2194.308792730285, 2206.7119991212976, 2278.1798552521077, 2488.8880000399845, 9803.068708472754, 10139.0, 10369.533370998248, 12803.359832008362, 14213.289335533182, 1450.840407555168, 1833.175071217944, 1948.6492431362349, 1956.7260583518319, 1989.801019898013, 127133.0, 129535.05697414547, 131999.68000320066, 132600.67399326077, 134798.0, 8043.477173983672, 9166.129217724305, 9878.407295562145, 9881.752956176153, 9982.750431239132, 10623.787524249627, 12287.815682764765, 12355.38223088842, 12416.56542021043, 12421.254724716546, 7818.8827167592535, 7936.16670249627, 7980.002499687539, 7987.920120798718, 8048.678052877819, 7831.373490120891, 8261.752147435587, 8282.50304981703, 8317.84525170196, 8422.368322375847, 3598.3163198992106, 3924.4309575111447, 3928.8035598219976, 3951.664108550748, 3985.8405663773706, 1698.787651543557, 1811.791643960935, 1855.554666879939, 1915.6647586672277, 1975.0815870620231, 831.7546323834483, 863.9352048596255, 864.3862857371337, 952.5475399185332, 985.4186030242191, 19247.51881202981, 20670.276540321018, 20727.27454539114, 20812.959352032336, 20874.165033398494, 885.6811547842772, 920.3925409229876, 921.5115988526092, 937.4000639590668, 994.105305225303, 9432.386894851812, 10789.946050269698, 10862.72843178911, 10867.45662716861, 10901.727456813644, 217728.0, 218820.0, 287229.0, 579581.0, 583301.0, 124655.75344246441, 126704.0, 127406.0, 127783.0, 129144.0, 874.208840998897, 983.6753871222622, 992.3797626483448, 1033.405791669793, 1034.3690348887174, 18094.81905180984, 18223.544411389823, 18232.635347292715, 18256.0, 18260.452186434428, 35907.820460898576, 36913.63086369102, 52974.0, 53381.665458362295, 55511.44488555063, 58708.706456467444, 73244.26755732375, 73781.0, 74343.0, 75976.62011689907, 7012.1935977363255, 7936.682532698627, 7993.800154995939, 8064.758057258292, 8229.300509456763, 10703.929607039234, 12321.691957701129, 12964.870351296744, 13002.024848136238, 13775.931120344334, 8509.234168924826, 9761.609535618496, 10835.808061113114, 10955.34267943926, 10984.395858227874, 10968.0, 11025.228234023542, 11128.833067503832, 11155.776884462428, 11223.66329010131, 11218.270812397168, 11259.831102533632, 11317.547298108313, 11614.593489227836, 11636.243644163102, 6529.314421985813, 7266.600336981517, 7325.340719335285, 7493.737719179802, 7597.772066838003, 9290.72127836166, 10793.40636265013, 11157.44212789341, 11183.049440797624, 11253.88746112561], "value": 8959.270491350202, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1372.2932689664822, 1455.5560554031017, 1623.5616383576435, 1743.2242652019847, 1768.5755418699516, 7397.667104980275, 7688.769336919888, 7893.250141236581, 8343.791405214874, 8349.874751878719, 7878.290953814156, 8250.58747062646, 8252.133525979762, 8254.628541715621, 8262.256396924276, 2288.39357570244, 2305.827062970284, 2308.3190458814606, 2317.177402022283, 2319.130326127702, 2240.350298413458, 2266.1048885690143, 2282.9200977965775, 2288.2677543186187, 2289.954200915981, 2291.9312420627407, 2301.8158547316257, 2311.075569772088, 2328.4528135888054, 2328.8951997160084, 2300.217925905192, 2310.7573704761094, 2326.8138548916045, 2332.38191879153, 2333.6266197408418, 2342.1802369170823, 2358.2099996501192, 2359.4927090675415, 2362.0, 2363.586372384843, 8158.592070396457, 8316.37627177964, 8331.458455200393, 8340.37447191463, 8370.497770133812, 4985.5513003829465, 5147.665401748912, 5154.123798954187, 5155.71643559604, 5158.587313014949, 3265.6081270247487, 3425.657434256579, 3516.1561225306, 3600.945985810215, 3622.275544891006, 1012.190247801763, 1035.7415739876012, 1080.2762149359949, 1097.4841824342545, 1099.4063205868822, 10512.21158413122, 10610.893891061069, 10612.0, 10655.84016239757, 10671.733206669818, 4487.88780280489, 4585.28928016158, 4668.3697700810635, 4676.579107880307, 4746.976265118721, 9288.953555232181, 9318.229536388064, 9318.534073296309, 9341.532923353805, 9398.389104708172, 5190.039842629126, 5202.635815492879, 5207.427183009866, 5211.088059589557, 5215.374155101412, 4161.944682261995, 4621.930671039937, 4750.097481478508, 4802.399700037496, 4807.807687692523, 3832.3485007548784, 3844.231153769257, 3919.980400097981, 3924.47019652349, 3948.9605103949166, 1263.620913725897, 1271.9491220351083, 1277.2719549856492, 1285.3483273992874, 1286.5175559165314, 772.5248971882364, 873.7073080517971, 898.5462341517492, 906.3746015249457, 915.1534830281962, 5516.062269414209, 5545.889082218333, 5557.610967232255, 5784.305883293948, 5809.8547536311935, 959.9376040557341, 973.2116985241977, 979.9314048016713, 1006.7986402719486, 1032.9845052324222, 3674.6876515495946, 3730.309892669865, 3755.3615885299564, 3761.7931013794505, 3781.489498917666, 9559.235261179088, 9848.261380396356, 9871.753206169904, 9887.703368898945, 9955.701328960144, 9883.0, 9977.60089596408, 10009.499525023719, 10009.849852252222, 10053.994600540034, 7635.541867487968, 7671.53970761756, 7768.41736869737, 7800.141984381714, 7805.102413222438, 5271.472852714698, 5355.651882627617, 5467.289252397322, 5495.752691128829, 5526.91709624364, 7461.440391970733, 9191.632334706537, 9243.907560924305, 9320.114589114024, 9343.906560934303, 9176.908230917605, 9350.95324523373, 9499.240060795259, 9512.476813775309, 9520.619175232912, 6187.412195841388, 6245.313015568375, 6450.129232553545, 6641.468682505291, 6711.161104861892, 7732.497387669783, 7828.138904720362, 7874.448788584973, 7883.921160788319, 7901.486403383762, 7014.193367762773, 7092.255313192247, 7096.2548932363425, 7131.0729605151, 7182.533135346186, 5571.944280557304, 6641.634710090992, 6773.762918297836, 6961.095057642474, 6998.0, 5614.747336369955, 6518.087467754613, 6576.145101136822, 6616.933830661825, 6622.602643841385, 4697.0840686065885, 4763.857084287477, 4786.521347865186, 4800.807967681254, 4864.805407783649, 4320.654347652243, 4686.8125274988615, 4729.148753224315, 4745.2882067690125, 4925.9014819704125], "value": 4383.456442367357, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.4265238698859, 310.29253302470374, 310.61328645835937, 312.4812810734182, 316.57894999650483, 5490.725463726812, 5509.5041446269925, 5520.861978450531, 5557.972210138949, 5565.749541270642, 4826.517348265181, 5143.382794064708, 5163.347728726809, 5382.300300960869, 5391.032273220276, 3633.6911362534215, 3685.539307586552, 3817.675497582709, 4030.9596904031023, 4156.480439945007, 3394.4738565522384, 3628.328759179547, 4139.668826493888, 4156.485326377543, 4215.178040282146, 3665.9083522911874, 4146.523149837777, 4152.418661387408, 4154.397612346208, 4155.898686847988, 3796.9430458543143, 3812.6759225465867, 4054.8580799671868, 4184.4351012613215, 4198.916021679549, 3179.5071763876635, 3525.841337139809, 3552.946705799415, 3558.3417067842274, 3563.826066099677, 4248.723832950848, 4689.343491911135, 5341.973290133564, 5348.51863332298, 5385.138377859563, 4380.233459144637, 4436.844710435119, 4598.724076555417, 4679.274712419547, 4732.242841145435, 5012.27322038306, 5733.340665823442, 5740.82777516671, 5753.597248192628, 5799.9130013049835, 3553.928921421583, 3802.239552089593, 3881.4954055972544, 3887.708421868343, 3923.6468717815246, 5398.433164517748, 5805.970970145122, 5873.530117590582, 5970.164177015221, 6058.697065146725, 3447.3967055765143, 3859.459675645412, 3905.2970465316516, 3976.960230397659, 3995.6015394611654, 3580.5703315602295, 3850.6726928211415, 3988.481497405319, 3999.7200195986584, 4027.4160246764054, 3923.7841918694176, 4713.835015774432, 4719.625467790477, 4738.881527961828, 4743.786529606176, 5331.333583302087, 5425.83722488326, 5815.505682017076, 5873.71219010125, 5876.676782776903, 1175.5767923547517, 1241.4661695470854, 1245.9875401245872, 1318.3803612302202, 1323.9007074469453, 822.4119754375531, 846.551327796269, 876.2332958661171, 891.8795962545103, 895.3329769321897, 4730.645201609893, 4777.140114779373, 5290.270359396616, 5319.468053194727, 5603.767171222325, 5271.0, 5974.581779275496, 6042.0, 6047.78832740852, 6054.697265136813, 4190.51809041964, 4882.633802464829, 5454.5181942216195, 5473.863153421196, 5644.943550564524, 2726.5364887969095, 2911.8835246590324, 2984.4627966966154, 3002.609660744089, 3029.9395211675737, 5494.862628434321, 5887.674368624369, 5911.793087241926, 5912.556558258148, 5939.613925094942, 5226.170840205898, 5375.75809088592, 5643.5203007743985, 5788.986927287708, 5862.912056319158, 5644.8306550803545, 5884.558658100659, 5904.261967254093, 5904.852378690395, 5905.438983296666, 5358.784729162471, 5693.28833895763, 5760.769569217352, 5782.653040817564, 5929.318128415288, 4527.977360113178, 5496.862578435571, 5529.894021195857, 5658.886822263614, 5679.602427830012, 5282.524572788468, 5339.225812257278, 5357.678539287655, 5499.312585926759, 5957.553183511341, 3445.08705193119, 3556.2531868307947, 3753.498600559798, 3754.6245375463327, 3786.280606684721, 4903.847595815052, 5250.081235783799, 5673.992783824579, 5722.570807189561, 5725.398833122629, 4935.654504184673, 5413.756380962787, 5496.422875598165, 5526.861828454321, 5559.718267645672, 4543.0, 4930.0, 4945.0, 5396.379416367168, 5417.322834645669, 4579.748113853637, 4663.720176789404, 5366.812161574326, 5370.248165256789, 5382.623216374816, 4730.124926888399, 4985.825496107619, 5611.466910643482, 5619.555493360402, 5779.942200577941, 4754.646450007269, 4828.155072862165, 5488.286522752017, 5613.915791263216, 5643.645525074035], "value": 4087.63913109865, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [685.1127789512581, 714.2357677285305, 736.4955005821013, 739.652363389207, 744.2260049548463, 14079.788803167949, 14814.333354999024, 14827.851721482783, 14859.405623775036, 14883.255837208137, 13416.932915335434, 13434.865651343483, 13475.0, 13487.865121348808, 13571.389287482063, 640.5100098424709, 661.8312330355776, 795.5505139596139, 797.86436305828, 824.0893812337371, 808.6280311056921, 816.073756286613, 816.5304949653943, 824.418784756745, 828.2876726015621, 867.4795122926225, 869.4870026684255, 872.1845074854999, 872.9694460693877, 873.1792115411524, 856.7815207122206, 859.6045818923303, 863.1713554987189, 867.3451544084176, 867.6182479708908, 641.4515589171285, 646.5474168082314, 646.7186773753432, 647.0294558162756, 652.0935899100255, 13822.308884555732, 13920.930395347958, 14083.507077252247, 14178.503752368617, 14443.350049247703, 13732.65668358289, 13788.172709637454, 13972.301384930814, 14000.089994150349, 14133.78799318011, 11260.21178517504, 11296.943515282372, 11507.597234096851, 11579.826302605468, 11590.594329198437, 3662.853485860562, 3687.8340474678703, 3694.5751238607636, 3694.9261014779822, 3759.605241449663, 81190.0, 81990.9999999994, 84738.57630711807, 84826.57586712026, 85930.28139437239, 8876.334274929406, 9517.90482095184, 10528.842067368996, 10717.0, 10789.892101079045, 17464.38874639407, 21009.264675736275, 21036.0, 21064.15743370248, 21382.037808298664, 7326.816829579303, 7693.192214817475, 7910.7231246906085, 7936.206379362133, 7998.48009879356, 4192.91614167721, 4319.956800431955, 4328.523862375137, 4331.956680433218, 4403.559644035635, 3313.784604000732, 3439.8280085995602, 3447.7241820654285, 3450.7929524228125, 3548.6451354864826, 673.6766352150995, 676.2426082787309, 687.1719577908608, 691.9238883722786, 692.8302565871394, 580.9448102430263, 584.514852672281, 645.1516256123183, 650.9283978762332, 685.9965700171467, 8093.231143041402, 8584.8712269316, 9133.086691330815, 9326.160645541933, 9342.813143737087, 525.6504424557703, 570.6433479075578, 598.2432223237608, 654.1430725749284, 668.7358493395081, 4339.717918335298, 4381.233284175257, 4561.292999585004, 4564.246899261651, 4757.238841785298, 75620.0, 106485.0, 112001.87998120078, 116804.41597791956, 122933.15600266003, 99728.00271997332, 104504.47747761212, 104811.0, 106156.93843061625, 107176.0, 75285.3650122254, 89229.66155507673, 89827.55086224657, 91409.54295228481, 92519.61220581697, 8170.754877353689, 8227.71203007892, 8723.694670686495, 8738.781530461789, 8861.33539984491, 58933.705331473066, 59498.405015951015, 60244.69877650759, 60525.0, 60611.39388606058, 80939.0, 82400.0, 82767.17232827835, 85129.14870851212, 87048.0, 10853.78292434131, 11231.21381503287, 11420.972112509913, 11427.942860285646, 11908.940455297668, 12243.081768867192, 12451.249458327055, 12477.75044499123, 12508.437120329425, 13025.413856376295, 11465.541378345106, 11504.94247528757, 11610.477528511068, 11879.703007424538, 11954.402279885797, 11422.885771142182, 12111.515539378326, 12183.390830458618, 12410.255384676948, 12436.00511959023, 10183.287169898036, 11851.05191584654, 11894.286342819458, 12286.262824230218, 12339.876601233873, 9628.934471406686, 9753.268504862022, 10234.692959211236, 10340.53467593945, 10374.325668831501, 9580.568874400808, 10191.592336306465, 10235.437050962268, 10248.538815753458, 10278.126359259546], "value": 7204.032158725466, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1537.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 120.0, 118.0, 117.0, 118.0, 118.0, 117.0, 117.0, 119.0, 117.0], "value": 117.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 118.0, 114.0, 163.0, 146.0, 123.0, 117.0, 120.0, 149.0, 120.0], "value": 131.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 196.0, 210.0, 196.0, 200.0, 199.0, 196.0, 194.0, 199.0, 199.0], "value": 198.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 60.0, 60.0, 88.0, 62.0, 62.0, 52.0, 71.0, 109.0, 81.0], "value": 72.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 72.0, 87.0, 70.0, 58.0, 77.0, 120.0, 56.0, 98.0, 119.0], "value": 84.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 89.0, 86.0, 91.0, 86.0, 90.0, 87.0, 88.0, 85.0], "value": 87.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 97.0, 117.0, 121.0, 97.0, 98.0, 95.0, 95.0, 96.0, 150.0], "value": 110.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 104.0, 133.0, 110.0, 93.0, 88.0, 90.0, 93.0, 87.0, 105.0], "value": 99.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 56.0, 56.0, 61.0, 60.0, 60.0, 59.0, 58.0, 59.0, 59.0], "value": 58.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 73.0, 77.0, 86.0, 81.0, 76.0, 69.0, 69.0, 70.0, 94.0], "value": 76.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 100.0, 87.0, 84.0, 88.0, 91.0, 86.0, 88.0, 85.0, 87.0], "value": 88.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 157.0, 160.0, 161.0, 155.0, 154.0, 155.0, 163.0, 200.0, 164.0], "value": 161.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 173.0, 171.0, 176.0, 170.0, 189.0, 224.0, 172.0, 174.0, 174.0], "value": 179.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 68.0, 70.0, 72.0, 72.0, 67.0, 69.0, 71.0, 67.0, 70.0], "value": 69.7, "unit": "score"}]}]} 17:45:40 INFO - Return code: 0 17:45:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:45:40 ERROR - # TBPL SUCCESS # 17:45:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:45:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:45:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:45:40 INFO - 2016-11-30 17:45:40.282 screenresolution[1652:10452] starting screenresolution argv=screenresolution get 17:45:40 INFO - 2016-11-30 17:45:40.287 screenresolution[1652:10452] Display 0: 1600x1200x32@60 17:45:40 INFO - 2016-11-30 17:45:40.293 screenresolution[1653:10455] starting screenresolution argv=screenresolution list 17:45:40 INFO - Available Modes on Display 0 17:45:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:45:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:45:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:45:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:45:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:45:40 INFO - Intel Iris: 17:45:40 INFO - Chipset Model: Intel Iris 17:45:40 INFO - Type: GPU 17:45:40 INFO - Bus: Built-In 17:45:40 INFO - VRAM (Dynamic, Max): 1536 MB 17:45:40 INFO - Vendor: Intel (0x8086) 17:45:40 INFO - Device ID: 0x0a2e 17:45:40 INFO - Revision ID: 0x0009 17:45:40 INFO - Displays: 17:45:40 INFO - Display: 17:45:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:45:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:45:40 INFO - Display Serial Number: 1600x1200 60 17:45:40 INFO - Main Display: Yes 17:45:40 INFO - Mirror: Off 17:45:40 INFO - Online: Yes 17:45:40 INFO - Rotation: Supported 17:45:40 INFO - Return code: 0 17:45:40 INFO - Running post-action listener: _resource_record_post_action 17:45:40 INFO - [mozharness: 2016-12-01 01:45:40.533875Z] Finished run-tests step (success) 17:45:40 INFO - Running post-run listener: _resource_record_post_run 17:45:40 INFO - Total resource usage - Wall time: 1211s; CPU: 55.0%; Read bytes: 38521344; Write bytes: 658857472; Read time: 245; Write time: 1940 17:45:40 INFO - TinderboxPrint: CPU usage
54.7% 17:45:40 INFO - TinderboxPrint: I/O read bytes / time
38,521,344 / 245 17:45:40 INFO - TinderboxPrint: I/O write bytes / time
658,857,472 / 1,940 17:45:40 INFO - TinderboxPrint: CPU idle
2,191.6 (45.3%) 17:45:40 INFO - TinderboxPrint: CPU system
983.1 (20.3%) 17:45:40 INFO - TinderboxPrint: CPU user
1,667.1 (34.4%) 17:45:40 INFO - TinderboxPrint: Swap in / out
387,256,320 / 0 17:45:40 INFO - install - Wall time: 31s; CPU: 55.0%; Read bytes: 345833984; Write bytes: 344167936; Read time: 26831; Write time: 731 17:45:40 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 36444672; Write bytes: 307603456; Read time: 225; Write time: 1179 17:45:41 INFO - Running post-run listener: _upload_blobber_files 17:45:41 INFO - Blob upload gear active. 17:45:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:45:41 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 17:45:41 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'] 17:45:41 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 17:45:41 INFO - (blobuploader) - INFO - Open directory for files ... 17:45:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:45:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:45:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:45:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:45:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:45:43 INFO - (blobuploader) - INFO - Done attempting. 17:45:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:45:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:45:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:45:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:45:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:45:44 INFO - (blobuploader) - INFO - Done attempting. 17:45:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:45:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:45:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:45:47 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:45:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:45:47 INFO - (blobuploader) - INFO - Done attempting. 17:45:47 INFO - (blobuploader) - INFO - Iteration through files over. 17:45:47 INFO - Return code: 0 17:45:47 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:45:47 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:45:47 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0ab67d4e9eaf0cd9ab3289c67d8a1bdc8e450787733352a25cbbf0bdc8079b8c2358c5fb5939a2be7f21ec548d9296b2feb036fc162adef3947661f584d34f63", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2dfa8df977fe0a948091d95367a5f2d62143e7bc55160d8cfd7dfa1c1ba1960c035c4c61047b49b898f39b7f12b087fbef1ba6ccf020acce92724b17c1a118ef", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/15f0d771ab427fc8b416ae91f39367ae91e067f727b7560e8763ed48baedecef0476c86760a3ae74a2ac196d7ad8b3ae0f38bb73f112781e069a41723a9dae23"} 17:45:47 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:45:47 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:45:47 INFO - Contents: 17:45:47 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0ab67d4e9eaf0cd9ab3289c67d8a1bdc8e450787733352a25cbbf0bdc8079b8c2358c5fb5939a2be7f21ec548d9296b2feb036fc162adef3947661f584d34f63", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2dfa8df977fe0a948091d95367a5f2d62143e7bc55160d8cfd7dfa1c1ba1960c035c4c61047b49b898f39b7f12b087fbef1ba6ccf020acce92724b17c1a118ef", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/15f0d771ab427fc8b416ae91f39367ae91e067f727b7560e8763ed48baedecef0476c86760a3ae74a2ac196d7ad8b3ae0f38bb73f112781e069a41723a9dae23"} 17:45:47 INFO - Running post-run listener: copy_logs_to_upload_dir 17:45:47 INFO - Copying logs to upload dir... 17:45:47 INFO - mkdir: /builds/slave/test/build/upload/logs 17:45:47 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.109432 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-30 17:45:47.299747) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-30 17:45:47.303690) ========= 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.nCJmDy8FT2/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.wJxczN5Hwd/Listeners TMPDIR=/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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/0ab67d4e9eaf0cd9ab3289c67d8a1bdc8e450787733352a25cbbf0bdc8079b8c2358c5fb5939a2be7f21ec548d9296b2feb036fc162adef3947661f584d34f63", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2dfa8df977fe0a948091d95367a5f2d62143e7bc55160d8cfd7dfa1c1ba1960c035c4c61047b49b898f39b7f12b087fbef1ba6ccf020acce92724b17c1a118ef", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/15f0d771ab427fc8b416ae91f39367ae91e067f727b7560e8763ed48baedecef0476c86760a3ae74a2ac196d7ad8b3ae0f38bb73f112781e069a41723a9dae23"} build_url:https://queue.taskcluster.net/v1/task/Xb7NEB8-SR6ucPQnPkRDSA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011086 build_url: 'https://queue.taskcluster.net/v1/task/Xb7NEB8-SR6ucPQnPkRDSA/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/0ab67d4e9eaf0cd9ab3289c67d8a1bdc8e450787733352a25cbbf0bdc8079b8c2358c5fb5939a2be7f21ec548d9296b2feb036fc162adef3947661f584d34f63", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2dfa8df977fe0a948091d95367a5f2d62143e7bc55160d8cfd7dfa1c1ba1960c035c4c61047b49b898f39b7f12b087fbef1ba6ccf020acce92724b17c1a118ef", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/15f0d771ab427fc8b416ae91f39367ae91e067f727b7560e8763ed48baedecef0476c86760a3ae74a2ac196d7ad8b3ae0f38bb73f112781e069a41723a9dae23"}' ========= master_lag: 0.08 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-30 17:45:47.397234) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 17:45:47.397570) ========= 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.nCJmDy8FT2/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.wJxczN5Hwd/Listeners TMPDIR=/var/folders/rf/yw1ztqxx66vgykp7w510cksc00000w/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.004503 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-30 17:45:47.423672) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-30 17:45:47.423997) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-30 17:45:47.424376) ========= ========= Total master_lag: 0.22 =========