builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0119 starttime: 1484330325.95 results: success (0) buildid: 20170113075917 builduid: 9ea2238896ba49b99d9eac59d95ccbf1 revision: 4749ac0674d3ba7884bc444d4132cb16b9c41a2b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-13 09:58:45.954849) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-13 09:58:45.955271) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-13 09:58:45.955646) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-13 09:58:45.983079) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 09:58:45.983450) ========= 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.MzWTRPMN7n/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.Tmd2BDOn5S/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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-13 09:58:46-- 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% 9.07M=0.001s 2017-01-13 09:58:46 (9.07 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.107056 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 09:58:46.116048) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 09:58:46.116388) ========= 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.MzWTRPMN7n/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.Tmd2BDOn5S/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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.027007 ========= master_lag: 0.11 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 09:58:46.251015) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-13 09:58:46.251361) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 4749ac0674d3ba7884bc444d4132cb16b9c41a2b --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 4749ac0674d3ba7884bc444d4132cb16b9c41a2b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.MzWTRPMN7n/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.Tmd2BDOn5S/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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-13 09:58:46,346 truncating revision to first 12 chars 2017-01-13 09:58:46,346 Setting DEBUG logging. 2017-01-13 09:58:46,346 attempt 1/10 2017-01-13 09:58:46,346 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/4749ac0674d3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-13 09:58:47,869 unpacking tar archive at: mozilla-aurora-4749ac0674d3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.777555 ========= master_lag: 0.33 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-13 09:58:48.355194) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-13 09:58:48.355581) ========= script_repo_revision: 4749ac0674d3ba7884bc444d4132cb16b9c41a2b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-13 09:58:48.355958) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-13 09:58:48.356224) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-13 09:58:48.377117) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-01-13 09:58:48.377462) ========= /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.MzWTRPMN7n/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.Tmd2BDOn5S/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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:58:48 INFO - MultiFileLogger online at 20170113 09:58:48 in /builds/slave/test 09:58:48 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:58:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:58:48 INFO - {'append_to_log': False, 09:58:48 INFO - 'base_work_dir': '/builds/slave/test', 09:58:48 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:58:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:58:48 INFO - 'branch': 'Mozilla-Aurora', 09:58:48 INFO - 'buildbot_json_path': 'buildprops.json', 09:58:48 INFO - 'config_files': ('talos/mac_config.py',), 09:58:48 INFO - 'default_actions': ('clobber', 09:58:48 INFO - 'read-buildbot-config', 09:58:48 INFO - 'download-and-extract', 09:58:48 INFO - 'populate-webroot', 09:58:48 INFO - 'create-virtualenv', 09:58:48 INFO - 'install', 09:58:48 INFO - 'run-tests'), 09:58:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:58:48 INFO - 'download_minidump_stackwalk': True, 09:58:48 INFO - 'download_symbols': 'ondemand', 09:58:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:58:48 INFO - 'tooltool.py': '/tools/tooltool.py', 09:58:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:58:48 INFO - '/tools/misc-python/virtualenv.py')}, 09:58:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:58:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:58:48 INFO - 'installer_path': 'installer.exe', 09:58:48 INFO - 'log_level': 'info', 09:58:48 INFO - 'log_name': 'talos', 09:58:48 INFO - 'log_to_console': True, 09:58:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:58:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:58:48 INFO - 'opt_config_files': (), 09:58:48 INFO - 'pip_index': False, 09:58:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:58:48 INFO - 'cmd': ('bash', 09:58:48 INFO - '-c', 09:58:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:58:48 INFO - 'enabled': True, 09:58:48 INFO - 'halt_on_failure': False, 09:58:48 INFO - 'name': 'check_screen_resolution'},), 09:58:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:58:48 INFO - 'cmd': ('bash', 09:58:48 INFO - '-c', 09:58:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:58:48 INFO - 'enabled': True, 09:58:48 INFO - 'halt_on_failure': False, 09:58:48 INFO - 'name': 'check_screen_resolution'},), 09:58:48 INFO - 'run_cmd_checks_enabled': True, 09:58:48 INFO - 'sps_profile': False, 09:58:48 INFO - 'sps_profile_interval': 0, 09:58:48 INFO - 'suite': 'dromaeojs', 09:58:48 INFO - 'system_bits': '32', 09:58:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:58:48 INFO - 'title': 't-yosemite-r7-0119', 09:58:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:58:48 INFO - 'use_talos_json': True, 09:58:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:58:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:58:48 INFO - 'work_dir': 'build'} 09:58:48 INFO - [mozharness: 2017-01-13 17:58:48.582913Z] Running clobber step. 09:58:48 INFO - Running pre-action listener: _resource_record_pre_action 09:58:48 INFO - Running main action method: clobber 09:58:48 INFO - rmtree: /builds/slave/test/build 09:58:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:58:50 INFO - Running post-action listener: _resource_record_post_action 09:58:50 INFO - [mozharness: 2017-01-13 17:58:50.999183Z] Finished clobber step (success) 09:58:50 INFO - [mozharness: 2017-01-13 17:58:50.999350Z] Running read-buildbot-config step. 09:58:50 INFO - Running pre-action listener: _resource_record_pre_action 09:58:50 INFO - Running main action method: read_buildbot_config 09:58:50 INFO - Using buildbot properties: 09:58:51 INFO - { 09:58:51 INFO - "project": "", 09:58:51 INFO - "product": "firefox", 09:58:51 INFO - "script_repo_revision": "production", 09:58:51 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 09:58:51 INFO - "repository": "", 09:58:51 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 09:58:51 INFO - "buildid": "20170113075917", 09:58:51 INFO - "pgo_build": "False", 09:58:51 INFO - "basedir": "/builds/slave/test", 09:58:51 INFO - "buildnumber": 85, 09:58:51 INFO - "slavename": "t-yosemite-r7-0119", 09:58:51 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 09:58:51 INFO - "platform": "yosemite_r7", 09:58:51 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:58:51 INFO - "branch": "mozilla-aurora", 09:58:51 INFO - "repo_path": "releases/mozilla-aurora", 09:58:51 INFO - "revision": "4749ac0674d3ba7884bc444d4132cb16b9c41a2b", 09:58:51 INFO - "stage_platform": "macosx64", 09:58:51 INFO - "builduid": "9ea2238896ba49b99d9eac59d95ccbf1", 09:58:51 INFO - "slavebuilddir": "test" 09:58:51 INFO - } 09:58:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/Bkw0sAzqTDes6j_ablcLvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 09:58:51 INFO - Running post-action listener: _resource_record_post_action 09:58:51 INFO - [mozharness: 2017-01-13 17:58:51.002683Z] Finished read-buildbot-config step (success) 09:58:51 INFO - [mozharness: 2017-01-13 17:58:51.002827Z] Running download-and-extract step. 09:58:51 INFO - Running pre-action listener: _resource_record_pre_action 09:58:51 INFO - Running main action method: download_and_extract 09:58:51 INFO - mkdir: /builds/slave/test/build/tests 09:58:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:51 INFO - trying https://queue.taskcluster.net/v1/task/Bkw0sAzqTDes6j_ablcLvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 09:58:51 INFO - Downloading https://queue.taskcluster.net/v1/task/Bkw0sAzqTDes6j_ablcLvQ/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:58:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Bkw0sAzqTDes6j_ablcLvQ/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:58:51 INFO - Downloaded 1199 bytes. 09:58:51 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 09:58:51 INFO - Using the following test package requirements: 09:58:51 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 09:58:51 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:58:51 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 09:58:51 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:58:51 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 09:58:51 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:58:51 INFO - u'jsshell-mac.zip'], 09:58:51 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:58:51 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 09:58:51 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 09:58:51 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:58:51 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 09:58:51 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:58:51 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 09:58:51 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:58:51 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 09:58:51 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:58:51 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 09:58:51 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:58:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Bkw0sAzqTDes6j_ablcLvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 09:58:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Bkw0sAzqTDes6j_ablcLvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:58:51 INFO - Fetch https://queue.taskcluster.net/v1/task/Bkw0sAzqTDes6j_ablcLvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 09:58:52 INFO - Http code: 200 09:58:52 INFO - via: 1.1 a75342fa94ff4a9431476624fe74f02e.cloudfront.net (CloudFront) 09:58:52 INFO - x-amz-cf-id: dp8ddqidF_TrDORCa36-tXtULSXdaBFBgt97r1P9nFMrANFHbZ8hXg== 09:58:52 INFO - x-amz-version-id: dpJxvyukvoeRttjJwKj49BesGfXKcgi9 09:58:52 INFO - Expected file size: 16275692 09:58:52 INFO - Obtained file size: 16275692 09:58:59 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:58:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Bkw0sAzqTDes6j_ablcLvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 09:58:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Bkw0sAzqTDes6j_ablcLvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:58:59 INFO - Fetch https://queue.taskcluster.net/v1/task/Bkw0sAzqTDes6j_ablcLvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 09:59:00 INFO - Http code: 200 09:59:00 INFO - via: 1.1 b3bccc0e4c2cc0a33e7e8308d88cacdd.cloudfront.net (CloudFront) 09:59:00 INFO - x-amz-cf-id: 6UORCP2o8X1FQY0tKmGpZ01mRlx4gXlOxeM9yAydb70Pppo0ACoCdg== 09:59:00 INFO - x-amz-version-id: dpJxvyukvoeRttjJwKj49BesGfXKcgi9 09:59:00 INFO - Expected file size: 16275692 09:59:00 INFO - Obtained file size: 16275692 09:59:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Bkw0sAzqTDes6j_ablcLvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 09:59:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Bkw0sAzqTDes6j_ablcLvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:59:06 INFO - Fetch https://queue.taskcluster.net/v1/task/Bkw0sAzqTDes6j_ablcLvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 09:59:07 INFO - Http code: 200 09:59:07 INFO - via: 1.1 ba26a6eef2785fa2e20e601890b882b2.cloudfront.net (CloudFront) 09:59:07 INFO - x-amz-cf-id: GlCgot2X_fB0ZEod0H_0XliSCkb7Kz__oB9VCY0aiXKwqZfEpN1O_w== 09:59:07 INFO - x-amz-version-id: 6TBJ9CNMd2u8L8cTbVcX6YlTQkreiw8p 09:59:07 INFO - Expected file size: 14409038 09:59:07 INFO - Obtained file size: 14409038 09:59:07 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:59:07 INFO - trying https://queue.taskcluster.net/v1/task/Bkw0sAzqTDes6j_ablcLvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:59:07 INFO - Downloading https://queue.taskcluster.net/v1/task/Bkw0sAzqTDes6j_ablcLvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 09:59:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Bkw0sAzqTDes6j_ablcLvQ/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:59:10 INFO - Downloaded 109996773 bytes. 09:59:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Bkw0sAzqTDes6j_ablcLvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:59:10 INFO - mkdir: /builds/slave/test/properties 09:59:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:59:10 INFO - Writing to file /builds/slave/test/properties/build_url 09:59:10 INFO - Contents: 09:59:10 INFO - build_url:https://queue.taskcluster.net/v1/task/Bkw0sAzqTDes6j_ablcLvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:59:11 INFO - Running post-action listener: _resource_record_post_action 09:59:11 INFO - Running post-action listener: set_extra_try_arguments 09:59:11 INFO - [mozharness: 2017-01-13 17:59:11.419362Z] Finished download-and-extract step (success) 09:59:11 INFO - [mozharness: 2017-01-13 17:59:11.419503Z] Running populate-webroot step. 09:59:11 INFO - Running pre-action listener: _resource_record_pre_action 09:59:11 INFO - Running main action method: populate_webroot 09:59:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:59:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:59:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:59:11 INFO - '--tppagecycles', 09:59:11 INFO - '7'], 09:59:11 INFO - 'tests': ['tsvgm']}}, 09:59:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:59:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:59:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:59:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:59:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:59:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:59:11 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:11 INFO - 'talos_options': ['--disable-e10s'], 09:59:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:59:11 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:59:11 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:11 INFO - 'talos_options': ['--disable-e10s'], 09:59:11 INFO - 'tests': ['damp', 'tps']}, 09:59:11 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:11 INFO - 'tests': ['damp', 'tps']}, 09:59:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:59:11 INFO - 'tests': ['dromaeo_dom']}, 09:59:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:59:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:59:11 INFO - 'tests': ['basic_compositor_video']}, 09:59:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:59:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:59:11 INFO - 'tests': ['a11yr', 09:59:11 INFO - 'ts_paint', 09:59:11 INFO - 'tpaint', 09:59:11 INFO - 'sessionrestore', 09:59:11 INFO - 'sessionrestore_no_auto_restore', 09:59:11 INFO - 'tabpaint']}, 09:59:11 INFO - 'other-e10s': {'tests': ['a11yr', 09:59:11 INFO - 'ts_paint', 09:59:11 INFO - 'tpaint', 09:59:11 INFO - 'sessionrestore', 09:59:11 INFO - 'sessionrestore_no_auto_restore', 09:59:11 INFO - 'tabpaint']}, 09:59:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:59:11 INFO - 'ts_paint', 09:59:11 INFO - 'tpaint', 09:59:11 INFO - 'sessionrestore', 09:59:11 INFO - 'sessionrestore_no_auto_restore', 09:59:11 INFO - 'tabpaint']}, 09:59:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:59:11 INFO - 'ts_paint', 09:59:11 INFO - 'tpaint', 09:59:11 INFO - 'sessionrestore', 09:59:11 INFO - 'sessionrestore_no_auto_restore', 09:59:11 INFO - 'tabpaint']}, 09:59:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:59:11 INFO - 'tests': ['a11yr', 09:59:11 INFO - 'ts_paint', 09:59:11 INFO - 'tpaint', 09:59:11 INFO - 'sessionrestore', 09:59:11 INFO - 'sessionrestore_no_auto_restore', 09:59:11 INFO - 'tabpaint']}, 09:59:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:59:11 INFO - 'tests': ['a11yr', 09:59:11 INFO - 'ts_paint', 09:59:11 INFO - 'tpaint', 09:59:11 INFO - 'sessionrestore', 09:59:11 INFO - 'sessionrestore_no_auto_restore', 09:59:11 INFO - 'tabpaint']}, 09:59:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:59:11 INFO - 'tests': ['tsvgx', 09:59:11 INFO - 'tsvgr_opacity', 09:59:11 INFO - 'tart', 09:59:11 INFO - 'tscrollx', 09:59:11 INFO - 'cart']}, 09:59:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:59:11 INFO - 'tsvgr_opacity', 09:59:11 INFO - 'tart', 09:59:11 INFO - 'tscrollx', 09:59:11 INFO - 'cart']}, 09:59:11 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:11 INFO - 'talos_options': ['--disable-e10s'], 09:59:11 INFO - 'tests': ['tp5o']}, 09:59:11 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:11 INFO - 'tests': ['tp5o']}, 09:59:11 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:11 INFO - 'talos_options': ['--disable-e10s', 09:59:11 INFO - '--xperf_path', 09:59:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:59:11 INFO - 'tests': ['tp5n']}, 09:59:11 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:59:11 INFO - 'talos_options': ['--xperf_path', 09:59:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:59:11 INFO - 'tests': ['tp5n']}}, 09:59:11 INFO - 'talos.zip': {'path': '', 09:59:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:59:11 INFO - Running post-action listener: _resource_record_post_action 09:59:11 INFO - [mozharness: 2017-01-13 17:59:11.437272Z] Finished populate-webroot step (success) 09:59:11 INFO - [mozharness: 2017-01-13 17:59:11.437413Z] Running create-virtualenv step. 09:59:11 INFO - Running pre-action listener: _resource_record_pre_action 09:59:11 INFO - Running main action method: create_virtualenv 09:59:11 INFO - Creating virtualenv /builds/slave/test/build/venv 09:59:11 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:59:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:59:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:59:11 INFO - Using real prefix '/tools/python27' 09:59:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:59:12 INFO - Installing distribute.............................................................................................................................................................................................done. 09:59:15 INFO - Installing pip.................done. 09:59:15 INFO - Return code: 0 09:59:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:59:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:59:15 INFO - Reading from file tmpfile_stdout 09:59:15 INFO - Output received: 09:59:15 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:59:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:59:15 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:59:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:15 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:59:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:15 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 0x104c70490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104be6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa394795d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a84d30>, '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 0x1048eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a7cb20>, '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.MzWTRPMN7n/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.Tmd2BDOn5S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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:59:15 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:59:15 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:59:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MzWTRPMN7n/Render', 09:59:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:59:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:59:15 INFO - 'HOME': '/Users/cltbld', 09:59:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:59:15 INFO - 'LOGNAME': 'cltbld', 09:59:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:15 INFO - 'MOZ_NO_REMOTE': '1', 09:59:15 INFO - 'NO_EM_RESTART': '1', 09:59:15 INFO - 'PAGER': '/bin/cat', 09:59:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:59:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:15 INFO - 'PWD': '/builds/slave/test', 09:59:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:59:15 INFO - 'SHELL': '/bin/bash', 09:59:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tmd2BDOn5S/Listeners', 09:59:15 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 09:59:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:59:15 INFO - 'USER': 'cltbld', 09:59:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:59:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:59:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:15 INFO - 'XPC_FLAGS': '0x0', 09:59:15 INFO - 'XPC_SERVICE_NAME': '0', 09:59:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:59:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:15 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:59:15 INFO - Cleaning up... 09:59:15 INFO - Return code: 0 09:59:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:59:15 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:59:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:15 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:59:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:15 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 0x104c70490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104be6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa394795d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a84d30>, '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 0x1048eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a7cb20>, '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.MzWTRPMN7n/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.Tmd2BDOn5S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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:59:15 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:59:15 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:59:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MzWTRPMN7n/Render', 09:59:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:59:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:59:15 INFO - 'HOME': '/Users/cltbld', 09:59:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:59:15 INFO - 'LOGNAME': 'cltbld', 09:59:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:15 INFO - 'MOZ_NO_REMOTE': '1', 09:59:15 INFO - 'NO_EM_RESTART': '1', 09:59:15 INFO - 'PAGER': '/bin/cat', 09:59:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:59:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:15 INFO - 'PWD': '/builds/slave/test', 09:59:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:59:15 INFO - 'SHELL': '/bin/bash', 09:59:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tmd2BDOn5S/Listeners', 09:59:15 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 09:59:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:59:15 INFO - 'USER': 'cltbld', 09:59:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:59:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:59:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:15 INFO - 'XPC_FLAGS': '0x0', 09:59:15 INFO - 'XPC_SERVICE_NAME': '0', 09:59:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:59:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:15 INFO - Downloading/unpacking psutil>=3.1.1 09:59:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:59:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:59:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:59:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:59:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:59:19 INFO - Installing collected packages: psutil 09:59:19 INFO - Running setup.py install for psutil 09:59:19 INFO - building 'psutil._psutil_osx' extension 09:59:19 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:59:19 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:59:20 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:59:20 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:59:20 INFO - building 'psutil._psutil_posix' extension 09:59:20 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:59:20 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:59:20 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:59:20 INFO - ^ 09:59:20 INFO - 1 warning generated. 09:59:20 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:59:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:59:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:59:20 INFO - Successfully installed psutil 09:59:20 INFO - Cleaning up... 09:59:20 INFO - Return code: 0 09:59:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:59:20 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:59:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:20 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:59:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:20 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 0x104c70490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104be6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa394795d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a84d30>, '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 0x1048eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a7cb20>, '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.MzWTRPMN7n/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.Tmd2BDOn5S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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:59:20 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:59:20 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:59:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MzWTRPMN7n/Render', 09:59:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:59:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:59:20 INFO - 'HOME': '/Users/cltbld', 09:59:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:59:20 INFO - 'LOGNAME': 'cltbld', 09:59:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:20 INFO - 'MOZ_NO_REMOTE': '1', 09:59:20 INFO - 'NO_EM_RESTART': '1', 09:59:20 INFO - 'PAGER': '/bin/cat', 09:59:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:59:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:20 INFO - 'PWD': '/builds/slave/test', 09:59:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:59:20 INFO - 'SHELL': '/bin/bash', 09:59:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tmd2BDOn5S/Listeners', 09:59:20 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 09:59:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:59:20 INFO - 'USER': 'cltbld', 09:59:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:59:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:59:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:20 INFO - 'XPC_FLAGS': '0x0', 09:59:20 INFO - 'XPC_SERVICE_NAME': '0', 09:59:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:59:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:59:20 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:59:20 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:59:20 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:59:20 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:59:20 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:59:20 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:59:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:59:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:59:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:59:23 INFO - Installing collected packages: mozsystemmonitor 09:59:23 INFO - Running setup.py install for mozsystemmonitor 09:59:23 INFO - Successfully installed mozsystemmonitor 09:59:23 INFO - Cleaning up... 09:59:23 INFO - Return code: 0 09:59:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:59:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:23 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 0x104c70490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104be6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa394795d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a84d30>, '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 0x1048eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a7cb20>, '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.MzWTRPMN7n/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.Tmd2BDOn5S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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:59:23 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:59:23 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:59:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MzWTRPMN7n/Render', 09:59:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:59:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:59:23 INFO - 'HOME': '/Users/cltbld', 09:59:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:59:23 INFO - 'LOGNAME': 'cltbld', 09:59:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:23 INFO - 'MOZ_NO_REMOTE': '1', 09:59:23 INFO - 'NO_EM_RESTART': '1', 09:59:23 INFO - 'PAGER': '/bin/cat', 09:59:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:59:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:23 INFO - 'PWD': '/builds/slave/test', 09:59:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:59:23 INFO - 'SHELL': '/bin/bash', 09:59:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tmd2BDOn5S/Listeners', 09:59:23 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 09:59:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:59:23 INFO - 'USER': 'cltbld', 09:59:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:59:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:59:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:23 INFO - 'XPC_FLAGS': '0x0', 09:59:23 INFO - 'XPC_SERVICE_NAME': '0', 09:59:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:59:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:24 INFO - Downloading/unpacking jsonschema==2.5.1 09:59:24 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:59:24 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:59:24 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:59:24 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:59:24 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:59:24 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:59:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:59:26 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:59:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:59:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:59:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:27 INFO - Downloading functools32-3.2.3-2.tar.gz 09:59:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:59:27 INFO - warning: no files found matching '*.txt' 09:59:27 INFO - no previously-included directories found matching 'build' 09:59:27 INFO - no previously-included directories found matching 'dist' 09:59:27 INFO - no previously-included directories found matching '.git*' 09:59:27 INFO - Installing collected packages: jsonschema, functools32 09:59:27 INFO - Running setup.py install for functools32 09:59:27 INFO - warning: no files found matching '*.txt' 09:59:27 INFO - no previously-included directories found matching 'build' 09:59:27 INFO - no previously-included directories found matching 'dist' 09:59:27 INFO - no previously-included directories found matching '.git*' 09:59:27 INFO - Successfully installed jsonschema functools32 09:59:27 INFO - Cleaning up... 09:59:27 INFO - Return code: 0 09:59:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:59:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:27 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 0x104c70490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104be6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa394795d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a84d30>, '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 0x1048eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a7cb20>, '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.MzWTRPMN7n/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.Tmd2BDOn5S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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:59:27 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:59:27 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:59:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MzWTRPMN7n/Render', 09:59:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:59:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:59:27 INFO - 'HOME': '/Users/cltbld', 09:59:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:59:27 INFO - 'LOGNAME': 'cltbld', 09:59:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:27 INFO - 'MOZ_NO_REMOTE': '1', 09:59:27 INFO - 'NO_EM_RESTART': '1', 09:59:27 INFO - 'PAGER': '/bin/cat', 09:59:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:59:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:27 INFO - 'PWD': '/builds/slave/test', 09:59:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:59:27 INFO - 'SHELL': '/bin/bash', 09:59:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tmd2BDOn5S/Listeners', 09:59:27 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 09:59:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:59:27 INFO - 'USER': 'cltbld', 09:59:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:59:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:59:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:27 INFO - 'XPC_FLAGS': '0x0', 09:59:27 INFO - 'XPC_SERVICE_NAME': '0', 09:59:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:59:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:59:27 INFO - Cleaning up... 09:59:27 INFO - Return code: 0 09:59:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:59:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:27 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 0x104c70490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104be6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa394795d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a84d30>, '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 0x1048eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a7cb20>, '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.MzWTRPMN7n/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.Tmd2BDOn5S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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:59:27 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:59:27 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:59:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MzWTRPMN7n/Render', 09:59:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:59:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:59:27 INFO - 'HOME': '/Users/cltbld', 09:59:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:59:27 INFO - 'LOGNAME': 'cltbld', 09:59:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:27 INFO - 'MOZ_NO_REMOTE': '1', 09:59:27 INFO - 'NO_EM_RESTART': '1', 09:59:27 INFO - 'PAGER': '/bin/cat', 09:59:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:59:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:27 INFO - 'PWD': '/builds/slave/test', 09:59:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:59:27 INFO - 'SHELL': '/bin/bash', 09:59:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tmd2BDOn5S/Listeners', 09:59:27 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 09:59:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:59:27 INFO - 'USER': 'cltbld', 09:59:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:59:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:59:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:27 INFO - 'XPC_FLAGS': '0x0', 09:59:27 INFO - 'XPC_SERVICE_NAME': '0', 09:59:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:59:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:28 INFO - Downloading/unpacking blobuploader==1.2.4 09:59:28 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:59:28 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:59:28 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:59:28 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:59:28 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:59:28 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:59:30 INFO - Downloading blobuploader-1.2.4.tar.gz 09:59:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:59:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:59:30 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:59:30 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:59:30 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:59:30 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:59:30 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:59:30 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:59:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:59:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:59:31 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:59:31 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:59:31 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:59:31 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:59:31 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:59:31 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:59:31 INFO - Downloading docopt-0.6.1.tar.gz 09:59:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:59:32 INFO - Installing collected packages: blobuploader, requests, docopt 09:59:32 INFO - Running setup.py install for blobuploader 09:59:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:59:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:59:32 INFO - Running setup.py install for requests 09:59:32 INFO - Running setup.py install for docopt 09:59:32 INFO - Successfully installed blobuploader requests docopt 09:59:32 INFO - Cleaning up... 09:59:33 INFO - Return code: 0 09:59:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:59:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:33 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 0x104c70490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104be6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa394795d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a84d30>, '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 0x1048eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a7cb20>, '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.MzWTRPMN7n/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.Tmd2BDOn5S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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:59:33 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:59:33 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:59:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MzWTRPMN7n/Render', 09:59:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:59:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:59:33 INFO - 'HOME': '/Users/cltbld', 09:59:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:59:33 INFO - 'LOGNAME': 'cltbld', 09:59:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:33 INFO - 'MOZ_NO_REMOTE': '1', 09:59:33 INFO - 'NO_EM_RESTART': '1', 09:59:33 INFO - 'PAGER': '/bin/cat', 09:59:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:59:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:33 INFO - 'PWD': '/builds/slave/test', 09:59:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:59:33 INFO - 'SHELL': '/bin/bash', 09:59:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tmd2BDOn5S/Listeners', 09:59:33 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 09:59:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:59:33 INFO - 'USER': 'cltbld', 09:59:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:59:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:59:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:33 INFO - 'XPC_FLAGS': '0x0', 09:59:33 INFO - 'XPC_SERVICE_NAME': '0', 09:59:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:59:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:59:33 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-au0es4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:59:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:59:33 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-Hd_tYj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:59:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:59:33 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-VUe8uz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:59:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:59:33 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-enXIV2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:59:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:59:33 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-x4vYAp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:59:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:59:33 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-sV0mK9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:59:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:59:33 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-45rfQ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:59:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:59:34 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-3j198m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:59:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:59:34 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-c0t8rl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:59:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:59:34 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-V1APtw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:59:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:59:34 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-MPNkh9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:59:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:59:34 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-IBkDF9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:59:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:59:34 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-SEhYvf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:59:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:59:34 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-hXVukK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:59:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:59:34 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-NYQLym-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:59:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:59:34 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-ye5u6g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:59:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:59:34 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-n5OIit-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:59:35 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:59:35 INFO - Running setup.py install for manifestparser 09:59:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:59:35 INFO - Running setup.py install for mozcrash 09:59:35 INFO - Running setup.py install for mozdebug 09:59:35 INFO - Running setup.py install for mozdevice 09:59:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:59:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:59:35 INFO - Running setup.py install for mozfile 09:59:35 INFO - Running setup.py install for mozhttpd 09:59:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:59:35 INFO - Running setup.py install for mozinfo 09:59:36 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:59:36 INFO - Running setup.py install for mozInstall 09:59:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:59:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:59:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:59:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:59:36 INFO - Running setup.py install for mozleak 09:59:36 INFO - Running setup.py install for mozlog 09:59:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:59:36 INFO - Running setup.py install for moznetwork 09:59:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:59:36 INFO - Running setup.py install for mozprocess 09:59:36 INFO - Running setup.py install for mozprofile 09:59:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:59:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:59:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:59:36 INFO - Running setup.py install for mozrunner 09:59:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:59:37 INFO - Running setup.py install for mozscreenshot 09:59:37 INFO - Running setup.py install for moztest 09:59:37 INFO - Running setup.py install for mozversion 09:59:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:59:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:59:37 INFO - Cleaning up... 09:59:37 INFO - Return code: 0 09:59:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:59:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:59:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:37 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 0x104c70490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104be6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa394795d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a84d30>, '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 0x1048eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a7cb20>, '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.MzWTRPMN7n/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.Tmd2BDOn5S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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:59:37 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:59:37 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:59:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MzWTRPMN7n/Render', 09:59:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:59:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:59:37 INFO - 'HOME': '/Users/cltbld', 09:59:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:59:37 INFO - 'LOGNAME': 'cltbld', 09:59:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:37 INFO - 'MOZ_NO_REMOTE': '1', 09:59:37 INFO - 'NO_EM_RESTART': '1', 09:59:37 INFO - 'PAGER': '/bin/cat', 09:59:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:59:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:37 INFO - 'PWD': '/builds/slave/test', 09:59:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:59:37 INFO - 'SHELL': '/bin/bash', 09:59:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tmd2BDOn5S/Listeners', 09:59:37 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 09:59:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:59:37 INFO - 'USER': 'cltbld', 09:59:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:59:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:59:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:37 INFO - 'XPC_FLAGS': '0x0', 09:59:37 INFO - 'XPC_SERVICE_NAME': '0', 09:59:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:59:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:59:37 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-UIQBT3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:59:37 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:59:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:59:37 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-Js3tTK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:59:38 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:59:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:59:38 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-w4MSg1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:59:38 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:59:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:59:38 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-IsKnxi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:59:38 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:59:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:59:38 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-x8hkGr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:59:38 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:59:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:59:38 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-X5kKjW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:59:38 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:59:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:59:38 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-Ge5ib6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:59:38 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:59:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:59:38 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-iEZe4Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:59:38 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:59:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:59:38 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-qxFiVi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:59:38 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:59:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:59:38 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-YEjmBu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:59:38 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:59:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:59:38 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-nZlmRd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:59:39 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:59:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:59:39 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-lZQyOI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:59:39 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:59:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:59:39 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-12AFV4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:59:39 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:59:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:59:39 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-Zg68rz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:59:39 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:59:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:59:39 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-IDIvF3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:59:39 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:59:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:59:39 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-XKp9y3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:59:39 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:59:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:59:39 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-OOKNvh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:59:39 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:59:39 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:59:39 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:59:39 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:59:39 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:59:39 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:59:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:59:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:59:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:59:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:59:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:59:42 INFO - Downloading blessings-1.6.tar.gz 09:59:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:59:42 INFO - Installing collected packages: blessings 09:59:42 INFO - Running setup.py install for blessings 09:59:42 INFO - Successfully installed blessings 09:59:42 INFO - Cleaning up... 09:59:42 INFO - Return code: 0 09:59:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:59:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:59:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:59:42 INFO - Reading from file tmpfile_stdout 09:59:42 INFO - Current package versions: 09:59:42 INFO - blessings == 1.6 09:59:42 INFO - blobuploader == 1.2.4 09:59:42 INFO - docopt == 0.6.1 09:59:42 INFO - functools32 == 3.2.3-2 09:59:42 INFO - jsonschema == 2.5.1 09:59:42 INFO - manifestparser == 1.1 09:59:42 INFO - mozInstall == 1.12 09:59:42 INFO - mozcrash == 1.0 09:59:42 INFO - mozdebug == 0.1 09:59:42 INFO - mozdevice == 0.48 09:59:42 INFO - mozfile == 1.2 09:59:42 INFO - mozhttpd == 0.7 09:59:42 INFO - mozinfo == 0.9 09:59:42 INFO - mozleak == 0.1 09:59:42 INFO - mozlog == 3.4 09:59:42 INFO - moznetwork == 0.27 09:59:42 INFO - mozprocess == 0.23 09:59:42 INFO - mozprofile == 0.28 09:59:42 INFO - mozrunner == 6.13 09:59:42 INFO - mozscreenshot == 0.1 09:59:42 INFO - mozsystemmonitor == 0.3 09:59:42 INFO - moztest == 0.8 09:59:42 INFO - mozversion == 1.4 09:59:42 INFO - psutil == 3.1.1 09:59:42 INFO - requests == 1.2.3 09:59:42 INFO - wsgiref == 0.1.2 09:59:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:59: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:59:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:43 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:59:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:43 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 0x104c70490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104be6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa394795d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a84d30>, '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 0x1048eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a7cb20>, '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.MzWTRPMN7n/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.Tmd2BDOn5S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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:59:43 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:59:43 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:59:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MzWTRPMN7n/Render', 09:59:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:59:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:59:43 INFO - 'HOME': '/Users/cltbld', 09:59:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:59:43 INFO - 'LOGNAME': 'cltbld', 09:59:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:43 INFO - 'MOZ_NO_REMOTE': '1', 09:59:43 INFO - 'NO_EM_RESTART': '1', 09:59:43 INFO - 'PAGER': '/bin/cat', 09:59:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:59:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:43 INFO - 'PWD': '/builds/slave/test', 09:59:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:59:43 INFO - 'SHELL': '/bin/bash', 09:59:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tmd2BDOn5S/Listeners', 09:59:43 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 09:59:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:59:43 INFO - 'USER': 'cltbld', 09:59:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:59:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:59:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:43 INFO - 'XPC_FLAGS': '0x0', 09:59:43 INFO - 'XPC_SERVICE_NAME': '0', 09:59:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:59:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:43 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:59:43 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:59:43 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:59:43 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:59:43 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:59:43 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:59:43 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:59:43 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:59:43 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:59:43 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:59:43 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:59:43 INFO - Cleaning up... 09:59:43 INFO - Return code: 0 09:59:43 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:59:43 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:59:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:59:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:43 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:59:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:59:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:59:44 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 0x104c70490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104be6570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7faa394795d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104a84d30>, '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 0x1048eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a7cb20>, '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.MzWTRPMN7n/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.Tmd2BDOn5S/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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:59:44 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:59:44 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:59:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MzWTRPMN7n/Render', 09:59:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:59:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:59:44 INFO - 'HOME': '/Users/cltbld', 09:59:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:59:44 INFO - 'LOGNAME': 'cltbld', 09:59:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:59:44 INFO - 'MOZ_NO_REMOTE': '1', 09:59:44 INFO - 'NO_EM_RESTART': '1', 09:59:44 INFO - 'PAGER': '/bin/cat', 09:59:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:59:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:59:44 INFO - 'PWD': '/builds/slave/test', 09:59:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:59:44 INFO - 'SHELL': '/bin/bash', 09:59:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tmd2BDOn5S/Listeners', 09:59:44 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 09:59:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:59:44 INFO - 'USER': 'cltbld', 09:59:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:59:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:59:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:59:44 INFO - 'XPC_FLAGS': '0x0', 09:59:44 INFO - 'XPC_SERVICE_NAME': '0', 09:59:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:59:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:59:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:59:44 INFO - Cleaning up... 09:59:44 INFO - Return code: 0 09:59:44 INFO - Running post-action listener: _resource_record_post_action 09:59:44 INFO - Running post-action listener: _start_resource_monitoring 09:59:44 INFO - Starting resource monitoring. 09:59:44 INFO - [mozharness: 2017-01-13 17:59:44.619030Z] Finished create-virtualenv step (success) 09:59:44 INFO - [mozharness: 2017-01-13 17:59:44.619990Z] Running install step. 09:59:44 INFO - Running pre-action listener: _resource_record_pre_action 09:59:44 INFO - Running main action method: install 09:59:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:59:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:59:44 INFO - Reading from file tmpfile_stdout 09:59:44 INFO - Detecting whether we're running mozinstall >=1.0... 09:59:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:59:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:59:44 INFO - Reading from file tmpfile_stdout 09:59:44 INFO - Output received: 09:59:44 INFO - Usage: mozinstall [options] installer 09:59:44 INFO - Options: 09:59:44 INFO - -h, --help show this help message and exit 09:59:44 INFO - -d DEST, --destination=DEST 09:59:44 INFO - Directory to install application into. [default: 09:59:44 INFO - "/builds/slave/test"] 09:59:44 INFO - --app=APP Application being installed. [default: firefox] 09:59:44 INFO - mkdir: /builds/slave/test/build/application 09:59:44 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:59:44 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 10:00:17 INFO - Reading from file tmpfile_stdout 10:00:17 INFO - Output received: 10:00:17 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 10:00:17 INFO - Running post-action listener: _resource_record_post_action 10:00:17 INFO - [mozharness: 2017-01-13 18:00:17.199825Z] Finished install step (success) 10:00:17 INFO - [mozharness: 2017-01-13 18:00:17.199964Z] Running run-tests step. 10:00:17 INFO - Running pre-action listener: _resource_record_pre_action 10:00:17 INFO - Running main action method: run_tests 10:00:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:00:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:00:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:00:17 INFO - 2017-01-13 10:00:17.214 screenresolution[1623:7435] starting screenresolution argv=screenresolution get 10:00:17 INFO - 2017-01-13 10:00:17.217 screenresolution[1623:7435] Display 0: 1600x1200x32@60 10:00:17 INFO - 2017-01-13 10:00:17.222 screenresolution[1624:7437] starting screenresolution argv=screenresolution list 10:00:17 INFO - Available Modes on Display 0 10:00:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:00:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:00:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:00:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:00:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:00:17 INFO - Intel Iris: 10:00:17 INFO - Chipset Model: Intel Iris 10:00:17 INFO - Type: GPU 10:00:17 INFO - Bus: Built-In 10:00:17 INFO - VRAM (Dynamic, Max): 1536 MB 10:00:17 INFO - Vendor: Intel (0x8086) 10:00:17 INFO - Device ID: 0x0a2e 10:00:17 INFO - Revision ID: 0x0009 10:00:17 INFO - Displays: 10:00:17 INFO - Display: 10:00:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:00:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:00:17 INFO - Display Serial Number: 1600x1200 60 10:00:17 INFO - Main Display: Yes 10:00:17 INFO - Mirror: Off 10:00:17 INFO - Online: Yes 10:00:17 INFO - Rotation: Supported 10:00:17 INFO - Return code: 0 10:00:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:00:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:00:17 INFO - Python 2.7.3 10:00:17 INFO - Return code: 0 10:00:17 INFO - grabbing minidump binary from tooltool 10:00: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')]} 10:00:17 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 0x104a84d30>, '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 0x1048eadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104a7cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:00:17 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 10:00:17 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 10:00:17 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 10:00:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:00:17 INFO - Return code: 0 10:00:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:00:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:00:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:00:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:00:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:00:17 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/Bkw0sAzqTDes6j_ablcLvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0119', '--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 10:00:17 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/Bkw0sAzqTDes6j_ablcLvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0119 --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 10:00:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.MzWTRPMN7n/Render', 10:00:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:00:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:00:17 INFO - 'HOME': '/Users/cltbld', 10:00:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:00:17 INFO - 'LOGNAME': 'cltbld', 10:00:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:00:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:00:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:00:17 INFO - 'MOZ_NO_REMOTE': '1', 10:00:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:00:17 INFO - 'NO_EM_RESTART': '1', 10:00:17 INFO - 'PAGER': '/bin/cat', 10:00:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:00:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:00:17 INFO - 'PWD': '/builds/slave/test', 10:00:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:00:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:00:17 INFO - 'SHELL': '/bin/bash', 10:00:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Tmd2BDOn5S/Listeners', 10:00:17 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 10:00:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:00:17 INFO - 'USER': 'cltbld', 10:00:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:00:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:00:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:00:17 INFO - 'XPC_FLAGS': '0x0', 10:00:17 INFO - 'XPC_SERVICE_NAME': '0', 10:00:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:00:17 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/Bkw0sAzqTDes6j_ablcLvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0119', '--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 10:00:17 INFO - mozversion application_buildid: 20170113075917 10:00:17 INFO - mozversion application_changeset: 4749ac0674d3ba7884bc444d4132cb16b9c41a2b 10:00:17 INFO - mozversion application_display_name: FirefoxDeveloperEdition 10:00:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:00:17 INFO - mozversion application_name: Firefox 10:00:17 INFO - mozversion application_remotingname: firefox-dev 10:00:17 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:00:17 INFO - mozversion application_vendor: Mozilla 10:00:17 INFO - mozversion application_version: 52.0a2 10:00:17 INFO - mozversion platform_buildid: 20170113075917 10:00:17 INFO - mozversion platform_changeset: 4749ac0674d3ba7884bc444d4132cb16b9c41a2b 10:00:17 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 10:00:17 INFO - mozversion platform_version: 52.0a2 10:00:17 INFO - using testdate: 1484330417 10:00:17 INFO - actual date: 1484330417 10:00:17 INFO - starting webserver on 'localhost:49240' 10:00:17 INFO - SUITE-START | Running 2 tests 10:00:17 INFO - TEST-START | dromaeo_css 10:00:17 INFO - operating with platform_type : mac_ 10:00:17 INFO - Initialising browser for dromaeo_css test... 10:00:17 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/tmpm40k0E/profile http://localhost:49240/getInfo.html) 10:00:20 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 10:00:20 INFO - PROCESS | 1634 | colorDepth:24 10:00:20 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 10:00:20 INFO - PROCESS | 1634 | __metrics 10:00:20 INFO - PROCESS | 1634 | 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:00:20 INFO - PROCESS | 1634 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:00:21 INFO - TEST-INFO | 1634: exit 0 10:00:21 INFO - Browser initialized. 10:00:21 INFO - Running cycle 1/1 for dromaeo_css test... 10:00:21 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/tmpm40k0E/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:00:34 INFO - PROCESS | 1636 | RSS: Main: 238006272 10:00:34 INFO - PROCESS | 1636 | 10:03:37 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49240/tests/dromaeo/cssquery-ext.html) 10:03:39 INFO - PROCESS | 1636 | RSS: Main: 407224320 10:03:39 INFO - PROCESS | 1636 | 10:06:42 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-ext.html (next: http://localhost:49240/tests/dromaeo/cssquery-jquery.html) 10:06:43 INFO - PROCESS | 1636 | RSS: Main: 360980480 10:06:43 INFO - PROCESS | 1636 | 10:09:45 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49240/tests/dromaeo/cssquery-mootools.html) 10:09:46 INFO - PROCESS | 1636 | RSS: Main: 323366912 10:09:46 INFO - PROCESS | 1636 | 10:12:48 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49240/tests/dromaeo/cssquery-prototype.html) 10:12:49 INFO - PROCESS | 1636 | RSS: Main: 344383488 10:12:49 INFO - PROCESS | 1636 | 10:15:52 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49240/tests/dromaeo/cssquery-yui.html) 10:15:53 INFO - PROCESS | 1636 | RSS: Main: 324100096 10:15:53 INFO - PROCESS | 1636 | 10:18:55 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49240/tests/dromaeo/cssquery-yui.html (next: http://localhost:49240/tests/dromaeo/cssquery-dojo.html) 10:18:56 INFO - PROCESS | 1636 | RSS: Main: 442220544 10:18:56 INFO - PROCESS | 1636 | 10:18:56 INFO - PROCESS | 1636 | __start_tp_report 10:18:56 INFO - PROCESS | 1636 | _x_x_mozilla_page_load 10:18:56 INFO - PROCESS | 1636 | _x_x_mozilla_page_load_details 10:18:56 INFO - PROCESS | 1636 | |i|pagename|runs| 10:18:56 INFO - PROCESS | 1636 | |0;dojo.html;3300.851461684223;3304.206990322323;3306.875662274826;3324.3517514084765;3358.8992330230103;5614.326280846299;5672.347680016789;5681.7727290908415;5729.971350143249;5740.0528912729405;5582.91625625616;5647.943520564793;5693.316801983768;5695.401982791809;5714.314282286132;5159.406668233145;5160.432352441228;5166.405863325719;5190.896182076356;5213.244079608455;5133.922991155136;5135.255387968742;5144.639875208718;5151.639385243016;5170.974145129269;5211.45279745627;5230.790768369261;5235.607329450288;5241.475852414766;5280.577553795687;5205.765740541685;5240.554552863011;5242.6330156889035;5251.894962100756;5279.049771041211;5015.122353588107;5023.572996295319;5085.974570127163;5156.871078223075;5163.044836705221;5588.832335029955;5590.944090559083;5605.047141985872;5605.551555875561;5643.971780141114;6057.757689692407;6105.581053426302;6121.66330851803;6129.417705317987;6143.539234557426;6123.160610921244;6130.63216207029;6140.793556413871;6168.537359698041;6179.5674302798825;5676.744546495418;5690.573207009491;5696.62971906833;5743.827685169452;5829.067349224104;6213.720382582795;6218.968905155445;6232.968835155795;6235.127082208494;6238.189035425366;5963;6024.3072046714315;6048.032314829607;6094.299155597163;6098.72555734993;6123.130109042722;6188.690565471798;6220.782272620437;6238;6238.064290356483;5940.227770389822;6008.789692360834;6164.106204600308;6173.043178307369;6187.381261873867;5755.079187330007;5788.623739456922;5799.101139323411;6177;6223.595466294676;4866.732329721899;4935.679180853233;4937.259411088366;4937.703737775745;4940.530649588283;4381.408509851193;4385.9912220189535;4414.823407063745;4425.800838962255;4429.53489883564;5748.827535173951;5797.36229014808;5801.94198058014;5806.622569532967;5806.983777838861;6122.1542093545395;6123.203983482119;6155.661438620829;6160.537959653044;6167.259928808572;5764.337101233328;5775.740091695884;5775.797847075332;5779.711014449261;5822.155787410802;5293.550048245942;5295.947040529545;5302.496262855023;5307.681539107666;5375.758090885842;6131.20294361748;6231.53263505248;6237.407446292595;6258.530610204253;6275.623462592259;6135.538906165067;6188.752449901954;6197.163382943245;6238.033104868752;6238.875222495434;5659.377468478547;6238.469730072995;6263.342349053284;6287.402696743802;6306.495480361468;4803.711777293373;5936.37668044849;6102.755889764538;6157.4458298753325;6166.938330616636;4945.455999839943;5714.342850572238;6133.028644416937;6168.753249869955;6175.382461753788;6108.205933228652;6115.057807494094;6120.050983828446;6133.212252453684;6135.692037377249;4658.068386322681;5896.882062358814;6029.638221706711;6038.184845045863;6124.050772130327;5695.889301586151;5743.0811070228565;5820.563457740602;5916.585838991229;5930.970345148246;6006.279246490449;6059.969700151471;6095.05526643371;6111.743676692254;6113.786017489544;5679.74441150141;5724.656520608777;5727.427257274239;5855.590108692521;5903.822885313448 10:18:56 INFO - PROCESS | 1636 | |1;ext.html;483.3667895057477;499.03685886239566;512.4644746240178;521.5280171444841;549.4368272520665;28427.86288548458;39422;40499.1900161997;40541.986450338685;40554.79722601383;40056.198876022536;40459.39310910339;40493;40575.188496230134;40881;9268.675596354129;9419.340646154771;9426.764330891714;9435.245180385587;9439.669611563597;9196.448213107202;9335.159835614793;9341.159295663389;9359.953200234024;9363.812723745521;9324.25405967521;9346.01866803986;9360.29797765167;9380.765480863;9456.196223321025;9286.72139835806;9345.813083738356;9350.438973661601;9351.812963740755;9385.202257808094;9160.450372977575;9199.264058875275;9211.217046551052;9247.49138797366;9250.444973301555;37897.24205515902;38095.619043809485;38252.42621360682;38255;38464.615353846384;3236.530703048045;3551.3607550640872;3710.344759075925;3836.3286424875537;3863.555691095532;27973.720262797317;28596.571051434246;28644.71355286462;29130.85434572835;29900;3170.096522491102;3180.7137357637694;3189.186757376854;3210.085125739153;3323.351946370459;461730;464044;464055;464572;474949;32040.519392209135;56072.71963640237;56497.43502565004;56539.43460565342;56572.15141772877;33170.83414582912;33612.66387336095;34027.48958765621;34062.148446289044;34072;14244;14367.85632143686;14397.85602143986;14420;14429.350679219251;12002.879971200351;12131.09016823724;12144.514219431123;12147.453364598614;12148.149629525848;6465.547411681231;7594.430417718693;7671.424643151786;7688.846223075507;7692.230776922263;3181.7295529879757;3227.5158726191257;3230.7738458308163;3230.9192270193435;3266.1671273825496;930.5719369090159;939.1172298039821;978.4814048554277;988.2736188901152;1097.7914196302677;25560.33857799237;28591.553517369557;29196;29207.707922920497;29227.56158657622;992.8709267795141;1019.5073046401625;1025.825609646362;1038.9324693895023;1119.980817456111;14518.927405362905;14889.404423823142;14945.3560108388;15296.158711270764;15329.233538323038;664413;754925;768093;769705;961135;46598.301025484645;50074.749626251636;51632.74183629058;52094.73952630288;52324.738376307876;1091.3288327678579;1225.6997035726126;1271.2690203133236;1353.5330311042674;1390.707951330232;26379.340516486474;28470.288242794097;28658;30283.24291892632;30816.691833081382;42503.787481063635;49660.75169624273;50432.49567504278;50614.49385506098;50681.49318506768;529128;534174;534402.3279883575;534573.3271333618;535449;22285.331440056823;22872.31383058511;24996.250112496655;25607.487850243262;26095.73904261009;39357.80321098376;42786;42887;42994.925126872076;43092.569074308856;35757.64242357543;36318.45522317114;36379.636203638685;36457.270854583294;36906.44640330344;35731;35784.642153579174;36069;36470.452943205346;36644.81677591702;34335.141621458664;34990.30019399649;35316.823415882754;35357.823210883784;35680.64319356877;29672.406551869273;31490.212744680648;31538.369232615678;31666.683333166373;31716;23988.920498577256;24031.63952540748;24400.26799196027;24459.266222013368;24495.387615309046 10:18:56 INFO - PROCESS | 1636 | |2;jquery.html;345.83399968015334;368.53932584269666;373.11571575366366;379.9753016053957;399.82008096356645;14459.349329280181;14944.5516634501;15281.658628747582;20302.593948121066;20317.288894888683;18858.62282754344;19275.4217373479;19836.702449463235;19893.502662433446;20170.394888153343;5681.431856814327;5954.732037058343;5965.582409231356;5985.461308482236;5990.371011043843;4637.884052898671;5894.410558944114;5938.168656388109;5949.851253718648;5950.315713692916;5608.6073974821575;5874.471297583216;5879.206307148523;5881.059030555112;5893.823185304448;5784.103463963072;5849.561282903778;5873.530117590625;5873.588848780587;5891.587588868738;5102.795888164469;5155;5170.121079416508;5170.250313704491;5378.542823859977;13133.080684352099;13140.671483212996;13334.733305333839;13344.199348039148;13379.197248165141;1868.4955062133147;2205.184081889695;2268.285490070639;2314.201600447843;2776.083892315531;9658.468784216866;10082.546285417175;10376.584936602527;10965.177611679155;14416.63958401038;1658.7677725118492;1713.288985071194;1778.1287169286957;1836.5500452389117;1840.68708319586;113872;116462.41768791102;123364.38317808353;125857;130465.34767326197;10399.688009359732;11402.258853174517;11550.537978480766;11574.363410012362;11690.649280521598;14519.05626134298;14522.128672279698;14576.927115364355;14596.41614335436;14597.78103328451;8376.371772117116;8480.363972702073;8490.575471226413;8508.191721786421;8617.69838055665;7321.633918304064;7938.444308898322;8429.114942931026;8537.359698022548;8589.055203927563;3240.254741409489;4218.261804184255;4435.824506505778;4436.511983681857;4437.912711385667;1822.2984151101657;1886.8584856135844;1886.9716954245698;1889.754331936867;1891.1678861300984;722.3246264742482;851.4167795060404;903.2503022491317;960.9663661771805;1006.4866917871792;19894.801051989296;20694.965251737354;20720.378388648063;20790.896045519676;20983.685244721342;883.8364902493059;902.9097090290918;934.528063328015;947.5688561704408;975.0103644800533;10273.537690803932;11024.338539687644;11030.669079927615;11043.061339786047;11160.609378671708;498553;512150;515048;592956;625381;91309;111149.44425277822;120331;127212;138424;841.5792103948025;912.5254867468879;936.489613160829;981.8183636027358;985.1822986920838;13950.720985580434;18175.909445433317;18211.36097751156;18648.906755466134;18815.623687526448;35246.82376588203;46685;48437.5156248433;49393.75303123461;51412.74293628508;76551;81133;84170.57914710387;84821;87383.56308218418;7867.370610351272;7877.212278772077;8062.193780622126;8097.311728503143;8201.79495512617;11846.703832403915;12172.14794964344;12252.142350035412;12530.436130374337;12556.121071524905;10674.306170098918;10718.624848130277;10887.891121089004;10990.890091099307;11020.008199262105;10339;10555.577776888838;10650.467476626292;10739.355638661706;10775.245732798943;10872.347659140476;10932.836007459735;10969.616063438061;11079.72300692489;11343.149263805082;7827.686892524235;7877.921220787719;8027.719029833927;8058;8078.7172448964;9544.618215271312;10146.594136234764;10201.897981020094;10572.418516981332;11084.833727493933 10:18:56 INFO - PROCESS | 1636 | |3;mootools.html;1371.0539727587964;1448.1890141520753;1598.5204438668397;1789.7494350790882;1810.4025671528389;7948;8358.91641083589;8369.288610468102;8375.455595386304;8388.706395276167;6209;7596.848063038736;7626.542407455557;8055.959720201406;8176.877346839802;2288.828337874666;2298.7586303438147;2338.1816364272454;2342.1451170322794;2347.542229265294;2311.514581937795;2342.4026873147322;2352.435415500276;2356.8703721295324;2360.2329242996093;2243.719535058118;2260.8756518391483;2269.1377276634857;2279.578278018572;2325.4883925536355;2303.6199027160496;2329.219711396684;2331.63859601762;2331.743508214112;2340.7776261255326;2322.384568089458;2354.6114891042957;2356.081128359941;2360.7167139943144;2362.0905951208906;8184.836303273961;8258.876116858251;8277.255047045795;8291.170882911721;8328.417010809246;5023.970086132371;5199.948000519984;5199.974000130013;5202.167653175512;5206.7917283308625;3281.5898012748407;3306.2891478332276;3501.19472521319;3548.4677298405186;3569.1434055826676;1042.11940909931;1059.4332032362727;1074.8011617850723;1093.1418836213534;1093.147345070846;10790.190735694776;10962.561497540168;10977.28647637901;10979.341239525655;11000.779984400348;4222.78886055696;4414.161309351213;4594.908101838011;4676.625869930397;4697.8355757548325;9308.95345523268;9326.673566425143;9338.439693618404;9343.71968840936;9368.484733339596;4942.011597680478;4943.134951383494;4944.48082951285;4945.876353091202;4949.985253023122;4183.058811767389;4321.632661223768;4500.5274446182675;4508.211063064016;4682.531746825291;3773.339665558516;3787.6401741835034;3859.073822282632;3876.9224615507533;3882.145927895858;1165.976680466386;1257.937103144839;1265.5697062998624;1267.5056727876135;1272.0332547264145;816.0003995105948;890.6259371064168;899.547241156279;912.3476714149415;915.9129882661136;4947.6289278304275;5696.715164241771;5766.481016708516;5822.679752613647;5832.300123985149;878.7934835313696;898.2006311145373;898.9865152022725;923.5659240157115;926.356182453202;3904.8480698194103;3917.9804100979313;3925.6270654287796;3945.3292940200236;4008.118213992917;9640.566174522022;10110.595576176871;10114.291999560108;10128.088472037552;10138;10056.74858128538;10061.69814905554;10064.597416103421;10064.798704025878;10086.848697269546;7453.925460745323;7752.806179845436;7852.214778522102;7859.882101768478;7891.092524359658;5788.739506722123;5830.096335068071;5935.554833387427;6031.7587296507645;6041.75832966694;8963.462192268486;9215.723528294162;9220.354575179674;9310.068993100636;9411.247100231829;9395.43627382332;9421.246300296098;9478.052194780743;9518.571664275263;9579.089986451276;6585.736570537317;6751.729930802713;6813.761518346834;7188.317109874553;7337.816554586178;7555.168931417649;7976.521408715495;8017.318527925192;8096.676132954616;8104.27061564462;7088.964555177191;7127.394171495481;7170.533915295489;7206.099237595301;7344.816379590553;7008.649567521706;7073.752418665322;7082.079329687108;7204.9639751800905;7222;6692.765753198614;6708.127943367526;6783.59298442095;6785.355391237825;6789.694463749242;4906.435759887516;4907.656464047483;4936.4569897311985;4952.059108769322;4994.500549944976;4871.95128048715;4878.292647566154;4880.536349046977;4973.204287314157;5054.241863720471 10:18:56 INFO - PROCESS | 1636 | |4;prototype.html;292.76578737010385;297.5447565225206;305.136463807425;311.3989999301347;311.80979602442494;5178.715170665617;5216.6087543434205;5245.763940622672;5260.526552610264;5301.1783173608055;4911.582515486179;5047.031657653518;5133.897322053556;5140.768665410065;5184.066867963775;3172.8730850766055;3859.1316953685337;3924.5879182685835;3930.0764320384687;3961.6632586230203;3127.218195451137;3886.7862267575274;3918.0792513759243;3935.9766460720134;3940.6404790347283;3239.951400728991;3391.576052993376;3626;3850.5764365919867;3913.823877925486;3545.670373609896;3572.392693242129;3602.5136606558303;3915.5497117831387;3939.251542206986;2832.900848470314;2921.4011127718977;2921.8539073046477;3367.848446819899;3387.7120444762227;4930.654854160211;4953.678010929314;4960.528749768766;4972.701637901738;4997.50024997501;3686.649768272037;4042.2117687051036;4366.737995720267;4445.266531022377;4454.576815202583;4330.783460826946;4928.581070609002;4947.282644016634;5370.462953704638;5412.5940554458575;3094.5512900629506;3539.9115022124397;3695.371786796251;3702.2780557791243;3723.9069023274365;3984.7409918355506;4695.084458530588;5427.294451721291;5664.49019588239;5714.200011998323;3154.148379937404;3256.1371236622313;3330.217398911254;3489.7906125632035;3725.403935370328;3216.967830321714;3776.206996530759;3786.3563194257044;3799.525059367579;3840;3807.029207552112;4012.993503248376;4242.4272723181975;4418.823247070145;4481.955180448219;4969;5128.948275775907;5390.9191362129595;5436.266104075899;5453.263809385682;1206.6319772469305;1216.7140721930339;1238.2756087688804;1241.8633950265464;1242.8197911302811;765.7511308824654;798.8601994650912;806.733777853307;829.9170082991652;836.9665213391396;3937.842486300573;3940.014996250937;4553.977230113895;4623.329617205553;4720.380188995141;4924.138275801721;5219.52072765441;5230.750777165779;5272.734543709559;5273.288106105703;4397.582229688174;4537.982241809226;4803.543663351976;4821.998870468787;4875.658703890694;2686.5835795451735;2701.797365197618;2719.864006799652;2737.466194092133;2768.9861550692117;4837.564619184291;5466.863328416742;5507.7246137692955;5509.889802203934;5522.2544956430365;4965.230389289666;5346.369357346047;5363.812266570651;5376.408535123506;5414.242006119146;5033.7734801934;5236.633435659467;5424.294841670558;5463.48096930791;5474.479924407175;4617.307403889444;5144.30551875492;5191.044001038626;5224.746060945422;5256.658317209369;4532.70537415067;4772.635292119117;5072.300779238996;5153.433122356463;5223.608229382736;4717.339572459927;4945.406551213878;5240.192133713863;5475.466869276609;5487.725613719378;3289.9342013159126;3300.636929937753;3555.1467647764866;3569.8572057117426;3573.7855728655322;4519.774011299356;5100.704682892639;5225.320708307896;5268.394134674561;5516.979358818631;4735.725164157437;5440.265564148789;5454.727263636882;5456.454354564512;5478.45215478449;4731.793392684844;4739.834105806281;4791.544803243686;5052.5705315048635;5439.537639300704;4893.657443978887;4993.650444468853;5274;5339.786408543615;5355.169948657965;5116.848709040436;5390.544552970754;5436.809711660231;5456.2906822114455;5459.317585301837;4764.737939413366;4871.707697538017;5296.576273898002;5298.311219541435;5343.839684809462 10:18:56 INFO - PROCESS | 1636 | |5;yui.html;697.5047716121553;718.6801873166442;735.029760715855;742.3467348733114;751.5528260684897;13986.09090409124;14497.057691250062;14526.41894324227;14536.491222807204;14628.341724622378;12733.236005839655;12859.742805143891;12864.67838304043;12916.160449570794;12979.091463597551;510.49971028392196;647.6729251727871;649.9057636642684;774.6862520679134;804.0351578106296;786.2491320788633;802.546561192927;803.2008151888879;803.9477433966803;804.4650307545476;854.1117238072397;855.3029281135854;855.3542075732813;856.7686724584358;856.7986523167051;658.4765111736182;681.2914568848391;816.7263966571206;849.218718778722;850.0352100366098;625.5308518611042;628.2963081348872;628.4249911331126;628.5066223014911;629.7732816186169;13846.653833654138;13881.791773123412;13899.722005559934;13938.58184254474;13952.86047139536;12906.419211135522;13000;13159.605211843564;13172.473101075946;13184.802227966493;11766.411679415995;11992.460339284666;12152.270863748203;12190.81713774294;12340.444679989332;3681.5214022177215;4165.271077561415;4187.288161012635;4433.689641725081;4489.59593636571;89181;98951;99039.50480247625;99258;99265;10199.38803671782;10255.794884102426;10554.788904221872;10581.523831427605;10855;20087.39737807868;20139.496512587302;20189.394318170478;20192.69710954337;20444.897775511326;7259.818504537428;7413.925860741431;7483;7538.811529711801;7625.1231108422135;4047.2714911315657;4166.895827604334;4184.560621134737;4288.206681763883;4289.656827453859;3157.463231250693;3238.951415728766;3244.4808830586994;3249.3014001989213;3257.0066129830157;600.0938582740093;620.2371083567238;620.9130721698966;621.273110460762;647.0552992630718;562.372954156113;607.9544034197453;609.1198218574207;612.8008397270905;622.5517627308421;7978.521288722695;8060.193980601893;8147.348212143014;8218.63016164274;8589.871151932726;506.2431664661355;570.5293133165155;579.2035950567969;592.2655906675691;627.2285089340137;4124.52567954689;4233.153369326147;4237.809298581571;4240.279172424756;4258.1696569169335;71188;94425.055749443;110084;110443.34334984982;114231;96617.51691241498;96996;99360;104329.47835260927;104757.47621261845;70990.64504677443;71556.64221678962;71775;72481.27518724745;91246.33159849804;9227.232979387883;9667.806643867223;9722.988797231166;9734.160698811831;9832;34341.454634541005;36295.45556816597;41020.589794101674;41051.17897641971;51036;48605;60077;70577;72811.63558490196;73748;11746.76506469883;12102.213356132175;12180.754368363216;12199.756004879675;12374.566890158801;12767.680807979876;13016.47934082664;13721.598720224121;13788;14463.421463141356;10995.890041099487;12379.69050773738;12739.55411560591;13019.544315948897;13122.286703166857;12463.439145238668;12600.369981501071;12852.365974420452;13225.669358266121;13432.194068355539;12122.786941769633;12498.625041248777;12553.623391298275;12698.68253293638;12831.807522887351;9885.456299903575;9979.950548001632;10034.849477257701;10280.177585793268;10286.382817031003;10404.063634272952;10409.791804163722;10472.717879425947;10560.524776385235;10576 10:18:56 INFO - PROCESS | 1636 | __end_tp_report 10:18:56 INFO - PROCESS | 1636 | __start_cc_report 10:18:56 INFO - PROCESS | 1636 | _x_x_mozilla_cycle_collect,2116 10:18:56 INFO - PROCESS | 1636 | __end_cc_report 10:18:56 INFO - PROCESS | 1636 | __startTimestamp1484331536253__endTimestamp 10:18:56 INFO - PROCESS | 1636 | 10:18:56 INFO - PROCESS | 1636 | ------- Summary: start ------- 10:18:56 INFO - PROCESS | 1636 | Number of tests: 6 10:18:56 INFO - PROCESS | 1636 | 10:18:56 INFO - PROCESS | 1636 | [#0] dojo.html Cycles:165 Average:5661.81 Median:5799.10 stddev:628.17 (10.8%) stddev-sans-first:602.17 10:18:56 INFO - PROCESS | 1636 | Values: 3300.9 3304.2 3306.9 3324.4 3358.9 5614.3 5672.3 5681.8 5730.0 5740.1 5582.9 5647.9 5693.3 5695.4 5714.3 5159.4 5160.4 5166.4 5190.9 5213.2 5133.9 5135.3 5144.6 5151.6 5171.0 5211.5 5230.8 5235.6 5241.5 5280.6 5205.8 5240.6 5242.6 5251.9 5279.0 5015.1 5023.6 5086.0 5156.9 5163.0 5588.8 5590.9 5605.0 5605.6 5644.0 6057.8 6105.6 6121.7 6129.4 6143.5 6123.2 6130.6 6140.8 6168.5 6179.6 5676.7 5690.6 5696.6 5743.8 5829.1 6213.7 6219.0 6233.0 6235.1 6238.2 5963.0 6024.3 6048.0 6094.3 6098.7 6123.1 6188.7 6220.8 6238.0 6238.1 5940.2 6008.8 6164.1 6173.0 6187.4 5755.1 5788.6 5799.1 6177.0 6223.6 4866.7 4935.7 4937.3 4937.7 4940.5 4381.4 4386.0 4414.8 4425.8 4429.5 5748.8 5797.4 5801.9 5806.6 5807.0 6122.2 6123.2 6155.7 6160.5 6167.3 5764.3 5775.7 5775.8 5779.7 5822.2 5293.6 5295.9 5302.5 5307.7 5375.8 6131.2 6231.5 6237.4 6258.5 6275.6 6135.5 6188.8 6197.2 6238.0 6238.9 5659.4 6238.5 6263.3 6287.4 6306.5 4803.7 5936.4 6102.8 6157.4 6166.9 4945.5 5714.3 6133.0 6168.8 6175.4 6108.2 6115.1 6120.1 6133.2 6135.7 4658.1 5896.9 6029.6 6038.2 6124.1 5695.9 5743.1 5820.6 5916.6 5931.0 6006.3 6060.0 6095.1 6111.7 6113.8 5679.7 5724.7 5727.4 5855.6 5903.8 10:18:56 INFO - PROCESS | 1636 | 10:18:56 INFO - PROCESS | 1636 | [#1] ext.html Cycles:180 Average:69711.03 Median:37996.43 stddev:165036.65 (434.3%) stddev-sans-first:165417.77 10:18:56 INFO - PROCESS | 1636 | Values: 483.4 499.0 512.5 521.5 549.4 28427.9 39422.0 40499.2 40542.0 40554.8 40056.2 40459.4 40493.0 40575.2 40881.0 9268.7 9419.3 9426.8 9435.2 9439.7 9196.4 9335.2 9341.2 9360.0 9363.8 9324.3 9346.0 9360.3 9380.8 9456.2 9286.7 9345.8 9350.4 9351.8 9385.2 9160.5 9199.3 9211.2 9247.5 9250.4 37897.2 38095.6 38252.4 38255.0 38464.6 3236.5 3551.4 3710.3 3836.3 3863.6 27973.7 28596.6 28644.7 29130.9 29900.0 3170.1 3180.7 3189.2 3210.1 3323.4 461730.0 464044.0 464055.0 464572.0 474949.0 32040.5 56072.7 56497.4 56539.4 56572.2 33170.8 33612.7 34027.5 34062.1 34072.0 14244.0 14367.9 14397.9 14420.0 14429.4 12002.9 12131.1 12144.5 12147.5 12148.1 6465.5 7594.4 7671.4 7688.8 7692.2 3181.7 3227.5 3230.8 3230.9 3266.2 930.6 939.1 978.5 988.3 1097.8 25560.3 28591.6 29196.0 29207.7 29227.6 992.9 1019.5 1025.8 1038.9 1120.0 14518.9 14889.4 14945.4 15296.2 15329.2 664413.0 754925.0 768093.0 769705.0 961135.0 46598.3 50074.7 51632.7 52094.7 52324.7 1091.3 1225.7 1271.3 1353.5 1390.7 26379.3 28470.3 28658.0 30283.2 30816.7 42503.8 49660.8 50432.5 50614.5 50681.5 529128.0 534174.0 534402.3 534573.3 535449.0 22285.3 22872.3 24996.3 25607.5 26095.7 39357.8 42786.0 42887.0 42994.9 43092.6 35757.6 36318.5 36379.6 36457.3 36906.4 35731.0 35784.6 36069.0 36470.5 36644.8 34335.1 34990.3 35316.8 35357.8 35680.6 29672.4 31490.2 31538.4 31666.7 31716.0 23988.9 24031.6 24400.3 24459.3 24495.4 10:18:56 INFO - PROCESS | 1636 | 10:18:56 INFO - PROCESS | 1636 | [#2] jquery.html Cycles:180 Average:32860.36 Median:2291.24 stddev:92484.62 (4036.4%) stddev-sans-first:92711.84 10:18:56 INFO - PROCESS | 1636 | Values: 345.8 368.5 373.1 380.0 399.8 14459.3 14944.6 15281.7 20302.6 20317.3 18858.6 19275.4 19836.7 19893.5 20170.4 5681.4 5954.7 5965.6 5985.5 5990.4 4637.9 5894.4 5938.2 5949.9 5950.3 5608.6 5874.5 5879.2 5881.1 5893.8 5784.1 5849.6 5873.5 5873.6 5891.6 5102.8 5155.0 5170.1 5170.3 5378.5 13133.1 13140.7 13334.7 13344.2 13379.2 1868.5 2205.2 2268.3 2314.2 2776.1 9658.5 10082.5 10376.6 10965.2 14416.6 1658.8 1713.3 1778.1 1836.6 1840.7 113872.0 116462.4 123364.4 125857.0 130465.3 10399.7 11402.3 11550.5 11574.4 11690.6 14519.1 14522.1 14576.9 14596.4 14597.8 8376.4 8480.4 8490.6 8508.2 8617.7 7321.6 7938.4 8429.1 8537.4 8589.1 3240.3 4218.3 4435.8 4436.5 4437.9 1822.3 1886.9 1887.0 1889.8 1891.2 722.3 851.4 903.3 961.0 1006.5 19894.8 20695.0 20720.4 20790.9 20983.7 883.8 902.9 934.5 947.6 975.0 10273.5 11024.3 11030.7 11043.1 11160.6 498553.0 512150.0 515048.0 592956.0 625381.0 91309.0 111149.4 120331.0 127212.0 138424.0 841.6 912.5 936.5 981.8 985.2 13950.7 18175.9 18211.4 18648.9 18815.6 35246.8 46685.0 48437.5 49393.8 51412.7 76551.0 81133.0 84170.6 84821.0 87383.6 7867.4 7877.2 8062.2 8097.3 8201.8 11846.7 12172.1 12252.1 12530.4 12556.1 10674.3 10718.6 10887.9 10990.9 11020.0 10339.0 10555.6 10650.5 10739.4 10775.2 10872.3 10932.8 10969.6 11079.7 11343.1 7827.7 7877.9 8027.7 8058.0 8078.7 9544.6 10146.6 10201.9 10572.4 11084.8 10:18:56 INFO - PROCESS | 1636 | 10:18:56 INFO - PROCESS | 1636 | [#3] mootools.html Cycles:180 Average:5441.81 Median:4951.02 stddev:2964.05 (59.9%) stddev-sans-first:2956.58 10:18:56 INFO - PROCESS | 1636 | Values: 1371.1 1448.2 1598.5 1789.7 1810.4 7948.0 8358.9 8369.3 8375.5 8388.7 6209.0 7596.8 7626.5 8056.0 8176.9 2288.8 2298.8 2338.2 2342.1 2347.5 2311.5 2342.4 2352.4 2356.9 2360.2 2243.7 2260.9 2269.1 2279.6 2325.5 2303.6 2329.2 2331.6 2331.7 2340.8 2322.4 2354.6 2356.1 2360.7 2362.1 8184.8 8258.9 8277.3 8291.2 8328.4 5024.0 5199.9 5200.0 5202.2 5206.8 3281.6 3306.3 3501.2 3548.5 3569.1 1042.1 1059.4 1074.8 1093.1 1093.1 10790.2 10962.6 10977.3 10979.3 11000.8 4222.8 4414.2 4594.9 4676.6 4697.8 9309.0 9326.7 9338.4 9343.7 9368.5 4942.0 4943.1 4944.5 4945.9 4950.0 4183.1 4321.6 4500.5 4508.2 4682.5 3773.3 3787.6 3859.1 3876.9 3882.1 1166.0 1257.9 1265.6 1267.5 1272.0 816.0 890.6 899.5 912.3 915.9 4947.6 5696.7 5766.5 5822.7 5832.3 878.8 898.2 899.0 923.6 926.4 3904.8 3918.0 3925.6 3945.3 4008.1 9640.6 10110.6 10114.3 10128.1 10138.0 10056.7 10061.7 10064.6 10064.8 10086.8 7453.9 7752.8 7852.2 7859.9 7891.1 5788.7 5830.1 5935.6 6031.8 6041.8 8963.5 9215.7 9220.4 9310.1 9411.2 9395.4 9421.2 9478.1 9518.6 9579.1 6585.7 6751.7 6813.8 7188.3 7337.8 7555.2 7976.5 8017.3 8096.7 8104.3 7089.0 7127.4 7170.5 7206.1 7344.8 7008.6 7073.8 7082.1 7205.0 7222.0 6692.8 6708.1 6783.6 6785.4 6789.7 4906.4 4907.7 4936.5 4952.1 4994.5 4872.0 4878.3 4880.5 4973.2 5054.2 10:18:56 INFO - PROCESS | 1636 | 10:18:56 INFO - PROCESS | 1636 | [#4] prototype.html Cycles:180 Average:4239.14 Median:4812.77 stddev:1317.72 (27.4%) stddev-sans-first:1287.70 10:18:56 INFO - PROCESS | 1636 | Values: 292.8 297.5 305.1 311.4 311.8 5178.7 5216.6 5245.8 5260.5 5301.2 4911.6 5047.0 5133.9 5140.8 5184.1 3172.9 3859.1 3924.6 3930.1 3961.7 3127.2 3886.8 3918.1 3936.0 3940.6 3240.0 3391.6 3626.0 3850.6 3913.8 3545.7 3572.4 3602.5 3915.5 3939.3 2832.9 2921.4 2921.9 3367.8 3387.7 4930.7 4953.7 4960.5 4972.7 4997.5 3686.6 4042.2 4366.7 4445.3 4454.6 4330.8 4928.6 4947.3 5370.5 5412.6 3094.6 3539.9 3695.4 3702.3 3723.9 3984.7 4695.1 5427.3 5664.5 5714.2 3154.1 3256.1 3330.2 3489.8 3725.4 3217.0 3776.2 3786.4 3799.5 3840.0 3807.0 4013.0 4242.4 4418.8 4482.0 4969.0 5128.9 5390.9 5436.3 5453.3 1206.6 1216.7 1238.3 1241.9 1242.8 765.8 798.9 806.7 829.9 837.0 3937.8 3940.0 4554.0 4623.3 4720.4 4924.1 5219.5 5230.8 5272.7 5273.3 4397.6 4538.0 4803.5 4822.0 4875.7 2686.6 2701.8 2719.9 2737.5 2769.0 4837.6 5466.9 5507.7 5509.9 5522.3 4965.2 5346.4 5363.8 5376.4 5414.2 5033.8 5236.6 5424.3 5463.5 5474.5 4617.3 5144.3 5191.0 5224.7 5256.7 4532.7 4772.6 5072.3 5153.4 5223.6 4717.3 4945.4 5240.2 5475.5 5487.7 3289.9 3300.6 3555.1 3569.9 3573.8 4519.8 5100.7 5225.3 5268.4 5517.0 4735.7 5440.3 5454.7 5456.5 5478.5 4731.8 4739.8 4791.5 5052.6 5439.5 4893.7 4993.7 5274.0 5339.8 5355.2 5116.8 5390.5 5436.8 5456.3 5459.3 4764.7 4871.7 5296.6 5298.3 5343.8 10:18:56 INFO - PROCESS | 1636 | 10:18:56 INFO - PROCESS | 1636 | [#5] yui.html Cycles:180 Average:19660.68 Median:4239.04 stddev:29150.70 (687.7%) stddev-sans-first:29197.70 10:18:56 INFO - PROCESS | 1636 | Values: 697.5 718.7 735.0 742.3 751.6 13986.1 14497.1 14526.4 14536.5 14628.3 12733.2 12859.7 12864.7 12916.2 12979.1 510.5 647.7 649.9 774.7 804.0 786.2 802.5 803.2 803.9 804.5 854.1 855.3 855.4 856.8 856.8 658.5 681.3 816.7 849.2 850.0 625.5 628.3 628.4 628.5 629.8 13846.7 13881.8 13899.7 13938.6 13952.9 12906.4 13000.0 13159.6 13172.5 13184.8 11766.4 11992.5 12152.3 12190.8 12340.4 3681.5 4165.3 4187.3 4433.7 4489.6 89181.0 98951.0 99039.5 99258.0 99265.0 10199.4 10255.8 10554.8 10581.5 10855.0 20087.4 20139.5 20189.4 20192.7 20444.9 7259.8 7413.9 7483.0 7538.8 7625.1 4047.3 4166.9 4184.6 4288.2 4289.7 3157.5 3239.0 3244.5 3249.3 3257.0 600.1 620.2 620.9 621.3 647.1 562.4 608.0 609.1 612.8 622.6 7978.5 8060.2 8147.3 8218.6 8589.9 506.2 570.5 579.2 592.3 627.2 4124.5 4233.2 4237.8 4240.3 4258.2 71188.0 94425.1 110084.0 110443.3 114231.0 96617.5 96996.0 99360.0 104329.5 104757.5 70990.6 71556.6 71775.0 72481.3 91246.3 9227.2 9667.8 9723.0 9734.2 9832.0 34341.5 36295.5 41020.6 41051.2 51036.0 48605.0 60077.0 70577.0 72811.6 73748.0 11746.8 12102.2 12180.8 12199.8 12374.6 12767.7 13016.5 13721.6 13788.0 14463.4 10995.9 12379.7 12739.6 13019.5 13122.3 12463.4 12600.4 12852.4 13225.7 13432.2 12122.8 12498.6 12553.6 12698.7 12831.8 9885.5 9980.0 10034.8 10280.2 10286.4 10404.1 10409.8 10472.7 10560.5 10576.0 10:18:56 INFO - PROCESS | 1636 | -------- Summary: end -------- 10:18:56 INFO - PROCESS | 1636 | 10:18:56 INFO - TEST-INFO | 1636: exit 0 10:18:56 INFO - TEST-OK | dromaeo_css | took 1119169ms 10:18:56 INFO - TEST-START | kraken 10:18:56 INFO - operating with platform_type : mac_ 10:18:56 INFO - Initialising browser for kraken test... 10:18:56 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/tmpqlHMfF/profile http://localhost:49240/getInfo.html) 10:18:59 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 10:18:59 INFO - PROCESS | 1647 | colorDepth:24 10:18:59 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 10:18:59 INFO - PROCESS | 1647 | __metrics 10:18:59 INFO - PROCESS | 1647 | 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:18:59 INFO - PROCESS | 1647 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:19:00 INFO - TEST-INFO | 1647: exit 0 10:19:00 INFO - Browser initialized. 10:19:00 INFO - Running cycle 1/1 for kraken test... 10:19:00 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/tmpqlHMfF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:19:12 INFO - PROCESS | 1648 | RSS: Main: 242429952 10:19:12 INFO - PROCESS | 1648 | 10:19:15 INFO - PROCESS | 1648 | 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:19:16 INFO - PROCESS | 1648 | RSS: Main: 287592448 10:19:16 INFO - PROCESS | 1648 | 10:19:19 INFO - PROCESS | 1648 | 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:19:19 INFO - PROCESS | 1648 | RSS: Main: 301432832 10:19:19 INFO - PROCESS | 1648 | 10:19:22 INFO - PROCESS | 1648 | 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:19:23 INFO - PROCESS | 1648 | RSS: Main: 395665408 10:19:23 INFO - PROCESS | 1648 | 10:19:25 INFO - PROCESS | 1648 | 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:19:26 INFO - PROCESS | 1648 | RSS: Main: 297414656 10:19:26 INFO - PROCESS | 1648 | 10:19:28 INFO - PROCESS | 1648 | 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:19:28 INFO - PROCESS | 1648 | RSS: Main: 298627072 10:19:28 INFO - PROCESS | 1648 | 10:19:31 INFO - PROCESS | 1648 | 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:19:32 INFO - PROCESS | 1648 | RSS: Main: 338186240 10:19:32 INFO - PROCESS | 1648 | 10:19:35 INFO - PROCESS | 1648 | 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:19:36 INFO - PROCESS | 1648 | RSS: Main: 341299200 10:19:36 INFO - PROCESS | 1648 | 10:19:38 INFO - PROCESS | 1648 | 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:19:39 INFO - PROCESS | 1648 | RSS: Main: 336928768 10:19:39 INFO - PROCESS | 1648 | 10:19:41 INFO - PROCESS | 1648 | 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:19:41 INFO - PROCESS | 1648 | RSS: Main: 311517184 10:19:41 INFO - PROCESS | 1648 | 10:19:43 INFO - PROCESS | 1648 | 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:19:44 INFO - PROCESS | 1648 | RSS: Main: 313376768 10:19:44 INFO - PROCESS | 1648 | 10:19:46 INFO - PROCESS | 1648 | 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:19:46 INFO - PROCESS | 1648 | RSS: Main: 315879424 10:19:46 INFO - PROCESS | 1648 | 10:19:49 INFO - PROCESS | 1648 | 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:19:50 INFO - PROCESS | 1648 | RSS: Main: 333746176 10:19:50 INFO - PROCESS | 1648 | 10:19:53 INFO - PROCESS | 1648 | 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:19:54 INFO - PROCESS | 1648 | RSS: Main: 327012352 10:19:54 INFO - PROCESS | 1648 | 10:19:55 INFO - PROCESS | 1648 | 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:19:56 INFO - PROCESS | 1648 | RSS: Main: 372060160 10:19:56 INFO - PROCESS | 1648 | 10:19:56 INFO - PROCESS | 1648 | __start_tp_report 10:19:56 INFO - PROCESS | 1648 | _x_x_mozilla_page_load 10:19:56 INFO - PROCESS | 1648 | _x_x_mozilla_page_load_details 10:19:56 INFO - PROCESS | 1648 | |i|pagename|runs| 10:19:56 INFO - PROCESS | 1648 | |0;ai-astar;104;115;116;115;117;115;115;116;114;114 10:19:56 INFO - PROCESS | 1648 | |1;audio-beat-detection;141;111;108;143;112;108;110;110;143;109 10:19:56 INFO - PROCESS | 1648 | |2;audio-dft;209;258;194;186;183;186;184;185;185;185 10:19:56 INFO - PROCESS | 1648 | |3;audio-fft;80;59;58;91;61;60;58;75;109;82 10:19:56 INFO - PROCESS | 1648 | |4;audio-oscillator;86;70;93;70;69;70;89;67;68;91 10:19:56 INFO - PROCESS | 1648 | |5;imaging-gaussian-blur;90;86;134;88;88;86;97;103;132;129 10:19:56 INFO - PROCESS | 1648 | |6;imaging-darkroom;95;96;100;150;136;96;123;115;96;95 10:19:56 INFO - PROCESS | 1648 | |7;imaging-desaturate;80;83;80;83;83;83;84;86;84;82 10:19:56 INFO - PROCESS | 1648 | |8;json-parse-financial;54;52;54;53;56;57;57;58;58;58 10:19:56 INFO - PROCESS | 1648 | |9;json-stringify-tinderbox;64;74;65;69;75;76;71;72;70;73 10:19:56 INFO - PROCESS | 1648 | |10;stanford-crypto-aes;75;102;82;82;83;85;84;83;84;84 10:19:56 INFO - PROCESS | 1648 | |11;stanford-crypto-ccm;142;156;157;145;141;142;142;142;187;141 10:19:56 INFO - PROCESS | 1648 | |12;stanford-crypto-pbkdf2;166;163;167;168;166;168;164;168;170;164 10:19:56 INFO - PROCESS | 1648 | |13;stanford-crypto-sha256-iterative;67;68;66;67;66;68;67;66;66;67 10:19:56 INFO - PROCESS | 1648 | __end_tp_report 10:19:56 INFO - PROCESS | 1648 | __start_cc_report 10:19:56 INFO - PROCESS | 1648 | _x_x_mozilla_cycle_collect,1985 10:19:56 INFO - PROCESS | 1648 | __end_cc_report 10:19:56 INFO - PROCESS | 1648 | __startTimestamp1484331596100__endTimestamp 10:19:56 INFO - PROCESS | 1648 | 10:19:56 INFO - PROCESS | 1648 | ------- Summary: start ------- 10:19:56 INFO - PROCESS | 1648 | Number of tests: 14 10:19:56 INFO - PROCESS | 1648 | 10:19:56 INFO - PROCESS | 1648 | [#0] ai-astar Cycles:10 Average:114.10 Median:115.00 stddev:3.67 (3.2%) stddev-sans-first:0.97 10:19:56 INFO - PROCESS | 1648 | Values: 104.0 115.0 116.0 115.0 117.0 115.0 115.0 116.0 114.0 114.0 10:19:56 INFO - PROCESS | 1648 | 10:19:56 INFO - PROCESS | 1648 | [#1] audio-beat-detection Cycles:10 Average:119.50 Median:111.50 stddev:15.81 (14.2%) stddev-sans-first:14.73 10:19:56 INFO - PROCESS | 1648 | Values: 141.0 111.0 108.0 143.0 112.0 108.0 110.0 110.0 143.0 109.0 10:19:56 INFO - PROCESS | 1648 | 10:19:56 INFO - PROCESS | 1648 | [#2] audio-dft Cycles:10 Average:195.50 Median:186.00 stddev:23.31 (12.5%) stddev-sans-first:24.21 10:19:56 INFO - PROCESS | 1648 | Values: 209.0 258.0 194.0 186.0 183.0 186.0 184.0 185.0 185.0 185.0 10:19:56 INFO - PROCESS | 1648 | 10:19:56 INFO - PROCESS | 1648 | [#3] audio-fft Cycles:10 Average:73.30 Median:68.00 stddev:17.36 (25.5%) stddev-sans-first:18.24 10:19:56 INFO - PROCESS | 1648 | Values: 80.0 59.0 58.0 91.0 61.0 60.0 58.0 75.0 109.0 82.0 10:19:56 INFO - PROCESS | 1648 | 10:19:56 INFO - PROCESS | 1648 | [#4] audio-oscillator Cycles:10 Average:77.30 Median:78.00 stddev:10.89 (14.0%) stddev-sans-first:11.09 10:19:56 INFO - PROCESS | 1648 | Values: 86.0 70.0 93.0 70.0 69.0 70.0 89.0 67.0 68.0 91.0 10:19:56 INFO - PROCESS | 1648 | 10:19:56 INFO - PROCESS | 1648 | [#5] imaging-gaussian-blur Cycles:10 Average:103.30 Median:87.00 stddev:20.30 (23.3%) stddev-sans-first:20.96 10:19:56 INFO - PROCESS | 1648 | Values: 90.0 86.0 134.0 88.0 88.0 86.0 97.0 103.0 132.0 129.0 10:19:56 INFO - PROCESS | 1648 | 10:19:56 INFO - PROCESS | 1648 | [#6] imaging-darkroom Cycles:10 Average:110.20 Median:95.00 stddev:20.02 (21.1%) stddev-sans-first:20.47 10:19:56 INFO - PROCESS | 1648 | Values: 95.0 96.0 100.0 150.0 136.0 96.0 123.0 115.0 96.0 95.0 10:19:56 INFO - PROCESS | 1648 | 10:19:56 INFO - PROCESS | 1648 | [#7] imaging-desaturate Cycles:10 Average:82.80 Median:83.00 stddev:1.81 (2.2%) stddev-sans-first:1.62 10:19:56 INFO - PROCESS | 1648 | Values: 80.0 83.0 80.0 83.0 83.0 83.0 84.0 86.0 84.0 82.0 10:19:56 INFO - PROCESS | 1648 | 10:19:56 INFO - PROCESS | 1648 | [#8] json-parse-financial Cycles:10 Average:55.70 Median:57.00 stddev:2.26 (4.0%) stddev-sans-first:2.32 10:19:56 INFO - PROCESS | 1648 | Values: 54.0 52.0 54.0 53.0 56.0 57.0 57.0 58.0 58.0 58.0 10:19:56 INFO - PROCESS | 1648 | 10:19:56 INFO - PROCESS | 1648 | [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:72.50 stddev:4.01 (5.5%) stddev-sans-first:3.39 10:19:56 INFO - PROCESS | 1648 | Values: 64.0 74.0 65.0 69.0 75.0 76.0 71.0 72.0 70.0 73.0 10:19:56 INFO - PROCESS | 1648 | 10:19:56 INFO - PROCESS | 1648 | [#10] stanford-crypto-aes Cycles:10 Average:84.40 Median:83.50 stddev:6.79 (8.1%) stddev-sans-first:6.29 10:19:56 INFO - PROCESS | 1648 | Values: 75.0 102.0 82.0 82.0 83.0 85.0 84.0 83.0 84.0 84.0 10:19:56 INFO - PROCESS | 1648 | 10:19:56 INFO - PROCESS | 1648 | [#11] stanford-crypto-ccm Cycles:10 Average:149.50 Median:143.50 stddev:14.51 (10.1%) stddev-sans-first:15.13 10:19:56 INFO - PROCESS | 1648 | Values: 142.0 156.0 157.0 145.0 141.0 142.0 142.0 142.0 187.0 141.0 10:19:56 INFO - PROCESS | 1648 | 10:19:56 INFO - PROCESS | 1648 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.40 Median:167.50 stddev:2.22 (1.3%) stddev-sans-first:2.35 10:19:56 INFO - PROCESS | 1648 | Values: 166.0 163.0 167.0 168.0 166.0 168.0 164.0 168.0 170.0 164.0 10:19:56 INFO - PROCESS | 1648 | 10:19:56 INFO - PROCESS | 1648 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:67.00 stddev:0.79 (1.2%) stddev-sans-first:0.83 10:19:56 INFO - PROCESS | 1648 | Values: 67.0 68.0 66.0 67.0 66.0 68.0 67.0 66.0 66.0 67.0 10:19:56 INFO - PROCESS | 1648 | -------- Summary: end -------- 10:19:56 INFO - PROCESS | 1648 | 10:19:57 INFO - TEST-INFO | 1648: exit 0 10:19:57 INFO - TEST-OK | kraken | took 60271ms 10:19:57 INFO - SUITE-END | took 1179s 10:19:57 INFO - Completed test suite (00:19:39) 10:19:57 INFO - javascript benchmark 10:19:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3300.851461684223, 3304.206990322323, 3306.875662274826, 3324.3517514084765, 3358.8992330230103, 5614.326280846299, 5672.347680016789, 5681.7727290908415, 5729.971350143249, 5740.0528912729405, 5582.91625625616, 5647.943520564793, 5693.316801983768, 5695.401982791809, 5714.314282286132, 5159.406668233145, 5160.432352441228, 5166.405863325719, 5190.896182076356, 5213.244079608455, 5133.922991155136, 5135.255387968742, 5144.639875208718, 5151.639385243016, 5170.974145129269, 5211.45279745627, 5230.790768369261, 5235.607329450288, 5241.475852414766, 5280.577553795687, 5205.765740541685, 5240.554552863011, 5242.6330156889035, 5251.894962100756, 5279.049771041211, 5015.122353588107, 5023.572996295319, 5085.974570127163, 5156.871078223075, 5163.044836705221, 5588.832335029955, 5590.944090559083, 5605.047141985872, 5605.551555875561, 5643.971780141114, 6057.757689692407, 6105.581053426302, 6121.66330851803, 6129.417705317987, 6143.539234557426, 6123.160610921244, 6130.63216207029, 6140.793556413871, 6168.537359698041, 6179.5674302798825, 5676.744546495418, 5690.573207009491, 5696.62971906833, 5743.827685169452, 5829.067349224104, 6213.720382582795, 6218.968905155445, 6232.968835155795, 6235.127082208494, 6238.189035425366, 5963.0, 6024.3072046714315, 6048.032314829607, 6094.299155597163, 6098.72555734993, 6123.130109042722, 6188.690565471798, 6220.782272620437, 6238.0, 6238.064290356483, 5940.227770389822, 6008.789692360834, 6164.106204600308, 6173.043178307369, 6187.381261873867, 5755.079187330007, 5788.623739456922, 5799.101139323411, 6177.0, 6223.595466294676, 4866.732329721899, 4935.679180853233, 4937.259411088366, 4937.703737775745, 4940.530649588283, 4381.408509851193, 4385.9912220189535, 4414.823407063745, 4425.800838962255, 4429.53489883564, 5748.827535173951, 5797.36229014808, 5801.94198058014, 5806.622569532967, 5806.983777838861, 6122.1542093545395, 6123.203983482119, 6155.661438620829, 6160.537959653044, 6167.259928808572, 5764.337101233328, 5775.740091695884, 5775.797847075332, 5779.711014449261, 5822.155787410802, 5293.550048245942, 5295.947040529545, 5302.496262855023, 5307.681539107666, 5375.758090885842, 6131.20294361748, 6231.53263505248, 6237.407446292595, 6258.530610204253, 6275.623462592259, 6135.538906165067, 6188.752449901954, 6197.163382943245, 6238.033104868752, 6238.875222495434, 5659.377468478547, 6238.469730072995, 6263.342349053284, 6287.402696743802, 6306.495480361468, 4803.711777293373, 5936.37668044849, 6102.755889764538, 6157.4458298753325, 6166.938330616636, 4945.455999839943, 5714.342850572238, 6133.028644416937, 6168.753249869955, 6175.382461753788, 6108.205933228652, 6115.057807494094, 6120.050983828446, 6133.212252453684, 6135.692037377249, 4658.068386322681, 5896.882062358814, 6029.638221706711, 6038.184845045863, 6124.050772130327, 5695.889301586151, 5743.0811070228565, 5820.563457740602, 5916.585838991229, 5930.970345148246, 6006.279246490449, 6059.969700151471, 6095.05526643371, 6111.743676692254, 6113.786017489544, 5679.74441150141, 5724.656520608777, 5727.427257274239, 5855.590108692521, 5903.822885313448], "value": 5623.262419646055, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [483.3667895057477, 499.03685886239566, 512.4644746240178, 521.5280171444841, 549.4368272520665, 28427.86288548458, 39422.0, 40499.1900161997, 40541.986450338685, 40554.79722601383, 40056.198876022536, 40459.39310910339, 40493.0, 40575.188496230134, 40881.0, 9268.675596354129, 9419.340646154771, 9426.764330891714, 9435.245180385587, 9439.669611563597, 9196.448213107202, 9335.159835614793, 9341.159295663389, 9359.953200234024, 9363.812723745521, 9324.25405967521, 9346.01866803986, 9360.29797765167, 9380.765480863, 9456.196223321025, 9286.72139835806, 9345.813083738356, 9350.438973661601, 9351.812963740755, 9385.202257808094, 9160.450372977575, 9199.264058875275, 9211.217046551052, 9247.49138797366, 9250.444973301555, 37897.24205515902, 38095.619043809485, 38252.42621360682, 38255.0, 38464.615353846384, 3236.530703048045, 3551.3607550640872, 3710.344759075925, 3836.3286424875537, 3863.555691095532, 27973.720262797317, 28596.571051434246, 28644.71355286462, 29130.85434572835, 29900.0, 3170.096522491102, 3180.7137357637694, 3189.186757376854, 3210.085125739153, 3323.351946370459, 461730.0, 464044.0, 464055.0, 464572.0, 474949.0, 32040.519392209135, 56072.71963640237, 56497.43502565004, 56539.43460565342, 56572.15141772877, 33170.83414582912, 33612.66387336095, 34027.48958765621, 34062.148446289044, 34072.0, 14244.0, 14367.85632143686, 14397.85602143986, 14420.0, 14429.350679219251, 12002.879971200351, 12131.09016823724, 12144.514219431123, 12147.453364598614, 12148.149629525848, 6465.547411681231, 7594.430417718693, 7671.424643151786, 7688.846223075507, 7692.230776922263, 3181.7295529879757, 3227.5158726191257, 3230.7738458308163, 3230.9192270193435, 3266.1671273825496, 930.5719369090159, 939.1172298039821, 978.4814048554277, 988.2736188901152, 1097.7914196302677, 25560.33857799237, 28591.553517369557, 29196.0, 29207.707922920497, 29227.56158657622, 992.8709267795141, 1019.5073046401625, 1025.825609646362, 1038.9324693895023, 1119.980817456111, 14518.927405362905, 14889.404423823142, 14945.3560108388, 15296.158711270764, 15329.233538323038, 664413.0, 754925.0, 768093.0, 769705.0, 961135.0, 46598.301025484645, 50074.749626251636, 51632.74183629058, 52094.73952630288, 52324.738376307876, 1091.3288327678579, 1225.6997035726126, 1271.2690203133236, 1353.5330311042674, 1390.707951330232, 26379.340516486474, 28470.288242794097, 28658.0, 30283.24291892632, 30816.691833081382, 42503.787481063635, 49660.75169624273, 50432.49567504278, 50614.49385506098, 50681.49318506768, 529128.0, 534174.0, 534402.3279883575, 534573.3271333618, 535449.0, 22285.331440056823, 22872.31383058511, 24996.250112496655, 25607.487850243262, 26095.73904261009, 39357.80321098376, 42786.0, 42887.0, 42994.925126872076, 43092.569074308856, 35757.64242357543, 36318.45522317114, 36379.636203638685, 36457.270854583294, 36906.44640330344, 35731.0, 35784.642153579174, 36069.0, 36470.452943205346, 36644.81677591702, 34335.141621458664, 34990.30019399649, 35316.823415882754, 35357.823210883784, 35680.64319356877, 29672.406551869273, 31490.212744680648, 31538.369232615678, 31666.683333166373, 31716.0, 23988.920498577256, 24031.63952540748, 24400.26799196027, 24459.266222013368, 24495.387615309046], "value": 17966.257579460653, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [345.83399968015334, 368.53932584269666, 373.11571575366366, 379.9753016053957, 399.82008096356645, 14459.349329280181, 14944.5516634501, 15281.658628747582, 20302.593948121066, 20317.288894888683, 18858.62282754344, 19275.4217373479, 19836.702449463235, 19893.502662433446, 20170.394888153343, 5681.431856814327, 5954.732037058343, 5965.582409231356, 5985.461308482236, 5990.371011043843, 4637.884052898671, 5894.410558944114, 5938.168656388109, 5949.851253718648, 5950.315713692916, 5608.6073974821575, 5874.471297583216, 5879.206307148523, 5881.059030555112, 5893.823185304448, 5784.103463963072, 5849.561282903778, 5873.530117590625, 5873.588848780587, 5891.587588868738, 5102.795888164469, 5155.0, 5170.121079416508, 5170.250313704491, 5378.542823859977, 13133.080684352099, 13140.671483212996, 13334.733305333839, 13344.199348039148, 13379.197248165141, 1868.4955062133147, 2205.184081889695, 2268.285490070639, 2314.201600447843, 2776.083892315531, 9658.468784216866, 10082.546285417175, 10376.584936602527, 10965.177611679155, 14416.63958401038, 1658.7677725118492, 1713.288985071194, 1778.1287169286957, 1836.5500452389117, 1840.68708319586, 113872.0, 116462.41768791102, 123364.38317808353, 125857.0, 130465.34767326197, 10399.688009359732, 11402.258853174517, 11550.537978480766, 11574.363410012362, 11690.649280521598, 14519.05626134298, 14522.128672279698, 14576.927115364355, 14596.41614335436, 14597.78103328451, 8376.371772117116, 8480.363972702073, 8490.575471226413, 8508.191721786421, 8617.69838055665, 7321.633918304064, 7938.444308898322, 8429.114942931026, 8537.359698022548, 8589.055203927563, 3240.254741409489, 4218.261804184255, 4435.824506505778, 4436.511983681857, 4437.912711385667, 1822.2984151101657, 1886.8584856135844, 1886.9716954245698, 1889.754331936867, 1891.1678861300984, 722.3246264742482, 851.4167795060404, 903.2503022491317, 960.9663661771805, 1006.4866917871792, 19894.801051989296, 20694.965251737354, 20720.378388648063, 20790.896045519676, 20983.685244721342, 883.8364902493059, 902.9097090290918, 934.528063328015, 947.5688561704408, 975.0103644800533, 10273.537690803932, 11024.338539687644, 11030.669079927615, 11043.061339786047, 11160.609378671708, 498553.0, 512150.0, 515048.0, 592956.0, 625381.0, 91309.0, 111149.44425277822, 120331.0, 127212.0, 138424.0, 841.5792103948025, 912.5254867468879, 936.489613160829, 981.8183636027358, 985.1822986920838, 13950.720985580434, 18175.909445433317, 18211.36097751156, 18648.906755466134, 18815.623687526448, 35246.82376588203, 46685.0, 48437.5156248433, 49393.75303123461, 51412.74293628508, 76551.0, 81133.0, 84170.57914710387, 84821.0, 87383.56308218418, 7867.370610351272, 7877.212278772077, 8062.193780622126, 8097.311728503143, 8201.79495512617, 11846.703832403915, 12172.14794964344, 12252.142350035412, 12530.436130374337, 12556.121071524905, 10674.306170098918, 10718.624848130277, 10887.891121089004, 10990.890091099307, 11020.008199262105, 10339.0, 10555.577776888838, 10650.467476626292, 10739.355638661706, 10775.245732798943, 10872.347659140476, 10932.836007459735, 10969.616063438061, 11079.72300692489, 11343.149263805082, 7827.686892524235, 7877.921220787719, 8027.719029833927, 8058.0, 8078.7172448964, 9544.618215271312, 10146.594136234764, 10201.897981020094, 10572.418516981332, 11084.833727493933], "value": 9127.270509231272, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1371.0539727587964, 1448.1890141520753, 1598.5204438668397, 1789.7494350790882, 1810.4025671528389, 7948.0, 8358.91641083589, 8369.288610468102, 8375.455595386304, 8388.706395276167, 6209.0, 7596.848063038736, 7626.542407455557, 8055.959720201406, 8176.877346839802, 2288.828337874666, 2298.7586303438147, 2338.1816364272454, 2342.1451170322794, 2347.542229265294, 2311.514581937795, 2342.4026873147322, 2352.435415500276, 2356.8703721295324, 2360.2329242996093, 2243.719535058118, 2260.8756518391483, 2269.1377276634857, 2279.578278018572, 2325.4883925536355, 2303.6199027160496, 2329.219711396684, 2331.63859601762, 2331.743508214112, 2340.7776261255326, 2322.384568089458, 2354.6114891042957, 2356.081128359941, 2360.7167139943144, 2362.0905951208906, 8184.836303273961, 8258.876116858251, 8277.255047045795, 8291.170882911721, 8328.417010809246, 5023.970086132371, 5199.948000519984, 5199.974000130013, 5202.167653175512, 5206.7917283308625, 3281.5898012748407, 3306.2891478332276, 3501.19472521319, 3548.4677298405186, 3569.1434055826676, 1042.11940909931, 1059.4332032362727, 1074.8011617850723, 1093.1418836213534, 1093.147345070846, 10790.190735694776, 10962.561497540168, 10977.28647637901, 10979.341239525655, 11000.779984400348, 4222.78886055696, 4414.161309351213, 4594.908101838011, 4676.625869930397, 4697.8355757548325, 9308.95345523268, 9326.673566425143, 9338.439693618404, 9343.71968840936, 9368.484733339596, 4942.011597680478, 4943.134951383494, 4944.48082951285, 4945.876353091202, 4949.985253023122, 4183.058811767389, 4321.632661223768, 4500.5274446182675, 4508.211063064016, 4682.531746825291, 3773.339665558516, 3787.6401741835034, 3859.073822282632, 3876.9224615507533, 3882.145927895858, 1165.976680466386, 1257.937103144839, 1265.5697062998624, 1267.5056727876135, 1272.0332547264145, 816.0003995105948, 890.6259371064168, 899.547241156279, 912.3476714149415, 915.9129882661136, 4947.6289278304275, 5696.715164241771, 5766.481016708516, 5822.679752613647, 5832.300123985149, 878.7934835313696, 898.2006311145373, 898.9865152022725, 923.5659240157115, 926.356182453202, 3904.8480698194103, 3917.9804100979313, 3925.6270654287796, 3945.3292940200236, 4008.118213992917, 9640.566174522022, 10110.595576176871, 10114.291999560108, 10128.088472037552, 10138.0, 10056.74858128538, 10061.69814905554, 10064.597416103421, 10064.798704025878, 10086.848697269546, 7453.925460745323, 7752.806179845436, 7852.214778522102, 7859.882101768478, 7891.092524359658, 5788.739506722123, 5830.096335068071, 5935.554833387427, 6031.7587296507645, 6041.75832966694, 8963.462192268486, 9215.723528294162, 9220.354575179674, 9310.068993100636, 9411.247100231829, 9395.43627382332, 9421.246300296098, 9478.052194780743, 9518.571664275263, 9579.089986451276, 6585.736570537317, 6751.729930802713, 6813.761518346834, 7188.317109874553, 7337.816554586178, 7555.168931417649, 7976.521408715495, 8017.318527925192, 8096.676132954616, 8104.27061564462, 7088.964555177191, 7127.394171495481, 7170.533915295489, 7206.099237595301, 7344.816379590553, 7008.649567521706, 7073.752418665322, 7082.079329687108, 7204.9639751800905, 7222.0, 6692.765753198614, 6708.127943367526, 6783.59298442095, 6785.355391237825, 6789.694463749242, 4906.435759887516, 4907.656464047483, 4936.4569897311985, 4952.059108769322, 4994.500549944976, 4871.95128048715, 4878.292647566154, 4880.536349046977, 4973.204287314157, 5054.241863720471], "value": 4421.50702440439, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [292.76578737010385, 297.5447565225206, 305.136463807425, 311.3989999301347, 311.80979602442494, 5178.715170665617, 5216.6087543434205, 5245.763940622672, 5260.526552610264, 5301.1783173608055, 4911.582515486179, 5047.031657653518, 5133.897322053556, 5140.768665410065, 5184.066867963775, 3172.8730850766055, 3859.1316953685337, 3924.5879182685835, 3930.0764320384687, 3961.6632586230203, 3127.218195451137, 3886.7862267575274, 3918.0792513759243, 3935.9766460720134, 3940.6404790347283, 3239.951400728991, 3391.576052993376, 3626.0, 3850.5764365919867, 3913.823877925486, 3545.670373609896, 3572.392693242129, 3602.5136606558303, 3915.5497117831387, 3939.251542206986, 2832.900848470314, 2921.4011127718977, 2921.8539073046477, 3367.848446819899, 3387.7120444762227, 4930.654854160211, 4953.678010929314, 4960.528749768766, 4972.701637901738, 4997.50024997501, 3686.649768272037, 4042.2117687051036, 4366.737995720267, 4445.266531022377, 4454.576815202583, 4330.783460826946, 4928.581070609002, 4947.282644016634, 5370.462953704638, 5412.5940554458575, 3094.5512900629506, 3539.9115022124397, 3695.371786796251, 3702.2780557791243, 3723.9069023274365, 3984.7409918355506, 4695.084458530588, 5427.294451721291, 5664.49019588239, 5714.200011998323, 3154.148379937404, 3256.1371236622313, 3330.217398911254, 3489.7906125632035, 3725.403935370328, 3216.967830321714, 3776.206996530759, 3786.3563194257044, 3799.525059367579, 3840.0, 3807.029207552112, 4012.993503248376, 4242.4272723181975, 4418.823247070145, 4481.955180448219, 4969.0, 5128.948275775907, 5390.9191362129595, 5436.266104075899, 5453.263809385682, 1206.6319772469305, 1216.7140721930339, 1238.2756087688804, 1241.8633950265464, 1242.8197911302811, 765.7511308824654, 798.8601994650912, 806.733777853307, 829.9170082991652, 836.9665213391396, 3937.842486300573, 3940.014996250937, 4553.977230113895, 4623.329617205553, 4720.380188995141, 4924.138275801721, 5219.52072765441, 5230.750777165779, 5272.734543709559, 5273.288106105703, 4397.582229688174, 4537.982241809226, 4803.543663351976, 4821.998870468787, 4875.658703890694, 2686.5835795451735, 2701.797365197618, 2719.864006799652, 2737.466194092133, 2768.9861550692117, 4837.564619184291, 5466.863328416742, 5507.7246137692955, 5509.889802203934, 5522.2544956430365, 4965.230389289666, 5346.369357346047, 5363.812266570651, 5376.408535123506, 5414.242006119146, 5033.7734801934, 5236.633435659467, 5424.294841670558, 5463.48096930791, 5474.479924407175, 4617.307403889444, 5144.30551875492, 5191.044001038626, 5224.746060945422, 5256.658317209369, 4532.70537415067, 4772.635292119117, 5072.300779238996, 5153.433122356463, 5223.608229382736, 4717.339572459927, 4945.406551213878, 5240.192133713863, 5475.466869276609, 5487.725613719378, 3289.9342013159126, 3300.636929937753, 3555.1467647764866, 3569.8572057117426, 3573.7855728655322, 4519.774011299356, 5100.704682892639, 5225.320708307896, 5268.394134674561, 5516.979358818631, 4735.725164157437, 5440.265564148789, 5454.727263636882, 5456.454354564512, 5478.45215478449, 4731.793392684844, 4739.834105806281, 4791.544803243686, 5052.5705315048635, 5439.537639300704, 4893.657443978887, 4993.650444468853, 5274.0, 5339.786408543615, 5355.169948657965, 5116.848709040436, 5390.544552970754, 5436.809711660231, 5456.2906822114455, 5459.317585301837, 4764.737939413366, 4871.707697538017, 5296.576273898002, 5298.311219541435, 5343.839684809462], "value": 3823.092188719662, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [697.5047716121553, 718.6801873166442, 735.029760715855, 742.3467348733114, 751.5528260684897, 13986.09090409124, 14497.057691250062, 14526.41894324227, 14536.491222807204, 14628.341724622378, 12733.236005839655, 12859.742805143891, 12864.67838304043, 12916.160449570794, 12979.091463597551, 510.49971028392196, 647.6729251727871, 649.9057636642684, 774.6862520679134, 804.0351578106296, 786.2491320788633, 802.546561192927, 803.2008151888879, 803.9477433966803, 804.4650307545476, 854.1117238072397, 855.3029281135854, 855.3542075732813, 856.7686724584358, 856.7986523167051, 658.4765111736182, 681.2914568848391, 816.7263966571206, 849.218718778722, 850.0352100366098, 625.5308518611042, 628.2963081348872, 628.4249911331126, 628.5066223014911, 629.7732816186169, 13846.653833654138, 13881.791773123412, 13899.722005559934, 13938.58184254474, 13952.86047139536, 12906.419211135522, 13000.0, 13159.605211843564, 13172.473101075946, 13184.802227966493, 11766.411679415995, 11992.460339284666, 12152.270863748203, 12190.81713774294, 12340.444679989332, 3681.5214022177215, 4165.271077561415, 4187.288161012635, 4433.689641725081, 4489.59593636571, 89181.0, 98951.0, 99039.50480247625, 99258.0, 99265.0, 10199.38803671782, 10255.794884102426, 10554.788904221872, 10581.523831427605, 10855.0, 20087.39737807868, 20139.496512587302, 20189.394318170478, 20192.69710954337, 20444.897775511326, 7259.818504537428, 7413.925860741431, 7483.0, 7538.811529711801, 7625.1231108422135, 4047.2714911315657, 4166.895827604334, 4184.560621134737, 4288.206681763883, 4289.656827453859, 3157.463231250693, 3238.951415728766, 3244.4808830586994, 3249.3014001989213, 3257.0066129830157, 600.0938582740093, 620.2371083567238, 620.9130721698966, 621.273110460762, 647.0552992630718, 562.372954156113, 607.9544034197453, 609.1198218574207, 612.8008397270905, 622.5517627308421, 7978.521288722695, 8060.193980601893, 8147.348212143014, 8218.63016164274, 8589.871151932726, 506.2431664661355, 570.5293133165155, 579.2035950567969, 592.2655906675691, 627.2285089340137, 4124.52567954689, 4233.153369326147, 4237.809298581571, 4240.279172424756, 4258.1696569169335, 71188.0, 94425.055749443, 110084.0, 110443.34334984982, 114231.0, 96617.51691241498, 96996.0, 99360.0, 104329.47835260927, 104757.47621261845, 70990.64504677443, 71556.64221678962, 71775.0, 72481.27518724745, 91246.33159849804, 9227.232979387883, 9667.806643867223, 9722.988797231166, 9734.160698811831, 9832.0, 34341.454634541005, 36295.45556816597, 41020.589794101674, 41051.17897641971, 51036.0, 48605.0, 60077.0, 70577.0, 72811.63558490196, 73748.0, 11746.76506469883, 12102.213356132175, 12180.754368363216, 12199.756004879675, 12374.566890158801, 12767.680807979876, 13016.47934082664, 13721.598720224121, 13788.0, 14463.421463141356, 10995.890041099487, 12379.69050773738, 12739.55411560591, 13019.544315948897, 13122.286703166857, 12463.439145238668, 12600.369981501071, 12852.365974420452, 13225.669358266121, 13432.194068355539, 12122.786941769633, 12498.625041248777, 12553.623391298275, 12698.68253293638, 12831.807522887351, 9885.456299903575, 9979.950548001632, 10034.849477257701, 10280.177585793268, 10286.382817031003, 10404.063634272952, 10409.791804163722, 10472.717879425947, 10560.524776385235, 10576.0], "value": 7024.077465439182, "unit": "score"}], "name": "dromaeo_css", "value": 6916.735918244154, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 115.0, 116.0, 115.0, 117.0, 115.0, 115.0, 116.0, 114.0, 114.0], "value": 114.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 111.0, 108.0, 143.0, 112.0, 108.0, 110.0, 110.0, 143.0, 109.0], "value": 119.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [209.0, 258.0, 194.0, 186.0, 183.0, 186.0, 184.0, 185.0, 185.0, 185.0], "value": 195.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 58.0, 91.0, 61.0, 60.0, 58.0, 75.0, 109.0, 82.0], "value": 73.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 70.0, 93.0, 70.0, 69.0, 70.0, 89.0, 67.0, 68.0, 91.0], "value": 77.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 86.0, 134.0, 88.0, 88.0, 86.0, 97.0, 103.0, 132.0, 129.0], "value": 103.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 100.0, 150.0, 136.0, 96.0, 123.0, 115.0, 96.0, 95.0], "value": 110.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 83.0, 80.0, 83.0, 83.0, 83.0, 84.0, 86.0, 84.0, 82.0], "value": 82.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 54.0, 53.0, 56.0, 57.0, 57.0, 58.0, 58.0, 58.0], "value": 55.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 74.0, 65.0, 69.0, 75.0, 76.0, 71.0, 72.0, 70.0, 73.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 102.0, 82.0, 82.0, 83.0, 85.0, 84.0, 83.0, 84.0, 84.0], "value": 84.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 156.0, 157.0, 145.0, 141.0, 142.0, 142.0, 142.0, 187.0, 141.0], "value": 149.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 163.0, 167.0, 168.0, 166.0, 168.0, 164.0, 168.0, 170.0, 164.0], "value": 166.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 66.0, 67.0, 66.0, 68.0, 67.0, 66.0, 66.0, 67.0], "value": 66.8, "unit": "score"}], "name": "kraken", "value": 1469.7, "alertThreshold": 2.0}]} 10:19:57 INFO - Return code: 0 10:19:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:19:57 INFO - # TBPL SUCCESS # 10:19:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:19:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:19:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:19:57 INFO - 2017-01-13 10:19:57.438 screenresolution[1652:9864] starting screenresolution argv=screenresolution get 10:19:57 INFO - 2017-01-13 10:19:57.442 screenresolution[1652:9864] Display 0: 1600x1200x32@60 10:19:57 INFO - 2017-01-13 10:19:57.448 screenresolution[1653:9867] starting screenresolution argv=screenresolution list 10:19:57 INFO - Available Modes on Display 0 10:19:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:19:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:19:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:19:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:19:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:19:57 INFO - Intel Iris: 10:19:57 INFO - Chipset Model: Intel Iris 10:19:57 INFO - Type: GPU 10:19:57 INFO - Bus: Built-In 10:19:57 INFO - VRAM (Dynamic, Max): 1536 MB 10:19:57 INFO - Vendor: Intel (0x8086) 10:19:57 INFO - Device ID: 0x0a2e 10:19:57 INFO - Revision ID: 0x0009 10:19:57 INFO - Displays: 10:19:57 INFO - Display: 10:19:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:19:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:19:57 INFO - Display Serial Number: 1600x1200 60 10:19:57 INFO - Main Display: Yes 10:19:57 INFO - Mirror: Off 10:19:57 INFO - Online: Yes 10:19:57 INFO - Rotation: Supported 10:19:57 INFO - Return code: 0 10:19:57 INFO - Running post-action listener: _resource_record_post_action 10:19:57 INFO - [mozharness: 2017-01-13 18:19:57.696901Z] Finished run-tests step (success) 10:19:57 INFO - Running post-run listener: _resource_record_post_run 10:19:57 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 39449600; Write bytes: 654187008; Read time: 397; Write time: 1971 10:19:57 INFO - TinderboxPrint: CPU usage
53.5% 10:19:57 INFO - TinderboxPrint: I/O read bytes / time
39,449,600 / 397 10:19:57 INFO - TinderboxPrint: I/O write bytes / time
654,187,008 / 1,971 10:19:57 INFO - TinderboxPrint: CPU idle
2,254.8 (46.5%) 10:19:57 INFO - TinderboxPrint: CPU system
972.0 (20.0%) 10:19:57 INFO - TinderboxPrint: CPU user
1,622.9 (33.5%) 10:19:57 INFO - TinderboxPrint: Swap in / out
388,055,040 / 0 10:19:57 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 345608704; Write bytes: 345827328; Read time: 28491; Write time: 733 10:19:57 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 37282816; Write bytes: 301433344; Read time: 362; Write time: 1212 10:19:58 INFO - Running post-run listener: _upload_blobber_files 10:19:58 INFO - Blob upload gear active. 10:19:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:19:58 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:19:58 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:19:58 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:19:58 INFO - (blobuploader) - INFO - Open directory for files ... 10:19:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:19:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:19:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:19:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:19:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:19:59 INFO - (blobuploader) - INFO - Done attempting. 10:19:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:19:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:19:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:20:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:20:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:20:00 INFO - (blobuploader) - INFO - Done attempting. 10:20:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:20:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:20:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:20:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:20:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:20:03 INFO - (blobuploader) - INFO - Done attempting. 10:20:03 INFO - (blobuploader) - INFO - Iteration through files over. 10:20:03 INFO - Return code: 0 10:20:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:20:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:20:03 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1a3dfcd09cd96696100b7e40dfd3816bdb297b2e1da75f3f2bdd76c153870698ac1b371d567474d1dfba0aa8c288b299e9ab14943c80b529439ca32651369a22", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f2307ee90ec1258798d5c7196bcf4ecb76bbcd524e2c95aaa8bcae456d9e3209c640cf3a5bae75ef82e4b2bbf8c800810dcc25b533487c1dc85e496d5a5a8412", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5d861e4e1d325c6e5aecab26dfbe0cd03e5b33a71a7b542e8945598ab6a00119a4a4198d07cd3a1429265f4bc5be6926ee410ee803df0e0c9140123cac7a4802"} 10:20:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:20:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:20:03 INFO - Contents: 10:20:03 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1a3dfcd09cd96696100b7e40dfd3816bdb297b2e1da75f3f2bdd76c153870698ac1b371d567474d1dfba0aa8c288b299e9ab14943c80b529439ca32651369a22", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f2307ee90ec1258798d5c7196bcf4ecb76bbcd524e2c95aaa8bcae456d9e3209c640cf3a5bae75ef82e4b2bbf8c800810dcc25b533487c1dc85e496d5a5a8412", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5d861e4e1d325c6e5aecab26dfbe0cd03e5b33a71a7b542e8945598ab6a00119a4a4198d07cd3a1429265f4bc5be6926ee410ee803df0e0c9140123cac7a4802"} 10:20:03 INFO - Running post-run listener: copy_logs_to_upload_dir 10:20:03 INFO - Copying logs to upload dir... 10:20:03 INFO - mkdir: /builds/slave/test/build/upload/logs 10:20:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.108532 ========= master_lag: 0.10 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2017-01-13 10:20:03.582364) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-13 10:20:03.588010) ========= 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.MzWTRPMN7n/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.Tmd2BDOn5S/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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/1a3dfcd09cd96696100b7e40dfd3816bdb297b2e1da75f3f2bdd76c153870698ac1b371d567474d1dfba0aa8c288b299e9ab14943c80b529439ca32651369a22", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f2307ee90ec1258798d5c7196bcf4ecb76bbcd524e2c95aaa8bcae456d9e3209c640cf3a5bae75ef82e4b2bbf8c800810dcc25b533487c1dc85e496d5a5a8412", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5d861e4e1d325c6e5aecab26dfbe0cd03e5b33a71a7b542e8945598ab6a00119a4a4198d07cd3a1429265f4bc5be6926ee410ee803df0e0c9140123cac7a4802"} build_url:https://queue.taskcluster.net/v1/task/Bkw0sAzqTDes6j_ablcLvQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010609 build_url: 'https://queue.taskcluster.net/v1/task/Bkw0sAzqTDes6j_ablcLvQ/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/1a3dfcd09cd96696100b7e40dfd3816bdb297b2e1da75f3f2bdd76c153870698ac1b371d567474d1dfba0aa8c288b299e9ab14943c80b529439ca32651369a22", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f2307ee90ec1258798d5c7196bcf4ecb76bbcd524e2c95aaa8bcae456d9e3209c640cf3a5bae75ef82e4b2bbf8c800810dcc25b533487c1dc85e496d5a5a8412", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5d861e4e1d325c6e5aecab26dfbe0cd03e5b33a71a7b542e8945598ab6a00119a4a4198d07cd3a1429265f4bc5be6926ee410ee803df0e0c9140123cac7a4802"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-13 10:20:03.622093) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 10:20:03.622423) ========= 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.MzWTRPMN7n/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.Tmd2BDOn5S/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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.005004 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 10:20:03.675298) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-13 10:20:03.677679) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-13 10:20:03.678026) ========= ========= Total master_lag: 0.63 =========