builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0248 starttime: 1483551799.73 results: success (0) buildid: 20170104075439 builduid: bf620d76b39049efa36d1077e6da3bb1 revision: 05a3443d658d29e77cae1da1915ca51814534d73 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-04 09:43:19.732002) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-04 09:43:19.732458) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-04 09:43:19.732731) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-04 09:43:19.759241) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 09:43:19.759623) ========= 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.GKFRJCRlhB/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.Lf3x9e275G/Listeners TMPDIR=/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-04 09:43:19-- 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.1M=0.001s 2017-01-04 09:43:19 (18.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.086140 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 09:43:19.869631) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 09:43:19.869946) ========= 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.GKFRJCRlhB/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.Lf3x9e275G/Listeners TMPDIR=/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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.029824 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 09:43:19.922325) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-04 09:43:19.922671) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 05a3443d658d29e77cae1da1915ca51814534d73 --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 05a3443d658d29e77cae1da1915ca51814534d73 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.GKFRJCRlhB/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.Lf3x9e275G/Listeners TMPDIR=/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-04 09:43:19,970 truncating revision to first 12 chars 2017-01-04 09:43:19,970 Setting DEBUG logging. 2017-01-04 09:43:19,970 attempt 1/10 2017-01-04 09:43:19,970 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/05a3443d658d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-04 09:43:21,691 unpacking tar archive at: mozilla-aurora-05a3443d658d/testing/mozharness/ program finished with exit code 0 elapsedTime=2.011670 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-04 09:43:21.957908) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-04 09:43:21.958241) ========= script_repo_revision: 05a3443d658d29e77cae1da1915ca51814534d73 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-04 09:43:21.958621) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-04 09:43:21.958885) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-04 09:43:21.978358) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-01-04 09:43:21.978661) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.GKFRJCRlhB/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.Lf3x9e275G/Listeners TMPDIR=/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 09:43:22 INFO - MultiFileLogger online at 20170104 09:43:22 in /builds/slave/test 09:43:22 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:43:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:43:22 INFO - {'append_to_log': False, 09:43:22 INFO - 'base_work_dir': '/builds/slave/test', 09:43:22 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:43:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:43:22 INFO - 'branch': 'Mozilla-Aurora', 09:43:22 INFO - 'buildbot_json_path': 'buildprops.json', 09:43:22 INFO - 'config_files': ('talos/mac_config.py',), 09:43:22 INFO - 'default_actions': ('clobber', 09:43:22 INFO - 'read-buildbot-config', 09:43:22 INFO - 'download-and-extract', 09:43:22 INFO - 'populate-webroot', 09:43:22 INFO - 'create-virtualenv', 09:43:22 INFO - 'install', 09:43:22 INFO - 'run-tests'), 09:43:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:43:22 INFO - 'download_minidump_stackwalk': True, 09:43:22 INFO - 'download_symbols': 'ondemand', 09:43:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:43:22 INFO - 'tooltool.py': '/tools/tooltool.py', 09:43:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:43:22 INFO - '/tools/misc-python/virtualenv.py')}, 09:43:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:43:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:43:22 INFO - 'installer_path': 'installer.exe', 09:43:22 INFO - 'log_level': 'info', 09:43:22 INFO - 'log_name': 'talos', 09:43:22 INFO - 'log_to_console': True, 09:43:22 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:43:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:43:22 INFO - 'opt_config_files': (), 09:43:22 INFO - 'pip_index': False, 09:43:22 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:43:22 INFO - 'cmd': ('bash', 09:43:22 INFO - '-c', 09:43:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:43:22 INFO - 'enabled': True, 09:43:22 INFO - 'halt_on_failure': False, 09:43:22 INFO - 'name': 'check_screen_resolution'},), 09:43:22 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:43:22 INFO - 'cmd': ('bash', 09:43:22 INFO - '-c', 09:43:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:43:22 INFO - 'enabled': True, 09:43:22 INFO - 'halt_on_failure': False, 09:43:22 INFO - 'name': 'check_screen_resolution'},), 09:43:22 INFO - 'run_cmd_checks_enabled': True, 09:43:22 INFO - 'sps_profile': False, 09:43:22 INFO - 'sps_profile_interval': 0, 09:43:22 INFO - 'suite': 'dromaeojs', 09:43:22 INFO - 'system_bits': '32', 09:43:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:43:22 INFO - 'title': 't-yosemite-r7-0248', 09:43:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:43:22 INFO - 'use_talos_json': True, 09:43:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:43:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:43:22 INFO - 'work_dir': 'build'} 09:43:22 INFO - [mozharness: 2017-01-04 17:43:22.131171Z] Running clobber step. 09:43:22 INFO - Running pre-action listener: _resource_record_pre_action 09:43:22 INFO - Running main action method: clobber 09:43:22 INFO - rmtree: /builds/slave/test/build 09:43:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:43:24 INFO - Running post-action listener: _resource_record_post_action 09:43:24 INFO - [mozharness: 2017-01-04 17:43:24.311546Z] Finished clobber step (success) 09:43:24 INFO - [mozharness: 2017-01-04 17:43:24.311667Z] Running read-buildbot-config step. 09:43:24 INFO - Running pre-action listener: _resource_record_pre_action 09:43:24 INFO - Running main action method: read_buildbot_config 09:43:24 INFO - Using buildbot properties: 09:43:24 INFO - { 09:43:24 INFO - "project": "", 09:43:24 INFO - "product": "firefox", 09:43:24 INFO - "script_repo_revision": "production", 09:43:24 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 09:43:24 INFO - "repository": "", 09:43:24 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 09:43:24 INFO - "buildid": "20170104075439", 09:43:24 INFO - "pgo_build": "False", 09:43:24 INFO - "basedir": "/builds/slave/test", 09:43:24 INFO - "buildnumber": 75, 09:43:24 INFO - "slavename": "t-yosemite-r7-0248", 09:43:24 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 09:43:24 INFO - "platform": "yosemite_r7", 09:43:24 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:43:24 INFO - "branch": "mozilla-aurora", 09:43:24 INFO - "repo_path": "releases/mozilla-aurora", 09:43:24 INFO - "revision": "05a3443d658d29e77cae1da1915ca51814534d73", 09:43:24 INFO - "stage_platform": "macosx64", 09:43:24 INFO - "builduid": "bf620d76b39049efa36d1077e6da3bb1", 09:43:24 INFO - "slavebuilddir": "test" 09:43:24 INFO - } 09:43:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/RPxl41XtQ4eEuYPZOtO0pw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 09:43:24 INFO - Running post-action listener: _resource_record_post_action 09:43:24 INFO - [mozharness: 2017-01-04 17:43:24.315337Z] Finished read-buildbot-config step (success) 09:43:24 INFO - [mozharness: 2017-01-04 17:43:24.315539Z] Running download-and-extract step. 09:43:24 INFO - Running pre-action listener: _resource_record_pre_action 09:43:24 INFO - Running main action method: download_and_extract 09:43:24 INFO - mkdir: /builds/slave/test/build/tests 09:43:24 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')]} 09:43:24 INFO - trying https://queue.taskcluster.net/v1/task/RPxl41XtQ4eEuYPZOtO0pw/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 09:43:24 INFO - Downloading https://queue.taskcluster.net/v1/task/RPxl41XtQ4eEuYPZOtO0pw/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 09:43:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RPxl41XtQ4eEuYPZOtO0pw/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 09:43:25 INFO - Downloaded 1199 bytes. 09:43:25 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 09:43:25 INFO - Using the following test package requirements: 09:43:25 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 09:43:25 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:43:25 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 09:43:25 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:43:25 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 09:43:25 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:43:25 INFO - u'jsshell-mac.zip'], 09:43:25 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:43:25 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 09:43:25 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 09:43:25 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:43:25 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 09:43:25 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:43:25 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 09:43:25 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:43:25 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 09:43:25 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:43:25 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 09:43:25 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:43:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RPxl41XtQ4eEuYPZOtO0pw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 09:43:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RPxl41XtQ4eEuYPZOtO0pw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:43:25 INFO - Fetch https://queue.taskcluster.net/v1/task/RPxl41XtQ4eEuYPZOtO0pw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 09:43:26 INFO - Http code: 200 09:43:26 INFO - via: 1.1 8008015354a3ca72f56c382a1d1cfe9f.cloudfront.net (CloudFront) 09:43:26 INFO - x-amz-cf-id: Oo1RfcUPQOecavhx9sF3vqa20gVzsvTplAbWETMitMfDtmddsknu4A== 09:43:26 INFO - x-amz-version-id: xiQIi6vFqUzdDZVjrvUVueoOVn2pAt_3 09:43:26 INFO - Expected file size: 16269736 09:43:26 INFO - Obtained file size: 16269736 09:43:33 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 09:43:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RPxl41XtQ4eEuYPZOtO0pw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 09:43:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RPxl41XtQ4eEuYPZOtO0pw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:43:33 INFO - Fetch https://queue.taskcluster.net/v1/task/RPxl41XtQ4eEuYPZOtO0pw/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 09:43:33 INFO - Http code: 200 09:43:33 INFO - via: 1.1 e1eb7447ab46f530f6009f1d93917c54.cloudfront.net (CloudFront) 09:43:33 INFO - x-amz-cf-id: EIcCncHpnrW8TbNpGq_NsIQ5XsOEFuUjo5r1P30jDLvzLbnNfIkoqw== 09:43:33 INFO - x-amz-version-id: xiQIi6vFqUzdDZVjrvUVueoOVn2pAt_3 09:43:33 INFO - Expected file size: 16269736 09:43:33 INFO - Obtained file size: 16269736 09:43:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/RPxl41XtQ4eEuYPZOtO0pw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 09:43:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/RPxl41XtQ4eEuYPZOtO0pw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:43:40 INFO - Fetch https://queue.taskcluster.net/v1/task/RPxl41XtQ4eEuYPZOtO0pw/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 09:43:41 INFO - Http code: 200 09:43:41 INFO - via: 1.1 455cf6ccbccc261c46d02110de1c0237.cloudfront.net (CloudFront) 09:43:41 INFO - x-amz-cf-id: epuI7Gkv4r1-ZBSS98rMtudIP3t9PruDh47ZzQVOYs-Cu92Z7cbLig== 09:43:41 INFO - x-amz-version-id: LIjeU08MbdcqBYZcOgmIInFI8zN.Pt7v 09:43:41 INFO - Expected file size: 14409038 09:43:41 INFO - Obtained file size: 14409038 09:43:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:43:42 INFO - trying https://queue.taskcluster.net/v1/task/RPxl41XtQ4eEuYPZOtO0pw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:43:42 INFO - Downloading https://queue.taskcluster.net/v1/task/RPxl41XtQ4eEuYPZOtO0pw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 09:43:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/RPxl41XtQ4eEuYPZOtO0pw/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 09:43:44 INFO - Downloaded 109833456 bytes. 09:43:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/RPxl41XtQ4eEuYPZOtO0pw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:43:44 INFO - mkdir: /builds/slave/test/properties 09:43:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:43:44 INFO - Writing to file /builds/slave/test/properties/build_url 09:43:44 INFO - Contents: 09:43:44 INFO - build_url:https://queue.taskcluster.net/v1/task/RPxl41XtQ4eEuYPZOtO0pw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:43:45 INFO - Running post-action listener: _resource_record_post_action 09:43:45 INFO - Running post-action listener: set_extra_try_arguments 09:43:45 INFO - [mozharness: 2017-01-04 17:43:45.495457Z] Finished download-and-extract step (success) 09:43:45 INFO - [mozharness: 2017-01-04 17:43:45.495599Z] Running populate-webroot step. 09:43:45 INFO - Running pre-action listener: _resource_record_pre_action 09:43:45 INFO - Running main action method: populate_webroot 09:43:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:43:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:43:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:43:45 INFO - '--tppagecycles', 09:43:45 INFO - '7'], 09:43:45 INFO - 'tests': ['tsvgm']}}, 09:43:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:43:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:43:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:43:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:43:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:43:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:43:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:45 INFO - 'talos_options': ['--disable-e10s'], 09:43:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:43:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:43:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:45 INFO - 'talos_options': ['--disable-e10s'], 09:43:45 INFO - 'tests': ['damp', 'tps']}, 09:43:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:45 INFO - 'tests': ['damp', 'tps']}, 09:43:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:43:45 INFO - 'tests': ['dromaeo_dom']}, 09:43:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:43:45 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:43:45 INFO - 'tests': ['basic_compositor_video']}, 09:43:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:43:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:43:45 INFO - 'tests': ['a11yr', 09:43:45 INFO - 'ts_paint', 09:43:45 INFO - 'tpaint', 09:43:45 INFO - 'sessionrestore', 09:43:45 INFO - 'sessionrestore_no_auto_restore', 09:43:45 INFO - 'tabpaint']}, 09:43:45 INFO - 'other-e10s': {'tests': ['a11yr', 09:43:45 INFO - 'ts_paint', 09:43:45 INFO - 'tpaint', 09:43:45 INFO - 'sessionrestore', 09:43:45 INFO - 'sessionrestore_no_auto_restore', 09:43:45 INFO - 'tabpaint']}, 09:43:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:43:45 INFO - 'ts_paint', 09:43:45 INFO - 'tpaint', 09:43:45 INFO - 'sessionrestore', 09:43:45 INFO - 'sessionrestore_no_auto_restore', 09:43:45 INFO - 'tabpaint']}, 09:43:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:43:45 INFO - 'ts_paint', 09:43:45 INFO - 'tpaint', 09:43:45 INFO - 'sessionrestore', 09:43:45 INFO - 'sessionrestore_no_auto_restore', 09:43:45 INFO - 'tabpaint']}, 09:43:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:43:45 INFO - 'tests': ['a11yr', 09:43:45 INFO - 'ts_paint', 09:43:45 INFO - 'tpaint', 09:43:45 INFO - 'sessionrestore', 09:43:45 INFO - 'sessionrestore_no_auto_restore', 09:43:45 INFO - 'tabpaint']}, 09:43:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:43:45 INFO - 'tests': ['a11yr', 09:43:45 INFO - 'ts_paint', 09:43:45 INFO - 'tpaint', 09:43:45 INFO - 'sessionrestore', 09:43:45 INFO - 'sessionrestore_no_auto_restore', 09:43:45 INFO - 'tabpaint']}, 09:43:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:43:45 INFO - 'tests': ['tsvgx', 09:43:45 INFO - 'tsvgr_opacity', 09:43:45 INFO - 'tart', 09:43:45 INFO - 'tscrollx', 09:43:45 INFO - 'cart']}, 09:43:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:43:45 INFO - 'tsvgr_opacity', 09:43:45 INFO - 'tart', 09:43:45 INFO - 'tscrollx', 09:43:45 INFO - 'cart']}, 09:43:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:45 INFO - 'talos_options': ['--disable-e10s'], 09:43:45 INFO - 'tests': ['tp5o']}, 09:43:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:45 INFO - 'tests': ['tp5o']}, 09:43:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:45 INFO - 'talos_options': ['--disable-e10s', 09:43:45 INFO - '--xperf_path', 09:43:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:43:45 INFO - 'tests': ['tp5n']}, 09:43:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:45 INFO - 'talos_options': ['--xperf_path', 09:43:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:43:45 INFO - 'tests': ['tp5n']}}, 09:43:45 INFO - 'talos.zip': {'path': '', 09:43:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:43:45 INFO - Running post-action listener: _resource_record_post_action 09:43:45 INFO - [mozharness: 2017-01-04 17:43:45.514000Z] Finished populate-webroot step (success) 09:43:45 INFO - [mozharness: 2017-01-04 17:43:45.514108Z] Running create-virtualenv step. 09:43:45 INFO - Running pre-action listener: _resource_record_pre_action 09:43:45 INFO - Running main action method: create_virtualenv 09:43:45 INFO - Creating virtualenv /builds/slave/test/build/venv 09:43:45 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 09:43:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:43:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:43:45 INFO - Using real prefix '/tools/python27' 09:43:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:43:46 INFO - Installing distribute.............................................................................................................................................................................................done. 09:43:49 INFO - Installing pip.................done. 09:43:49 INFO - Return code: 0 09:43:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:43:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:43:49 INFO - Reading from file tmpfile_stdout 09:43:49 INFO - Output received: 09:43:49 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) 09:43:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:43:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:43:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:43:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109265490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091db570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe27a4bb9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109079d30>, '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 0x108edfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109071b20>, '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.GKFRJCRlhB/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.Lf3x9e275G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 09:43:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:43:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:43:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GKFRJCRlhB/Render', 09:43:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:49 INFO - 'HOME': '/Users/cltbld', 09:43:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:49 INFO - 'LOGNAME': 'cltbld', 09:43:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:49 INFO - 'MOZ_NO_REMOTE': '1', 09:43:49 INFO - 'NO_EM_RESTART': '1', 09:43:49 INFO - 'PAGER': '/bin/cat', 09:43:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:49 INFO - 'PWD': '/builds/slave/test', 09:43:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:49 INFO - 'SHELL': '/bin/bash', 09:43:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lf3x9e275G/Listeners', 09:43:49 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 09:43:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:49 INFO - 'USER': 'cltbld', 09:43:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:49 INFO - 'XPC_FLAGS': '0x0', 09:43:49 INFO - 'XPC_SERVICE_NAME': '0', 09:43:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:49 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 09:43:49 INFO - Cleaning up... 09:43:49 INFO - Return code: 0 09:43:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:43:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:43:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:43:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109265490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091db570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe27a4bb9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109079d30>, '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 0x108edfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109071b20>, '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.GKFRJCRlhB/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.Lf3x9e275G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 09:43:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:43:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:43:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GKFRJCRlhB/Render', 09:43:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:49 INFO - 'HOME': '/Users/cltbld', 09:43:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:49 INFO - 'LOGNAME': 'cltbld', 09:43:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:49 INFO - 'MOZ_NO_REMOTE': '1', 09:43:49 INFO - 'NO_EM_RESTART': '1', 09:43:49 INFO - 'PAGER': '/bin/cat', 09:43:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:49 INFO - 'PWD': '/builds/slave/test', 09:43:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:49 INFO - 'SHELL': '/bin/bash', 09:43:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lf3x9e275G/Listeners', 09:43:49 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 09:43:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:49 INFO - 'USER': 'cltbld', 09:43:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:49 INFO - 'XPC_FLAGS': '0x0', 09:43:49 INFO - 'XPC_SERVICE_NAME': '0', 09:43:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:50 INFO - Downloading/unpacking psutil>=3.1.1 09:43:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:43:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:43:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:43:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:43:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:43:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:43:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:43:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:43:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:43:53 INFO - Installing collected packages: psutil 09:43:53 INFO - Running setup.py install for psutil 09:43:53 INFO - building 'psutil._psutil_osx' extension 09:43:53 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 09:43:54 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 09:43:54 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 09:43:54 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 09:43:54 INFO - building 'psutil._psutil_posix' extension 09:43:54 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 09:43:54 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:43:54 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:43:54 INFO - ^ 09:43:54 INFO - 1 warning generated. 09:43:54 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 09:43:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:43:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:43:54 INFO - Successfully installed psutil 09:43:54 INFO - Cleaning up... 09:43:54 INFO - Return code: 0 09:43:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:43:54 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')]} 09:43:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:54 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')]} 09:43:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:54 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 0x109265490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091db570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe27a4bb9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109079d30>, '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 0x108edfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109071b20>, '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.GKFRJCRlhB/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.Lf3x9e275G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 09:43:54 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 09:43:54 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 09:43:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GKFRJCRlhB/Render', 09:43:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:54 INFO - 'HOME': '/Users/cltbld', 09:43:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:54 INFO - 'LOGNAME': 'cltbld', 09:43:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:54 INFO - 'MOZ_NO_REMOTE': '1', 09:43:54 INFO - 'NO_EM_RESTART': '1', 09:43:54 INFO - 'PAGER': '/bin/cat', 09:43:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:54 INFO - 'PWD': '/builds/slave/test', 09:43:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:54 INFO - 'SHELL': '/bin/bash', 09:43:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lf3x9e275G/Listeners', 09:43:54 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 09:43:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:54 INFO - 'USER': 'cltbld', 09:43:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:54 INFO - 'XPC_FLAGS': '0x0', 09:43:54 INFO - 'XPC_SERVICE_NAME': '0', 09:43:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:43:54 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 09:43:54 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 09:43:54 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 09:43:54 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 09:43:54 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 09:43:54 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 09:43:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:43:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:43:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:43:57 INFO - Installing collected packages: mozsystemmonitor 09:43:57 INFO - Running setup.py install for mozsystemmonitor 09:43:57 INFO - Successfully installed mozsystemmonitor 09:43:57 INFO - Cleaning up... 09:43:57 INFO - Return code: 0 09:43:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:43:57 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')]} 09:43:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:57 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')]} 09:43:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:57 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 0x109265490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091db570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe27a4bb9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109079d30>, '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 0x108edfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109071b20>, '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.GKFRJCRlhB/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.Lf3x9e275G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 09:43:57 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 09:43:57 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 09:43:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GKFRJCRlhB/Render', 09:43:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:57 INFO - 'HOME': '/Users/cltbld', 09:43:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:57 INFO - 'LOGNAME': 'cltbld', 09:43:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:57 INFO - 'MOZ_NO_REMOTE': '1', 09:43:57 INFO - 'NO_EM_RESTART': '1', 09:43:57 INFO - 'PAGER': '/bin/cat', 09:43:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:57 INFO - 'PWD': '/builds/slave/test', 09:43:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:57 INFO - 'SHELL': '/bin/bash', 09:43:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lf3x9e275G/Listeners', 09:43:57 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 09:43:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:57 INFO - 'USER': 'cltbld', 09:43:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:57 INFO - 'XPC_FLAGS': '0x0', 09:43:57 INFO - 'XPC_SERVICE_NAME': '0', 09:43:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:58 INFO - Downloading/unpacking jsonschema==2.5.1 09:43:58 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 09:43:58 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 09:43:58 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 09:43:58 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 09:43:58 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 09:43:58 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 09:44:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:44:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:44: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 09:44: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 09:44: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 09:44: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 09:44: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 09:44: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 09:44:01 INFO - Downloading functools32-3.2.3-2.tar.gz 09:44:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:44:01 INFO - warning: no files found matching '*.txt' 09:44:01 INFO - no previously-included directories found matching 'build' 09:44:01 INFO - no previously-included directories found matching 'dist' 09:44:01 INFO - no previously-included directories found matching '.git*' 09:44:01 INFO - Installing collected packages: jsonschema, functools32 09:44:01 INFO - Running setup.py install for functools32 09:44:01 INFO - warning: no files found matching '*.txt' 09:44:01 INFO - no previously-included directories found matching 'build' 09:44:01 INFO - no previously-included directories found matching 'dist' 09:44:01 INFO - no previously-included directories found matching '.git*' 09:44:01 INFO - Successfully installed jsonschema functools32 09:44:01 INFO - Cleaning up... 09:44:01 INFO - Return code: 0 09:44:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:44: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')]} 09:44:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44: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')]} 09:44:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109265490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091db570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe27a4bb9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109079d30>, '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 0x108edfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109071b20>, '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.GKFRJCRlhB/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.Lf3x9e275G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 09:44:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:44:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:44:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GKFRJCRlhB/Render', 09:44:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:01 INFO - 'HOME': '/Users/cltbld', 09:44:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:01 INFO - 'LOGNAME': 'cltbld', 09:44:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:01 INFO - 'MOZ_NO_REMOTE': '1', 09:44:01 INFO - 'NO_EM_RESTART': '1', 09:44:01 INFO - 'PAGER': '/bin/cat', 09:44:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:01 INFO - 'PWD': '/builds/slave/test', 09:44:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:01 INFO - 'SHELL': '/bin/bash', 09:44:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lf3x9e275G/Listeners', 09:44:01 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 09:44:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:01 INFO - 'USER': 'cltbld', 09:44:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:01 INFO - 'XPC_FLAGS': '0x0', 09:44:01 INFO - 'XPC_SERVICE_NAME': '0', 09:44:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:44:01 INFO - Cleaning up... 09:44:01 INFO - Return code: 0 09:44:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:44: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')]} 09:44:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44: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')]} 09:44:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x109265490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091db570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe27a4bb9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109079d30>, '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 0x108edfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109071b20>, '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.GKFRJCRlhB/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.Lf3x9e275G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 09:44:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:44:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:44:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GKFRJCRlhB/Render', 09:44:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:01 INFO - 'HOME': '/Users/cltbld', 09:44:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:01 INFO - 'LOGNAME': 'cltbld', 09:44:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:01 INFO - 'MOZ_NO_REMOTE': '1', 09:44:01 INFO - 'NO_EM_RESTART': '1', 09:44:01 INFO - 'PAGER': '/bin/cat', 09:44:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:01 INFO - 'PWD': '/builds/slave/test', 09:44:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:01 INFO - 'SHELL': '/bin/bash', 09:44:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lf3x9e275G/Listeners', 09:44:01 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 09:44:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:01 INFO - 'USER': 'cltbld', 09:44:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:01 INFO - 'XPC_FLAGS': '0x0', 09:44:01 INFO - 'XPC_SERVICE_NAME': '0', 09:44:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:02 INFO - Downloading/unpacking blobuploader==1.2.4 09:44:02 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 09:44:02 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 09:44:02 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 09:44:02 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 09:44:02 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 09:44:02 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 09:44:04 INFO - Downloading blobuploader-1.2.4.tar.gz 09:44:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:44:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:44:04 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 09:44:04 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 09:44:04 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 09:44:04 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 09:44:04 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 09:44:04 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 09:44:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:44:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:44: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 09:44: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 09:44: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 09:44: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 09:44: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 09:44: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 09:44:05 INFO - Downloading docopt-0.6.1.tar.gz 09:44:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:44:06 INFO - Installing collected packages: blobuploader, requests, docopt 09:44:06 INFO - Running setup.py install for blobuploader 09:44:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:44:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:44:06 INFO - Running setup.py install for requests 09:44:06 INFO - Running setup.py install for docopt 09:44:06 INFO - Successfully installed blobuploader requests docopt 09:44:06 INFO - Cleaning up... 09:44:06 INFO - Return code: 0 09:44:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:44:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:44:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:44:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:06 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 0x109265490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091db570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe27a4bb9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109079d30>, '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 0x108edfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109071b20>, '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.GKFRJCRlhB/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.Lf3x9e275G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 09:44:06 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 09:44:06 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 09:44:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GKFRJCRlhB/Render', 09:44:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:06 INFO - 'HOME': '/Users/cltbld', 09:44:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:06 INFO - 'LOGNAME': 'cltbld', 09:44:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:06 INFO - 'MOZ_NO_REMOTE': '1', 09:44:06 INFO - 'NO_EM_RESTART': '1', 09:44:06 INFO - 'PAGER': '/bin/cat', 09:44:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:06 INFO - 'PWD': '/builds/slave/test', 09:44:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:06 INFO - 'SHELL': '/bin/bash', 09:44:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lf3x9e275G/Listeners', 09:44:06 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 09:44:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:06 INFO - 'USER': 'cltbld', 09:44:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:06 INFO - 'XPC_FLAGS': '0x0', 09:44:06 INFO - 'XPC_SERVICE_NAME': '0', 09:44:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:44:07 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-KkJx_6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:44:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:44:07 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-WcqezQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:44:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:44:07 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-UpC0t4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:44:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:44:07 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-CXssat-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:44:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:44:07 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-NQA_Gj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:44:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:44:07 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-33iMu2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:44:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:44:07 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-R6bjru-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:44:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:44:07 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-e7gd_x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:44:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:44:07 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-bcAD5C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:44:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:44:08 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-xvJzBE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:44:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:44:08 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-uN_mtq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:44:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:44:08 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-a0fTgG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:44:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:44:08 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-gj6KsT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:44:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:44:08 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-NK94u2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:44:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:44:08 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-BuZ7GX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:44:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:44:08 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-St31Td-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:44:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:44:08 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-yE0kuL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:44:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:44:08 INFO - Running setup.py install for manifestparser 09:44:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:44:09 INFO - Running setup.py install for mozcrash 09:44:09 INFO - Running setup.py install for mozdebug 09:44:09 INFO - Running setup.py install for mozdevice 09:44:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:44:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:44:09 INFO - Running setup.py install for mozfile 09:44:09 INFO - Running setup.py install for mozhttpd 09:44:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:44:09 INFO - Running setup.py install for mozinfo 09:44:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:44:09 INFO - Running setup.py install for mozInstall 09:44:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:44:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:44:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:44:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:44:10 INFO - Running setup.py install for mozleak 09:44:10 INFO - Running setup.py install for mozlog 09:44:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:44:10 INFO - Running setup.py install for moznetwork 09:44:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:44:10 INFO - Running setup.py install for mozprocess 09:44:10 INFO - Running setup.py install for mozprofile 09:44:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:44:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:44:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:44:10 INFO - Running setup.py install for mozrunner 09:44:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:44:11 INFO - Running setup.py install for mozscreenshot 09:44:11 INFO - Running setup.py install for moztest 09:44:11 INFO - Running setup.py install for mozversion 09:44:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:44:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:44:11 INFO - Cleaning up... 09:44:11 INFO - Return code: 0 09:44:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:44: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')]} 09:44:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44: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')]} 09:44:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:11 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 0x109265490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091db570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe27a4bb9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109079d30>, '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 0x108edfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109071b20>, '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.GKFRJCRlhB/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.Lf3x9e275G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 09:44:11 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 09:44:11 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 09:44:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GKFRJCRlhB/Render', 09:44:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:11 INFO - 'HOME': '/Users/cltbld', 09:44:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:11 INFO - 'LOGNAME': 'cltbld', 09:44:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:11 INFO - 'MOZ_NO_REMOTE': '1', 09:44:11 INFO - 'NO_EM_RESTART': '1', 09:44:11 INFO - 'PAGER': '/bin/cat', 09:44:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:11 INFO - 'PWD': '/builds/slave/test', 09:44:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:11 INFO - 'SHELL': '/bin/bash', 09:44:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lf3x9e275G/Listeners', 09:44:11 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 09:44:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:11 INFO - 'USER': 'cltbld', 09:44:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:11 INFO - 'XPC_FLAGS': '0x0', 09:44:11 INFO - 'XPC_SERVICE_NAME': '0', 09:44:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:44:11 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-MxNMlU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:44:11 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)) 09:44:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:44:11 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-l1irWF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:44:11 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)) 09:44:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:44:11 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-380AZ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:44:12 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)) 09:44:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:44:12 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-UDSQjX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:44:12 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)) 09:44:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:44:12 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-SDGETs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:44:12 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)) 09:44:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:44:12 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-9rZR1O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:44:12 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)) 09:44:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:44:12 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-ldsC9k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:44:12 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)) 09:44:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:44:12 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-KV8pcm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:44:12 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)) 09:44:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:44:12 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-ADwwc6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:44:12 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)) 09:44:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:44:12 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-yvevd6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:44:12 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)) 09:44:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:44:12 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-zF6XJG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:44:12 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)) 09:44:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:44:12 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-sgJCLd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:44:12 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)) 09:44:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:44:13 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-J3nDZz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:44:13 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)) 09:44:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:44:13 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-hPzcUu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:44:13 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)) 09:44:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:44:13 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-ItVUP_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:44:13 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)) 09:44:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:44:13 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-RuycWP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:44:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:44:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:44:13 INFO - Running setup.py (path:/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/pip-WlTDUs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:44:13 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)) 09:44:13 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)) 09:44:13 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)) 09:44:13 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)) 09:44:13 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)) 09:44:13 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)) 09:44:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:44:13 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 09:44:13 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 09:44:13 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 09:44:13 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 09:44:13 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 09:44:13 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 09:44:16 INFO - Downloading blessings-1.6.tar.gz 09:44:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:44:16 INFO - Installing collected packages: blessings 09:44:16 INFO - Running setup.py install for blessings 09:44:16 INFO - Successfully installed blessings 09:44:16 INFO - Cleaning up... 09:44:16 INFO - Return code: 0 09:44:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:44:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:44:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:44:16 INFO - Reading from file tmpfile_stdout 09:44:16 INFO - Current package versions: 09:44:16 INFO - blessings == 1.6 09:44:16 INFO - blobuploader == 1.2.4 09:44:16 INFO - docopt == 0.6.1 09:44:16 INFO - functools32 == 3.2.3-2 09:44:16 INFO - jsonschema == 2.5.1 09:44:16 INFO - manifestparser == 1.1 09:44:16 INFO - mozInstall == 1.12 09:44:16 INFO - mozcrash == 1.0 09:44:16 INFO - mozdebug == 0.1 09:44:16 INFO - mozdevice == 0.48 09:44:16 INFO - mozfile == 1.2 09:44:16 INFO - mozhttpd == 0.7 09:44:16 INFO - mozinfo == 0.9 09:44:16 INFO - mozleak == 0.1 09:44:16 INFO - mozlog == 3.4 09:44:16 INFO - moznetwork == 0.27 09:44:16 INFO - mozprocess == 0.23 09:44:16 INFO - mozprofile == 0.28 09:44:16 INFO - mozrunner == 6.13 09:44:16 INFO - mozscreenshot == 0.1 09:44:16 INFO - mozsystemmonitor == 0.3 09:44:16 INFO - moztest == 0.8 09:44:16 INFO - mozversion == 1.4 09:44:16 INFO - psutil == 3.1.1 09:44:16 INFO - requests == 1.2.3 09:44:16 INFO - wsgiref == 0.1.2 09:44:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:44: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')]} 09:44:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:44:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:17 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 0x109265490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091db570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe27a4bb9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109079d30>, '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 0x108edfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109071b20>, '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.GKFRJCRlhB/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.Lf3x9e275G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 09:44:17 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 09:44:17 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 09:44:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GKFRJCRlhB/Render', 09:44:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:17 INFO - 'HOME': '/Users/cltbld', 09:44:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:17 INFO - 'LOGNAME': 'cltbld', 09:44:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:17 INFO - 'MOZ_NO_REMOTE': '1', 09:44:17 INFO - 'NO_EM_RESTART': '1', 09:44:17 INFO - 'PAGER': '/bin/cat', 09:44:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:17 INFO - 'PWD': '/builds/slave/test', 09:44:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:17 INFO - 'SHELL': '/bin/bash', 09:44:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lf3x9e275G/Listeners', 09:44:17 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 09:44:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:17 INFO - 'USER': 'cltbld', 09:44:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:17 INFO - 'XPC_FLAGS': '0x0', 09:44:17 INFO - 'XPC_SERVICE_NAME': '0', 09:44:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:17 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)) 09:44:17 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)) 09:44:17 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)) 09:44:17 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)) 09:44:17 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)) 09:44:17 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)) 09:44:17 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)) 09:44:17 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)) 09:44:17 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)) 09:44:17 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)) 09:44:17 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)) 09:44:17 INFO - Cleaning up... 09:44:17 INFO - Return code: 0 09:44:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:44:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:44:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:44:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109265490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091db570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe27a4bb9f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109079d30>, '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 0x108edfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109071b20>, '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.GKFRJCRlhB/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.Lf3x9e275G/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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 09:44:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:44:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:44:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GKFRJCRlhB/Render', 09:44:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:18 INFO - 'HOME': '/Users/cltbld', 09:44:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:18 INFO - 'LOGNAME': 'cltbld', 09:44:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:18 INFO - 'MOZ_NO_REMOTE': '1', 09:44:18 INFO - 'NO_EM_RESTART': '1', 09:44:18 INFO - 'PAGER': '/bin/cat', 09:44:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:18 INFO - 'PWD': '/builds/slave/test', 09:44:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:18 INFO - 'SHELL': '/bin/bash', 09:44:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lf3x9e275G/Listeners', 09:44:18 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 09:44:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:18 INFO - 'USER': 'cltbld', 09:44:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:18 INFO - 'XPC_FLAGS': '0x0', 09:44:18 INFO - 'XPC_SERVICE_NAME': '0', 09:44:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:44:18 INFO - Cleaning up... 09:44:18 INFO - Return code: 0 09:44:18 INFO - Running post-action listener: _resource_record_post_action 09:44:18 INFO - Running post-action listener: _start_resource_monitoring 09:44:18 INFO - Starting resource monitoring. 09:44:18 INFO - [mozharness: 2017-01-04 17:44:18.607022Z] Finished create-virtualenv step (success) 09:44:18 INFO - [mozharness: 2017-01-04 17:44:18.607993Z] Running install step. 09:44:18 INFO - Running pre-action listener: _resource_record_pre_action 09:44:18 INFO - Running main action method: install 09:44:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:44:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:44:18 INFO - Reading from file tmpfile_stdout 09:44:18 INFO - Detecting whether we're running mozinstall >=1.0... 09:44:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:44:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:44:18 INFO - Reading from file tmpfile_stdout 09:44:18 INFO - Output received: 09:44:18 INFO - Usage: mozinstall [options] installer 09:44:18 INFO - Options: 09:44:18 INFO - -h, --help show this help message and exit 09:44:18 INFO - -d DEST, --destination=DEST 09:44:18 INFO - Directory to install application into. [default: 09:44:18 INFO - "/builds/slave/test"] 09:44:18 INFO - --app=APP Application being installed. [default: firefox] 09:44:18 INFO - mkdir: /builds/slave/test/build/application 09:44:18 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'] 09:44:18 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 09:44:49 INFO - Reading from file tmpfile_stdout 09:44:49 INFO - Output received: 09:44:49 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:44:49 INFO - Running post-action listener: _resource_record_post_action 09:44:49 INFO - [mozharness: 2017-01-04 17:44:49.625851Z] Finished install step (success) 09:44:49 INFO - [mozharness: 2017-01-04 17:44:49.626012Z] Running run-tests step. 09:44:49 INFO - Running pre-action listener: _resource_record_pre_action 09:44:49 INFO - Running main action method: run_tests 09:44:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:44:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:44:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:44:49 INFO - 2017-01-04 09:44:49.640 screenresolution[1620:7398] starting screenresolution argv=screenresolution get 09:44:49 INFO - 2017-01-04 09:44:49.642 screenresolution[1620:7398] Display 0: 1600x1200x32@60 09:44:49 INFO - 2017-01-04 09:44:49.648 screenresolution[1621:7401] starting screenresolution argv=screenresolution list 09:44:49 INFO - Available Modes on Display 0 09:44:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:44:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:44:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:44:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:44:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:44:49 INFO - Intel Iris: 09:44:49 INFO - Chipset Model: Intel Iris 09:44:49 INFO - Type: GPU 09:44:49 INFO - Bus: Built-In 09:44:49 INFO - VRAM (Dynamic, Max): 1536 MB 09:44:49 INFO - Vendor: Intel (0x8086) 09:44:49 INFO - Device ID: 0x0a2e 09:44:49 INFO - Revision ID: 0x0009 09:44:49 INFO - Displays: 09:44:49 INFO - Display: 09:44:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:44:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:44:49 INFO - Display Serial Number: 1600x1200 60 09:44:49 INFO - Main Display: Yes 09:44:49 INFO - Mirror: Off 09:44:49 INFO - Online: Yes 09:44:49 INFO - Rotation: Supported 09:44:49 INFO - Return code: 0 09:44:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:44:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:44:49 INFO - Python 2.7.3 09:44:49 INFO - Return code: 0 09:44:49 INFO - grabbing minidump binary from tooltool 09:44:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:44:49 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 0x109079d30>, '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 0x108edfdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109071b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:44:49 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 09:44:49 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 09:44:49 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 09:44:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:44:50 INFO - Return code: 0 09:44:50 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:44:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:44:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:44:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:44:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:44:50 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', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RPxl41XtQ4eEuYPZOtO0pw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0248', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:44:50 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 --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/RPxl41XtQ4eEuYPZOtO0pw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0248 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:44:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.GKFRJCRlhB/Render', 09:44:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:50 INFO - 'HOME': '/Users/cltbld', 09:44:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:50 INFO - 'LOGNAME': 'cltbld', 09:44:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:44:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:44:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:50 INFO - 'MOZ_NO_REMOTE': '1', 09:44:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:44:50 INFO - 'NO_EM_RESTART': '1', 09:44:50 INFO - 'PAGER': '/bin/cat', 09:44:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:50 INFO - 'PWD': '/builds/slave/test', 09:44:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:44:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:50 INFO - 'SHELL': '/bin/bash', 09:44:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Lf3x9e275G/Listeners', 09:44:50 INFO - 'TMPDIR': '/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/', 09:44:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:50 INFO - 'USER': 'cltbld', 09:44:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:50 INFO - 'XPC_FLAGS': '0x0', 09:44:50 INFO - 'XPC_SERVICE_NAME': '0', 09:44:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/RPxl41XtQ4eEuYPZOtO0pw/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0248', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:44:50 INFO - mozversion application_buildid: 20170104075439 09:44:50 INFO - mozversion application_changeset: 05a3443d658d29e77cae1da1915ca51814534d73 09:44:50 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:44:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:44:50 INFO - mozversion application_name: Firefox 09:44:50 INFO - mozversion application_remotingname: firefox-dev 09:44:50 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:44:50 INFO - mozversion application_vendor: Mozilla 09:44:50 INFO - mozversion application_version: 52.0a2 09:44:50 INFO - mozversion platform_buildid: 20170104075439 09:44:50 INFO - mozversion platform_changeset: 05a3443d658d29e77cae1da1915ca51814534d73 09:44:50 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:44:50 INFO - mozversion platform_version: 52.0a2 09:44:50 INFO - using testdate: 1483551890 09:44:50 INFO - actual date: 1483551890 09:44:50 INFO - starting webserver on 'localhost:49240' 09:44:50 INFO - SUITE-START | Running 2 tests 09:44:50 INFO - TEST-START | dromaeo_css 09:44:50 INFO - operating with platform_type : mac_ 09:44:50 INFO - Initialising browser for dromaeo_css test... 09:44:50 INFO - TEST-INFO | started process 1631 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/tmpN80ZsH/profile http://localhost:49240/getInfo.html) 09:44:53 INFO - PROCESS | 1631 | __metrics Screen width/height:1600/1200 09:44:53 INFO - PROCESS | 1631 | colorDepth:24 09:44:53 INFO - PROCESS | 1631 | Browser inner width/height: 1024/689 09:44:53 INFO - PROCESS | 1631 | __metrics 09:44:53 INFO - PROCESS | 1631 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:44:53 INFO - PROCESS | 1631 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:44:53 INFO - TEST-INFO | 1631: exit 0 09:44:53 INFO - Browser initialized. 09:44:53 INFO - Running cycle 1/1 for dromaeo_css test... 09:44:53 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/tmpN80ZsH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:45:06 INFO - PROCESS | 1633 | RSS: Main: 222932992 09:45:06 INFO - PROCESS | 1633 | 09:48:09 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 09:48:13 INFO - PROCESS | 1633 | RSS: Main: 497795072 09:48:13 INFO - PROCESS | 1633 | 09:51:16 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 09:51:16 INFO - PROCESS | 1633 | RSS: Main: 350617600 09:51:16 INFO - PROCESS | 1633 | 09:54:19 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 09:54:20 INFO - PROCESS | 1633 | RSS: Main: 320667648 09:54:20 INFO - PROCESS | 1633 | 09:57:22 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 09:57:22 INFO - PROCESS | 1633 | RSS: Main: 317222912 09:57:22 INFO - PROCESS | 1633 | 10:00:26 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 10:00:26 INFO - PROCESS | 1633 | RSS: Main: 310603776 10:00:26 INFO - PROCESS | 1633 | 10:03:30 INFO - PROCESS | 1633 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 10:03:30 INFO - PROCESS | 1633 | RSS: Main: 383004672 10:03:30 INFO - PROCESS | 1633 | 10:03:30 INFO - PROCESS | 1633 | __start_tp_report 10:03:30 INFO - PROCESS | 1633 | _x_x_mozilla_page_load 10:03:30 INFO - PROCESS | 1633 | _x_x_mozilla_page_load_details 10:03:30 INFO - PROCESS | 1633 | |i|pagename|runs| 10:03:30 INFO - PROCESS | 1633 | |0;dojo.html;3265.1510607242117;3268.329992351568;3333.0667413124334;3337.349216902705;3375.4768010958296;4981.651284410088;5461.262729531512;5462.399136095027;5544.86137846554;5583.162525621159;5470.8085217017315;5515.4760297771645;5579.581531385153;5594.9720251398685;5607.243022192003;5050.419201791795;5071.087204303224;5108.3359163308905;5154.536091751742;5181.481851814826;5032.874178145539;5059.241113832918;5066.74666266687;5139.6659217150955;5190.844274671747;5119.206522988924;5135.1526998045465;5136.922946155811;5143.022825663131;5143.897122057575;5112.130937740593;5116.386033675945;5116.795328186868;5119.308893299394;5119.974400128012;4974.204127339644;5070.797168113271;5074.568661663763;5083.085044691954;5083.694978301313;5584.274044374246;5587.636803607793;5591.329040515164;5592.664440133564;5611.2144299798065;5976.372480889488;5998.970005149947;6002.879942401171;6014.3684913083935;6017.608855424383;6063.848403789896;6072.666003369813;6083.330833608299;6096.268447786294;6116.816495505142;5556.388797232301;5573.386927437979;5606.803761868315;5615.129654903497;5618.15727640853;6141.692915354214;6278.905816412757;6285.748570057147;6286.151369565142;6289;4900.534449227318;5837.416258374214;5878.882422351615;5884.941150588439;5917.201177840936;4817.397825271841;6052.425019623129;6082;6088.4824789893355;6147.754089836357;6062.787802426894;6065.3934606539;6074.908876366858;6075.848103797441;6090.421409966135;5881.823545293562;5936.910946335808;6039.033754599243;6072.483838873745;6104.17593624855;4806.927896081562;4811.182099043171;4818.4217893852965;4827.300041493963;4851.102546028996;4287.056847493546;4300.311950087971;4309.870703878889;4311.655067594647;4343.5656434356315;5585.3297604287745;5623.578231632644;5644.322681278273;5674.602777805514;5781.566382521328;5199.922001169985;5200.92198617021;6050.57645964787;6161.537884658668;6163.753449862045;5725.656460612293;5765.423457654285;5772.018756811268;5777.162311464814;5780.479756821822;5202.193659982709;5287.286216360819;5300.045991721528;5304.363476382781;5317.73411329432;6235.469985051322;6279.30927597973;6297.433231009231;6305;6371.87256254863;6186.845328866634;6197.101420294122;6202.844928876633;6203.8138855834395;6212.099245609452;6308;6367.42693157618;6384.680765961591;6386.457151142205;6391.169148010729;4702.636235491806;4882.877928051827;5732;5886.028805247086;6059.878802424015;6062.151298818081;6064.211652485325;6067.059605761114;6068.362821903813;6071.908921366095;4536.478304994967;5789.957807594674;5880.323762767336;5882.617629854046;5895.970520147372;5803.506701930383;5868.2077919480325;5871.1486834409625;5872.207252020922;5880.117982302689;5748.48263656273;5755.366909639853;5762.654240745569;5773.595848290579;5788.942110579009;5821.592488525932;5845;5895.263092113486;5895.823125306248;5900.468957793819;5727.885442291047;5789.421057894177;5836.241288632451;5843.094320380348;5846.473817356458 10:03:30 INFO - PROCESS | 1633 | |1;ext.html;462.8889066624011;497.8574172274629;511.8183045019018;522.6184885033925;561.1134407635933;28278.858605706995;39658.60341396585;40692.59307406925;40745.79627101864;42939.785301073454;35109.29781404377;42231;42395;42487.3626895596;42502;9295.93096793869;9448;9470.905290947107;9480.194183494412;9506.382085164441;8426.283765879907;8671.783205419852;11108.222424430292;11382.146339024564;11429.828552571718;11303.660890173307;11398.373089480076;11408.771824563504;11417.972382485576;11431.828522572127;8474.703385381483;9356;11343.773124537545;11367;11394.088472922187;8507.61715722794;8630.136986301382;8634.740957771277;8639.39524233304;10912.290701104403;39487.60512394868;39665.80167099175;39715.60284397177;39755;39778.60221397778;3054.404391143728;3221.8872339468007;3234.3046245057426;3377.7466689998105;3382.6279109297957;29277.56083658747;29786.10641680753;29823.25441863949;29960.550591740925;30104.24739381533;3033.7572994160414;3146.8583913723937;3174.3333899881054;3234.094453552985;3304.884329048472;468206;479528.9999999965;496606;497366;497532;60711;61061.69469152626;61115.38884611186;61150.69424652848;61198;63955.36044639587;63999.68000159969;64041;64071.359286406536;64092;15732.606684832741;16227.188640567925;16241.2691428886;16270.43053493122;16290.592735181715;11211.383373914514;11537.942310288563;12505.124641275024;12572.245665260114;12625.873741262654;16762.916185418995;17183.3985810496;17277.481675549752;17288.481345559652;17332.65334693299;5716.628419152742;6695.263521012782;6716.697748601325;6766.864662706718;6775.085363475868;874.8731433942042;925.6204955968021;992.1071036067597;1038.2317085356933;1043.6764602973103;29003.564946525818;29036.12891613255;29279.268018299288;29395.97114101039;29827.254318642208;1046.4401545173296;1070.175964507325;1070.7911957168278;1104.2435931387026;1126.0316128129803;12887;14793.260336983109;14893.925530372278;15112.471063512725;15304;645611;648494;650165.7491712606;653165.7341713262;654340;56838.7158064207;57022.14466783001;57064;57080;57221.14168287479;1341.8926485881107;1374.3952660829202;1393.136255521583;1397.5038861204125;1397.5527831093952;28709.56935645925;28876.42247155087;29194.7080529192;29844.70155298506;29877.701222987493;50776.74611626918;51118.48881511137;51130.74434627803;51256;51256.23115653343;583772;584428;586445.0677746584;587476;587884;22854.200102996314;25093.121740738985;26392.472150557267;27229.591556126277;40525.594744053364;44103;44110;44432.66701998945;44672.32991505065;44751.10497790091;36196.81901590475;36482.8175859119;36631.26737465289;36862.44706329353;37649.81175094107;33944.490832637035;35764.82117589395;35865.641343587275;36705.63294367022;36891.26217475689;35314.646853531136;35608;35676.82161589176;36099.63900360963;36245.27509449743;31491.685083148874;33125;33552.16119597029;33595.992120236435;33606.66393336035;24471.51056978886;24661.506769864143;24710.50578988446;25180.748192518575;25241 10:03:30 INFO - PROCESS | 1633 | |2;jquery.html;355.0325363384778;370.8942951258892;377.4338492261606;385.4353372309566;403.8525938032619;14633.268336583169;14818.03682760621;16047;19789.505262368413;20493.897530512364;18959.14683839227;19035.333763318285;19042.2383104676;19938.700919486182;20169.092390842412;4724.976375118128;5859.091840764689;5864.501517371029;5866.5306775458075;5870.853228669274;4691.967767091234;5844.883102337951;5894.233749612566;5900.380460051697;5909.645421274716;5055.494450554934;5573.526250268732;5833.154192642064;5848.912266316009;5850.824475265727;5670.574706896979;5746.741396637161;5778.24882765242;5783.450572195635;5811.5350771938565;4844.32179494871;5045.344105266292;5071.54356107952;5079.263506791532;5083.186690129562;20565.38303850872;24913.377165570826;24979.62530562043;25301.240962771146;25423.49153016948;2228.342638921522;2296.311106667993;2373.703287089114;2403.771641694036;2472.777450029506;14008.299585020708;14979.400823967027;15028;15457.480947651566;15518.301676424588;1785.8839175453554;1798.8291112344436;1822.906256246255;1848.223746026672;1865.8507319414414;116392.41803790927;117260.24109638362;120217.57932996351;122087;122499;7662;9252.67615633463;9667.743193384815;9672.371295865747;10647.414392208348;11922;13146.4084116215;13530.052896297362;13533.052686311863;13571.389287482087;8151.755447336471;8239.093699693029;8258.298044666271;8266.462679925786;8398;7424.406047516294;8272.503649781032;8301.626426810808;8415.158484151536;8485.533295668674;6261.499080073583;6386.808395748135;6388.520860935449;6388.968055159788;6405.935940640534;1554.4947891935167;1689.6705142497342;1842.7420161177447;1844.7140693192578;1888.6789245828243;727.2799928071172;800.6717245929258;842.5702891525243;915.7527467583715;1090.0734375780523;15881.126538040518;20500.89749551243;21622.89188554047;21671.45821354479;21717.02273397701;955.7658373698488;990.4598349566493;990.9554070066864;1000.8098461292332;1036.8548403223556;11084.612038578773;11487.597934072268;11494.310341379376;11525.76948461026;11612.6516204514;619177;620905;632410;638886;640436;87996;108946.45526772315;109984;110903.44548277208;111260.443697781;999;1027.917766578687;1070.2347821307803;1093.4095588382183;1095.638439315024;18481.630367392845;18768.530786729894;19110;19141.61716765685;19146.52133696669;50105.498945010084;50841.983160337324;53367.7331613355;54447.72776136094;54844.17733734077;69754;69928;71499.28500714926;71884.28115718775;73323;6258.155149055002;7597.278258565428;7637.961810190914;7726.1114971776715;7836.686532538634;12678;12695.80956285638;12702.745945080862;12771.80842287385;12852.55016074433;9429.57566909477;10879.836802448128;11315.434228288717;11420.257683250562;11467.025302849017;9189.632414703337;10798;11026.724331891766;11097.889021110008;11230.494627741607;11174.88825111771;11292.49183786748;11458.427078645867;11463.968242858184;11694.2983420995;6549.901751473636;7195;7203.783886483414;7742;8150.18498150204;10539.736506587396;10702.09032232269;10758.946205268923;11088.33469991803;11158.107351411705 10:03:30 INFO - PROCESS | 1633 | |3;mootools.html;1449.2174225918002;1488.7022595480903;1620.8379162083793;1758.34062226665;1809.5295223241963;7977.601119944002;8257.504549727013;8278.379121565891;8352.081271060179;8375.53934533602;7335.486515943886;7559.206283340252;7569.205233450477;7905.604719764017;8135.755927322175;2284.223364056225;2328.0571368595747;2328.871912044837;2329.5108027314286;2330.615448451012;2326.325365643961;2353.305774796439;2357.870317132557;2366.0890557135453;2368.2421625079983;2297.4026753044245;2309.2494939144763;2324.7094113235844;2330.778576035274;2340.508493216427;2296.5751336002727;2300.217925905192;2330.0912644068826;2331.370529956919;2334.766523347652;2319.5708793873187;2339.9298021059394;2343.0510643189536;2345.1440224318003;2345.3784747042046;8059.556724380157;8236.958815205886;8245.051819040767;8246.340292776624;8255;4965.925511117336;5116.692998420107;5121.462246464105;5123.871903202413;5144.536991670768;3144.213946513372;3271.607407111162;3481.3037392521596;3537.398642230801;3539.2744487380023;896.1576118448637;1026.3380119822673;1040.5317607076845;1096.2052511928787;1112.1714322829544;9494.287928405329;10178.287519873538;10819.67540973772;10842.62050828225;10848.240623156304;4270.594293542109;4337.457817772778;4447.066116115588;4465.218586747371;4465.4864690561;8533.274671652855;8537.701180458655;8546.700865469804;8555.101714320032;8564.3148548116;5058;5058.747062646853;5058.924116138261;5060.822871199564;5091.210862316347;3827.8564004157206;4183.100633363842;4687.062587482517;4764.118638051971;4770.403699537558;3550.1302180965827;3558.466230065459;3626.673599376069;3652.3060618482405;3667.908302292464;1015.4973288222377;1051.374432212832;1096.9670909872716;1101.7990390474422;1106.8548286666326;774.5120574038319;838.8867502887155;875.5359659380539;893.8167675626481;910.4127837544746;5291.523762861284;5492.368377636623;5518.420565840609;5529.861753456195;5741.971290143606;873.6505397840914;914.1224424552504;914.3782228084966;915.5422288855572;919.2462181011506;3595.5325807645363;3618.782873027627;3680.3191409589313;3779.3008293465796;3781.6785573226034;9768.413895166314;9986.450745209084;10000.199984001263;10253.974602539836;10274.640387586398;10191.337563058378;10208.744781380376;10209.336393134423;10213.642522511676;10218.897811021943;7418.406527477789;7724.420668449889;7873.566953817481;7941.126476087741;7946.443748937634;5557.44425557441;5572.637778544382;5655.377908429987;5760.164776107525;5782.971085144547;8350.123237060265;9115.544222788967;9181.265498760216;9192.678256260999;9447.905520944703;9353.672621458216;9410.811783764424;9533.142017218484;9534.666286679932;9696.95151524238;6562.8359291018105;6782.559133656297;6803.4557235422035;7041.753538626123;7253.057102576631;7631;7911.564863932309;7913.604319784103;8010.639521221676;8011.3590912728005;7030.753923612649;7167.462440316893;7214.6392680364725;7215.855682886207;7286.453515986428;7008.628763874806;7022.157341118894;7026.789196324118;7166.175889772743;7181.533200341961;5402.270693456333;6640.568363056386;6651.667416629246;6717.2946840581035;6726.831829204309;4683.742394168354;4707.929381059218;4737.857864264078;4770.642201834946;4787.521247875185;4685.882852928704;4696.483386827378;4745.715257084586;4891.559759621651;4941.061198372297 10:03:30 INFO - PROCESS | 1633 | |4;prototype.html;253.70950261950068;286.63740368433935;298.44042420461636;312.49220017471606;314.6428803308244;4945.406551213855;5299.304222648754;5307.734613269336;5347.438518955512;5363.517283444489;4265.6374208192265;4957.182064959286;5088.338515992916;5115.462876397981;5234.92147617786;3753.155540003491;3936.6063393660547;3938.5864484229173;3942.7831469269177;3951.170254246612;3827.54069511659;4026.4161696553983;4028;4058.4521089652812;4063.2279866825356;3901.6878649708096;3977.880663580097;4002;4005.0387906902424;4006.879793606182;4004.2592120457516;4016.2369149861433;4018.839246430139;4040.838366465338;4054.148628787958;3054.862531186102;3086.706762857525;3460.4117300058797;3504.5444092267844;3535.840887160084;4911;5093.886037294518;5097.057044446789;5107.642465027449;5121.4622464641425;4233.9626791435985;4453.576910193527;4516.458025037016;4530.728156310632;4588.334691469719;4199.664026877843;4621.861344159681;5479.0959491683825;5493.582823557662;5528.806491772769;3605.981970090159;3642.7814331140216;3838.7504812187117;3854.5759966403953;3868;4657.697249678794;4902.338184345139;5356.598255130881;5499.295218482243;5772.653640781525;3755.605661405568;3796.557308222913;3813.1420430402936;3814.3706288462095;3823.3500304948225;3171.5084161954933;3859.845606175778;3873.4577159197734;3897.6492115709157;3897.902552436212;3803.6386543278345;4200.873973780792;4289.420928174656;4550.294704320836;4568.428946381702;5489.862753431116;5526.9049332374;5548.500634942875;5550.91673624896;5554.333479982428;1066.7386490309768;1204.9518019279305;1242.6520574239228;1245.3959829482694;1249.5626530714178;785.3873978296938;789.6170357376668;806.6047636657995;808.8099296665279;845.3659755183612;4197.775179154992;5023.874403139951;5025.522575355335;5035.19139222042;5047.9364111889545;4704.964907720365;4756.001239739693;5040.899182016339;5392.973035134799;5395.487428694268;4032.697547683935;4101.179764047202;4889.975550122226;4936.308916751657;5003.324551185616;2462.5936720440927;2677.919662410131;2804.9761836929374;2825.56203788409;2825.7252911406104;4798.95201047992;5064.974675126601;5645.5483561314995;5654.491095801398;5682.948654498931;5094.592432605456;5442.836714898559;5478.01395748769;5492.752826122754;5495.587830912697;5164.225366195025;5553.444655534495;5598.412166722598;5654;5674.070815922606;4752.928706069342;4911.575643063615;5148;5333.73331333424;5366.436524165063;4424.623906967944;4802.519748025309;5114.846554603219;5255.894882102413;5294.391145018372;4766.950211408988;5067.189249720027;5072.442031376472;5376.677089681266;5544.362398324083;3079.8441091235777;3234.466313058319;3317.402867483876;3590.5870824855474;3596.6403359663823;5072;5083.957788653391;5333.919991200212;5340.973295133499;5342.465753424626;4793.304970779287;5185.75541869956;5208.895822083613;5225.529702326657;5231.064506132755;4281.957180428156;4695.366125573004;5108.846734597968;5194.714290714047;5213.6350455467755;3882.6699730523223;5258.211268309784;5445.564354851523;5563;5565.248691426767;4342.500612429485;5096.51583099605;5311.309529761106;5348.117560602457;5497.945020549743;4882.902341953212;5302.867428314323;5503.944960550503;5510.421405752339;5523.922835047128 10:03:30 INFO - PROCESS | 1633 | |5;yui.html;656.3928366261209;747.379674869858;779.1507257089771;784.1256998446731;788.8146285622878;11670.241434306765;15191.46829860955;15332.386704531833;15349.309281082351;15391.84608153918;11206.495707693153;13365.599032029031;13439.798403023962;13542.729145417086;13634.931825340884;834.2908527751408;842.0568962761715;844.1558441558442;845.5222799118509;849.668629234599;651.0754728285846;675.2572170612311;677.0386051806447;788.5190034079208;789.8775689768066;872.1409411729442;898.3397203055749;899.7255836969722;901.4275934781429;902.2736696958946;892.2415946445469;893.678275820704;894.5974311559759;894.8926128864513;895.9910400895973;510.6655140882728;533.8558589180899;642.2934771751037;659.5284371674228;665.8568407792347;14122.293885305797;14195;14207.147571145764;14234.932380071432;14307.713845723027;13057.934710326388;13351.265680387574;13389.732205355936;13456.93271533646;13464.124831885896;10807.35155890649;11648.329864208526;11722.765544689059;12161.331126788024;12278.263304201777;4214.91570168598;4292.978535107336;4367.694261401703;4405.31717583775;4411.360352748866;82546.17453825445;89947;92930;95957.52021239919;96362.036379636;10282.948585257025;10466.953304669472;10505.474726263656;10522.210834187466;10646.201534884915;16468.83531164697;19677.146813712217;19869.60260794776;19876.900615497118;19965.700514492004;7062.788116356517;7289.416846652255;7420.072490938633;7429.7399591014055;7448.627568621656;4089.4274801527213;4197.622214000755;4280.079782846703;4380.912381752347;4439.578240067189;3154.794938329001;3211.245357341023;3246.9350612988083;3248.4964830451318;3250.3824273388454;603.7554790309931;612.8712970276292;620.7516993202755;632.6393955445444;650.8991106378519;467.3503829676752;504.344352341954;557.7211394302849;600.438589918419;645.3224114679567;8459.280961118375;8480.872786908201;8501.047172633913;8576.485410875368;8589.65641374338;477.56302982770376;519.4208457569782;589.3899813692807;609.3814778000333;666.2205219892641;3837.808109594509;4272.572742725764;4365.628921541641;4365.781710914442;4529.456465224128;86008;97089.05821883523;119002.21496677557;123748;126727;74827;78315.60842195753;87563;104950;105143.47428262963;49672.50327496751;65673.34326656672;75734.86397703929;82242;84626.88283315973;8971.14774096486;9110.817783644157;9637.325387222827;9649.276304277068;9686.612535498502;50448;56204;59240.70379648074;60421.69789151026;62619;81035.59482202551;81874.18125818665;86164.13835861562;88257;90036.19927601542;12020;12312.753744924872;12971.935140324238;13052.804207937079;13133.408996595368;13437.664058398619;13502.392392342172;14157.716845663235;14289.984456578271;15176.620584485476;13547.79678304846;13750.65623359424;13775.173489590657;13914.652133696738;14054.648633783829;12832.871671283168;12951.870481295067;13513.459461621425;13591.388387522387;14070.226137562533;12048.337341445955;12143.39283035827;12770.489180432678;13032.022598305355;13033.739325213632;10845.945770271099;11006.944965275123;11266.493007814504;11266.7746645065;11404.429778511207;9733.951330243304;11239.60661376848;11327.32036077838;11494.655160345203;11534 10:03:30 INFO - PROCESS | 1633 | __end_tp_report 10:03:30 INFO - PROCESS | 1633 | __start_cc_report 10:03:30 INFO - PROCESS | 1633 | _x_x_mozilla_cycle_collect,3502 10:03:30 INFO - PROCESS | 1633 | __end_cc_report 10:03:30 INFO - PROCESS | 1633 | __startTimestamp1483553010308__endTimestamp 10:03:30 INFO - PROCESS | 1633 | 10:03:30 INFO - PROCESS | 1633 | ------- Summary: start ------- 10:03:30 INFO - PROCESS | 1633 | Number of tests: 6 10:03:30 INFO - PROCESS | 1633 | 10:03:30 INFO - PROCESS | 1633 | [#0] dojo.html Cycles:165 Average:5570.19 Median:5765.42 stddev:637.93 (11.1%) stddev-sans-first:613.72 10:03:30 INFO - PROCESS | 1633 | Values: 3265.2 3268.3 3333.1 3337.3 3375.5 4981.7 5461.3 5462.4 5544.9 5583.2 5470.8 5515.5 5579.6 5595.0 5607.2 5050.4 5071.1 5108.3 5154.5 5181.5 5032.9 5059.2 5066.7 5139.7 5190.8 5119.2 5135.2 5136.9 5143.0 5143.9 5112.1 5116.4 5116.8 5119.3 5120.0 4974.2 5070.8 5074.6 5083.1 5083.7 5584.3 5587.6 5591.3 5592.7 5611.2 5976.4 5999.0 6002.9 6014.4 6017.6 6063.8 6072.7 6083.3 6096.3 6116.8 5556.4 5573.4 5606.8 5615.1 5618.2 6141.7 6278.9 6285.7 6286.2 6289.0 4900.5 5837.4 5878.9 5884.9 5917.2 4817.4 6052.4 6082.0 6088.5 6147.8 6062.8 6065.4 6074.9 6075.8 6090.4 5881.8 5936.9 6039.0 6072.5 6104.2 4806.9 4811.2 4818.4 4827.3 4851.1 4287.1 4300.3 4309.9 4311.7 4343.6 5585.3 5623.6 5644.3 5674.6 5781.6 5199.9 5200.9 6050.6 6161.5 6163.8 5725.7 5765.4 5772.0 5777.2 5780.5 5202.2 5287.3 5300.0 5304.4 5317.7 6235.5 6279.3 6297.4 6305.0 6371.9 6186.8 6197.1 6202.8 6203.8 6212.1 6308.0 6367.4 6384.7 6386.5 6391.2 4702.6 4882.9 5732.0 5886.0 6059.9 6062.2 6064.2 6067.1 6068.4 6071.9 4536.5 5790.0 5880.3 5882.6 5896.0 5803.5 5868.2 5871.1 5872.2 5880.1 5748.5 5755.4 5762.7 5773.6 5788.9 5821.6 5845.0 5895.3 5895.8 5900.5 5727.9 5789.4 5836.2 5843.1 5846.5 10:03:30 INFO - PROCESS | 1633 | 10:03:30 INFO - PROCESS | 1633 | [#1] ext.html Cycles:180 Average:70319.11 Median:35982.64 stddev:154767.25 (430.1%) stddev-sans-first:155112.54 10:03:30 INFO - PROCESS | 1633 | Values: 462.9 497.9 511.8 522.6 561.1 28278.9 39658.6 40692.6 40745.8 42939.8 35109.3 42231.0 42395.0 42487.4 42502.0 9295.9 9448.0 9470.9 9480.2 9506.4 8426.3 8671.8 11108.2 11382.1 11429.8 11303.7 11398.4 11408.8 11418.0 11431.8 8474.7 9356.0 11343.8 11367.0 11394.1 8507.6 8630.1 8634.7 8639.4 10912.3 39487.6 39665.8 39715.6 39755.0 39778.6 3054.4 3221.9 3234.3 3377.7 3382.6 29277.6 29786.1 29823.3 29960.6 30104.2 3033.8 3146.9 3174.3 3234.1 3304.9 468206.0 479529.0 496606.0 497366.0 497532.0 60711.0 61061.7 61115.4 61150.7 61198.0 63955.4 63999.7 64041.0 64071.4 64092.0 15732.6 16227.2 16241.3 16270.4 16290.6 11211.4 11537.9 12505.1 12572.2 12625.9 16762.9 17183.4 17277.5 17288.5 17332.7 5716.6 6695.3 6716.7 6766.9 6775.1 874.9 925.6 992.1 1038.2 1043.7 29003.6 29036.1 29279.3 29396.0 29827.3 1046.4 1070.2 1070.8 1104.2 1126.0 12887.0 14793.3 14893.9 15112.5 15304.0 645611.0 648494.0 650165.7 653165.7 654340.0 56838.7 57022.1 57064.0 57080.0 57221.1 1341.9 1374.4 1393.1 1397.5 1397.6 28709.6 28876.4 29194.7 29844.7 29877.7 50776.7 51118.5 51130.7 51256.0 51256.2 583772.0 584428.0 586445.1 587476.0 587884.0 22854.2 25093.1 26392.5 27229.6 40525.6 44103.0 44110.0 44432.7 44672.3 44751.1 36196.8 36482.8 36631.3 36862.4 37649.8 33944.5 35764.8 35865.6 36705.6 36891.3 35314.6 35608.0 35676.8 36099.6 36245.3 31491.7 33125.0 33552.2 33596.0 33606.7 24471.5 24661.5 24710.5 25180.7 25241.0 10:03:30 INFO - PROCESS | 1633 | 10:03:30 INFO - PROCESS | 1633 | [#2] jquery.html Cycles:180 Average:34999.38 Median:13693.08 stddev:104450.40 (762.8%) stddev-sans-first:104711.02 10:03:30 INFO - PROCESS | 1633 | Values: 355.0 370.9 377.4 385.4 403.9 14633.3 14818.0 16047.0 19789.5 20493.9 18959.1 19035.3 19042.2 19938.7 20169.1 4725.0 5859.1 5864.5 5866.5 5870.9 4692.0 5844.9 5894.2 5900.4 5909.6 5055.5 5573.5 5833.2 5848.9 5850.8 5670.6 5746.7 5778.2 5783.5 5811.5 4844.3 5045.3 5071.5 5079.3 5083.2 20565.4 24913.4 24979.6 25301.2 25423.5 2228.3 2296.3 2373.7 2403.8 2472.8 14008.3 14979.4 15028.0 15457.5 15518.3 1785.9 1798.8 1822.9 1848.2 1865.9 116392.4 117260.2 120217.6 122087.0 122499.0 7662.0 9252.7 9667.7 9672.4 10647.4 11922.0 13146.4 13530.1 13533.1 13571.4 8151.8 8239.1 8258.3 8266.5 8398.0 7424.4 8272.5 8301.6 8415.2 8485.5 6261.5 6386.8 6388.5 6389.0 6405.9 1554.5 1689.7 1842.7 1844.7 1888.7 727.3 800.7 842.6 915.8 1090.1 15881.1 20500.9 21622.9 21671.5 21717.0 955.8 990.5 991.0 1000.8 1036.9 11084.6 11487.6 11494.3 11525.8 11612.7 619177.0 620905.0 632410.0 638886.0 640436.0 87996.0 108946.5 109984.0 110903.4 111260.4 999.0 1027.9 1070.2 1093.4 1095.6 18481.6 18768.5 19110.0 19141.6 19146.5 50105.5 50842.0 53367.7 54447.7 54844.2 69754.0 69928.0 71499.3 71884.3 73323.0 6258.2 7597.3 7638.0 7726.1 7836.7 12678.0 12695.8 12702.7 12771.8 12852.6 9429.6 10879.8 11315.4 11420.3 11467.0 9189.6 10798.0 11026.7 11097.9 11230.5 11174.9 11292.5 11458.4 11464.0 11694.3 6549.9 7195.0 7203.8 7742.0 8150.2 10539.7 10702.1 10758.9 11088.3 11158.1 10:03:30 INFO - PROCESS | 1633 | 10:03:30 INFO - PROCESS | 1633 | [#3] mootools.html Cycles:180 Average:5355.30 Median:5076.02 stddev:2928.55 (57.7%) stddev-sans-first:2922.05 10:03:30 INFO - PROCESS | 1633 | Values: 1449.2 1488.7 1620.8 1758.3 1809.5 7977.6 8257.5 8278.4 8352.1 8375.5 7335.5 7559.2 7569.2 7905.6 8135.8 2284.2 2328.1 2328.9 2329.5 2330.6 2326.3 2353.3 2357.9 2366.1 2368.2 2297.4 2309.2 2324.7 2330.8 2340.5 2296.6 2300.2 2330.1 2331.4 2334.8 2319.6 2339.9 2343.1 2345.1 2345.4 8059.6 8237.0 8245.1 8246.3 8255.0 4965.9 5116.7 5121.5 5123.9 5144.5 3144.2 3271.6 3481.3 3537.4 3539.3 896.2 1026.3 1040.5 1096.2 1112.2 9494.3 10178.3 10819.7 10842.6 10848.2 4270.6 4337.5 4447.1 4465.2 4465.5 8533.3 8537.7 8546.7 8555.1 8564.3 5058.0 5058.7 5058.9 5060.8 5091.2 3827.9 4183.1 4687.1 4764.1 4770.4 3550.1 3558.5 3626.7 3652.3 3667.9 1015.5 1051.4 1097.0 1101.8 1106.9 774.5 838.9 875.5 893.8 910.4 5291.5 5492.4 5518.4 5529.9 5742.0 873.7 914.1 914.4 915.5 919.2 3595.5 3618.8 3680.3 3779.3 3781.7 9768.4 9986.5 10000.2 10254.0 10274.6 10191.3 10208.7 10209.3 10213.6 10218.9 7418.4 7724.4 7873.6 7941.1 7946.4 5557.4 5572.6 5655.4 5760.2 5783.0 8350.1 9115.5 9181.3 9192.7 9447.9 9353.7 9410.8 9533.1 9534.7 9697.0 6562.8 6782.6 6803.5 7041.8 7253.1 7631.0 7911.6 7913.6 8010.6 8011.4 7030.8 7167.5 7214.6 7215.9 7286.5 7008.6 7022.2 7026.8 7166.2 7181.5 5402.3 6640.6 6651.7 6717.3 6726.8 4683.7 4707.9 4737.9 4770.6 4787.5 4685.9 4696.5 4745.7 4891.6 4941.1 10:03:30 INFO - PROCESS | 1633 | 10:03:30 INFO - PROCESS | 1633 | [#4] prototype.html Cycles:180 Average:4324.83 Median:4896.16 stddev:1315.85 (26.9%) stddev-sans-first:1283.57 10:03:30 INFO - PROCESS | 1633 | Values: 253.7 286.6 298.4 312.5 314.6 4945.4 5299.3 5307.7 5347.4 5363.5 4265.6 4957.2 5088.3 5115.5 5234.9 3753.2 3936.6 3938.6 3942.8 3951.2 3827.5 4026.4 4028.0 4058.5 4063.2 3901.7 3977.9 4002.0 4005.0 4006.9 4004.3 4016.2 4018.8 4040.8 4054.1 3054.9 3086.7 3460.4 3504.5 3535.8 4911.0 5093.9 5097.1 5107.6 5121.5 4234.0 4453.6 4516.5 4530.7 4588.3 4199.7 4621.9 5479.1 5493.6 5528.8 3606.0 3642.8 3838.8 3854.6 3868.0 4657.7 4902.3 5356.6 5499.3 5772.7 3755.6 3796.6 3813.1 3814.4 3823.4 3171.5 3859.8 3873.5 3897.6 3897.9 3803.6 4200.9 4289.4 4550.3 4568.4 5489.9 5526.9 5548.5 5550.9 5554.3 1066.7 1205.0 1242.7 1245.4 1249.6 785.4 789.6 806.6 808.8 845.4 4197.8 5023.9 5025.5 5035.2 5047.9 4705.0 4756.0 5040.9 5393.0 5395.5 4032.7 4101.2 4890.0 4936.3 5003.3 2462.6 2677.9 2805.0 2825.6 2825.7 4799.0 5065.0 5645.5 5654.5 5682.9 5094.6 5442.8 5478.0 5492.8 5495.6 5164.2 5553.4 5598.4 5654.0 5674.1 4752.9 4911.6 5148.0 5333.7 5366.4 4424.6 4802.5 5114.8 5255.9 5294.4 4767.0 5067.2 5072.4 5376.7 5544.4 3079.8 3234.5 3317.4 3590.6 3596.6 5072.0 5084.0 5333.9 5341.0 5342.5 4793.3 5185.8 5208.9 5225.5 5231.1 4282.0 4695.4 5108.8 5194.7 5213.6 3882.7 5258.2 5445.6 5563.0 5565.2 4342.5 5096.5 5311.3 5348.1 5497.9 4882.9 5302.9 5503.9 5510.4 5523.9 10:03:30 INFO - PROCESS | 1633 | 10:03:30 INFO - PROCESS | 1633 | [#5] yui.html Cycles:180 Average:20564.05 Median:4408.34 stddev:30231.16 (685.8%) stddev-sans-first:30279.01 10:03:30 INFO - PROCESS | 1633 | Values: 656.4 747.4 779.2 784.1 788.8 11670.2 15191.5 15332.4 15349.3 15391.8 11206.5 13365.6 13439.8 13542.7 13634.9 834.3 842.1 844.2 845.5 849.7 651.1 675.3 677.0 788.5 789.9 872.1 898.3 899.7 901.4 902.3 892.2 893.7 894.6 894.9 896.0 510.7 533.9 642.3 659.5 665.9 14122.3 14195.0 14207.1 14234.9 14307.7 13057.9 13351.3 13389.7 13456.9 13464.1 10807.4 11648.3 11722.8 12161.3 12278.3 4214.9 4293.0 4367.7 4405.3 4411.4 82546.2 89947.0 92930.0 95957.5 96362.0 10282.9 10467.0 10505.5 10522.2 10646.2 16468.8 19677.1 19869.6 19876.9 19965.7 7062.8 7289.4 7420.1 7429.7 7448.6 4089.4 4197.6 4280.1 4380.9 4439.6 3154.8 3211.2 3246.9 3248.5 3250.4 603.8 612.9 620.8 632.6 650.9 467.4 504.3 557.7 600.4 645.3 8459.3 8480.9 8501.0 8576.5 8589.7 477.6 519.4 589.4 609.4 666.2 3837.8 4272.6 4365.6 4365.8 4529.5 86008.0 97089.1 119002.2 123748.0 126727.0 74827.0 78315.6 87563.0 104950.0 105143.5 49672.5 65673.3 75734.9 82242.0 84626.9 8971.1 9110.8 9637.3 9649.3 9686.6 50448.0 56204.0 59240.7 60421.7 62619.0 81035.6 81874.2 86164.1 88257.0 90036.2 12020.0 12312.8 12971.9 13052.8 13133.4 13437.7 13502.4 14157.7 14290.0 15176.6 13547.8 13750.7 13775.2 13914.7 14054.6 12832.9 12951.9 13513.5 13591.4 14070.2 12048.3 12143.4 12770.5 13032.0 13033.7 10845.9 11006.9 11266.5 11266.8 11404.4 9734.0 11239.6 11327.3 11494.7 11534.0 10:03:30 INFO - PROCESS | 1633 | -------- Summary: end -------- 10:03:30 INFO - PROCESS | 1633 | 10:03:30 INFO - TEST-INFO | 1633: exit 0 10:03:30 INFO - TEST-OK | dromaeo_css | took 1120725ms 10:03:30 INFO - TEST-START | kraken 10:03:30 INFO - operating with platform_type : mac_ 10:03:30 INFO - Initialising browser for kraken test... 10:03:30 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/tmpYDewp1/profile http://localhost:49240/getInfo.html) 10:03:33 INFO - PROCESS | 1642 | __metrics Screen width/height:1600/1200 10:03:33 INFO - PROCESS | 1642 | colorDepth:24 10:03:33 INFO - PROCESS | 1642 | Browser inner width/height: 1024/689 10:03:33 INFO - PROCESS | 1642 | __metrics 10:03:33 INFO - PROCESS | 1642 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:03:33 INFO - PROCESS | 1642 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:03:34 INFO - TEST-INFO | 1642: exit 0 10:03:34 INFO - Browser initialized. 10:03:34 INFO - Running cycle 1/1 for kraken test... 10:03:34 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/T/tmpYDewp1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:03:46 INFO - PROCESS | 1643 | RSS: Main: 222892032 10:03:46 INFO - PROCESS | 1643 | 10:03:49 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection) 10:03:50 INFO - PROCESS | 1643 | RSS: Main: 272375808 10:03:50 INFO - PROCESS | 1643 | 10:03:52 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-dft) 10:03:53 INFO - PROCESS | 1643 | RSS: Main: 283115520 10:03:53 INFO - PROCESS | 1643 | 10:03:56 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-fft) 10:03:57 INFO - PROCESS | 1643 | RSS: Main: 380678144 10:03:57 INFO - PROCESS | 1643 | 10:03:59 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator) 10:03:59 INFO - PROCESS | 1643 | RSS: Main: 282234880 10:03:59 INFO - PROCESS | 1643 | 10:04:01 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:04:02 INFO - PROCESS | 1643 | RSS: Main: 284782592 10:04:02 INFO - PROCESS | 1643 | 10:04:05 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom) 10:04:05 INFO - PROCESS | 1643 | RSS: Main: 326381568 10:04:05 INFO - PROCESS | 1643 | 10:04:08 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate) 10:04:09 INFO - PROCESS | 1643 | RSS: Main: 326541312 10:04:09 INFO - PROCESS | 1643 | 10:04:12 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial) 10:04:12 INFO - PROCESS | 1643 | RSS: Main: 323112960 10:04:12 INFO - PROCESS | 1643 | 10:04:14 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:04:14 INFO - PROCESS | 1643 | RSS: Main: 298868736 10:04:14 INFO - PROCESS | 1643 | 10:04:16 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:04:17 INFO - PROCESS | 1643 | RSS: Main: 300728320 10:04:17 INFO - PROCESS | 1643 | 10:04:19 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:04:20 INFO - PROCESS | 1643 | RSS: Main: 303185920 10:04:20 INFO - PROCESS | 1643 | 10:04:23 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:04:23 INFO - PROCESS | 1643 | RSS: Main: 315260928 10:04:23 INFO - PROCESS | 1643 | 10:04:26 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:04:27 INFO - PROCESS | 1643 | RSS: Main: 314929152 10:04:27 INFO - PROCESS | 1643 | 10:04:29 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49240/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49240/tests/kraken/driver.html?testName=ai-astar) 10:04:29 INFO - PROCESS | 1643 | RSS: Main: 358936576 10:04:29 INFO - PROCESS | 1643 | 10:04:29 INFO - PROCESS | 1643 | __start_tp_report 10:04:29 INFO - PROCESS | 1643 | _x_x_mozilla_page_load 10:04:29 INFO - PROCESS | 1643 | _x_x_mozilla_page_load_details 10:04:29 INFO - PROCESS | 1643 | |i|pagename|runs| 10:04:29 INFO - PROCESS | 1643 | |0;ai-astar;114;114;114;113;112;114;111;113;113;113 10:04:29 INFO - PROCESS | 1643 | |1;audio-beat-detection;145;109;109;142;101;111;123;144;171;108 10:04:29 INFO - PROCESS | 1643 | |2;audio-dft;181;183;181;184;184;181;181;184;182;185 10:04:29 INFO - PROCESS | 1643 | |3;audio-fft;81;59;60;88;60;59;59;70;106;79 10:04:29 INFO - PROCESS | 1643 | |4;audio-oscillator;87;70;94;69;68;67;89;67;67;91 10:04:29 INFO - PROCESS | 1643 | |5;imaging-gaussian-blur;86;96;107;83;86;82;87;84;85;83 10:04:29 INFO - PROCESS | 1643 | |6;imaging-darkroom;92;93;93;94;93;93;93;93;92;92 10:04:29 INFO - PROCESS | 1643 | |7;imaging-desaturate;82;97;122;122;85;83;82;84;83;80 10:04:29 INFO - PROCESS | 1643 | |8;json-parse-financial;53;53;54;52;56;58;57;57;55;57 10:04:29 INFO - PROCESS | 1643 | |9;json-stringify-tinderbox;66;75;76;74;71;70;70;71;71;71 10:04:29 INFO - PROCESS | 1643 | |10;stanford-crypto-aes;84;85;84;86;84;81;82;80;83;82 10:04:29 INFO - PROCESS | 1643 | |11;stanford-crypto-ccm;140;129;150;143;151;140;142;144;182;148 10:04:29 INFO - PROCESS | 1643 | |12;stanford-crypto-pbkdf2;162;165;167;163;163;164;164;223;208;166 10:04:29 INFO - PROCESS | 1643 | |13;stanford-crypto-sha256-iterative;68;67;68;69;67;68;65;67;68;67 10:04:29 INFO - PROCESS | 1643 | __end_tp_report 10:04:29 INFO - PROCESS | 1643 | __start_cc_report 10:04:29 INFO - PROCESS | 1643 | _x_x_mozilla_cycle_collect,1976 10:04:29 INFO - PROCESS | 1643 | __end_cc_report 10:04:29 INFO - PROCESS | 1643 | __startTimestamp1483553069274__endTimestamp 10:04:29 INFO - PROCESS | 1643 | 10:04:29 INFO - PROCESS | 1643 | ------- Summary: start ------- 10:04:29 INFO - PROCESS | 1643 | Number of tests: 14 10:04:29 INFO - PROCESS | 1643 | 10:04:29 INFO - PROCESS | 1643 | [#0] ai-astar Cycles:10 Average:113.10 Median:113.50 stddev:0.99 (0.9%) stddev-sans-first:1.00 10:04:29 INFO - PROCESS | 1643 | Values: 114.0 114.0 114.0 113.0 112.0 114.0 111.0 113.0 113.0 113.0 10:04:29 INFO - PROCESS | 1643 | 10:04:29 INFO - PROCESS | 1643 | [#1] audio-beat-detection Cycles:10 Average:126.30 Median:132.50 stddev:22.92 (17.3%) stddev-sans-first:23.29 10:04:29 INFO - PROCESS | 1643 | Values: 145.0 109.0 109.0 142.0 101.0 111.0 123.0 144.0 171.0 108.0 10:04:29 INFO - PROCESS | 1643 | 10:04:29 INFO - PROCESS | 1643 | [#2] audio-dft Cycles:10 Average:182.60 Median:183.50 stddev:1.58 (0.9%) stddev-sans-first:1.56 10:04:29 INFO - PROCESS | 1643 | Values: 181.0 183.0 181.0 184.0 184.0 181.0 181.0 184.0 182.0 185.0 10:04:29 INFO - PROCESS | 1643 | 10:04:29 INFO - PROCESS | 1643 | [#3] audio-fft Cycles:10 Average:72.10 Median:65.00 stddev:16.13 (24.8%) stddev-sans-first:16.78 10:04:29 INFO - PROCESS | 1643 | Values: 81.0 59.0 60.0 88.0 60.0 59.0 59.0 70.0 106.0 79.0 10:04:29 INFO - PROCESS | 1643 | 10:04:29 INFO - PROCESS | 1643 | [#4] audio-oscillator Cycles:10 Average:76.90 Median:78.50 stddev:11.66 (14.8%) stddev-sans-first:11.78 10:04:29 INFO - PROCESS | 1643 | Values: 87.0 70.0 94.0 69.0 68.0 67.0 89.0 67.0 67.0 91.0 10:04:29 INFO - PROCESS | 1643 | 10:04:29 INFO - PROCESS | 1643 | [#5] imaging-gaussian-blur Cycles:10 Average:87.90 Median:85.50 stddev:7.78 (9.1%) stddev-sans-first:8.22 10:04:29 INFO - PROCESS | 1643 | Values: 86.0 96.0 107.0 83.0 86.0 82.0 87.0 84.0 85.0 83.0 10:04:29 INFO - PROCESS | 1643 | 10:04:29 INFO - PROCESS | 1643 | [#6] imaging-darkroom Cycles:10 Average:92.80 Median:93.00 stddev:0.63 (0.7%) stddev-sans-first:0.60 10:04:29 INFO - PROCESS | 1643 | Values: 92.0 93.0 93.0 94.0 93.0 93.0 93.0 93.0 92.0 92.0 10:04:29 INFO - PROCESS | 1643 | 10:04:29 INFO - PROCESS | 1643 | [#7] imaging-desaturate Cycles:10 Average:92.00 Median:83.00 stddev:16.48 (19.9%) stddev-sans-first:17.08 10:04:29 INFO - PROCESS | 1643 | Values: 82.0 97.0 122.0 122.0 85.0 83.0 82.0 84.0 83.0 80.0 10:04:29 INFO - PROCESS | 1643 | 10:04:29 INFO - PROCESS | 1643 | [#8] json-parse-financial Cycles:10 Average:55.20 Median:56.50 stddev:2.10 (3.7%) stddev-sans-first:2.07 10:04:29 INFO - PROCESS | 1643 | Values: 53.0 53.0 54.0 52.0 56.0 58.0 57.0 57.0 55.0 57.0 10:04:29 INFO - PROCESS | 1643 | 10:04:29 INFO - PROCESS | 1643 | [#9] json-stringify-tinderbox Cycles:10 Average:71.50 Median:71.00 stddev:2.88 (4.1%) stddev-sans-first:2.26 10:04:29 INFO - PROCESS | 1643 | Values: 66.0 75.0 76.0 74.0 71.0 70.0 70.0 71.0 71.0 71.0 10:04:29 INFO - PROCESS | 1643 | 10:04:29 INFO - PROCESS | 1643 | [#10] stanford-crypto-aes Cycles:10 Average:83.10 Median:84.00 stddev:1.85 (2.2%) stddev-sans-first:1.94 10:04:29 INFO - PROCESS | 1643 | Values: 84.0 85.0 84.0 86.0 84.0 81.0 82.0 80.0 83.0 82.0 10:04:29 INFO - PROCESS | 1643 | 10:04:29 INFO - PROCESS | 1643 | [#11] stanford-crypto-ccm Cycles:10 Average:146.90 Median:146.00 stddev:13.84 (9.5%) stddev-sans-first:14.45 10:04:29 INFO - PROCESS | 1643 | Values: 140.0 129.0 150.0 143.0 151.0 140.0 142.0 144.0 182.0 148.0 10:04:29 INFO - PROCESS | 1643 | 10:04:29 INFO - PROCESS | 1643 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.50 Median:165.50 stddev:21.95 (13.3%) stddev-sans-first:22.81 10:04:29 INFO - PROCESS | 1643 | Values: 162.0 165.0 167.0 163.0 163.0 164.0 164.0 223.0 208.0 166.0 10:04:29 INFO - PROCESS | 1643 | 10:04:29 INFO - PROCESS | 1643 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:68.00 stddev:1.07 (1.6%) stddev-sans-first:1.12 10:04:29 INFO - PROCESS | 1643 | Values: 68.0 67.0 68.0 69.0 67.0 68.0 65.0 67.0 68.0 67.0 10:04:29 INFO - PROCESS | 1643 | -------- Summary: end -------- 10:04:29 INFO - PROCESS | 1643 | 10:04:30 INFO - TEST-INFO | 1643: exit 0 10:04:30 INFO - TEST-OK | kraken | took 59514ms 10:04:30 INFO - SUITE-END | took 1180s 10:04:30 INFO - Completed test suite (00:19:40) 10:04:30 INFO - javascript benchmark 10:04:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3265.1510607242117, 3268.329992351568, 3333.0667413124334, 3337.349216902705, 3375.4768010958296, 4981.651284410088, 5461.262729531512, 5462.399136095027, 5544.86137846554, 5583.162525621159, 5470.8085217017315, 5515.4760297771645, 5579.581531385153, 5594.9720251398685, 5607.243022192003, 5050.419201791795, 5071.087204303224, 5108.3359163308905, 5154.536091751742, 5181.481851814826, 5032.874178145539, 5059.241113832918, 5066.74666266687, 5139.6659217150955, 5190.844274671747, 5119.206522988924, 5135.1526998045465, 5136.922946155811, 5143.022825663131, 5143.897122057575, 5112.130937740593, 5116.386033675945, 5116.795328186868, 5119.308893299394, 5119.974400128012, 4974.204127339644, 5070.797168113271, 5074.568661663763, 5083.085044691954, 5083.694978301313, 5584.274044374246, 5587.636803607793, 5591.329040515164, 5592.664440133564, 5611.2144299798065, 5976.372480889488, 5998.970005149947, 6002.879942401171, 6014.3684913083935, 6017.608855424383, 6063.848403789896, 6072.666003369813, 6083.330833608299, 6096.268447786294, 6116.816495505142, 5556.388797232301, 5573.386927437979, 5606.803761868315, 5615.129654903497, 5618.15727640853, 6141.692915354214, 6278.905816412757, 6285.748570057147, 6286.151369565142, 6289.0, 4900.534449227318, 5837.416258374214, 5878.882422351615, 5884.941150588439, 5917.201177840936, 4817.397825271841, 6052.425019623129, 6082.0, 6088.4824789893355, 6147.754089836357, 6062.787802426894, 6065.3934606539, 6074.908876366858, 6075.848103797441, 6090.421409966135, 5881.823545293562, 5936.910946335808, 6039.033754599243, 6072.483838873745, 6104.17593624855, 4806.927896081562, 4811.182099043171, 4818.4217893852965, 4827.300041493963, 4851.102546028996, 4287.056847493546, 4300.311950087971, 4309.870703878889, 4311.655067594647, 4343.5656434356315, 5585.3297604287745, 5623.578231632644, 5644.322681278273, 5674.602777805514, 5781.566382521328, 5199.922001169985, 5200.92198617021, 6050.57645964787, 6161.537884658668, 6163.753449862045, 5725.656460612293, 5765.423457654285, 5772.018756811268, 5777.162311464814, 5780.479756821822, 5202.193659982709, 5287.286216360819, 5300.045991721528, 5304.363476382781, 5317.73411329432, 6235.469985051322, 6279.30927597973, 6297.433231009231, 6305.0, 6371.87256254863, 6186.845328866634, 6197.101420294122, 6202.844928876633, 6203.8138855834395, 6212.099245609452, 6308.0, 6367.42693157618, 6384.680765961591, 6386.457151142205, 6391.169148010729, 4702.636235491806, 4882.877928051827, 5732.0, 5886.028805247086, 6059.878802424015, 6062.151298818081, 6064.211652485325, 6067.059605761114, 6068.362821903813, 6071.908921366095, 4536.478304994967, 5789.957807594674, 5880.323762767336, 5882.617629854046, 5895.970520147372, 5803.506701930383, 5868.2077919480325, 5871.1486834409625, 5872.207252020922, 5880.117982302689, 5748.48263656273, 5755.366909639853, 5762.654240745569, 5773.595848290579, 5788.942110579009, 5821.592488525932, 5845.0, 5895.263092113486, 5895.823125306248, 5900.468957793819, 5727.885442291047, 5789.421057894177, 5836.241288632451, 5843.094320380348, 5846.473817356458], "value": 5531.63756859667, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [462.8889066624011, 497.8574172274629, 511.8183045019018, 522.6184885033925, 561.1134407635933, 28278.858605706995, 39658.60341396585, 40692.59307406925, 40745.79627101864, 42939.785301073454, 35109.29781404377, 42231.0, 42395.0, 42487.3626895596, 42502.0, 9295.93096793869, 9448.0, 9470.905290947107, 9480.194183494412, 9506.382085164441, 8426.283765879907, 8671.783205419852, 11108.222424430292, 11382.146339024564, 11429.828552571718, 11303.660890173307, 11398.373089480076, 11408.771824563504, 11417.972382485576, 11431.828522572127, 8474.703385381483, 9356.0, 11343.773124537545, 11367.0, 11394.088472922187, 8507.61715722794, 8630.136986301382, 8634.740957771277, 8639.39524233304, 10912.290701104403, 39487.60512394868, 39665.80167099175, 39715.60284397177, 39755.0, 39778.60221397778, 3054.404391143728, 3221.8872339468007, 3234.3046245057426, 3377.7466689998105, 3382.6279109297957, 29277.56083658747, 29786.10641680753, 29823.25441863949, 29960.550591740925, 30104.24739381533, 3033.7572994160414, 3146.8583913723937, 3174.3333899881054, 3234.094453552985, 3304.884329048472, 468206.0, 479528.9999999965, 496606.0, 497366.0, 497532.0, 60711.0, 61061.69469152626, 61115.38884611186, 61150.69424652848, 61198.0, 63955.36044639587, 63999.68000159969, 64041.0, 64071.359286406536, 64092.0, 15732.606684832741, 16227.188640567925, 16241.2691428886, 16270.43053493122, 16290.592735181715, 11211.383373914514, 11537.942310288563, 12505.124641275024, 12572.245665260114, 12625.873741262654, 16762.916185418995, 17183.3985810496, 17277.481675549752, 17288.481345559652, 17332.65334693299, 5716.628419152742, 6695.263521012782, 6716.697748601325, 6766.864662706718, 6775.085363475868, 874.8731433942042, 925.6204955968021, 992.1071036067597, 1038.2317085356933, 1043.6764602973103, 29003.564946525818, 29036.12891613255, 29279.268018299288, 29395.97114101039, 29827.254318642208, 1046.4401545173296, 1070.175964507325, 1070.7911957168278, 1104.2435931387026, 1126.0316128129803, 12887.0, 14793.260336983109, 14893.925530372278, 15112.471063512725, 15304.0, 645611.0, 648494.0, 650165.7491712606, 653165.7341713262, 654340.0, 56838.7158064207, 57022.14466783001, 57064.0, 57080.0, 57221.14168287479, 1341.8926485881107, 1374.3952660829202, 1393.136255521583, 1397.5038861204125, 1397.5527831093952, 28709.56935645925, 28876.42247155087, 29194.7080529192, 29844.70155298506, 29877.701222987493, 50776.74611626918, 51118.48881511137, 51130.74434627803, 51256.0, 51256.23115653343, 583772.0, 584428.0, 586445.0677746584, 587476.0, 587884.0, 22854.200102996314, 25093.121740738985, 26392.472150557267, 27229.591556126277, 40525.594744053364, 44103.0, 44110.0, 44432.66701998945, 44672.32991505065, 44751.10497790091, 36196.81901590475, 36482.8175859119, 36631.26737465289, 36862.44706329353, 37649.81175094107, 33944.490832637035, 35764.82117589395, 35865.641343587275, 36705.63294367022, 36891.26217475689, 35314.646853531136, 35608.0, 35676.82161589176, 36099.63900360963, 36245.27509449743, 31491.685083148874, 33125.0, 33552.16119597029, 33595.992120236435, 33606.66393336035, 24471.51056978886, 24661.506769864143, 24710.50578988446, 25180.748192518575, 25241.0], "value": 19734.138792530357, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.0325363384778, 370.8942951258892, 377.4338492261606, 385.4353372309566, 403.8525938032619, 14633.268336583169, 14818.03682760621, 16047.0, 19789.505262368413, 20493.897530512364, 18959.14683839227, 19035.333763318285, 19042.2383104676, 19938.700919486182, 20169.092390842412, 4724.976375118128, 5859.091840764689, 5864.501517371029, 5866.5306775458075, 5870.853228669274, 4691.967767091234, 5844.883102337951, 5894.233749612566, 5900.380460051697, 5909.645421274716, 5055.494450554934, 5573.526250268732, 5833.154192642064, 5848.912266316009, 5850.824475265727, 5670.574706896979, 5746.741396637161, 5778.24882765242, 5783.450572195635, 5811.5350771938565, 4844.32179494871, 5045.344105266292, 5071.54356107952, 5079.263506791532, 5083.186690129562, 20565.38303850872, 24913.377165570826, 24979.62530562043, 25301.240962771146, 25423.49153016948, 2228.342638921522, 2296.311106667993, 2373.703287089114, 2403.771641694036, 2472.777450029506, 14008.299585020708, 14979.400823967027, 15028.0, 15457.480947651566, 15518.301676424588, 1785.8839175453554, 1798.8291112344436, 1822.906256246255, 1848.223746026672, 1865.8507319414414, 116392.41803790927, 117260.24109638362, 120217.57932996351, 122087.0, 122499.0, 7662.0, 9252.67615633463, 9667.743193384815, 9672.371295865747, 10647.414392208348, 11922.0, 13146.4084116215, 13530.052896297362, 13533.052686311863, 13571.389287482087, 8151.755447336471, 8239.093699693029, 8258.298044666271, 8266.462679925786, 8398.0, 7424.406047516294, 8272.503649781032, 8301.626426810808, 8415.158484151536, 8485.533295668674, 6261.499080073583, 6386.808395748135, 6388.520860935449, 6388.968055159788, 6405.935940640534, 1554.4947891935167, 1689.6705142497342, 1842.7420161177447, 1844.7140693192578, 1888.6789245828243, 727.2799928071172, 800.6717245929258, 842.5702891525243, 915.7527467583715, 1090.0734375780523, 15881.126538040518, 20500.89749551243, 21622.89188554047, 21671.45821354479, 21717.02273397701, 955.7658373698488, 990.4598349566493, 990.9554070066864, 1000.8098461292332, 1036.8548403223556, 11084.612038578773, 11487.597934072268, 11494.310341379376, 11525.76948461026, 11612.6516204514, 619177.0, 620905.0, 632410.0, 638886.0, 640436.0, 87996.0, 108946.45526772315, 109984.0, 110903.44548277208, 111260.443697781, 999.0, 1027.917766578687, 1070.2347821307803, 1093.4095588382183, 1095.638439315024, 18481.630367392845, 18768.530786729894, 19110.0, 19141.61716765685, 19146.52133696669, 50105.498945010084, 50841.983160337324, 53367.7331613355, 54447.72776136094, 54844.17733734077, 69754.0, 69928.0, 71499.28500714926, 71884.28115718775, 73323.0, 6258.155149055002, 7597.278258565428, 7637.961810190914, 7726.1114971776715, 7836.686532538634, 12678.0, 12695.80956285638, 12702.745945080862, 12771.80842287385, 12852.55016074433, 9429.57566909477, 10879.836802448128, 11315.434228288717, 11420.257683250562, 11467.025302849017, 9189.632414703337, 10798.0, 11026.724331891766, 11097.889021110008, 11230.494627741607, 11174.88825111771, 11292.49183786748, 11458.427078645867, 11463.968242858184, 11694.2983420995, 6549.901751473636, 7195.0, 7203.783886483414, 7742.0, 8150.18498150204, 10539.736506587396, 10702.09032232269, 10758.946205268923, 11088.33469991803, 11158.107351411705], "value": 9421.507724806317, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1449.2174225918002, 1488.7022595480903, 1620.8379162083793, 1758.34062226665, 1809.5295223241963, 7977.601119944002, 8257.504549727013, 8278.379121565891, 8352.081271060179, 8375.53934533602, 7335.486515943886, 7559.206283340252, 7569.205233450477, 7905.604719764017, 8135.755927322175, 2284.223364056225, 2328.0571368595747, 2328.871912044837, 2329.5108027314286, 2330.615448451012, 2326.325365643961, 2353.305774796439, 2357.870317132557, 2366.0890557135453, 2368.2421625079983, 2297.4026753044245, 2309.2494939144763, 2324.7094113235844, 2330.778576035274, 2340.508493216427, 2296.5751336002727, 2300.217925905192, 2330.0912644068826, 2331.370529956919, 2334.766523347652, 2319.5708793873187, 2339.9298021059394, 2343.0510643189536, 2345.1440224318003, 2345.3784747042046, 8059.556724380157, 8236.958815205886, 8245.051819040767, 8246.340292776624, 8255.0, 4965.925511117336, 5116.692998420107, 5121.462246464105, 5123.871903202413, 5144.536991670768, 3144.213946513372, 3271.607407111162, 3481.3037392521596, 3537.398642230801, 3539.2744487380023, 896.1576118448637, 1026.3380119822673, 1040.5317607076845, 1096.2052511928787, 1112.1714322829544, 9494.287928405329, 10178.287519873538, 10819.67540973772, 10842.62050828225, 10848.240623156304, 4270.594293542109, 4337.457817772778, 4447.066116115588, 4465.218586747371, 4465.4864690561, 8533.274671652855, 8537.701180458655, 8546.700865469804, 8555.101714320032, 8564.3148548116, 5058.0, 5058.747062646853, 5058.924116138261, 5060.822871199564, 5091.210862316347, 3827.8564004157206, 4183.100633363842, 4687.062587482517, 4764.118638051971, 4770.403699537558, 3550.1302180965827, 3558.466230065459, 3626.673599376069, 3652.3060618482405, 3667.908302292464, 1015.4973288222377, 1051.374432212832, 1096.9670909872716, 1101.7990390474422, 1106.8548286666326, 774.5120574038319, 838.8867502887155, 875.5359659380539, 893.8167675626481, 910.4127837544746, 5291.523762861284, 5492.368377636623, 5518.420565840609, 5529.861753456195, 5741.971290143606, 873.6505397840914, 914.1224424552504, 914.3782228084966, 915.5422288855572, 919.2462181011506, 3595.5325807645363, 3618.782873027627, 3680.3191409589313, 3779.3008293465796, 3781.6785573226034, 9768.413895166314, 9986.450745209084, 10000.199984001263, 10253.974602539836, 10274.640387586398, 10191.337563058378, 10208.744781380376, 10209.336393134423, 10213.642522511676, 10218.897811021943, 7418.406527477789, 7724.420668449889, 7873.566953817481, 7941.126476087741, 7946.443748937634, 5557.44425557441, 5572.637778544382, 5655.377908429987, 5760.164776107525, 5782.971085144547, 8350.123237060265, 9115.544222788967, 9181.265498760216, 9192.678256260999, 9447.905520944703, 9353.672621458216, 9410.811783764424, 9533.142017218484, 9534.666286679932, 9696.95151524238, 6562.8359291018105, 6782.559133656297, 6803.4557235422035, 7041.753538626123, 7253.057102576631, 7631.0, 7911.564863932309, 7913.604319784103, 8010.639521221676, 8011.3590912728005, 7030.753923612649, 7167.462440316893, 7214.6392680364725, 7215.855682886207, 7286.453515986428, 7008.628763874806, 7022.157341118894, 7026.789196324118, 7166.175889772743, 7181.533200341961, 5402.270693456333, 6640.568363056386, 6651.667416629246, 6717.2946840581035, 6726.831829204309, 4683.742394168354, 4707.929381059218, 4737.857864264078, 4770.642201834946, 4787.521247875185, 4685.882852928704, 4696.483386827378, 4745.715257084586, 4891.559759621651, 4941.061198372297], "value": 4340.445695152093, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [253.70950261950068, 286.63740368433935, 298.44042420461636, 312.49220017471606, 314.6428803308244, 4945.406551213855, 5299.304222648754, 5307.734613269336, 5347.438518955512, 5363.517283444489, 4265.6374208192265, 4957.182064959286, 5088.338515992916, 5115.462876397981, 5234.92147617786, 3753.155540003491, 3936.6063393660547, 3938.5864484229173, 3942.7831469269177, 3951.170254246612, 3827.54069511659, 4026.4161696553983, 4028.0, 4058.4521089652812, 4063.2279866825356, 3901.6878649708096, 3977.880663580097, 4002.0, 4005.0387906902424, 4006.879793606182, 4004.2592120457516, 4016.2369149861433, 4018.839246430139, 4040.838366465338, 4054.148628787958, 3054.862531186102, 3086.706762857525, 3460.4117300058797, 3504.5444092267844, 3535.840887160084, 4911.0, 5093.886037294518, 5097.057044446789, 5107.642465027449, 5121.4622464641425, 4233.9626791435985, 4453.576910193527, 4516.458025037016, 4530.728156310632, 4588.334691469719, 4199.664026877843, 4621.861344159681, 5479.0959491683825, 5493.582823557662, 5528.806491772769, 3605.981970090159, 3642.7814331140216, 3838.7504812187117, 3854.5759966403953, 3868.0, 4657.697249678794, 4902.338184345139, 5356.598255130881, 5499.295218482243, 5772.653640781525, 3755.605661405568, 3796.557308222913, 3813.1420430402936, 3814.3706288462095, 3823.3500304948225, 3171.5084161954933, 3859.845606175778, 3873.4577159197734, 3897.6492115709157, 3897.902552436212, 3803.6386543278345, 4200.873973780792, 4289.420928174656, 4550.294704320836, 4568.428946381702, 5489.862753431116, 5526.9049332374, 5548.500634942875, 5550.91673624896, 5554.333479982428, 1066.7386490309768, 1204.9518019279305, 1242.6520574239228, 1245.3959829482694, 1249.5626530714178, 785.3873978296938, 789.6170357376668, 806.6047636657995, 808.8099296665279, 845.3659755183612, 4197.775179154992, 5023.874403139951, 5025.522575355335, 5035.19139222042, 5047.9364111889545, 4704.964907720365, 4756.001239739693, 5040.899182016339, 5392.973035134799, 5395.487428694268, 4032.697547683935, 4101.179764047202, 4889.975550122226, 4936.308916751657, 5003.324551185616, 2462.5936720440927, 2677.919662410131, 2804.9761836929374, 2825.56203788409, 2825.7252911406104, 4798.95201047992, 5064.974675126601, 5645.5483561314995, 5654.491095801398, 5682.948654498931, 5094.592432605456, 5442.836714898559, 5478.01395748769, 5492.752826122754, 5495.587830912697, 5164.225366195025, 5553.444655534495, 5598.412166722598, 5654.0, 5674.070815922606, 4752.928706069342, 4911.575643063615, 5148.0, 5333.73331333424, 5366.436524165063, 4424.623906967944, 4802.519748025309, 5114.846554603219, 5255.894882102413, 5294.391145018372, 4766.950211408988, 5067.189249720027, 5072.442031376472, 5376.677089681266, 5544.362398324083, 3079.8441091235777, 3234.466313058319, 3317.402867483876, 3590.5870824855474, 3596.6403359663823, 5072.0, 5083.957788653391, 5333.919991200212, 5340.973295133499, 5342.465753424626, 4793.304970779287, 5185.75541869956, 5208.895822083613, 5225.529702326657, 5231.064506132755, 4281.957180428156, 4695.366125573004, 5108.846734597968, 5194.714290714047, 5213.6350455467755, 3882.6699730523223, 5258.211268309784, 5445.564354851523, 5563.0, 5565.248691426767, 4342.500612429485, 5096.51583099605, 5311.309529761106, 5348.117560602457, 5497.945020549743, 4882.902341953212, 5302.867428314323, 5503.944960550503, 5510.421405752339, 5523.922835047128], "value": 3899.3038651674556, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [656.3928366261209, 747.379674869858, 779.1507257089771, 784.1256998446731, 788.8146285622878, 11670.241434306765, 15191.46829860955, 15332.386704531833, 15349.309281082351, 15391.84608153918, 11206.495707693153, 13365.599032029031, 13439.798403023962, 13542.729145417086, 13634.931825340884, 834.2908527751408, 842.0568962761715, 844.1558441558442, 845.5222799118509, 849.668629234599, 651.0754728285846, 675.2572170612311, 677.0386051806447, 788.5190034079208, 789.8775689768066, 872.1409411729442, 898.3397203055749, 899.7255836969722, 901.4275934781429, 902.2736696958946, 892.2415946445469, 893.678275820704, 894.5974311559759, 894.8926128864513, 895.9910400895973, 510.6655140882728, 533.8558589180899, 642.2934771751037, 659.5284371674228, 665.8568407792347, 14122.293885305797, 14195.0, 14207.147571145764, 14234.932380071432, 14307.713845723027, 13057.934710326388, 13351.265680387574, 13389.732205355936, 13456.93271533646, 13464.124831885896, 10807.35155890649, 11648.329864208526, 11722.765544689059, 12161.331126788024, 12278.263304201777, 4214.91570168598, 4292.978535107336, 4367.694261401703, 4405.31717583775, 4411.360352748866, 82546.17453825445, 89947.0, 92930.0, 95957.52021239919, 96362.036379636, 10282.948585257025, 10466.953304669472, 10505.474726263656, 10522.210834187466, 10646.201534884915, 16468.83531164697, 19677.146813712217, 19869.60260794776, 19876.900615497118, 19965.700514492004, 7062.788116356517, 7289.416846652255, 7420.072490938633, 7429.7399591014055, 7448.627568621656, 4089.4274801527213, 4197.622214000755, 4280.079782846703, 4380.912381752347, 4439.578240067189, 3154.794938329001, 3211.245357341023, 3246.9350612988083, 3248.4964830451318, 3250.3824273388454, 603.7554790309931, 612.8712970276292, 620.7516993202755, 632.6393955445444, 650.8991106378519, 467.3503829676752, 504.344352341954, 557.7211394302849, 600.438589918419, 645.3224114679567, 8459.280961118375, 8480.872786908201, 8501.047172633913, 8576.485410875368, 8589.65641374338, 477.56302982770376, 519.4208457569782, 589.3899813692807, 609.3814778000333, 666.2205219892641, 3837.808109594509, 4272.572742725764, 4365.628921541641, 4365.781710914442, 4529.456465224128, 86008.0, 97089.05821883523, 119002.21496677557, 123748.0, 126727.0, 74827.0, 78315.60842195753, 87563.0, 104950.0, 105143.47428262963, 49672.50327496751, 65673.34326656672, 75734.86397703929, 82242.0, 84626.88283315973, 8971.14774096486, 9110.817783644157, 9637.325387222827, 9649.276304277068, 9686.612535498502, 50448.0, 56204.0, 59240.70379648074, 60421.69789151026, 62619.0, 81035.59482202551, 81874.18125818665, 86164.13835861562, 88257.0, 90036.19927601542, 12020.0, 12312.753744924872, 12971.935140324238, 13052.804207937079, 13133.408996595368, 13437.664058398619, 13502.392392342172, 14157.716845663235, 14289.984456578271, 15176.620584485476, 13547.79678304846, 13750.65623359424, 13775.173489590657, 13914.652133696738, 14054.648633783829, 12832.871671283168, 12951.870481295067, 13513.459461621425, 13591.388387522387, 14070.226137562533, 12048.337341445955, 12143.39283035827, 12770.489180432678, 13032.022598305355, 13033.739325213632, 10845.945770271099, 11006.944965275123, 11266.493007814504, 11266.7746645065, 11404.429778511207, 9733.951330243304, 11239.60661376848, 11327.32036077838, 11494.655160345203, 11534.0], "value": 7248.584324984566, "unit": "score"}], "name": "dromaeo_css", "value": 7082.221210903511, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 114.0, 114.0, 113.0, 112.0, 114.0, 111.0, 113.0, 113.0, 113.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 109.0, 109.0, 142.0, 101.0, 111.0, 123.0, 144.0, 171.0, 108.0], "value": 126.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 183.0, 181.0, 184.0, 184.0, 181.0, 181.0, 184.0, 182.0, 185.0], "value": 182.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 60.0, 88.0, 60.0, 59.0, 59.0, 70.0, 106.0, 79.0], "value": 72.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 70.0, 94.0, 69.0, 68.0, 67.0, 89.0, 67.0, 67.0, 91.0], "value": 76.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 96.0, 107.0, 83.0, 86.0, 82.0, 87.0, 84.0, 85.0, 83.0], "value": 87.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 93.0, 94.0, 93.0, 93.0, 93.0, 93.0, 92.0, 92.0], "value": 92.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 97.0, 122.0, 122.0, 85.0, 83.0, 82.0, 84.0, 83.0, 80.0], "value": 92.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 54.0, 52.0, 56.0, 58.0, 57.0, 57.0, 55.0, 57.0], "value": 55.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 75.0, 76.0, 74.0, 71.0, 70.0, 70.0, 71.0, 71.0, 71.0], "value": 71.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 84.0, 86.0, 84.0, 81.0, 82.0, 80.0, 83.0, 82.0], "value": 83.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 129.0, 150.0, 143.0, 151.0, 140.0, 142.0, 144.0, 182.0, 148.0], "value": 146.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 165.0, 167.0, 163.0, 163.0, 164.0, 164.0, 223.0, 208.0, 166.0], "value": 174.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 68.0, 69.0, 67.0, 68.0, 65.0, 67.0, 68.0, 67.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1442.3000000000002, "alertThreshold": 2.0}]} 10:04:31 INFO - Return code: 0 10:04:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:04:31 INFO - # TBPL SUCCESS # 10:04:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:04:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:04:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:04:31 INFO - 2017-01-04 10:04:31.115 screenresolution[1647:9933] starting screenresolution argv=screenresolution get 10:04:31 INFO - 2017-01-04 10:04:31.118 screenresolution[1647:9933] Display 0: 1600x1200x32@60 10:04:31 INFO - 2017-01-04 10:04:31.124 screenresolution[1648:9936] starting screenresolution argv=screenresolution list 10:04:31 INFO - Available Modes on Display 0 10:04:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:04:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:04:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:04:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:04:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:04:31 INFO - Intel Iris: 10:04:31 INFO - Chipset Model: Intel Iris 10:04:31 INFO - Type: GPU 10:04:31 INFO - Bus: Built-In 10:04:31 INFO - VRAM (Dynamic, Max): 1536 MB 10:04:31 INFO - Vendor: Intel (0x8086) 10:04:31 INFO - Device ID: 0x0a2e 10:04:31 INFO - Revision ID: 0x0009 10:04:31 INFO - Displays: 10:04:31 INFO - Display: 10:04:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:04:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:04:31 INFO - Display Serial Number: 1600x1200 60 10:04:31 INFO - Main Display: Yes 10:04:31 INFO - Mirror: Off 10:04:31 INFO - Online: Yes 10:04:31 INFO - Rotation: Supported 10:04:31 INFO - Return code: 0 10:04:31 INFO - Running post-action listener: _resource_record_post_action 10:04:31 INFO - [mozharness: 2017-01-04 18:04:31.365154Z] Finished run-tests step (success) 10:04:31 INFO - Running post-run listener: _resource_record_post_run 10:04:31 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 36918272; Write bytes: 659584000; Read time: 244; Write time: 1884 10:04:31 INFO - TinderboxPrint: CPU usage
53.7% 10:04:31 INFO - TinderboxPrint: I/O read bytes / time
36,918,272 / 244 10:04:31 INFO - TinderboxPrint: I/O write bytes / time
659,584,000 / 1,884 10:04:31 INFO - TinderboxPrint: CPU idle
2,244.3 (46.3%) 10:04:31 INFO - TinderboxPrint: CPU system
974.8 (20.1%) 10:04:31 INFO - TinderboxPrint: CPU user
1,630.6 (33.6%) 10:04:31 INFO - TinderboxPrint: Swap in / out
385,699,840 / 0 10:04:31 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 270336; Write bytes: 352609792; Read time: 4; Write time: 738 10:04:31 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 34444288; Write bytes: 305397248; Read time: 223; Write time: 1137 10:04:31 INFO - Running post-run listener: _upload_blobber_files 10:04:31 INFO - Blob upload gear active. 10:04:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:04:31 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 10:04:31 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'] 10:04:31 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 10:04:32 INFO - (blobuploader) - INFO - Open directory for files ... 10:04:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:04:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:04:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:04:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:04:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:04:33 INFO - (blobuploader) - INFO - Done attempting. 10:04:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:04:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:04:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:04:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:04:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:04:34 INFO - (blobuploader) - INFO - Done attempting. 10:04:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:04:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:04:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:04:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:04:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:04:36 INFO - (blobuploader) - INFO - Done attempting. 10:04:36 INFO - (blobuploader) - INFO - Iteration through files over. 10:04:36 INFO - Return code: 0 10:04:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:04:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:04:36 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e20327dfb1810dbac21177101f45af67d0ac50a30ac558641313f2739297c7352c011989cd30af31ca6a74fb3a1ca48f49dd860f0b4fa5b8ba96d918a51f9ee9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/33bac2418cf204b51594bb47b787c8a13e41860ba38b5739c067b28c3c2f4ce6c37c0a3f793afc36818c2cb3a28ddeaad18bf87690d3fb92761bd8de10a98cf7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/85cf9c34d5e297c45ff078feec2703e1c8b5e3a1446124c1dacb66375bb505e6c83d2370c00ba8da490a1a5fe78361b629cabb112d9af096b4d72562d04e16a8"} 10:04:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:04:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:04:36 INFO - Contents: 10:04:36 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e20327dfb1810dbac21177101f45af67d0ac50a30ac558641313f2739297c7352c011989cd30af31ca6a74fb3a1ca48f49dd860f0b4fa5b8ba96d918a51f9ee9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/33bac2418cf204b51594bb47b787c8a13e41860ba38b5739c067b28c3c2f4ce6c37c0a3f793afc36818c2cb3a28ddeaad18bf87690d3fb92761bd8de10a98cf7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/85cf9c34d5e297c45ff078feec2703e1c8b5e3a1446124c1dacb66375bb505e6c83d2370c00ba8da490a1a5fe78361b629cabb112d9af096b4d72562d04e16a8"} 10:04:36 INFO - Running post-run listener: copy_logs_to_upload_dir 10:04:36 INFO - Copying logs to upload dir... 10:04:36 INFO - mkdir: /builds/slave/test/build/upload/logs 10:04:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.863472 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2017-01-04 10:04:36.878354) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-04 10:04:36.881698) ========= 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.GKFRJCRlhB/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.Lf3x9e275G/Listeners TMPDIR=/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e20327dfb1810dbac21177101f45af67d0ac50a30ac558641313f2739297c7352c011989cd30af31ca6a74fb3a1ca48f49dd860f0b4fa5b8ba96d918a51f9ee9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/33bac2418cf204b51594bb47b787c8a13e41860ba38b5739c067b28c3c2f4ce6c37c0a3f793afc36818c2cb3a28ddeaad18bf87690d3fb92761bd8de10a98cf7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/85cf9c34d5e297c45ff078feec2703e1c8b5e3a1446124c1dacb66375bb505e6c83d2370c00ba8da490a1a5fe78361b629cabb112d9af096b4d72562d04e16a8"} build_url:https://queue.taskcluster.net/v1/task/RPxl41XtQ4eEuYPZOtO0pw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011101 build_url: 'https://queue.taskcluster.net/v1/task/RPxl41XtQ4eEuYPZOtO0pw/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e20327dfb1810dbac21177101f45af67d0ac50a30ac558641313f2739297c7352c011989cd30af31ca6a74fb3a1ca48f49dd860f0b4fa5b8ba96d918a51f9ee9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/33bac2418cf204b51594bb47b787c8a13e41860ba38b5739c067b28c3c2f4ce6c37c0a3f793afc36818c2cb3a28ddeaad18bf87690d3fb92761bd8de10a98cf7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/85cf9c34d5e297c45ff078feec2703e1c8b5e3a1446124c1dacb66375bb505e6c83d2370c00ba8da490a1a5fe78361b629cabb112d9af096b4d72562d04e16a8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-04 10:04:36.917492) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 10:04:36.917896) ========= 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.GKFRJCRlhB/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.Lf3x9e275G/Listeners TMPDIR=/var/folders/mn/l_v3fwc55qs05rgmm7hhhx2c00000w/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.005039 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-04 10:04:36.967380) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-04 10:04:36.967693) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-04 10:04:36.968049) ========= ========= Total master_lag: 0.18 =========