builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0111 starttime: 1479248308.15 results: success (0) buildid: 20161115115349 builduid: bcb6b2599da74adbbce2d9d08ca0f296 revision: 07f57a55ee1b4d5fb5655fd4c5a9094e2d8a0f09 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-15 14:18:28.145967) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-15 14:18:28.146428) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-15 14:18:28.146713) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-15 14:18:28.176116) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-15 14:18:28.176508) ========= 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.dKYVcusZKS/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.yUEGvkqWPt/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-15 14:18:28-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.6M=0.001s 2016-11-15 14:18:28 (14.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.121295 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-15 14:18:28.323059) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-15 14:18:28.323788) ========= 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.dKYVcusZKS/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.yUEGvkqWPt/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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.025621 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-15 14:18:28.373277) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-15 14:18:28.373622) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 07f57a55ee1b4d5fb5655fd4c5a9094e2d8a0f09 --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 07f57a55ee1b4d5fb5655fd4c5a9094e2d8a0f09 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dKYVcusZKS/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.yUEGvkqWPt/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-15 14:18:28,499 truncating revision to first 12 chars 2016-11-15 14:18:28,499 Setting DEBUG logging. 2016-11-15 14:18:28,499 attempt 1/10 2016-11-15 14:18:28,499 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/07f57a55ee1b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-15 14:18:30,294 unpacking tar archive at: mozilla-aurora-07f57a55ee1b/testing/mozharness/ program finished with exit code 0 elapsedTime=2.092232 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-15 14:18:30.487636) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-15 14:18:30.487952) ========= script_repo_revision: 07f57a55ee1b4d5fb5655fd4c5a9094e2d8a0f09 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-15 14:18:30.488344) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-15 14:18:30.488614) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-15 14:18:30.506735) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-15 14:18:30.507071) ========= /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.dKYVcusZKS/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.yUEGvkqWPt/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 14:18:30 INFO - MultiFileLogger online at 20161115 14:18:30 in /builds/slave/test 14:18:30 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 14:18:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:18:30 INFO - {'append_to_log': False, 14:18:30 INFO - 'base_work_dir': '/builds/slave/test', 14:18:30 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 14:18:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:18:30 INFO - 'branch': 'Mozilla-Aurora', 14:18:30 INFO - 'buildbot_json_path': 'buildprops.json', 14:18:30 INFO - 'config_files': ('talos/mac_config.py',), 14:18:30 INFO - 'default_actions': ('clobber', 14:18:30 INFO - 'read-buildbot-config', 14:18:30 INFO - 'download-and-extract', 14:18:30 INFO - 'populate-webroot', 14:18:30 INFO - 'create-virtualenv', 14:18:30 INFO - 'install', 14:18:30 INFO - 'run-tests'), 14:18:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:18:30 INFO - 'download_minidump_stackwalk': True, 14:18:30 INFO - 'download_symbols': 'ondemand', 14:18:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:18:30 INFO - 'tooltool.py': '/tools/tooltool.py', 14:18:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:18:30 INFO - '/tools/misc-python/virtualenv.py')}, 14:18:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:18:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:18:30 INFO - 'installer_path': 'installer.exe', 14:18:30 INFO - 'log_level': 'info', 14:18:30 INFO - 'log_name': 'talos', 14:18:30 INFO - 'log_to_console': True, 14:18:30 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:18:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:18:30 INFO - 'opt_config_files': (), 14:18:30 INFO - 'pip_index': False, 14:18:30 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:18:30 INFO - 'cmd': ('bash', 14:18:30 INFO - '-c', 14:18:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:18:30 INFO - 'enabled': True, 14:18:30 INFO - 'halt_on_failure': False, 14:18:30 INFO - 'name': 'check_screen_resolution'},), 14:18:30 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:18:30 INFO - 'cmd': ('bash', 14:18:30 INFO - '-c', 14:18:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:18:30 INFO - 'enabled': True, 14:18:30 INFO - 'halt_on_failure': False, 14:18:30 INFO - 'name': 'check_screen_resolution'},), 14:18:30 INFO - 'run_cmd_checks_enabled': True, 14:18:30 INFO - 'sps_profile': False, 14:18:30 INFO - 'sps_profile_interval': 0, 14:18:30 INFO - 'suite': 'dromaeojs', 14:18:30 INFO - 'system_bits': '32', 14:18:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:18:30 INFO - 'title': 't-yosemite-r7-0111', 14:18:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:18:30 INFO - 'use_talos_json': True, 14:18:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:18:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:18:30 INFO - 'work_dir': 'build'} 14:18:30 INFO - [mozharness: 2016-11-15 22:18:30.714018Z] Running clobber step. 14:18:30 INFO - Running pre-action listener: _resource_record_pre_action 14:18:30 INFO - Running main action method: clobber 14:18:30 INFO - rmtree: /builds/slave/test/build 14:18:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:18:33 INFO - Running post-action listener: _resource_record_post_action 14:18:33 INFO - [mozharness: 2016-11-15 22:18:33.032249Z] Finished clobber step (success) 14:18:33 INFO - [mozharness: 2016-11-15 22:18:33.032377Z] Running read-buildbot-config step. 14:18:33 INFO - Running pre-action listener: _resource_record_pre_action 14:18:33 INFO - Running main action method: read_buildbot_config 14:18:33 INFO - Using buildbot properties: 14:18:33 INFO - { 14:18:33 INFO - "project": "", 14:18:33 INFO - "product": "firefox", 14:18:33 INFO - "script_repo_revision": "production", 14:18:33 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 14:18:33 INFO - "repository": "", 14:18:33 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 14:18:33 INFO - "buildid": "20161115115349", 14:18:33 INFO - "pgo_build": "False", 14:18:33 INFO - "basedir": "/builds/slave/test", 14:18:33 INFO - "buildnumber": 25, 14:18:33 INFO - "slavename": "t-yosemite-r7-0111", 14:18:33 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 14:18:33 INFO - "platform": "yosemite_r7", 14:18:33 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 14:18:33 INFO - "branch": "mozilla-aurora", 14:18:33 INFO - "repo_path": "releases/mozilla-aurora", 14:18:33 INFO - "revision": "07f57a55ee1b4d5fb5655fd4c5a9094e2d8a0f09", 14:18:33 INFO - "stage_platform": "macosx64", 14:18:33 INFO - "builduid": "bcb6b2599da74adbbce2d9d08ca0f296", 14:18:33 INFO - "slavebuilddir": "test" 14:18:33 INFO - } 14:18:33 INFO - Found installer url https://queue.taskcluster.net/v1/task/HdnhV3ZmRi64iAGZpVSh4w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 14:18:33 INFO - Running post-action listener: _resource_record_post_action 14:18:33 INFO - [mozharness: 2016-11-15 22:18:33.035738Z] Finished read-buildbot-config step (success) 14:18:33 INFO - [mozharness: 2016-11-15 22:18:33.035840Z] Running download-and-extract step. 14:18:33 INFO - Running pre-action listener: _resource_record_pre_action 14:18:33 INFO - Running main action method: download_and_extract 14:18:33 INFO - mkdir: /builds/slave/test/build/tests 14:18: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')]} 14:18:33 INFO - trying https://queue.taskcluster.net/v1/task/HdnhV3ZmRi64iAGZpVSh4w/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 14:18:33 INFO - Downloading https://queue.taskcluster.net/v1/task/HdnhV3ZmRi64iAGZpVSh4w/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 14:18:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HdnhV3ZmRi64iAGZpVSh4w/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 14:18:33 INFO - Downloaded 1199 bytes. 14:18:33 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 14:18:33 INFO - Using the following test package requirements: 14:18:33 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 14:18:33 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:18:33 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 14:18:33 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:18:33 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 14:18:33 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:18:33 INFO - u'jsshell-mac.zip'], 14:18:33 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:18:33 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 14:18:33 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 14:18:33 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:18:33 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 14:18:33 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:18:33 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 14:18:33 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:18:33 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 14:18:33 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:18:33 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 14:18:33 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 14:18:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HdnhV3ZmRi64iAGZpVSh4w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 14:18:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HdnhV3ZmRi64iAGZpVSh4w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 14:18:33 INFO - Fetch https://queue.taskcluster.net/v1/task/HdnhV3ZmRi64iAGZpVSh4w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 14:18:34 INFO - Http code: 200 14:18:34 INFO - via: 1.1 ccaa055c9a26ce13da709353563e04a5.cloudfront.net (CloudFront) 14:18:34 INFO - x-amz-cf-id: CzbdaDATNkQBoCDTynCBA1Ik9QfQU88Fq3thKKYxijw8caZcLTjjZg== 14:18:34 INFO - x-amz-version-id: YO_0mBb2fHVXRrARdWSmP8dTgM1zB5F_ 14:18:34 INFO - Expected file size: 16327499 14:18:34 INFO - Obtained file size: 16327499 14:18:41 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 14:18:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HdnhV3ZmRi64iAGZpVSh4w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 14:18:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HdnhV3ZmRi64iAGZpVSh4w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 14:18:41 INFO - Fetch https://queue.taskcluster.net/v1/task/HdnhV3ZmRi64iAGZpVSh4w/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 14:18:42 INFO - Http code: 200 14:18:42 INFO - via: 1.1 73fa5a1ee49cf827e596b502b5927eab.cloudfront.net (CloudFront) 14:18:42 INFO - x-amz-cf-id: 0Hmd7-5D6Q4sh7CN7ODgKFdfNPH6FLn2keKQmGrfECZBxv2Qy7R2TQ== 14:18:42 INFO - x-amz-version-id: YO_0mBb2fHVXRrARdWSmP8dTgM1zB5F_ 14:18:42 INFO - Expected file size: 16327499 14:18:42 INFO - Obtained file size: 16327499 14:18:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/HdnhV3ZmRi64iAGZpVSh4w/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 14:18:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/HdnhV3ZmRi64iAGZpVSh4w/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 14:18:49 INFO - Fetch https://queue.taskcluster.net/v1/task/HdnhV3ZmRi64iAGZpVSh4w/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 14:18:49 INFO - Http code: 200 14:18:49 INFO - via: 1.1 d2bd759914e30b1d5aee2929535c55f9.cloudfront.net (CloudFront) 14:18:49 INFO - x-amz-cf-id: bjtj_2gq6-faQl-y0wAg-ak7RPhQD1bvZbmYpG1kmVtgSkBxULPmyQ== 14:18:49 INFO - x-amz-version-id: p4D2L1zEt_3I_hUTahskL2skqrZHd3NC 14:18:50 INFO - Expected file size: 14409038 14:18:50 INFO - Obtained file size: 14409038 14:18:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:50 INFO - trying https://queue.taskcluster.net/v1/task/HdnhV3ZmRi64iAGZpVSh4w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 14:18:50 INFO - Downloading https://queue.taskcluster.net/v1/task/HdnhV3ZmRi64iAGZpVSh4w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 14:18:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/HdnhV3ZmRi64iAGZpVSh4w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 14:18:53 INFO - Downloaded 114361887 bytes. 14:18:53 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HdnhV3ZmRi64iAGZpVSh4w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 14:18:53 INFO - mkdir: /builds/slave/test/properties 14:18:53 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:18:53 INFO - Writing to file /builds/slave/test/properties/build_url 14:18:53 INFO - Contents: 14:18:53 INFO - build_url:https://queue.taskcluster.net/v1/task/HdnhV3ZmRi64iAGZpVSh4w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 14:18:53 INFO - Running post-action listener: _resource_record_post_action 14:18:53 INFO - Running post-action listener: set_extra_try_arguments 14:18:53 INFO - [mozharness: 2016-11-15 22:18:53.971685Z] Finished download-and-extract step (success) 14:18:53 INFO - [mozharness: 2016-11-15 22:18:53.971830Z] Running populate-webroot step. 14:18:53 INFO - Running pre-action listener: _resource_record_pre_action 14:18:53 INFO - Running main action method: populate_webroot 14:18:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:18:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:18:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:18:53 INFO - '--tppagecycles', 14:18:53 INFO - '7'], 14:18:53 INFO - 'tests': ['tsvgm']}}, 14:18:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:18:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:18:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:18:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:18:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:18:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:18:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:18:53 INFO - 'talos_options': ['--disable-e10s'], 14:18:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:18:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:18:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:18:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:18:53 INFO - 'talos_options': ['--disable-e10s'], 14:18:53 INFO - 'tests': ['damp', 'tps']}, 14:18:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:18:53 INFO - 'tests': ['damp', 'tps']}, 14:18:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:18:53 INFO - 'tests': ['dromaeo_dom']}, 14:18:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:18:53 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:18:53 INFO - 'tests': ['basic_compositor_video']}, 14:18:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:18:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:18:53 INFO - 'tests': ['a11yr', 14:18:53 INFO - 'ts_paint', 14:18:53 INFO - 'tpaint', 14:18:53 INFO - 'sessionrestore', 14:18:53 INFO - 'sessionrestore_no_auto_restore', 14:18:53 INFO - 'tabpaint']}, 14:18:53 INFO - 'other-e10s': {'tests': ['a11yr', 14:18:53 INFO - 'ts_paint', 14:18:53 INFO - 'tpaint', 14:18:53 INFO - 'sessionrestore', 14:18:53 INFO - 'sessionrestore_no_auto_restore', 14:18:53 INFO - 'tabpaint']}, 14:18:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:18:53 INFO - 'ts_paint', 14:18:53 INFO - 'tpaint', 14:18:53 INFO - 'sessionrestore', 14:18:53 INFO - 'sessionrestore_no_auto_restore', 14:18:53 INFO - 'tabpaint']}, 14:18:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:18:53 INFO - 'ts_paint', 14:18:53 INFO - 'tpaint', 14:18:53 INFO - 'sessionrestore', 14:18:53 INFO - 'sessionrestore_no_auto_restore', 14:18:53 INFO - 'tabpaint']}, 14:18:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:18:53 INFO - 'tests': ['a11yr', 14:18:53 INFO - 'ts_paint', 14:18:53 INFO - 'tpaint', 14:18:53 INFO - 'sessionrestore', 14:18:53 INFO - 'sessionrestore_no_auto_restore', 14:18:53 INFO - 'tabpaint']}, 14:18:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:18:53 INFO - 'tests': ['a11yr', 14:18:53 INFO - 'ts_paint', 14:18:53 INFO - 'tpaint', 14:18:53 INFO - 'sessionrestore', 14:18:53 INFO - 'sessionrestore_no_auto_restore', 14:18:53 INFO - 'tabpaint']}, 14:18:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:18:53 INFO - 'tests': ['tsvgx', 14:18:53 INFO - 'tsvgr_opacity', 14:18:53 INFO - 'tart', 14:18:53 INFO - 'tscrollx', 14:18:53 INFO - 'cart']}, 14:18:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:18:53 INFO - 'tsvgr_opacity', 14:18:53 INFO - 'tart', 14:18:53 INFO - 'tscrollx', 14:18:53 INFO - 'cart']}, 14:18:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:18:53 INFO - 'talos_options': ['--disable-e10s'], 14:18:53 INFO - 'tests': ['tp5o']}, 14:18:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:18:53 INFO - 'tests': ['tp5o']}, 14:18:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:18:53 INFO - 'talos_options': ['--disable-e10s', 14:18:53 INFO - '--xperf_path', 14:18:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:18:53 INFO - 'tests': ['tp5n']}, 14:18:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:18:53 INFO - 'talos_options': ['--xperf_path', 14:18:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:18:53 INFO - 'tests': ['tp5n']}}, 14:18:53 INFO - 'talos.zip': {'path': '', 14:18:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:18:53 INFO - Running post-action listener: _resource_record_post_action 14:18:53 INFO - [mozharness: 2016-11-15 22:18:53.990591Z] Finished populate-webroot step (success) 14:18:53 INFO - [mozharness: 2016-11-15 22:18:53.990707Z] Running create-virtualenv step. 14:18:53 INFO - Running pre-action listener: _resource_record_pre_action 14:18:53 INFO - Running main action method: create_virtualenv 14:18:53 INFO - Creating virtualenv /builds/slave/test/build/venv 14:18:53 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 14:18:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:18:54 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:18:54 INFO - Using real prefix '/tools/python27' 14:18:54 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:18:54 INFO - Installing distribute.............................................................................................................................................................................................done. 14:18:57 INFO - Installing pip.................done. 14:18:57 INFO - Return code: 0 14:18:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:18:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:18:58 INFO - Reading from file tmpfile_stdout 14:18:58 INFO - Output received: 14:18:58 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 14:18:58 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:18:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:58 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 0x106232490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061a8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7e85cafb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106046d30>, '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 0x105eacdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10603eb20>, '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.dKYVcusZKS/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.yUEGvkqWPt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:18:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:18:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:18:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dKYVcusZKS/Render', 14:18:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:18:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:18:58 INFO - 'HOME': '/Users/cltbld', 14:18:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:18:58 INFO - 'LOGNAME': 'cltbld', 14:18:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:58 INFO - 'MOZ_NO_REMOTE': '1', 14:18:58 INFO - 'NO_EM_RESTART': '1', 14:18:58 INFO - 'PAGER': '/bin/cat', 14:18:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:18:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:58 INFO - 'PWD': '/builds/slave/test', 14:18:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:18:58 INFO - 'SHELL': '/bin/bash', 14:18:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yUEGvkqWPt/Listeners', 14:18:58 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 14:18:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:18:58 INFO - 'USER': 'cltbld', 14:18:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:18:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:18:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:58 INFO - 'XPC_FLAGS': '0x0', 14:18:58 INFO - 'XPC_SERVICE_NAME': '0', 14:18:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:18:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:58 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 14:18:58 INFO - Cleaning up... 14:18:58 INFO - Return code: 0 14:18:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:18:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:18:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:18:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:18:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:18:58 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 0x106232490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061a8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7e85cafb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106046d30>, '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 0x105eacdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10603eb20>, '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.dKYVcusZKS/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.yUEGvkqWPt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:18:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:18:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:18:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dKYVcusZKS/Render', 14:18:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:18:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:18:58 INFO - 'HOME': '/Users/cltbld', 14:18:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:18:58 INFO - 'LOGNAME': 'cltbld', 14:18:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:18:58 INFO - 'MOZ_NO_REMOTE': '1', 14:18:58 INFO - 'NO_EM_RESTART': '1', 14:18:58 INFO - 'PAGER': '/bin/cat', 14:18:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:18:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:18:58 INFO - 'PWD': '/builds/slave/test', 14:18:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:18:58 INFO - 'SHELL': '/bin/bash', 14:18:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yUEGvkqWPt/Listeners', 14:18:58 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 14:18:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:18:58 INFO - 'USER': 'cltbld', 14:18:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:18:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:18:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:18:58 INFO - 'XPC_FLAGS': '0x0', 14:18:58 INFO - 'XPC_SERVICE_NAME': '0', 14:18:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:18:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:18:58 INFO - Downloading/unpacking psutil>=3.1.1 14:18:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:18:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:18:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:18:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:18:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:18:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:19:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:19:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:19:01 INFO - Installing collected packages: psutil 14:19:01 INFO - Running setup.py install for psutil 14:19:02 INFO - building 'psutil._psutil_osx' extension 14:19:02 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 14:19:02 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 14:19:02 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 14:19:02 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 14:19:02 INFO - building 'psutil._psutil_posix' extension 14:19:02 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 14:19:02 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:19:02 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:19:02 INFO - ^ 14:19:03 INFO - 1 warning generated. 14:19:03 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 14:19:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:19:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:19:03 INFO - Successfully installed psutil 14:19:03 INFO - Cleaning up... 14:19:03 INFO - Return code: 0 14:19:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:19:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:19:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:19:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:03 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 0x106232490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061a8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7e85cafb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106046d30>, '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 0x105eacdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10603eb20>, '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.dKYVcusZKS/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.yUEGvkqWPt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:19:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:19:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:19:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dKYVcusZKS/Render', 14:19:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:19:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:19:03 INFO - 'HOME': '/Users/cltbld', 14:19:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:19:03 INFO - 'LOGNAME': 'cltbld', 14:19:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:03 INFO - 'MOZ_NO_REMOTE': '1', 14:19:03 INFO - 'NO_EM_RESTART': '1', 14:19:03 INFO - 'PAGER': '/bin/cat', 14:19:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:19:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:03 INFO - 'PWD': '/builds/slave/test', 14:19:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:19:03 INFO - 'SHELL': '/bin/bash', 14:19:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yUEGvkqWPt/Listeners', 14:19:03 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 14:19:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:19:03 INFO - 'USER': 'cltbld', 14:19:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:19:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:19:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:03 INFO - 'XPC_FLAGS': '0x0', 14:19:03 INFO - 'XPC_SERVICE_NAME': '0', 14:19:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:19:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:19:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:19:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:19:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:19:06 INFO - Installing collected packages: mozsystemmonitor 14:19:06 INFO - Running setup.py install for mozsystemmonitor 14:19:06 INFO - Successfully installed mozsystemmonitor 14:19:06 INFO - Cleaning up... 14:19:06 INFO - Return code: 0 14:19:06 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:19:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:19:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:19:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:06 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106232490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061a8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7e85cafb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106046d30>, '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 0x105eacdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10603eb20>, '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.dKYVcusZKS/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.yUEGvkqWPt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:19:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:19:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:19:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dKYVcusZKS/Render', 14:19:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:19:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:19:06 INFO - 'HOME': '/Users/cltbld', 14:19:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:19:06 INFO - 'LOGNAME': 'cltbld', 14:19:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:06 INFO - 'MOZ_NO_REMOTE': '1', 14:19:06 INFO - 'NO_EM_RESTART': '1', 14:19:06 INFO - 'PAGER': '/bin/cat', 14:19:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:19:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:06 INFO - 'PWD': '/builds/slave/test', 14:19:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:19:06 INFO - 'SHELL': '/bin/bash', 14:19:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yUEGvkqWPt/Listeners', 14:19:06 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 14:19:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:19:06 INFO - 'USER': 'cltbld', 14:19:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:19:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:19:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:06 INFO - 'XPC_FLAGS': '0x0', 14:19:06 INFO - 'XPC_SERVICE_NAME': '0', 14:19:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:19:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:06 INFO - Downloading/unpacking jsonschema==2.5.1 14:19:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:19:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:19:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:09 INFO - Downloading functools32-3.2.3-2.tar.gz 14:19:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:19:09 INFO - warning: no files found matching '*.txt' 14:19:09 INFO - no previously-included directories found matching 'build' 14:19:09 INFO - no previously-included directories found matching 'dist' 14:19:09 INFO - no previously-included directories found matching '.git*' 14:19:09 INFO - Installing collected packages: jsonschema, functools32 14:19:09 INFO - Running setup.py install for functools32 14:19:09 INFO - warning: no files found matching '*.txt' 14:19:09 INFO - no previously-included directories found matching 'build' 14:19:09 INFO - no previously-included directories found matching 'dist' 14:19:09 INFO - no previously-included directories found matching '.git*' 14:19:09 INFO - Successfully installed jsonschema functools32 14:19:09 INFO - Cleaning up... 14:19:09 INFO - Return code: 0 14:19:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:19:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:19:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:19:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:09 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 0x106232490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061a8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7e85cafb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106046d30>, '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 0x105eacdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10603eb20>, '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.dKYVcusZKS/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.yUEGvkqWPt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:19:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:19:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 14:19:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dKYVcusZKS/Render', 14:19:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:19:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:19:09 INFO - 'HOME': '/Users/cltbld', 14:19:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:19:09 INFO - 'LOGNAME': 'cltbld', 14:19:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:09 INFO - 'MOZ_NO_REMOTE': '1', 14:19:09 INFO - 'NO_EM_RESTART': '1', 14:19:09 INFO - 'PAGER': '/bin/cat', 14:19:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:19:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:09 INFO - 'PWD': '/builds/slave/test', 14:19:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:19:09 INFO - 'SHELL': '/bin/bash', 14:19:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yUEGvkqWPt/Listeners', 14:19:09 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 14:19:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:19:09 INFO - 'USER': 'cltbld', 14:19:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:19:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:19:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:09 INFO - 'XPC_FLAGS': '0x0', 14:19:09 INFO - 'XPC_SERVICE_NAME': '0', 14:19:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:19:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:19:10 INFO - Cleaning up... 14:19:10 INFO - Return code: 0 14:19:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:19:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:19:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:19:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:10 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 0x106232490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061a8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7e85cafb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106046d30>, '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 0x105eacdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10603eb20>, '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.dKYVcusZKS/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.yUEGvkqWPt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:19:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:19:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:19:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dKYVcusZKS/Render', 14:19:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:19:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:19:10 INFO - 'HOME': '/Users/cltbld', 14:19:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:19:10 INFO - 'LOGNAME': 'cltbld', 14:19:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:10 INFO - 'MOZ_NO_REMOTE': '1', 14:19:10 INFO - 'NO_EM_RESTART': '1', 14:19:10 INFO - 'PAGER': '/bin/cat', 14:19:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:19:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:10 INFO - 'PWD': '/builds/slave/test', 14:19:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:19:10 INFO - 'SHELL': '/bin/bash', 14:19:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yUEGvkqWPt/Listeners', 14:19:10 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 14:19:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:19:10 INFO - 'USER': 'cltbld', 14:19:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:19:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:19:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:10 INFO - 'XPC_FLAGS': '0x0', 14:19:10 INFO - 'XPC_SERVICE_NAME': '0', 14:19:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:19:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:10 INFO - Downloading/unpacking blobuploader==1.2.4 14:19:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:12 INFO - Downloading blobuploader-1.2.4.tar.gz 14:19:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:19:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:19:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:19:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:19:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:14 INFO - Downloading docopt-0.6.1.tar.gz 14:19:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:19:14 INFO - Installing collected packages: blobuploader, requests, docopt 14:19:14 INFO - Running setup.py install for blobuploader 14:19:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:19:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:19:14 INFO - Running setup.py install for requests 14:19:14 INFO - Running setup.py install for docopt 14:19:14 INFO - Successfully installed blobuploader requests docopt 14:19:14 INFO - Cleaning up... 14:19:15 INFO - Return code: 0 14:19:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:19: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')]} 14:19:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19: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')]} 14:19:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:15 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 0x106232490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061a8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7e85cafb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106046d30>, '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 0x105eacdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10603eb20>, '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.dKYVcusZKS/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.yUEGvkqWPt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:19:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:19:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:19:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dKYVcusZKS/Render', 14:19:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:19:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:19:15 INFO - 'HOME': '/Users/cltbld', 14:19:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:19:15 INFO - 'LOGNAME': 'cltbld', 14:19:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:15 INFO - 'MOZ_NO_REMOTE': '1', 14:19:15 INFO - 'NO_EM_RESTART': '1', 14:19:15 INFO - 'PAGER': '/bin/cat', 14:19:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:19:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:15 INFO - 'PWD': '/builds/slave/test', 14:19:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:19:15 INFO - 'SHELL': '/bin/bash', 14:19:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yUEGvkqWPt/Listeners', 14:19:15 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 14:19:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:19:15 INFO - 'USER': 'cltbld', 14:19:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:19:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:19:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:15 INFO - 'XPC_FLAGS': '0x0', 14:19:15 INFO - 'XPC_SERVICE_NAME': '0', 14:19:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:19:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:19:15 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-M6HMK5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:19:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:19:15 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-g95F6W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:19:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:19:15 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-zgxX3f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:19:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:19:15 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-LQhxP9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:19:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:19:15 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-O0Gz8C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:19:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:19:15 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-65oVlX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:19:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:19:15 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-EG0nkm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:19:16 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-IrxaB_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:19:16 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-sJu28P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:19:16 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-0njxNv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:19:16 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-boD_s1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:19:16 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-eYuk95-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:19:16 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-Ye6_hU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:19:16 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-axpWKN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:19:16 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-q73DZn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:19:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:19:16 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-zIsPiD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:19:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:19:17 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-6KXuFc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:19:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:19:17 INFO - Running setup.py install for manifestparser 14:19:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:19:17 INFO - Running setup.py install for mozcrash 14:19:17 INFO - Running setup.py install for mozdebug 14:19:17 INFO - Running setup.py install for mozdevice 14:19:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:19:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:19:17 INFO - Running setup.py install for mozfile 14:19:17 INFO - Running setup.py install for mozhttpd 14:19:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:19:17 INFO - Running setup.py install for mozinfo 14:19:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:19:18 INFO - Running setup.py install for mozInstall 14:19:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:19:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:19:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:19:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:19:18 INFO - Running setup.py install for mozleak 14:19:18 INFO - Running setup.py install for mozlog 14:19:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:19:18 INFO - Running setup.py install for moznetwork 14:19:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:19:18 INFO - Running setup.py install for mozprocess 14:19:18 INFO - Running setup.py install for mozprofile 14:19:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:19:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:19:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:19:19 INFO - Running setup.py install for mozrunner 14:19:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:19:19 INFO - Running setup.py install for mozscreenshot 14:19:19 INFO - Running setup.py install for moztest 14:19:19 INFO - Running setup.py install for mozversion 14:19:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:19:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:19:19 INFO - Cleaning up... 14:19:19 INFO - Return code: 0 14:19:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:19:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:19:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:19:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:19 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 0x106232490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061a8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7e85cafb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106046d30>, '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 0x105eacdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10603eb20>, '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.dKYVcusZKS/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.yUEGvkqWPt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:19:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:19:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:19:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dKYVcusZKS/Render', 14:19:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:19:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:19:19 INFO - 'HOME': '/Users/cltbld', 14:19:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:19:19 INFO - 'LOGNAME': 'cltbld', 14:19:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:19 INFO - 'MOZ_NO_REMOTE': '1', 14:19:19 INFO - 'NO_EM_RESTART': '1', 14:19:19 INFO - 'PAGER': '/bin/cat', 14:19:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:19:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:19 INFO - 'PWD': '/builds/slave/test', 14:19:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:19:19 INFO - 'SHELL': '/bin/bash', 14:19:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yUEGvkqWPt/Listeners', 14:19:19 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 14:19:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:19:19 INFO - 'USER': 'cltbld', 14:19:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:19:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:19:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:19 INFO - 'XPC_FLAGS': '0x0', 14:19:19 INFO - 'XPC_SERVICE_NAME': '0', 14:19:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:19:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:19:19 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-Qw9tNJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:19:20 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 14:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:19:20 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-A8IxAQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:19:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:19:20 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-rc50gH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:19:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:19:20 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-Yfylq4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:19:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:19:20 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-0XRxmb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:19:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 14:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:19:20 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-0eOKKy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:19:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 14:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:19:20 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-LfrT9R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:19:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 14:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:19:20 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-4YKXzr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:19:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 14:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:19:20 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-kJjw3c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:19:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 14:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:19:20 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-loTXOH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:19:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:19:21 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-_CkOYB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:19:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 14:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:19:21 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-FSDrJP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:19:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 14:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:19:21 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-DmNiUf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:19:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 14:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:19:21 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-80vuY0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:19:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 14:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:19:21 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-JANIT_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:19:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 14:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:19:21 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-Lk0mTi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:19:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 14:19:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:19:21 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-OjQhmN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:19:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 14:19:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:19:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:19:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:19:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:19:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:19:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:19:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:19:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:19:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:19:24 INFO - Downloading blessings-1.6.tar.gz 14:19:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:19:24 INFO - Installing collected packages: blessings 14:19:24 INFO - Running setup.py install for blessings 14:19:24 INFO - Successfully installed blessings 14:19:24 INFO - Cleaning up... 14:19:24 INFO - Return code: 0 14:19:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:19:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:19:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:19:25 INFO - Reading from file tmpfile_stdout 14:19:25 INFO - Current package versions: 14:19:25 INFO - blessings == 1.6 14:19:25 INFO - blobuploader == 1.2.4 14:19:25 INFO - docopt == 0.6.1 14:19:25 INFO - functools32 == 3.2.3-2 14:19:25 INFO - jsonschema == 2.5.1 14:19:25 INFO - manifestparser == 1.1 14:19:25 INFO - mozInstall == 1.12 14:19:25 INFO - mozcrash == 1.0 14:19:25 INFO - mozdebug == 0.1 14:19:25 INFO - mozdevice == 0.48 14:19:25 INFO - mozfile == 1.2 14:19:25 INFO - mozhttpd == 0.7 14:19:25 INFO - mozinfo == 0.9 14:19:25 INFO - mozleak == 0.1 14:19:25 INFO - mozlog == 3.4 14:19:25 INFO - moznetwork == 0.27 14:19:25 INFO - mozprocess == 0.23 14:19:25 INFO - mozprofile == 0.28 14:19:25 INFO - mozrunner == 6.13 14:19:25 INFO - mozscreenshot == 0.1 14:19:25 INFO - mozsystemmonitor == 0.3 14:19:25 INFO - moztest == 0.7 14:19:25 INFO - mozversion == 1.4 14:19:25 INFO - psutil == 3.1.1 14:19:25 INFO - requests == 1.2.3 14:19:25 INFO - wsgiref == 0.1.2 14:19:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:19:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:19:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:19:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:25 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 0x106232490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061a8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7e85cafb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106046d30>, '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 0x105eacdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10603eb20>, '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.dKYVcusZKS/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.yUEGvkqWPt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:19:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 14:19:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:19:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dKYVcusZKS/Render', 14:19:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:19:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:19:25 INFO - 'HOME': '/Users/cltbld', 14:19:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:19:25 INFO - 'LOGNAME': 'cltbld', 14:19:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:25 INFO - 'MOZ_NO_REMOTE': '1', 14:19:25 INFO - 'NO_EM_RESTART': '1', 14:19:25 INFO - 'PAGER': '/bin/cat', 14:19:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:19:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:25 INFO - 'PWD': '/builds/slave/test', 14:19:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:19:25 INFO - 'SHELL': '/bin/bash', 14:19:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yUEGvkqWPt/Listeners', 14:19:25 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 14:19:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:19:25 INFO - 'USER': 'cltbld', 14:19:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:19:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:19:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:25 INFO - 'XPC_FLAGS': '0x0', 14:19:25 INFO - 'XPC_SERVICE_NAME': '0', 14:19:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:19:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:19:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 14:19:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 14:19:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:19:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 14:19:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 14:19:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 14:19:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 14:19:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 14:19:26 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:19:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:19:26 INFO - Cleaning up... 14:19:26 INFO - Return code: 0 14:19:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:19:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:19:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:19:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:19:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:19:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:19:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x106232490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1061a8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7e85cafb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106046d30>, '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 0x105eacdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10603eb20>, '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.dKYVcusZKS/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.yUEGvkqWPt/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:19:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:19:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:19:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dKYVcusZKS/Render', 14:19:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:19:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:19:26 INFO - 'HOME': '/Users/cltbld', 14:19:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:19:26 INFO - 'LOGNAME': 'cltbld', 14:19:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:19:26 INFO - 'MOZ_NO_REMOTE': '1', 14:19:26 INFO - 'NO_EM_RESTART': '1', 14:19:26 INFO - 'PAGER': '/bin/cat', 14:19:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:19:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:19:26 INFO - 'PWD': '/builds/slave/test', 14:19:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:19:26 INFO - 'SHELL': '/bin/bash', 14:19:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yUEGvkqWPt/Listeners', 14:19:26 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 14:19:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:19:26 INFO - 'USER': 'cltbld', 14:19:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:19:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:19:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:19:26 INFO - 'XPC_FLAGS': '0x0', 14:19:26 INFO - 'XPC_SERVICE_NAME': '0', 14:19:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:19:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:19:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:19:26 INFO - Cleaning up... 14:19:26 INFO - Return code: 0 14:19:26 INFO - Running post-action listener: _resource_record_post_action 14:19:26 INFO - Running post-action listener: _start_resource_monitoring 14:19:26 INFO - Starting resource monitoring. 14:19:26 INFO - [mozharness: 2016-11-15 22:19:26.731591Z] Finished create-virtualenv step (success) 14:19:26 INFO - [mozharness: 2016-11-15 22:19:26.732552Z] Running install step. 14:19:26 INFO - Running pre-action listener: _resource_record_pre_action 14:19:26 INFO - Running main action method: install 14:19:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:19:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:19:26 INFO - Reading from file tmpfile_stdout 14:19:26 INFO - Detecting whether we're running mozinstall >=1.0... 14:19:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:19:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:19:27 INFO - Reading from file tmpfile_stdout 14:19:27 INFO - Output received: 14:19:27 INFO - Usage: mozinstall [options] installer 14:19:27 INFO - Options: 14:19:27 INFO - -h, --help show this help message and exit 14:19:27 INFO - -d DEST, --destination=DEST 14:19:27 INFO - Directory to install application into. [default: 14:19:27 INFO - "/builds/slave/test"] 14:19:27 INFO - --app=APP Application being installed. [default: firefox] 14:19:27 INFO - mkdir: /builds/slave/test/build/application 14:19:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:19:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 14:19:59 INFO - Reading from file tmpfile_stdout 14:19:59 INFO - Output received: 14:19:59 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 14:19:59 INFO - Running post-action listener: _resource_record_post_action 14:19:59 INFO - [mozharness: 2016-11-15 22:19:59.884670Z] Finished install step (success) 14:19:59 INFO - [mozharness: 2016-11-15 22:19:59.884833Z] Running run-tests step. 14:19:59 INFO - Running pre-action listener: _resource_record_pre_action 14:19:59 INFO - Running main action method: run_tests 14:19:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:19:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:19:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:19:59 INFO - 2016-11-15 14:19:59.899 screenresolution[1624:7397] starting screenresolution argv=screenresolution get 14:19:59 INFO - 2016-11-15 14:19:59.902 screenresolution[1624:7397] Display 0: 1600x1200x32@60 14:19:59 INFO - 2016-11-15 14:19:59.908 screenresolution[1625:7399] starting screenresolution argv=screenresolution list 14:19:59 INFO - Available Modes on Display 0 14:19:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:19:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:19:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:19:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:20:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:20:00 INFO - Intel Iris: 14:20:00 INFO - Chipset Model: Intel Iris 14:20:00 INFO - Type: GPU 14:20:00 INFO - Bus: Built-In 14:20:00 INFO - VRAM (Dynamic, Max): 1536 MB 14:20:00 INFO - Vendor: Intel (0x8086) 14:20:00 INFO - Device ID: 0x0a2e 14:20:00 INFO - Revision ID: 0x0009 14:20:00 INFO - Displays: 14:20:00 INFO - Display: 14:20:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:20:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:20:00 INFO - Display Serial Number: 1600x1200 60 14:20:00 INFO - Main Display: Yes 14:20:00 INFO - Mirror: Off 14:20:00 INFO - Online: Yes 14:20:00 INFO - Rotation: Supported 14:20:00 INFO - Return code: 0 14:20:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:20:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:20:00 INFO - Python 2.7.3 14:20:00 INFO - Return code: 0 14:20:00 INFO - grabbing minidump binary from tooltool 14:20:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:20:00 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 0x106046d30>, '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 0x105eacdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10603eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:20:00 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 14:20:00 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 14:20:00 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 14:20:00 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:20:00 INFO - Return code: 0 14:20:00 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:20:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:20:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:20:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:20:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:20:00 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/HdnhV3ZmRi64iAGZpVSh4w/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0111', '--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 14:20:00 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/HdnhV3ZmRi64iAGZpVSh4w/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0111 --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 14:20:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dKYVcusZKS/Render', 14:20:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:20:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:20:00 INFO - 'HOME': '/Users/cltbld', 14:20:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:20:00 INFO - 'LOGNAME': 'cltbld', 14:20:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:20:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:20:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:20:00 INFO - 'MOZ_NO_REMOTE': '1', 14:20:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:20:00 INFO - 'NO_EM_RESTART': '1', 14:20:00 INFO - 'PAGER': '/bin/cat', 14:20:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:20:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:20:00 INFO - 'PWD': '/builds/slave/test', 14:20:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:20:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:20:00 INFO - 'SHELL': '/bin/bash', 14:20:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.yUEGvkqWPt/Listeners', 14:20:00 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 14:20:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:20:00 INFO - 'USER': 'cltbld', 14:20:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:20:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:20:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:20:00 INFO - 'XPC_FLAGS': '0x0', 14:20:00 INFO - 'XPC_SERVICE_NAME': '0', 14:20:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:20:00 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/HdnhV3ZmRi64iAGZpVSh4w/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0111', '--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 14:20:00 INFO - mozversion application_buildid: 20161115115349 14:20:00 INFO - mozversion application_changeset: 07f57a55ee1b4d5fb5655fd4c5a9094e2d8a0f09 14:20:00 INFO - mozversion application_display_name: FirefoxDeveloperEdition 14:20:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:20:00 INFO - mozversion application_name: Firefox 14:20:00 INFO - mozversion application_remotingname: firefox-dev 14:20:00 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:20:00 INFO - mozversion application_vendor: Mozilla 14:20:00 INFO - mozversion application_version: 52.0a2 14:20:00 INFO - mozversion platform_buildid: 20161115115349 14:20:00 INFO - mozversion platform_changeset: 07f57a55ee1b4d5fb5655fd4c5a9094e2d8a0f09 14:20:00 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:20:00 INFO - mozversion platform_version: 52.0a2 14:20:00 INFO - using testdate: 1479248400 14:20:00 INFO - actual date: 1479248400 14:20:00 INFO - starting webserver on 'localhost:49242' 14:20:00 INFO - SUITE-START | Running 2 tests 14:20:00 INFO - TEST-START | dromaeo_css 14:20:00 INFO - operating with platform_type : mac_ 14:20:00 INFO - Initialising browser for dromaeo_css test... 14:20:00 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpqSGuq6/profile http://localhost:49242/getInfo.html) 14:20:02 ERROR - PROCESS | 1635 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 14:20:02 INFO - PROCESS | 1635 | __metrics Screen width/height:1600/1200 14:20:02 INFO - PROCESS | 1635 | colorDepth:24 14:20:02 INFO - PROCESS | 1635 | Browser inner width/height: 1024/689 14:20:02 INFO - PROCESS | 1635 | __metrics 14:20:03 INFO - PROCESS | 1635 | 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" 14:20:03 INFO - PROCESS | 1635 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:20:03 INFO - TEST-INFO | 1635: exit 0 14:20:03 INFO - Browser initialized. 14:20:03 INFO - Running cycle 1/1 for dromaeo_css test... 14:20:03 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpqSGuq6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:20:15 INFO - PROCESS | 1638 | RSS: Main: 217485312 14:20:15 INFO - PROCESS | 1638 | 14:21:07 INFO - PROCESS | 1638 | 1479248467037 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 14:23:20 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 14:23:22 INFO - PROCESS | 1638 | RSS: Main: 354193408 14:23:22 INFO - PROCESS | 1638 | 14:26:24 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 14:26:25 INFO - PROCESS | 1638 | RSS: Main: 351531008 14:26:25 INFO - PROCESS | 1638 | 14:29:27 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 14:29:28 INFO - PROCESS | 1638 | RSS: Main: 325869568 14:29:28 INFO - PROCESS | 1638 | 14:32:30 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 14:32:31 INFO - PROCESS | 1638 | RSS: Main: 326184960 14:32:31 INFO - PROCESS | 1638 | 14:35:34 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 14:35:35 INFO - PROCESS | 1638 | RSS: Main: 311259136 14:35:35 INFO - PROCESS | 1638 | 14:38:38 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 14:38:38 INFO - PROCESS | 1638 | RSS: Main: 533962752 14:38:38 INFO - PROCESS | 1638 | 14:38:38 INFO - PROCESS | 1638 | __start_tp_report 14:38:38 INFO - PROCESS | 1638 | _x_x_mozilla_page_load 14:38:38 INFO - PROCESS | 1638 | _x_x_mozilla_page_load_details 14:38:38 INFO - PROCESS | 1638 | |i|pagename|runs| 14:38:38 INFO - PROCESS | 1638 | |0;dojo.html;2666.0535509894;3226.887058952937;3331.2504686445554;3365.62978072412;3407.1993081625815;4760.404949381327;5589.608727389084;5618.12918997555;5674.517665998395;5703.144528320754;5107.361579802525;5716.114002329635;5790.449907258814;5807.73865176067;5852.824415267538;4448.088141930913;5243.68537887727;5249.658772179796;5283.181106928413;5400.972995135019;5034.471380505049;5039.647224694273;5057.241413787943;5266.499682530172;5368.651037682557;5205.7397130143345;5256.053910296145;5271.657342272759;5290.1800220965615;5297.735113244342;5271.841844744645;5275.024120537712;5340.732963351817;5362.892742145174;5364.946350536484;5151.9484805152215;5211.7133557654315;5286.418493965661;5307.575393968474;5333.413324534298;5604.915926261109;5703.885922281573;5728.427157284238;5730.656160630376;5773.076307790733;6241.500679945639;6295.842603934892;6299.8740025199695;6303.432691057827;6360.395762402564;6219.533534984849;6268.373162683741;6292.937070629281;6296.96851515744;6297.716602752887;5761.279840019997;5799.130130480462;5820.767169313265;5853.853653658693;5926.111083337534;5497.670139791625;6182;6314.905276420857;6342.270638876588;6357.745690172434;6059.333473317932;6220.03589443637;6232.743583354099;6236.158118653923;6253.437190652863;6119.388061193845;6198.473129783929;6222.12890195373;6303.653299068504;6328.398802113792;6208.596441231305;6211.3478084801345;6213.285472170758;6213.844653883689;6218.720157592919;6149.569530132937;6158.7228574714245;6216.906746398808;6227.844303892439;6273.52948528853;4989.900201996013;5010.974945125251;5012.473690262543;5023.120953833065;5036.722980236123;3611.638836116367;4446.599806017474;4459.264221403432;4487.708298960557;4501.369808226851;5806.622569533051;5832.416758324133;5840.269966254218;5914.319853216849;5974.641521508724;6240.126382306481;6254;6256.061590761331;6268.247810262797;6283.748650053946;5998.1002849572915;6015.518758499309;6016.8796624068145;6027.156198132265;6028.367021462684;5347.786088556414;5468.261784659099;5470.343558772973;5489.451054894478;5499.340079190442;5998.640081595118;6250.624962502265;6296.961001434712;6326.936730632635;6386.9361306386345;6251.218597675291;6254.937450625435;6262.373762623702;6273.65494897785;6279.497640188865;6377.93622063792;6395.584287021329;6443.291237963726;6444.323346048597;6459.870802583828;6069.241344831896;6114.145302971568;6199.690015499117;6242.937570624235;6280.937190628035;5630.662160270397;6123.693815309306;6251.562390632612;6313.779017734358;6320.936790632035;6154.415330543591;6176.845578860385;6183.258009038944;6192.164057852313;6208.130861679459;5992.161097446274;5999.430054144849;6029.095635654687;6099.603525770811;6170.444659980623;5845.853853653693;5935.673537955454;5938.317093534299;5989.580729348903;5990.7903223386975;6066;6083.69581520931;6084.39156084388;6101.908471372844;6102.572819902564;5814.76740930358;5846.883062338815;5900.43945825146;5905.055191169392;5925.111233315038 14:38:38 INFO - PROCESS | 1638 | |1;ext.html;461.2965228027258;477.23403936681626;516.2617457036438;519.9740012999349;552.3978863039287;30144.84927575362;31764.888228911994;39348.60651393489;40017;40107.398389024114;40744.59255407445;40985.38521922166;41067.58932410675;41078.794606027004;41171;9255.722328330145;9429.90570094297;9438.197753190987;9449.480278584675;9457.668981585646;9148.725538233864;9242.260619150451;9353.251739860794;9353.485558294291;9397.342186046979;9373.203277721403;9410.905890941105;9422.15200631943;9425.434473931551;9437.103475169848;9319.720408387726;9374.765630859249;9376.203022743075;9380.90619093814;9383.390079644802;9208.3093767967;9279.53602319888;9292.349535532516;9305.767355816091;9311.813763724687;39356.80321598402;39719.40420893689;39748;39816;39929.20141597152;3221.355728854215;3259.9894032849656;3339.231976645361;3695.316366472211;3836.865709700175;28580.285492862637;28658.570121448196;30142.698573014426;30697.079087627408;30963.380732385453;3167.572377729;3214.164317277501;3214.276787722744;3285.326508065865;3314.254292784128;474334;475546;476219;476443.6177819123;485965;32652;33619.15952101168;60023.39976600266;60334.698326508085;60513;34385;34777.82611086928;34790.47814282788;34876.302473950884;34909.301813964084;13942.163470191617;14095;14106.788398174036;14121.293935303194;14130.788038179435;11839.34883581394;11886.524539018343;11909;11922;11922.225055371373;7711.421643376769;7742.380609551335;7794.713872211135;7800.84398312042;7824.92175078242;2529.29179829648;3153.3377990622225;3186.729128024144;3202.9359412812078;3217.6621454747383;925.546482223719;932.3380399916;935.1443429262149;1038.0190719769746;1066.99466502667;21862.797546134796;29034.27414314617;29443.29569740512;29518.852405737834;29952;885.3581153663542;917.655879045358;993.8360170571775;1002.6540843409012;1075.6504136155781;12878.742425151444;15304.387824486896;15538.922305388402;15655;15724;661349;668209;668895;669627;669728;57023.71488142533;57583.712081440164;57841;57921;57990;1315.3489022933709;1353.2489468345163;1356.552337728567;1375.9793603095761;1378.1042322490262;25098.12156574511;28622.284442889093;29402.264943376587;30257.848710756305;31077.533836992916;49117.75441122772;49547.752261238464;49609.25586116139;49636.50363496463;49679.503204967485;560085;564950;566938;566966;567951;26704.332391690365;27067.32331691645;27100.72899271061;27122.864385677945;27213.591796122677;45158.32262516131;45330.54669453263;45604.08791824211;46088.078238435715;46625;33460.15969121722;36597.26805463929;37537;38047.809760951015;38199.61800382072;32558.837205814765;35889;37575.43636845504;37950;38123.61876381201;30709.995530912507;33047;33841.83079084589;37501.62498374981;38398.616013839506;28192.85903570469;32164.014577740487;34085.82957085199;34098.318033638694;34456.82771586126;24304.75695243096;25303.922547160542;25563.872180638977;26015.479690406464;26048.8697556511 14:38:38 INFO - PROCESS | 1638 | |2;jquery.html;349.32405794787076;367.7977112588076;372.7986887080978;380.8000799580221;400.27350359098233;14562.417503299868;14967;15332.999999999985;19359.709604355947;20038.398848034547;19292.61414771704;19711.310104146356;20228.69656955143;20474.181032758708;20619.896900515476;5659.943400566003;5847.678377689225;5909.11363295507;5974.79088231912;5981.371955944606;5502.58730595205;5896.616719913192;5925.88148237035;5926.348101708809;5937.317208521011;5592.6085174037835;5772.163036359725;5777.393373695764;5797.927383434078;5896.823095307148;5708.343540492855;5838.5329173666005;5839.64962102271;5840.123981402781;5857.472827445529;4884.511548845087;5073.746312684351;5116.283720279164;5132.076226279269;5140.408852981917;24587.508249834904;24664.0134394624;24845.5030899381;24935.501289974098;25130.497390052096;2073.2536286936693;2352.764723527651;2641.2076377086796;3050.3746731919905;3081.4915538936275;14154.92922535391;15423.074615523105;15610.76583851243;16063;16146.273417696115;1761.806201317854;1781.946541603754;1783.0460703523552;1803.846673032794;1809.1316168239186;59637;62862.685686571276;104206;107623.46188269087;112152;9782.266330025277;10748.71015478132;10826.404547749791;11072.280301780358;11326.886731132749;13965.65085872841;13999.790003149961;14005.5098071567;14010.789838152436;14045.157290562598;8187.4268801184535;8410.411271211078;8427;8517.914820851836;8571;8328.708495202638;8340.124286949904;8355.206255405727;8494.74515764528;8504.404691671647;6557.540972132;6667.266600673922;6673.666316684146;6693.297203793629;6695.196576410862;1819.6997495413373;1854.4158590044092;1854.7681539807525;1856.8885866848032;1857.071464267866;890.2610833008588;936.138752347828;944.8818897637796;951.6003278622995;983.9373476645206;19200.519987000436;20648.793512064687;20689.655172413746;21914.342569722932;21996.560068798535;773.860705073081;907.6006557114848;942.1991307388802;947.7346343023827;987.4026214140486;9612.558116282473;10099.192064634812;11422.600208992812;11430.314181149157;11467.31196128235;454172;514768;551911;559216;593955;89520;105364;110527;111103.44448277706;114409.4279528597;744.3970383988986;855.5038077914794;945.2249155692404;973.776031512756;989.7970915962211;18847.6230475387;19094.80905190986;19548.60902781908;19587.902060489607;19603.117859696064;34336;44147.89630259269;48886.11138886083;49808.51340406137;50321.993560129325;69994;77222.2277777215;77776.8333474987;79602.4079518418;80556.38887222105;7143.82140446493;7308.722464333404;7439.442041846775;7668.233176682287;7756.185600512091;10968;12312.994282754507;12631.11582189238;13187.076904616968;13237.867621324049;10937.617183398543;10999.945000274947;11079.670439546984;11205.327680339205;11294.152938529478;10964.506597202986;11005.064569511682;11172.385518796544;11246.943765281121;11345.432728363714;10094.54574544162;11154.274972126786;11479.081673465937;11486.94256528712;11592.304461732323;6994.300569942965;7378.557286562824;7497.175310715926;7623.542587444771;7729.033870766154;10477.52851121686;11021.779564408827;11095.889041109485;11389.974902258837;11456.94271528637 14:38:38 INFO - PROCESS | 1638 | |3;mootools.html;1304.054560443678;1329.5545992092648;1447.594673491422;1567.373050779688;1777.1380880273066;7455.739049133282;7818.5926533224065;7904.2886140247365;8294.253517183452;8341.124181960882;8040.356771458284;8269.91730082699;8278.875816862752;8285.668573257062;8289.171082891708;2285.02886273334;2332.4868528923694;2332.6501024846325;2333.8132949364094;2333.8482998605036;2316.027268547214;2363.5036642305136;2363.5745565798147;2365.9171928982487;2368.917087901924;2294.1167650454613;2299.701038864945;2334.4747431827873;2335.1476711000446;2339.040993192791;2285.394370491821;2287.7597852225526;2323;2323.047550504285;2324.418895276181;2323.14043803792;2343.7070366204225;2346.530693861235;2346.6949296591506;2347.6713260143424;7332.266773322678;8007.359411247086;8268.09051004395;8276.462029968034;8310.376721745833;5071.442141364447;5194.065068287707;5194.740262986836;5196.376434827807;5208.921866171972;3295.934081318384;3514.7012503937194;3557.4130268505664;3604.9279014419826;3606.477060826192;1064.627380416848;1074.940878251696;1090.231386872253;1094.1301665176204;1101.6529793115224;10307.587696492132;10392.636257730943;10502.317349372266;10507.474626268733;10511.84232236517;4447.755373454426;4482.462104547475;4573.679842411066;4579.244424669892;4650.116477869194;9455.763605909908;9457.527123643791;9463;9472.384295020802;9480.146786789222;5169.689818610896;5173.767180476888;5174.922376164361;5177.378714554277;5177.922331165036;4106.7946602669745;4304.418903448057;4424.203643344164;4429.092036132585;4605;3812.504374431306;3834.079820842978;3912.902177445587;3919.725619206685;3930.941035884464;1241.7702724995904;1262.8737126287297;1264.6711854917787;1273.949042038308;1279.0790630745848;756.7048850948134;781.4803155901376;890.6882591093195;897.5915958238986;902.251131560803;5442.782288708408;5563.749631266603;5654.40628733977;5746.310442746897;5879.265091863517;934.1265916368218;936.283742936654;970.2432102959702;987.713563066717;1017.5573625472942;3586.3544561978574;3621.203335266237;3744.26986737589;3761.4169803679924;3854.884353469344;8717.433366831263;9651.855222171673;10025.849612255823;10067;10152.39085654863;9535.713928582154;9980.151687106532;10078.193744500422;10080;10085.899141008642;7615.847683046308;7829.099653539915;7829.412794040356;7836.686532538634;7845.921540784633;5390.434004429479;5402.75687594067;5474.39781624029;5677.9495793278375;5735.483806457439;9003.90996090057;9363.672271470467;9387.577559009724;9453.905460945303;9476.241900648069;9194.17252447283;9361;9388.906110938804;9528.333016689043;9559.761005974628;7007.474439416962;7050.964745176241;7054.611996340251;7077.504574679723;7144.214136444883;7676.117246516499;7733.574653394117;7738.4969976951315;7742.92257077422;7752.18602046777;6985.720571177096;7017.684204210721;7111;7143.4999550031;7240.96379518099;6895.586264824126;6938.514303998873;6973.5118541701595;6986.301369863177;6992.650367481504;5659.434056594308;6620.040094186413;6704.564203326768;6716.496262780213;6718.563293385914;4747.264174053027;4779.617630589614;4809.951900480951;4851.248056551241;4854.90290194187;4713.104510143062;4729.479757226771;4749.216379297515;4931.3589233399425;4979.800807967641 14:38:38 INFO - PROCESS | 1638 | |4;prototype.html;294.5758108324014;295.3649653245522;302.9636443626765;308.0865234579471;309.0388890550386;5249.1601343785005;5272.523693365859;5300.879648140745;5336.425754402451;5385.21914322423;4376.584224498675;4411.4265145531035;4530.456345238576;4841.346418233537;5109.208072748721;3272.574565306513;3482.599501057373;3698.8718440875523;3785.9621403786023;3951.723379363446;3954.545227298854;3976.741511801724;3983.223271462066;4017.4174744662005;4037.4672161858375;3302.5871766029245;3328.733701303902;3487.9884833398282;3586.202791583588;3779.469314927459;3963.8018099095075;3971.5829837867186;4003.619656132663;4006.278869803434;4040.5959404059654;3455.51622772812;3467.7052450541732;3495.405781017233;3498.4052711039185;3500.5799304083416;4057.8173982170874;4128.153728485653;4200.957990420118;4580.938577640075;4788.730986288601;4383.772543687773;4490.618297444721;4530.411046563926;4568.794404251817;4578.221702310615;4796.544328288842;5431.375391829911;5444.7822087116465;5469.945300546983;5534.533348662608;3832.1833452412457;3832.310184166849;3842.634949679776;3851.614838516154;3857.4792403025513;4718;5391;5456.727163641802;5568.220449137124;5664.886702265972;3511.9122021949142;3627.891163265106;3690.741648084662;3890.396988466792;3907.29668659644;3936.5472970608175;3938.2320447513034;3946.1515774108702;3950.1902110067367;3991.92016159681;3858.7877666728036;4064.309067458539;4336.089421221579;4551.271796512542;4593.242115050979;4850.054239423349;5298.841034768963;5472.716370177941;5488.585022590048;5491.505764481216;1163.4996951311018;1226.533917111492;1238.467458992642;1251.960872475843;1253.783829685204;746.7013194722045;778.2023425988407;815.8980127484065;816.31429599137;860.5353109321022;3856.8650097246464;4713.599344055794;4877.926831097537;5182.352205974254;5241.955637301456;4798.224656876908;4987.152184128707;5706.686132262766;5709.857253568694;5715.799947001919;4689.591783723289;4904.485029071968;5152.793888244429;5218.686878787209;5238.109521381374;2735.617013618095;2772.431651511435;2840.8920520996826;2841.758450531728;2856.4715527627454;4935.777889994959;5190.091733946544;5575.219469274305;5641.75881306112;5643.35101463337;5172.586193104543;5480.314960629921;5603;5843.912341314884;5914.9112763308585;5240.873212259383;5572.888542229132;5607.915881261702;5857.577528965604;5968.76124954997;5519.227308176778;5735.254416925773;5743.224664670216;5748.735278238781;5784.334801497882;4778.1638213311835;5587.329520457409;5619.353774315842;5623.69069701154;5663.32040155184;5812.389699081705;5847.736851841592;5849.017001329267;5950.9469233114505;5951.285845698544;3012.789104762733;3661.2128392394698;3663.084228942764;3796.772193668389;3870.6709929656295;4766.9036121691515;5177.0940085485645;5388.757505912165;5723.971380143073;5756.424357564378;5295.637270333289;5595.300587426572;5595.944040559542;5609.849980753854;5615.943840561706;5291.7618707159045;5504.834854954358;5566.638168519034;5584.246126772996;5609.9158512621525;4307.590778876001;4382.912341753084;5307.0098712982635;5424.728763561727;5479.0411677957;4601.769911504478;4864.324621458113;5304.469553044819;5370.837600859365;5481.205225242238;4998.825041123543;5346.0911645021215;5414.485623865727;5427.94155139744;5454.91817622728 14:38:38 INFO - PROCESS | 1638 | |5;yui.html;576.6972339521752;663.9734410623576;679.134104017378;683.7369004258447;715.8818794898842;13399.665008374783;13855.722885542284;14161.575152745409;14304.570862874105;14511.056781309208;12628.936855315711;12787.296698681568;12933.547325843598;12950.093493455463;12992;776.185005743968;802.5144787403624;803.1847674610275;803.9919600804005;804.4569915307173;650.3139188156493;667.6094484726441;668.9931652862435;776.8407476467311;803.5540275147291;848.5205858689851;850.7277671145235;851.9275861551776;852.0201767966826;852.2117041736369;844.907060223375;845.1801752300258;846.1792061700172;846.6951897316961;846.6951897316961;500.49200061937165;511.872031992002;600.9939900600982;625.9499240060784;626.9279032911228;11789.764204715857;13496.865031349756;13905.582832515041;13911.165330080228;13941.233232172226;12743.553975610901;12872;13005.154664946844;13055.543055992996;13108.737825243537;11882.049436045096;11944.70138246542;11952.760944781056;12026.69933251667;12094.334811585359;4180.623743863037;4198.8110535026;4231.048014196812;4416.470023597157;4424.513303536608;81640;83086;85988.71016934751;87368.99999999936;88236;9101.863472047924;10165.440900750382;10223.642172523925;10336.0697537222;10493.52779124926;19566.70649940252;19679.508012299808;19733.013349332476;19755.901220493804;20010.799892001185;7501.812454688567;7506.286902744339;7578.052743407075;7580.929723462007;7648.694052237959;4123.134141830249;4220.451341325608;4263.829446822155;4378.9928316487385;4399.560043995575;3240.951385729216;3347.765656404077;3353.245519758035;3356.3790698720813;3359.664033596621;627.7583130494701;629.3014753623474;633.9017452294902;650.2749434380631;659.0970370592223;523.9633225674243;580.8896309701144;586.6656006076496;616.1220261127875;622.0793226025507;8255.174482551696;8664.350173736995;8666.6100025499;8735.213830755263;8810.77973050666;538.7871790642674;554.3264933106215;605.2887856768367;616.5098746496546;634.8603307272392;4102.815373308208;4225.345071513921;4262.87211383659;4290.806913688892;4378.584034516716;104528.72827630215;108974;109932;110841;113239;89601.55199223962;89944;90449.09550904538;90950;92447.0755292452;86330.82015079116;86654.70017949736;87105;89011.55494222487;89866.10133898577;8014.517314296873;8339.20777526134;8351.290140337896;8409.789755256168;8681.739547813575;50182.749086254335;55410.44589554053;56992.71503642455;57577.71211143918;57927.131093034484;59168;68753;72145.27854721385;77818.66544003689;81352;10659.20055995819;10699.090577300705;10809.189310801565;10956;11106.278091924;11529;11786.646400607995;11800.17398782077;11898.345590992578;12167.087468439726;10875.129989600971;11064.225504214628;11154;11264.7747045057;11382.487788049391;10304.381737095799;10445.477726113511;10574.788504229718;10998.285111467729;11741.354225517678;8569.914300856912;11250.606228781953;11266.718332041764;11279.830802538132;11284;8982.550872456482;9077.273818094669;9763.414195148314;9854;9914;7835.49069310493;8047.476914000571;9246.445213287225;9605.759856003655;9609.759756006155 14:38:38 INFO - PROCESS | 1638 | __end_tp_report 14:38:38 INFO - PROCESS | 1638 | __start_cc_report 14:38:38 INFO - PROCESS | 1638 | _x_x_mozilla_cycle_collect,1485 14:38:38 INFO - PROCESS | 1638 | __end_cc_report 14:38:38 INFO - PROCESS | 1638 | __startTimestamp1479249518630__endTimestamp 14:38:38 INFO - PROCESS | 1638 | 14:38:38 INFO - PROCESS | 1638 | ------- Summary: start ------- 14:38:38 INFO - PROCESS | 1638 | Number of tests: 6 14:38:38 INFO - PROCESS | 1638 | 14:38:38 INFO - PROCESS | 1638 | [#0] dojo.html Cycles:165 Average:5753.79 Median:5992.16 stddev:680.73 (11.4%) stddev-sans-first:638.27 14:38:38 INFO - PROCESS | 1638 | Values: 2666.1 3226.9 3331.3 3365.6 3407.2 4760.4 5589.6 5618.1 5674.5 5703.1 5107.4 5716.1 5790.4 5807.7 5852.8 4448.1 5243.7 5249.7 5283.2 5401.0 5034.5 5039.6 5057.2 5266.5 5368.7 5205.7 5256.1 5271.7 5290.2 5297.7 5271.8 5275.0 5340.7 5362.9 5364.9 5151.9 5211.7 5286.4 5307.6 5333.4 5604.9 5703.9 5728.4 5730.7 5773.1 6241.5 6295.8 6299.9 6303.4 6360.4 6219.5 6268.4 6292.9 6297.0 6297.7 5761.3 5799.1 5820.8 5853.9 5926.1 5497.7 6182.0 6314.9 6342.3 6357.7 6059.3 6220.0 6232.7 6236.2 6253.4 6119.4 6198.5 6222.1 6303.7 6328.4 6208.6 6211.3 6213.3 6213.8 6218.7 6149.6 6158.7 6216.9 6227.8 6273.5 4989.9 5011.0 5012.5 5023.1 5036.7 3611.6 4446.6 4459.3 4487.7 4501.4 5806.6 5832.4 5840.3 5914.3 5974.6 6240.1 6254.0 6256.1 6268.2 6283.7 5998.1 6015.5 6016.9 6027.2 6028.4 5347.8 5468.3 5470.3 5489.5 5499.3 5998.6 6250.6 6297.0 6326.9 6386.9 6251.2 6254.9 6262.4 6273.7 6279.5 6377.9 6395.6 6443.3 6444.3 6459.9 6069.2 6114.1 6199.7 6242.9 6280.9 5630.7 6123.7 6251.6 6313.8 6320.9 6154.4 6176.8 6183.3 6192.2 6208.1 5992.2 5999.4 6029.1 6099.6 6170.4 5845.9 5935.7 5938.3 5989.6 5990.8 6066.0 6083.7 6084.4 6101.9 6102.6 5814.8 5846.9 5900.4 5905.1 5925.1 14:38:38 INFO - PROCESS | 1638 | 14:38:38 INFO - PROCESS | 1638 | [#1] ext.html Cycles:180 Average:68153.51 Median:21018.24 stddev:154320.36 (734.2%) stddev-sans-first:154669.58 14:38:38 INFO - PROCESS | 1638 | Values: 461.3 477.2 516.3 520.0 552.4 30144.8 31764.9 39348.6 40017.0 40107.4 40744.6 40985.4 41067.6 41078.8 41171.0 9255.7 9429.9 9438.2 9449.5 9457.7 9148.7 9242.3 9353.3 9353.5 9397.3 9373.2 9410.9 9422.2 9425.4 9437.1 9319.7 9374.8 9376.2 9380.9 9383.4 9208.3 9279.5 9292.3 9305.8 9311.8 39356.8 39719.4 39748.0 39816.0 39929.2 3221.4 3260.0 3339.2 3695.3 3836.9 28580.3 28658.6 30142.7 30697.1 30963.4 3167.6 3214.2 3214.3 3285.3 3314.3 474334.0 475546.0 476219.0 476443.6 485965.0 32652.0 33619.2 60023.4 60334.7 60513.0 34385.0 34777.8 34790.5 34876.3 34909.3 13942.2 14095.0 14106.8 14121.3 14130.8 11839.3 11886.5 11909.0 11922.0 11922.2 7711.4 7742.4 7794.7 7800.8 7824.9 2529.3 3153.3 3186.7 3202.9 3217.7 925.5 932.3 935.1 1038.0 1067.0 21862.8 29034.3 29443.3 29518.9 29952.0 885.4 917.7 993.8 1002.7 1075.7 12878.7 15304.4 15538.9 15655.0 15724.0 661349.0 668209.0 668895.0 669627.0 669728.0 57023.7 57583.7 57841.0 57921.0 57990.0 1315.3 1353.2 1356.6 1376.0 1378.1 25098.1 28622.3 29402.3 30257.8 31077.5 49117.8 49547.8 49609.3 49636.5 49679.5 560085.0 564950.0 566938.0 566966.0 567951.0 26704.3 27067.3 27100.7 27122.9 27213.6 45158.3 45330.5 45604.1 46088.1 46625.0 33460.2 36597.3 37537.0 38047.8 38199.6 32558.8 35889.0 37575.4 37950.0 38123.6 30710.0 33047.0 33841.8 37501.6 38398.6 28192.9 32164.0 34085.8 34098.3 34456.8 24304.8 25303.9 25563.9 26015.5 26048.9 14:38:38 INFO - PROCESS | 1638 | 14:38:38 INFO - PROCESS | 1638 | [#2] jquery.html Cycles:180 Average:31637.96 Median:17342.66 stddev:89077.89 (513.6%) stddev-sans-first:89296.79 14:38:38 INFO - PROCESS | 1638 | Values: 349.3 367.8 372.8 380.8 400.3 14562.4 14967.0 15333.0 19359.7 20038.4 19292.6 19711.3 20228.7 20474.2 20619.9 5659.9 5847.7 5909.1 5974.8 5981.4 5502.6 5896.6 5925.9 5926.3 5937.3 5592.6 5772.2 5777.4 5797.9 5896.8 5708.3 5838.5 5839.6 5840.1 5857.5 4884.5 5073.7 5116.3 5132.1 5140.4 24587.5 24664.0 24845.5 24935.5 25130.5 2073.3 2352.8 2641.2 3050.4 3081.5 14154.9 15423.1 15610.8 16063.0 16146.3 1761.8 1781.9 1783.0 1803.8 1809.1 59637.0 62862.7 104206.0 107623.5 112152.0 9782.3 10748.7 10826.4 11072.3 11326.9 13965.7 13999.8 14005.5 14010.8 14045.2 8187.4 8410.4 8427.0 8517.9 8571.0 8328.7 8340.1 8355.2 8494.7 8504.4 6557.5 6667.3 6673.7 6693.3 6695.2 1819.7 1854.4 1854.8 1856.9 1857.1 890.3 936.1 944.9 951.6 983.9 19200.5 20648.8 20689.7 21914.3 21996.6 773.9 907.6 942.2 947.7 987.4 9612.6 10099.2 11422.6 11430.3 11467.3 454172.0 514768.0 551911.0 559216.0 593955.0 89520.0 105364.0 110527.0 111103.4 114409.4 744.4 855.5 945.2 973.8 989.8 18847.6 19094.8 19548.6 19587.9 19603.1 34336.0 44147.9 48886.1 49808.5 50322.0 69994.0 77222.2 77776.8 79602.4 80556.4 7143.8 7308.7 7439.4 7668.2 7756.2 10968.0 12313.0 12631.1 13187.1 13237.9 10937.6 10999.9 11079.7 11205.3 11294.2 10964.5 11005.1 11172.4 11246.9 11345.4 10094.5 11154.3 11479.1 11486.9 11592.3 6994.3 7378.6 7497.2 7623.5 7729.0 10477.5 11021.8 11095.9 11390.0 11456.9 14:38:38 INFO - PROCESS | 1638 | 14:38:38 INFO - PROCESS | 1638 | [#3] mootools.html Cycles:180 Average:5394.25 Median:5176.15 stddev:2922.55 (56.5%) stddev-sans-first:2914.58 14:38:38 INFO - PROCESS | 1638 | Values: 1304.1 1329.6 1447.6 1567.4 1777.1 7455.7 7818.6 7904.3 8294.3 8341.1 8040.4 8269.9 8278.9 8285.7 8289.2 2285.0 2332.5 2332.7 2333.8 2333.8 2316.0 2363.5 2363.6 2365.9 2368.9 2294.1 2299.7 2334.5 2335.1 2339.0 2285.4 2287.8 2323.0 2323.0 2324.4 2323.1 2343.7 2346.5 2346.7 2347.7 7332.3 8007.4 8268.1 8276.5 8310.4 5071.4 5194.1 5194.7 5196.4 5208.9 3295.9 3514.7 3557.4 3604.9 3606.5 1064.6 1074.9 1090.2 1094.1 1101.7 10307.6 10392.6 10502.3 10507.5 10511.8 4447.8 4482.5 4573.7 4579.2 4650.1 9455.8 9457.5 9463.0 9472.4 9480.1 5169.7 5173.8 5174.9 5177.4 5177.9 4106.8 4304.4 4424.2 4429.1 4605.0 3812.5 3834.1 3912.9 3919.7 3930.9 1241.8 1262.9 1264.7 1273.9 1279.1 756.7 781.5 890.7 897.6 902.3 5442.8 5563.7 5654.4 5746.3 5879.3 934.1 936.3 970.2 987.7 1017.6 3586.4 3621.2 3744.3 3761.4 3854.9 8717.4 9651.9 10025.8 10067.0 10152.4 9535.7 9980.2 10078.2 10080.0 10085.9 7615.8 7829.1 7829.4 7836.7 7845.9 5390.4 5402.8 5474.4 5677.9 5735.5 9003.9 9363.7 9387.6 9453.9 9476.2 9194.2 9361.0 9388.9 9528.3 9559.8 7007.5 7051.0 7054.6 7077.5 7144.2 7676.1 7733.6 7738.5 7742.9 7752.2 6985.7 7017.7 7111.0 7143.5 7241.0 6895.6 6938.5 6973.5 6986.3 6992.7 5659.4 6620.0 6704.6 6716.5 6718.6 4747.3 4779.6 4810.0 4851.2 4854.9 4713.1 4729.5 4749.2 4931.4 4979.8 14:38:38 INFO - PROCESS | 1638 | 14:38:38 INFO - PROCESS | 1638 | [#4] prototype.html Cycles:180 Average:4409.33 Median:4857.19 stddev:1376.79 (28.3%) stddev-sans-first:1345.57 14:38:38 INFO - PROCESS | 1638 | Values: 294.6 295.4 303.0 308.1 309.0 5249.2 5272.5 5300.9 5336.4 5385.2 4376.6 4411.4 4530.5 4841.3 5109.2 3272.6 3482.6 3698.9 3786.0 3951.7 3954.5 3976.7 3983.2 4017.4 4037.5 3302.6 3328.7 3488.0 3586.2 3779.5 3963.8 3971.6 4003.6 4006.3 4040.6 3455.5 3467.7 3495.4 3498.4 3500.6 4057.8 4128.2 4201.0 4580.9 4788.7 4383.8 4490.6 4530.4 4568.8 4578.2 4796.5 5431.4 5444.8 5469.9 5534.5 3832.2 3832.3 3842.6 3851.6 3857.5 4718.0 5391.0 5456.7 5568.2 5664.9 3511.9 3627.9 3690.7 3890.4 3907.3 3936.5 3938.2 3946.2 3950.2 3991.9 3858.8 4064.3 4336.1 4551.3 4593.2 4850.1 5298.8 5472.7 5488.6 5491.5 1163.5 1226.5 1238.5 1252.0 1253.8 746.7 778.2 815.9 816.3 860.5 3856.9 4713.6 4877.9 5182.4 5242.0 4798.2 4987.2 5706.7 5709.9 5715.8 4689.6 4904.5 5152.8 5218.7 5238.1 2735.6 2772.4 2840.9 2841.8 2856.5 4935.8 5190.1 5575.2 5641.8 5643.4 5172.6 5480.3 5603.0 5843.9 5914.9 5240.9 5572.9 5607.9 5857.6 5968.8 5519.2 5735.3 5743.2 5748.7 5784.3 4778.2 5587.3 5619.4 5623.7 5663.3 5812.4 5847.7 5849.0 5950.9 5951.3 3012.8 3661.2 3663.1 3796.8 3870.7 4766.9 5177.1 5388.8 5724.0 5756.4 5295.6 5595.3 5595.9 5609.8 5615.9 5291.8 5504.8 5566.6 5584.2 5609.9 4307.6 4382.9 5307.0 5424.7 5479.0 4601.8 4864.3 5304.5 5370.8 5481.2 4998.8 5346.1 5414.5 5427.9 5454.9 14:38:38 INFO - PROCESS | 1638 | 14:38:38 INFO - PROCESS | 1638 | [#5] yui.html Cycles:180 Average:19902.53 Median:57285.21 stddev:29745.34 (51.9%) stddev-sans-first:29793.39 14:38:38 INFO - PROCESS | 1638 | Values: 576.7 664.0 679.1 683.7 715.9 13399.7 13855.7 14161.6 14304.6 14511.1 12628.9 12787.3 12933.5 12950.1 12992.0 776.2 802.5 803.2 804.0 804.5 650.3 667.6 669.0 776.8 803.6 848.5 850.7 851.9 852.0 852.2 844.9 845.2 846.2 846.7 846.7 500.5 511.9 601.0 625.9 626.9 11789.8 13496.9 13905.6 13911.2 13941.2 12743.6 12872.0 13005.2 13055.5 13108.7 11882.0 11944.7 11952.8 12026.7 12094.3 4180.6 4198.8 4231.0 4416.5 4424.5 81640.0 83086.0 85988.7 87369.0 88236.0 9101.9 10165.4 10223.6 10336.1 10493.5 19566.7 19679.5 19733.0 19755.9 20010.8 7501.8 7506.3 7578.1 7580.9 7648.7 4123.1 4220.5 4263.8 4379.0 4399.6 3241.0 3347.8 3353.2 3356.4 3359.7 627.8 629.3 633.9 650.3 659.1 524.0 580.9 586.7 616.1 622.1 8255.2 8664.4 8666.6 8735.2 8810.8 538.8 554.3 605.3 616.5 634.9 4102.8 4225.3 4262.9 4290.8 4378.6 104528.7 108974.0 109932.0 110841.0 113239.0 89601.6 89944.0 90449.1 90950.0 92447.1 86330.8 86654.7 87105.0 89011.6 89866.1 8014.5 8339.2 8351.3 8409.8 8681.7 50182.7 55410.4 56992.7 57577.7 57927.1 59168.0 68753.0 72145.3 77818.7 81352.0 10659.2 10699.1 10809.2 10956.0 11106.3 11529.0 11786.6 11800.2 11898.3 12167.1 10875.1 11064.2 11154.0 11264.8 11382.5 10304.4 10445.5 10574.8 10998.3 11741.4 8569.9 11250.6 11266.7 11279.8 11284.0 8982.6 9077.3 9763.4 9854.0 9914.0 7835.5 8047.5 9246.4 9605.8 9609.8 14:38:38 INFO - PROCESS | 1638 | -------- Summary: end -------- 14:38:38 INFO - PROCESS | 1638 | 14:38:39 INFO - TEST-INFO | 1638: exit 0 14:38:39 INFO - TEST-OK | dromaeo_css | took 1118805ms 14:38:39 INFO - TEST-START | kraken 14:38:39 INFO - operating with platform_type : mac_ 14:38:39 INFO - Initialising browser for kraken test... 14:38:39 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpkM9NjU/profile http://localhost:49242/getInfo.html) 14:38:41 ERROR - PROCESS | 1648 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 14:38:41 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 14:38:41 INFO - PROCESS | 1648 | colorDepth:24 14:38:41 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 14:38:41 INFO - PROCESS | 1648 | __metrics 14:38:41 INFO - PROCESS | 1648 | 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" 14:38:41 INFO - PROCESS | 1648 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:38:42 INFO - TEST-INFO | 1648: exit 0 14:38:42 INFO - Browser initialized. 14:38:42 INFO - Running cycle 1/1 for kraken test... 14:38:42 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpkM9NjU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:38:54 INFO - PROCESS | 1649 | RSS: Main: 217526272 14:38:54 INFO - PROCESS | 1649 | 14:38:57 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 14:38:58 INFO - PROCESS | 1649 | RSS: Main: 265166848 14:38:58 INFO - PROCESS | 1649 | 14:39:00 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 14:39:01 INFO - PROCESS | 1649 | RSS: Main: 277753856 14:39:01 INFO - PROCESS | 1649 | 14:39:04 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 14:39:05 INFO - PROCESS | 1649 | RSS: Main: 373739520 14:39:05 INFO - PROCESS | 1649 | 14:39:07 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 14:39:07 INFO - PROCESS | 1649 | RSS: Main: 278990848 14:39:07 INFO - PROCESS | 1649 | 14:39:09 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:39:10 INFO - PROCESS | 1649 | RSS: Main: 280432640 14:39:10 INFO - PROCESS | 1649 | 14:39:13 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 14:39:14 INFO - PROCESS | 1649 | RSS: Main: 321765376 14:39:14 INFO - PROCESS | 1649 | 14:39:16 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 14:39:17 INFO - PROCESS | 1649 | RSS: Main: 320106496 14:39:17 INFO - PROCESS | 1649 | 14:39:20 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 14:39:20 INFO - PROCESS | 1649 | RSS: Main: 318091264 14:39:20 INFO - PROCESS | 1649 | 14:39:22 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:39:23 INFO - PROCESS | 1649 | RSS: Main: 296218624 14:39:23 INFO - PROCESS | 1649 | 14:39:25 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:39:25 INFO - PROCESS | 1649 | RSS: Main: 298180608 14:39:25 INFO - PROCESS | 1649 | 14:39:27 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:39:28 INFO - PROCESS | 1649 | RSS: Main: 300875776 14:39:28 INFO - PROCESS | 1649 | 14:39:31 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:39:32 INFO - PROCESS | 1649 | RSS: Main: 316993536 14:39:32 INFO - PROCESS | 1649 | 14:39:35 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:39:35 INFO - PROCESS | 1649 | RSS: Main: 310644736 14:39:35 INFO - PROCESS | 1649 | 14:39:37 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 14:39:37 INFO - PROCESS | 1649 | RSS: Main: 354607104 14:39:37 INFO - PROCESS | 1649 | 14:39:37 INFO - PROCESS | 1649 | __start_tp_report 14:39:37 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 14:39:37 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 14:39:37 INFO - PROCESS | 1649 | |i|pagename|runs| 14:39:37 INFO - PROCESS | 1649 | |0;ai-astar;103;113;114;114;114;114;113;113;113;113 14:39:37 INFO - PROCESS | 1649 | |1;audio-beat-detection;154;112;115;145;115;111;113;112;142;114 14:39:37 INFO - PROCESS | 1649 | |2;audio-dft;184;184;182;186;185;184;182;183;185;183 14:39:37 INFO - PROCESS | 1649 | |3;audio-fft;81;60;74;112;83;60;59;58;83;58 14:39:37 INFO - PROCESS | 1649 | |4;audio-oscillator;89;69;94;70;68;69;88;68;68;91 14:39:37 INFO - PROCESS | 1649 | |5;imaging-gaussian-blur;91;87;92;89;86;82;113;129;125;87 14:39:37 INFO - PROCESS | 1649 | |6;imaging-darkroom;96;95;95;96;96;96;99;97;95;95 14:39:37 INFO - PROCESS | 1649 | |7;imaging-desaturate;81;83;79;79;81;80;85;83;84;81 14:39:37 INFO - PROCESS | 1649 | |8;json-parse-financial;58;53;53;54;55;58;57;57;56;57 14:39:37 INFO - PROCESS | 1649 | |9;json-stringify-tinderbox;67;73;77;74;73;69;70;69;69;69 14:39:37 INFO - PROCESS | 1649 | |10;stanford-crypto-aes;82;85;83;83;91;86;82;86;86;88 14:39:37 INFO - PROCESS | 1649 | |11;stanford-crypto-ccm;142;157;153;147;146;149;159;159;194;158 14:39:37 INFO - PROCESS | 1649 | |12;stanford-crypto-pbkdf2;169;217;168;167;166;167;166;166;167;165 14:39:37 INFO - PROCESS | 1649 | |13;stanford-crypto-sha256-iterative;65;66;67;67;67;66;68;81;87;85 14:39:37 INFO - PROCESS | 1649 | __end_tp_report 14:39:37 INFO - PROCESS | 1649 | __start_cc_report 14:39:37 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,2002 14:39:37 INFO - PROCESS | 1649 | __end_cc_report 14:39:37 INFO - PROCESS | 1649 | __startTimestamp1479249577832__endTimestamp 14:39:37 INFO - PROCESS | 1649 | 14:39:37 INFO - PROCESS | 1649 | ------- Summary: start ------- 14:39:37 INFO - PROCESS | 1649 | Number of tests: 14 14:39:37 INFO - PROCESS | 1649 | 14:39:37 INFO - PROCESS | 1649 | [#0] ai-astar Cycles:10 Average:112.40 Median:113.50 stddev:3.34 (2.9%) stddev-sans-first:0.53 14:39:37 INFO - PROCESS | 1649 | Values: 103.0 113.0 114.0 114.0 114.0 114.0 113.0 113.0 113.0 113.0 14:39:37 INFO - PROCESS | 1649 | 14:39:37 INFO - PROCESS | 1649 | [#1] audio-beat-detection Cycles:10 Average:123.30 Median:115.00 stddev:16.67 (14.5%) stddev-sans-first:13.48 14:39:37 INFO - PROCESS | 1649 | Values: 154.0 112.0 115.0 145.0 115.0 111.0 113.0 112.0 142.0 114.0 14:39:37 INFO - PROCESS | 1649 | 14:39:37 INFO - PROCESS | 1649 | [#2] audio-dft Cycles:10 Average:183.80 Median:184.00 stddev:1.32 (0.7%) stddev-sans-first:1.39 14:39:37 INFO - PROCESS | 1649 | Values: 184.0 184.0 182.0 186.0 185.0 184.0 182.0 183.0 185.0 183.0 14:39:37 INFO - PROCESS | 1649 | 14:39:37 INFO - PROCESS | 1649 | [#3] audio-fft Cycles:10 Average:72.80 Median:67.00 stddev:17.54 (26.2%) stddev-sans-first:18.35 14:39:37 INFO - PROCESS | 1649 | Values: 81.0 60.0 74.0 112.0 83.0 60.0 59.0 58.0 83.0 58.0 14:39:37 INFO - PROCESS | 1649 | 14:39:37 INFO - PROCESS | 1649 | [#4] audio-oscillator Cycles:10 Average:77.40 Median:79.00 stddev:11.39 (14.4%) stddev-sans-first:11.29 14:39:37 INFO - PROCESS | 1649 | Values: 89.0 69.0 94.0 70.0 68.0 69.0 88.0 68.0 68.0 91.0 14:39:37 INFO - PROCESS | 1649 | 14:39:37 INFO - PROCESS | 1649 | [#5] imaging-gaussian-blur Cycles:10 Average:98.10 Median:87.00 stddev:17.39 (20.0%) stddev-sans-first:18.26 14:39:37 INFO - PROCESS | 1649 | Values: 91.0 87.0 92.0 89.0 86.0 82.0 113.0 129.0 125.0 87.0 14:39:37 INFO - PROCESS | 1649 | 14:39:37 INFO - PROCESS | 1649 | [#6] imaging-darkroom Cycles:10 Average:96.00 Median:96.00 stddev:1.25 (1.3%) stddev-sans-first:1.32 14:39:37 INFO - PROCESS | 1649 | Values: 96.0 95.0 95.0 96.0 96.0 96.0 99.0 97.0 95.0 95.0 14:39:37 INFO - PROCESS | 1649 | 14:39:37 INFO - PROCESS | 1649 | [#7] imaging-desaturate Cycles:10 Average:81.60 Median:82.00 stddev:2.07 (2.5%) stddev-sans-first:2.18 14:39:37 INFO - PROCESS | 1649 | Values: 81.0 83.0 79.0 79.0 81.0 80.0 85.0 83.0 84.0 81.0 14:39:37 INFO - PROCESS | 1649 | 14:39:37 INFO - PROCESS | 1649 | [#8] json-parse-financial Cycles:10 Average:55.80 Median:57.00 stddev:1.93 (3.4%) stddev-sans-first:1.88 14:39:37 INFO - PROCESS | 1649 | Values: 58.0 53.0 53.0 54.0 55.0 58.0 57.0 57.0 56.0 57.0 14:39:37 INFO - PROCESS | 1649 | 14:39:37 INFO - PROCESS | 1649 | [#9] json-stringify-tinderbox Cycles:10 Average:71.00 Median:71.50 stddev:3.09 (4.3%) stddev-sans-first:2.92 14:39:37 INFO - PROCESS | 1649 | Values: 67.0 73.0 77.0 74.0 73.0 69.0 70.0 69.0 69.0 69.0 14:39:37 INFO - PROCESS | 1649 | 14:39:37 INFO - PROCESS | 1649 | [#10] stanford-crypto-aes Cycles:10 Average:85.20 Median:86.00 stddev:2.86 (3.3%) stddev-sans-first:2.79 14:39:37 INFO - PROCESS | 1649 | Values: 82.0 85.0 83.0 83.0 91.0 86.0 82.0 86.0 86.0 88.0 14:39:37 INFO - PROCESS | 1649 | 14:39:37 INFO - PROCESS | 1649 | [#11] stanford-crypto-ccm Cycles:10 Average:156.40 Median:157.50 stddev:14.53 (9.2%) stddev-sans-first:14.45 14:39:37 INFO - PROCESS | 1649 | Values: 142.0 157.0 153.0 147.0 146.0 149.0 159.0 159.0 194.0 158.0 14:39:37 INFO - PROCESS | 1649 | 14:39:37 INFO - PROCESS | 1649 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.80 Median:167.00 stddev:15.92 (9.5%) stddev-sans-first:16.86 14:39:37 INFO - PROCESS | 1649 | Values: 169.0 217.0 168.0 167.0 166.0 167.0 166.0 166.0 167.0 165.0 14:39:37 INFO - PROCESS | 1649 | 14:39:37 INFO - PROCESS | 1649 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.90 Median:67.50 stddev:8.74 (12.9%) stddev-sans-first:8.90 14:39:37 INFO - PROCESS | 1649 | Values: 65.0 66.0 67.0 67.0 67.0 66.0 68.0 81.0 87.0 85.0 14:39:37 INFO - PROCESS | 1649 | -------- Summary: end -------- 14:39:37 INFO - PROCESS | 1649 | 14:39:38 INFO - TEST-INFO | 1649: exit 0 14:39:38 INFO - TEST-OK | kraken | took 59428ms 14:39:38 INFO - SUITE-END | took 1178s 14:39:38 INFO - Completed test suite (00:19:38) 14:39:38 INFO - javascript benchmark 14:39:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2666.0535509894, 3226.887058952937, 3331.2504686445554, 3365.62978072412, 3407.1993081625815, 4760.404949381327, 5589.608727389084, 5618.12918997555, 5674.517665998395, 5703.144528320754, 5107.361579802525, 5716.114002329635, 5790.449907258814, 5807.73865176067, 5852.824415267538, 4448.088141930913, 5243.68537887727, 5249.658772179796, 5283.181106928413, 5400.972995135019, 5034.471380505049, 5039.647224694273, 5057.241413787943, 5266.499682530172, 5368.651037682557, 5205.7397130143345, 5256.053910296145, 5271.657342272759, 5290.1800220965615, 5297.735113244342, 5271.841844744645, 5275.024120537712, 5340.732963351817, 5362.892742145174, 5364.946350536484, 5151.9484805152215, 5211.7133557654315, 5286.418493965661, 5307.575393968474, 5333.413324534298, 5604.915926261109, 5703.885922281573, 5728.427157284238, 5730.656160630376, 5773.076307790733, 6241.500679945639, 6295.842603934892, 6299.8740025199695, 6303.432691057827, 6360.395762402564, 6219.533534984849, 6268.373162683741, 6292.937070629281, 6296.96851515744, 6297.716602752887, 5761.279840019997, 5799.130130480462, 5820.767169313265, 5853.853653658693, 5926.111083337534, 5497.670139791625, 6182.0, 6314.905276420857, 6342.270638876588, 6357.745690172434, 6059.333473317932, 6220.03589443637, 6232.743583354099, 6236.158118653923, 6253.437190652863, 6119.388061193845, 6198.473129783929, 6222.12890195373, 6303.653299068504, 6328.398802113792, 6208.596441231305, 6211.3478084801345, 6213.285472170758, 6213.844653883689, 6218.720157592919, 6149.569530132937, 6158.7228574714245, 6216.906746398808, 6227.844303892439, 6273.52948528853, 4989.900201996013, 5010.974945125251, 5012.473690262543, 5023.120953833065, 5036.722980236123, 3611.638836116367, 4446.599806017474, 4459.264221403432, 4487.708298960557, 4501.369808226851, 5806.622569533051, 5832.416758324133, 5840.269966254218, 5914.319853216849, 5974.641521508724, 6240.126382306481, 6254.0, 6256.061590761331, 6268.247810262797, 6283.748650053946, 5998.1002849572915, 6015.518758499309, 6016.8796624068145, 6027.156198132265, 6028.367021462684, 5347.786088556414, 5468.261784659099, 5470.343558772973, 5489.451054894478, 5499.340079190442, 5998.640081595118, 6250.624962502265, 6296.961001434712, 6326.936730632635, 6386.9361306386345, 6251.218597675291, 6254.937450625435, 6262.373762623702, 6273.65494897785, 6279.497640188865, 6377.93622063792, 6395.584287021329, 6443.291237963726, 6444.323346048597, 6459.870802583828, 6069.241344831896, 6114.145302971568, 6199.690015499117, 6242.937570624235, 6280.937190628035, 5630.662160270397, 6123.693815309306, 6251.562390632612, 6313.779017734358, 6320.936790632035, 6154.415330543591, 6176.845578860385, 6183.258009038944, 6192.164057852313, 6208.130861679459, 5992.161097446274, 5999.430054144849, 6029.095635654687, 6099.603525770811, 6170.444659980623, 5845.853853653693, 5935.673537955454, 5938.317093534299, 5989.580729348903, 5990.7903223386975, 6066.0, 6083.69581520931, 6084.39156084388, 6101.908471372844, 6102.572819902564, 5814.76740930358, 5846.883062338815, 5900.43945825146, 5905.055191169392, 5925.111233315038], "value": 5707.205722158185, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [461.2965228027258, 477.23403936681626, 516.2617457036438, 519.9740012999349, 552.3978863039287, 30144.84927575362, 31764.888228911994, 39348.60651393489, 40017.0, 40107.398389024114, 40744.59255407445, 40985.38521922166, 41067.58932410675, 41078.794606027004, 41171.0, 9255.722328330145, 9429.90570094297, 9438.197753190987, 9449.480278584675, 9457.668981585646, 9148.725538233864, 9242.260619150451, 9353.251739860794, 9353.485558294291, 9397.342186046979, 9373.203277721403, 9410.905890941105, 9422.15200631943, 9425.434473931551, 9437.103475169848, 9319.720408387726, 9374.765630859249, 9376.203022743075, 9380.90619093814, 9383.390079644802, 9208.3093767967, 9279.53602319888, 9292.349535532516, 9305.767355816091, 9311.813763724687, 39356.80321598402, 39719.40420893689, 39748.0, 39816.0, 39929.20141597152, 3221.355728854215, 3259.9894032849656, 3339.231976645361, 3695.316366472211, 3836.865709700175, 28580.285492862637, 28658.570121448196, 30142.698573014426, 30697.079087627408, 30963.380732385453, 3167.572377729, 3214.164317277501, 3214.276787722744, 3285.326508065865, 3314.254292784128, 474334.0, 475546.0, 476219.0, 476443.6177819123, 485965.0, 32652.0, 33619.15952101168, 60023.39976600266, 60334.698326508085, 60513.0, 34385.0, 34777.82611086928, 34790.47814282788, 34876.302473950884, 34909.301813964084, 13942.163470191617, 14095.0, 14106.788398174036, 14121.293935303194, 14130.788038179435, 11839.34883581394, 11886.524539018343, 11909.0, 11922.0, 11922.225055371373, 7711.421643376769, 7742.380609551335, 7794.713872211135, 7800.84398312042, 7824.92175078242, 2529.29179829648, 3153.3377990622225, 3186.729128024144, 3202.9359412812078, 3217.6621454747383, 925.546482223719, 932.3380399916, 935.1443429262149, 1038.0190719769746, 1066.99466502667, 21862.797546134796, 29034.27414314617, 29443.29569740512, 29518.852405737834, 29952.0, 885.3581153663542, 917.655879045358, 993.8360170571775, 1002.6540843409012, 1075.6504136155781, 12878.742425151444, 15304.387824486896, 15538.922305388402, 15655.0, 15724.0, 661349.0, 668209.0, 668895.0, 669627.0, 669728.0, 57023.71488142533, 57583.712081440164, 57841.0, 57921.0, 57990.0, 1315.3489022933709, 1353.2489468345163, 1356.552337728567, 1375.9793603095761, 1378.1042322490262, 25098.12156574511, 28622.284442889093, 29402.264943376587, 30257.848710756305, 31077.533836992916, 49117.75441122772, 49547.752261238464, 49609.25586116139, 49636.50363496463, 49679.503204967485, 560085.0, 564950.0, 566938.0, 566966.0, 567951.0, 26704.332391690365, 27067.32331691645, 27100.72899271061, 27122.864385677945, 27213.591796122677, 45158.32262516131, 45330.54669453263, 45604.08791824211, 46088.078238435715, 46625.0, 33460.15969121722, 36597.26805463929, 37537.0, 38047.809760951015, 38199.61800382072, 32558.837205814765, 35889.0, 37575.43636845504, 37950.0, 38123.61876381201, 30709.995530912507, 33047.0, 33841.83079084589, 37501.62498374981, 38398.616013839506, 28192.85903570469, 32164.014577740487, 34085.82957085199, 34098.318033638694, 34456.82771586126, 24304.75695243096, 25303.922547160542, 25563.872180638977, 26015.479690406464, 26048.8697556511], "value": 18102.704514303725, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.32405794787076, 367.7977112588076, 372.7986887080978, 380.8000799580221, 400.27350359098233, 14562.417503299868, 14967.0, 15332.999999999985, 19359.709604355947, 20038.398848034547, 19292.61414771704, 19711.310104146356, 20228.69656955143, 20474.181032758708, 20619.896900515476, 5659.943400566003, 5847.678377689225, 5909.11363295507, 5974.79088231912, 5981.371955944606, 5502.58730595205, 5896.616719913192, 5925.88148237035, 5926.348101708809, 5937.317208521011, 5592.6085174037835, 5772.163036359725, 5777.393373695764, 5797.927383434078, 5896.823095307148, 5708.343540492855, 5838.5329173666005, 5839.64962102271, 5840.123981402781, 5857.472827445529, 4884.511548845087, 5073.746312684351, 5116.283720279164, 5132.076226279269, 5140.408852981917, 24587.508249834904, 24664.0134394624, 24845.5030899381, 24935.501289974098, 25130.497390052096, 2073.2536286936693, 2352.764723527651, 2641.2076377086796, 3050.3746731919905, 3081.4915538936275, 14154.92922535391, 15423.074615523105, 15610.76583851243, 16063.0, 16146.273417696115, 1761.806201317854, 1781.946541603754, 1783.0460703523552, 1803.846673032794, 1809.1316168239186, 59637.0, 62862.685686571276, 104206.0, 107623.46188269087, 112152.0, 9782.266330025277, 10748.71015478132, 10826.404547749791, 11072.280301780358, 11326.886731132749, 13965.65085872841, 13999.790003149961, 14005.5098071567, 14010.789838152436, 14045.157290562598, 8187.4268801184535, 8410.411271211078, 8427.0, 8517.914820851836, 8571.0, 8328.708495202638, 8340.124286949904, 8355.206255405727, 8494.74515764528, 8504.404691671647, 6557.540972132, 6667.266600673922, 6673.666316684146, 6693.297203793629, 6695.196576410862, 1819.6997495413373, 1854.4158590044092, 1854.7681539807525, 1856.8885866848032, 1857.071464267866, 890.2610833008588, 936.138752347828, 944.8818897637796, 951.6003278622995, 983.9373476645206, 19200.519987000436, 20648.793512064687, 20689.655172413746, 21914.342569722932, 21996.560068798535, 773.860705073081, 907.6006557114848, 942.1991307388802, 947.7346343023827, 987.4026214140486, 9612.558116282473, 10099.192064634812, 11422.600208992812, 11430.314181149157, 11467.31196128235, 454172.0, 514768.0, 551911.0, 559216.0, 593955.0, 89520.0, 105364.0, 110527.0, 111103.44448277706, 114409.4279528597, 744.3970383988986, 855.5038077914794, 945.2249155692404, 973.776031512756, 989.7970915962211, 18847.6230475387, 19094.80905190986, 19548.60902781908, 19587.902060489607, 19603.117859696064, 34336.0, 44147.89630259269, 48886.11138886083, 49808.51340406137, 50321.993560129325, 69994.0, 77222.2277777215, 77776.8333474987, 79602.4079518418, 80556.38887222105, 7143.82140446493, 7308.722464333404, 7439.442041846775, 7668.233176682287, 7756.185600512091, 10968.0, 12312.994282754507, 12631.11582189238, 13187.076904616968, 13237.867621324049, 10937.617183398543, 10999.945000274947, 11079.670439546984, 11205.327680339205, 11294.152938529478, 10964.506597202986, 11005.064569511682, 11172.385518796544, 11246.943765281121, 11345.432728363714, 10094.54574544162, 11154.274972126786, 11479.081673465937, 11486.94256528712, 11592.304461732323, 6994.300569942965, 7378.557286562824, 7497.175310715926, 7623.542587444771, 7729.033870766154, 10477.52851121686, 11021.779564408827, 11095.889041109485, 11389.974902258837, 11456.94271528637], "value": 9411.391301498801, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1304.054560443678, 1329.5545992092648, 1447.594673491422, 1567.373050779688, 1777.1380880273066, 7455.739049133282, 7818.5926533224065, 7904.2886140247365, 8294.253517183452, 8341.124181960882, 8040.356771458284, 8269.91730082699, 8278.875816862752, 8285.668573257062, 8289.171082891708, 2285.02886273334, 2332.4868528923694, 2332.6501024846325, 2333.8132949364094, 2333.8482998605036, 2316.027268547214, 2363.5036642305136, 2363.5745565798147, 2365.9171928982487, 2368.917087901924, 2294.1167650454613, 2299.701038864945, 2334.4747431827873, 2335.1476711000446, 2339.040993192791, 2285.394370491821, 2287.7597852225526, 2323.0, 2323.047550504285, 2324.418895276181, 2323.14043803792, 2343.7070366204225, 2346.530693861235, 2346.6949296591506, 2347.6713260143424, 7332.266773322678, 8007.359411247086, 8268.09051004395, 8276.462029968034, 8310.376721745833, 5071.442141364447, 5194.065068287707, 5194.740262986836, 5196.376434827807, 5208.921866171972, 3295.934081318384, 3514.7012503937194, 3557.4130268505664, 3604.9279014419826, 3606.477060826192, 1064.627380416848, 1074.940878251696, 1090.231386872253, 1094.1301665176204, 1101.6529793115224, 10307.587696492132, 10392.636257730943, 10502.317349372266, 10507.474626268733, 10511.84232236517, 4447.755373454426, 4482.462104547475, 4573.679842411066, 4579.244424669892, 4650.116477869194, 9455.763605909908, 9457.527123643791, 9463.0, 9472.384295020802, 9480.146786789222, 5169.689818610896, 5173.767180476888, 5174.922376164361, 5177.378714554277, 5177.922331165036, 4106.7946602669745, 4304.418903448057, 4424.203643344164, 4429.092036132585, 4605.0, 3812.504374431306, 3834.079820842978, 3912.902177445587, 3919.725619206685, 3930.941035884464, 1241.7702724995904, 1262.8737126287297, 1264.6711854917787, 1273.949042038308, 1279.0790630745848, 756.7048850948134, 781.4803155901376, 890.6882591093195, 897.5915958238986, 902.251131560803, 5442.782288708408, 5563.749631266603, 5654.40628733977, 5746.310442746897, 5879.265091863517, 934.1265916368218, 936.283742936654, 970.2432102959702, 987.713563066717, 1017.5573625472942, 3586.3544561978574, 3621.203335266237, 3744.26986737589, 3761.4169803679924, 3854.884353469344, 8717.433366831263, 9651.855222171673, 10025.849612255823, 10067.0, 10152.39085654863, 9535.713928582154, 9980.151687106532, 10078.193744500422, 10080.0, 10085.899141008642, 7615.847683046308, 7829.099653539915, 7829.412794040356, 7836.686532538634, 7845.921540784633, 5390.434004429479, 5402.75687594067, 5474.39781624029, 5677.9495793278375, 5735.483806457439, 9003.90996090057, 9363.672271470467, 9387.577559009724, 9453.905460945303, 9476.241900648069, 9194.17252447283, 9361.0, 9388.906110938804, 9528.333016689043, 9559.761005974628, 7007.474439416962, 7050.964745176241, 7054.611996340251, 7077.504574679723, 7144.214136444883, 7676.117246516499, 7733.574653394117, 7738.4969976951315, 7742.92257077422, 7752.18602046777, 6985.720571177096, 7017.684204210721, 7111.0, 7143.4999550031, 7240.96379518099, 6895.586264824126, 6938.514303998873, 6973.5118541701595, 6986.301369863177, 6992.650367481504, 5659.434056594308, 6620.040094186413, 6704.564203326768, 6716.496262780213, 6718.563293385914, 4747.264174053027, 4779.617630589614, 4809.951900480951, 4851.248056551241, 4854.90290194187, 4713.104510143062, 4729.479757226771, 4749.216379297515, 4931.3589233399425, 4979.800807967641], "value": 4391.317113159929, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [294.5758108324014, 295.3649653245522, 302.9636443626765, 308.0865234579471, 309.0388890550386, 5249.1601343785005, 5272.523693365859, 5300.879648140745, 5336.425754402451, 5385.21914322423, 4376.584224498675, 4411.4265145531035, 4530.456345238576, 4841.346418233537, 5109.208072748721, 3272.574565306513, 3482.599501057373, 3698.8718440875523, 3785.9621403786023, 3951.723379363446, 3954.545227298854, 3976.741511801724, 3983.223271462066, 4017.4174744662005, 4037.4672161858375, 3302.5871766029245, 3328.733701303902, 3487.9884833398282, 3586.202791583588, 3779.469314927459, 3963.8018099095075, 3971.5829837867186, 4003.619656132663, 4006.278869803434, 4040.5959404059654, 3455.51622772812, 3467.7052450541732, 3495.405781017233, 3498.4052711039185, 3500.5799304083416, 4057.8173982170874, 4128.153728485653, 4200.957990420118, 4580.938577640075, 4788.730986288601, 4383.772543687773, 4490.618297444721, 4530.411046563926, 4568.794404251817, 4578.221702310615, 4796.544328288842, 5431.375391829911, 5444.7822087116465, 5469.945300546983, 5534.533348662608, 3832.1833452412457, 3832.310184166849, 3842.634949679776, 3851.614838516154, 3857.4792403025513, 4718.0, 5391.0, 5456.727163641802, 5568.220449137124, 5664.886702265972, 3511.9122021949142, 3627.891163265106, 3690.741648084662, 3890.396988466792, 3907.29668659644, 3936.5472970608175, 3938.2320447513034, 3946.1515774108702, 3950.1902110067367, 3991.92016159681, 3858.7877666728036, 4064.309067458539, 4336.089421221579, 4551.271796512542, 4593.242115050979, 4850.054239423349, 5298.841034768963, 5472.716370177941, 5488.585022590048, 5491.505764481216, 1163.4996951311018, 1226.533917111492, 1238.467458992642, 1251.960872475843, 1253.783829685204, 746.7013194722045, 778.2023425988407, 815.8980127484065, 816.31429599137, 860.5353109321022, 3856.8650097246464, 4713.599344055794, 4877.926831097537, 5182.352205974254, 5241.955637301456, 4798.224656876908, 4987.152184128707, 5706.686132262766, 5709.857253568694, 5715.799947001919, 4689.591783723289, 4904.485029071968, 5152.793888244429, 5218.686878787209, 5238.109521381374, 2735.617013618095, 2772.431651511435, 2840.8920520996826, 2841.758450531728, 2856.4715527627454, 4935.777889994959, 5190.091733946544, 5575.219469274305, 5641.75881306112, 5643.35101463337, 5172.586193104543, 5480.314960629921, 5603.0, 5843.912341314884, 5914.9112763308585, 5240.873212259383, 5572.888542229132, 5607.915881261702, 5857.577528965604, 5968.76124954997, 5519.227308176778, 5735.254416925773, 5743.224664670216, 5748.735278238781, 5784.334801497882, 4778.1638213311835, 5587.329520457409, 5619.353774315842, 5623.69069701154, 5663.32040155184, 5812.389699081705, 5847.736851841592, 5849.017001329267, 5950.9469233114505, 5951.285845698544, 3012.789104762733, 3661.2128392394698, 3663.084228942764, 3796.772193668389, 3870.6709929656295, 4766.9036121691515, 5177.0940085485645, 5388.757505912165, 5723.971380143073, 5756.424357564378, 5295.637270333289, 5595.300587426572, 5595.944040559542, 5609.849980753854, 5615.943840561706, 5291.7618707159045, 5504.834854954358, 5566.638168519034, 5584.246126772996, 5609.9158512621525, 4307.590778876001, 4382.912341753084, 5307.0098712982635, 5424.728763561727, 5479.0411677957, 4601.769911504478, 4864.324621458113, 5304.469553044819, 5370.837600859365, 5481.205225242238, 4998.825041123543, 5346.0911645021215, 5414.485623865727, 5427.94155139744, 5454.91817622728], "value": 3965.2942590740186, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [576.6972339521752, 663.9734410623576, 679.134104017378, 683.7369004258447, 715.8818794898842, 13399.665008374783, 13855.722885542284, 14161.575152745409, 14304.570862874105, 14511.056781309208, 12628.936855315711, 12787.296698681568, 12933.547325843598, 12950.093493455463, 12992.0, 776.185005743968, 802.5144787403624, 803.1847674610275, 803.9919600804005, 804.4569915307173, 650.3139188156493, 667.6094484726441, 668.9931652862435, 776.8407476467311, 803.5540275147291, 848.5205858689851, 850.7277671145235, 851.9275861551776, 852.0201767966826, 852.2117041736369, 844.907060223375, 845.1801752300258, 846.1792061700172, 846.6951897316961, 846.6951897316961, 500.49200061937165, 511.872031992002, 600.9939900600982, 625.9499240060784, 626.9279032911228, 11789.764204715857, 13496.865031349756, 13905.582832515041, 13911.165330080228, 13941.233232172226, 12743.553975610901, 12872.0, 13005.154664946844, 13055.543055992996, 13108.737825243537, 11882.049436045096, 11944.70138246542, 11952.760944781056, 12026.69933251667, 12094.334811585359, 4180.623743863037, 4198.8110535026, 4231.048014196812, 4416.470023597157, 4424.513303536608, 81640.0, 83086.0, 85988.71016934751, 87368.99999999936, 88236.0, 9101.863472047924, 10165.440900750382, 10223.642172523925, 10336.0697537222, 10493.52779124926, 19566.70649940252, 19679.508012299808, 19733.013349332476, 19755.901220493804, 20010.799892001185, 7501.812454688567, 7506.286902744339, 7578.052743407075, 7580.929723462007, 7648.694052237959, 4123.134141830249, 4220.451341325608, 4263.829446822155, 4378.9928316487385, 4399.560043995575, 3240.951385729216, 3347.765656404077, 3353.245519758035, 3356.3790698720813, 3359.664033596621, 627.7583130494701, 629.3014753623474, 633.9017452294902, 650.2749434380631, 659.0970370592223, 523.9633225674243, 580.8896309701144, 586.6656006076496, 616.1220261127875, 622.0793226025507, 8255.174482551696, 8664.350173736995, 8666.6100025499, 8735.213830755263, 8810.77973050666, 538.7871790642674, 554.3264933106215, 605.2887856768367, 616.5098746496546, 634.8603307272392, 4102.815373308208, 4225.345071513921, 4262.87211383659, 4290.806913688892, 4378.584034516716, 104528.72827630215, 108974.0, 109932.0, 110841.0, 113239.0, 89601.55199223962, 89944.0, 90449.09550904538, 90950.0, 92447.0755292452, 86330.82015079116, 86654.70017949736, 87105.0, 89011.55494222487, 89866.10133898577, 8014.517314296873, 8339.20777526134, 8351.290140337896, 8409.789755256168, 8681.739547813575, 50182.749086254335, 55410.44589554053, 56992.71503642455, 57577.71211143918, 57927.131093034484, 59168.0, 68753.0, 72145.27854721385, 77818.66544003689, 81352.0, 10659.20055995819, 10699.090577300705, 10809.189310801565, 10956.0, 11106.278091924, 11529.0, 11786.646400607995, 11800.17398782077, 11898.345590992578, 12167.087468439726, 10875.129989600971, 11064.225504214628, 11154.0, 11264.7747045057, 11382.487788049391, 10304.381737095799, 10445.477726113511, 10574.788504229718, 10998.285111467729, 11741.354225517678, 8569.914300856912, 11250.606228781953, 11266.718332041764, 11279.830802538132, 11284.0, 8982.550872456482, 9077.273818094669, 9763.414195148314, 9854.0, 9914.0, 7835.49069310493, 8047.476914000571, 9246.445213287225, 9605.759856003655, 9609.759756006155], "value": 6894.970173341137, "unit": "score"}], "name": "dromaeo_css", "value": 6991.087256286422, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 113.0, 114.0, 114.0, 114.0, 114.0, 113.0, 113.0, 113.0, 113.0], "value": 112.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 112.0, 115.0, 145.0, 115.0, 111.0, 113.0, 112.0, 142.0, 114.0], "value": 123.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 182.0, 186.0, 185.0, 184.0, 182.0, 183.0, 185.0, 183.0], "value": 183.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 74.0, 112.0, 83.0, 60.0, 59.0, 58.0, 83.0, 58.0], "value": 72.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 69.0, 94.0, 70.0, 68.0, 69.0, 88.0, 68.0, 68.0, 91.0], "value": 77.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 87.0, 92.0, 89.0, 86.0, 82.0, 113.0, 129.0, 125.0, 87.0], "value": 98.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 96.0, 96.0, 96.0, 99.0, 97.0, 95.0, 95.0], "value": 96.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 83.0, 79.0, 79.0, 81.0, 80.0, 85.0, 83.0, 84.0, 81.0], "value": 81.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 53.0, 53.0, 54.0, 55.0, 58.0, 57.0, 57.0, 56.0, 57.0], "value": 55.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 73.0, 77.0, 74.0, 73.0, 69.0, 70.0, 69.0, 69.0, 69.0], "value": 71.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 85.0, 83.0, 83.0, 91.0, 86.0, 82.0, 86.0, 86.0, 88.0], "value": 85.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 157.0, 153.0, 147.0, 146.0, 149.0, 159.0, 159.0, 194.0, 158.0], "value": 156.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 217.0, 168.0, 167.0, 166.0, 167.0, 166.0, 166.0, 167.0, 165.0], "value": 171.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 67.0, 67.0, 67.0, 66.0, 68.0, 81.0, 87.0, 85.0], "value": 71.9, "unit": "score"}], "name": "kraken", "value": 1457.5000000000002, "alertThreshold": 2.0}]} 14:39:39 INFO - Return code: 0 14:39:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:39:39 ERROR - # TBPL SUCCESS # 14:39:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:39:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:39:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:39:39 INFO - 2016-11-15 14:39:39.123 screenresolution[1653:9777] starting screenresolution argv=screenresolution get 14:39:39 INFO - 2016-11-15 14:39:39.126 screenresolution[1653:9777] Display 0: 1600x1200x32@60 14:39:39 INFO - 2016-11-15 14:39:39.132 screenresolution[1654:9781] starting screenresolution argv=screenresolution list 14:39:39 INFO - Available Modes on Display 0 14:39:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:39:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:39:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:39:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:39:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:39:39 INFO - Intel Iris: 14:39:39 INFO - Chipset Model: Intel Iris 14:39:39 INFO - Type: GPU 14:39:39 INFO - Bus: Built-In 14:39:39 INFO - VRAM (Dynamic, Max): 1536 MB 14:39:39 INFO - Vendor: Intel (0x8086) 14:39:39 INFO - Device ID: 0x0a2e 14:39:39 INFO - Revision ID: 0x0009 14:39:39 INFO - Displays: 14:39:39 INFO - Display: 14:39:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:39:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:39:39 INFO - Display Serial Number: 1600x1200 60 14:39:39 INFO - Main Display: Yes 14:39:39 INFO - Mirror: Off 14:39:39 INFO - Online: Yes 14:39:39 INFO - Rotation: Supported 14:39:39 INFO - Return code: 0 14:39:39 INFO - Running post-action listener: _resource_record_post_action 14:39:39 INFO - [mozharness: 2016-11-15 22:39:39.370919Z] Finished run-tests step (success) 14:39:39 INFO - Running post-run listener: _resource_record_post_run 14:39:39 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 39068672; Write bytes: 653033984; Read time: 385; Write time: 1936 14:39:39 INFO - TinderboxPrint: CPU usage
53.4% 14:39:39 INFO - TinderboxPrint: I/O read bytes / time
39,068,672 / 385 14:39:39 INFO - TinderboxPrint: I/O write bytes / time
653,033,984 / 1,936 14:39:39 INFO - TinderboxPrint: CPU idle
2,259.3 (46.6%) 14:39:39 INFO - TinderboxPrint: CPU system
974.0 (20.1%) 14:39:39 INFO - TinderboxPrint: CPU user
1,616.4 (33.3%) 14:39:39 INFO - TinderboxPrint: Swap in / out
391,618,560 / 0 14:39:39 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 354155008; Write bytes: 350619136; Read time: 28905; Write time: 676 14:39:39 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 34792448; Write bytes: 296287232; Read time: 350; Write time: 1234 14:39:39 INFO - Running post-run listener: _upload_blobber_files 14:39:39 INFO - Blob upload gear active. 14:39:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:39:39 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 14:39:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:39:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:39:40 INFO - (blobuploader) - INFO - Open directory for files ... 14:39:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:39:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:39:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:39:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:39:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:39:40 INFO - (blobuploader) - INFO - Done attempting. 14:39:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:39:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:39:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:39:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:39:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:39:41 INFO - (blobuploader) - INFO - Done attempting. 14:39:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:39:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:39:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:39:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:39:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:39:44 INFO - (blobuploader) - INFO - Done attempting. 14:39:44 INFO - (blobuploader) - INFO - Iteration through files over. 14:39:44 INFO - Return code: 0 14:39:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:39:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:39:44 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ee1c85f55d9bd0a02005bcfb4e4efa7d3bc8e2a94233868b6a51485fcfcf1c7ac3171602909ed5b878586350ccccd22d0a41d7036e8bee129188c42f6c177720", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1921069df8438d669711e98bda0d35d6d03616675bc24c9d8e920082b433e8142ab337655bee72ef0f9706217cc1125b07fcc4e6d7e85b3ea33bb975e98c65ea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a5a1e2541c26fd6fce82eba92243c83056007dee8d7663f4f35a99a21c932dacccd743e5de4a248a23b38122016797deeec50284cb40619c2e9a58806dd7c138"} 14:39:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:39:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:39:44 INFO - Contents: 14:39:44 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ee1c85f55d9bd0a02005bcfb4e4efa7d3bc8e2a94233868b6a51485fcfcf1c7ac3171602909ed5b878586350ccccd22d0a41d7036e8bee129188c42f6c177720", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1921069df8438d669711e98bda0d35d6d03616675bc24c9d8e920082b433e8142ab337655bee72ef0f9706217cc1125b07fcc4e6d7e85b3ea33bb975e98c65ea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a5a1e2541c26fd6fce82eba92243c83056007dee8d7663f4f35a99a21c932dacccd743e5de4a248a23b38122016797deeec50284cb40619c2e9a58806dd7c138"} 14:39:44 INFO - Running post-run listener: copy_logs_to_upload_dir 14:39:44 INFO - Copying logs to upload dir... 14:39:44 INFO - mkdir: /builds/slave/test/build/upload/logs 14:39:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.673158 ========= master_lag: 0.17 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-11-15 14:39:44.353114) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-15 14:39:44.357046) ========= 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.dKYVcusZKS/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.yUEGvkqWPt/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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/ee1c85f55d9bd0a02005bcfb4e4efa7d3bc8e2a94233868b6a51485fcfcf1c7ac3171602909ed5b878586350ccccd22d0a41d7036e8bee129188c42f6c177720", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1921069df8438d669711e98bda0d35d6d03616675bc24c9d8e920082b433e8142ab337655bee72ef0f9706217cc1125b07fcc4e6d7e85b3ea33bb975e98c65ea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a5a1e2541c26fd6fce82eba92243c83056007dee8d7663f4f35a99a21c932dacccd743e5de4a248a23b38122016797deeec50284cb40619c2e9a58806dd7c138"} build_url:https://queue.taskcluster.net/v1/task/HdnhV3ZmRi64iAGZpVSh4w/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011123 build_url: 'https://queue.taskcluster.net/v1/task/HdnhV3ZmRi64iAGZpVSh4w/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/ee1c85f55d9bd0a02005bcfb4e4efa7d3bc8e2a94233868b6a51485fcfcf1c7ac3171602909ed5b878586350ccccd22d0a41d7036e8bee129188c42f6c177720", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1921069df8438d669711e98bda0d35d6d03616675bc24c9d8e920082b433e8142ab337655bee72ef0f9706217cc1125b07fcc4e6d7e85b3ea33bb975e98c65ea", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a5a1e2541c26fd6fce82eba92243c83056007dee8d7663f4f35a99a21c932dacccd743e5de4a248a23b38122016797deeec50284cb40619c2e9a58806dd7c138"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-15 14:39:44.393668) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-15 14:39:44.394460) ========= 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.dKYVcusZKS/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.yUEGvkqWPt/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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.005055 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-15 14:39:44.443390) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-15 14:39:44.443710) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-15 14:39:44.444007) ========= ========= Total master_lag: 0.31 =========