builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0327 starttime: 1479859767.06 results: success (0) buildid: 20161122145719 builduid: a5135d4e4298484e8ca5c03daec65fef revision: 5468cd9ed8e4a4cc32d37135842c04a7df8a58ee ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-22 16:09:27.063413) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-22 16:09:27.063937) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-22 16:09:27.064223) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-22 16:09:27.090725) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 16:09:27.091081) ========= 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.KxnlVYIBXz/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.gNwKrvOas0/Listeners TMPDIR=/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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-22 16:09:27-- 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% 19.8M=0.001s 2016-11-22 16:09:27 (19.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.079236 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 16:09:27.195022) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 16:09:27.195414) ========= 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.KxnlVYIBXz/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.gNwKrvOas0/Listeners TMPDIR=/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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.024896 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 16:09:27.244923) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-22 16:09:27.245313) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 5468cd9ed8e4a4cc32d37135842c04a7df8a58ee --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 5468cd9ed8e4a4cc32d37135842c04a7df8a58ee --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KxnlVYIBXz/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.gNwKrvOas0/Listeners TMPDIR=/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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-22 16:09:27,338 truncating revision to first 12 chars 2016-11-22 16:09:27,338 Setting DEBUG logging. 2016-11-22 16:09:27,338 attempt 1/10 2016-11-22 16:09:27,339 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/5468cd9ed8e4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-22 16:09:29,055 unpacking tar archive at: mozilla-aurora-5468cd9ed8e4/testing/mozharness/ program finished with exit code 0 elapsedTime=1.994847 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-11-22 16:09:29.264103) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-22 16:09:29.264444) ========= script_repo_revision: 5468cd9ed8e4a4cc32d37135842c04a7df8a58ee ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-22 16:09:29.264851) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-22 16:09:29.265124) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-22 16:09:29.284992) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-11-22 16:09:29.285381) ========= /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.KxnlVYIBXz/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.gNwKrvOas0/Listeners TMPDIR=/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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 16:09:29 INFO - MultiFileLogger online at 20161122 16:09:29 in /builds/slave/test 16:09:29 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 16:09:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:09:29 INFO - {'append_to_log': False, 16:09:29 INFO - 'base_work_dir': '/builds/slave/test', 16:09:29 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 16:09:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:09:29 INFO - 'branch': 'Mozilla-Aurora', 16:09:29 INFO - 'buildbot_json_path': 'buildprops.json', 16:09:29 INFO - 'config_files': ('talos/mac_config.py',), 16:09:29 INFO - 'default_actions': ('clobber', 16:09:29 INFO - 'read-buildbot-config', 16:09:29 INFO - 'download-and-extract', 16:09:29 INFO - 'populate-webroot', 16:09:29 INFO - 'create-virtualenv', 16:09:29 INFO - 'install', 16:09:29 INFO - 'run-tests'), 16:09:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:09:29 INFO - 'download_minidump_stackwalk': True, 16:09:29 INFO - 'download_symbols': 'ondemand', 16:09:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:09:29 INFO - 'tooltool.py': '/tools/tooltool.py', 16:09:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:09:29 INFO - '/tools/misc-python/virtualenv.py')}, 16:09:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:09:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:09:29 INFO - 'installer_path': 'installer.exe', 16:09:29 INFO - 'log_level': 'info', 16:09:29 INFO - 'log_name': 'talos', 16:09:29 INFO - 'log_to_console': True, 16:09:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:09:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:09:29 INFO - 'opt_config_files': (), 16:09:29 INFO - 'pip_index': False, 16:09:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:09:29 INFO - 'cmd': ('bash', 16:09:29 INFO - '-c', 16:09:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:09:29 INFO - 'enabled': True, 16:09:29 INFO - 'halt_on_failure': False, 16:09:29 INFO - 'name': 'check_screen_resolution'},), 16:09:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:09:29 INFO - 'cmd': ('bash', 16:09:29 INFO - '-c', 16:09:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:09:29 INFO - 'enabled': True, 16:09:29 INFO - 'halt_on_failure': False, 16:09:29 INFO - 'name': 'check_screen_resolution'},), 16:09:29 INFO - 'run_cmd_checks_enabled': True, 16:09:29 INFO - 'sps_profile': False, 16:09:29 INFO - 'sps_profile_interval': 0, 16:09:29 INFO - 'suite': 'dromaeojs', 16:09:29 INFO - 'system_bits': '32', 16:09:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:09:29 INFO - 'title': 't-yosemite-r7-0327', 16:09:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:09:29 INFO - 'use_talos_json': True, 16:09:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:09:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:09:29 INFO - 'work_dir': 'build'} 16:09:29 INFO - [mozharness: 2016-11-23 00:09:29.486449Z] Running clobber step. 16:09:29 INFO - Running pre-action listener: _resource_record_pre_action 16:09:29 INFO - Running main action method: clobber 16:09:29 INFO - rmtree: /builds/slave/test/build 16:09:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:09:32 INFO - Running post-action listener: _resource_record_post_action 16:09:32 INFO - [mozharness: 2016-11-23 00:09:32.235136Z] Finished clobber step (success) 16:09:32 INFO - [mozharness: 2016-11-23 00:09:32.235258Z] Running read-buildbot-config step. 16:09:32 INFO - Running pre-action listener: _resource_record_pre_action 16:09:32 INFO - Running main action method: read_buildbot_config 16:09:32 INFO - Using buildbot properties: 16:09:32 INFO - { 16:09:32 INFO - "project": "", 16:09:32 INFO - "product": "firefox", 16:09:32 INFO - "script_repo_revision": "production", 16:09:32 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 16:09:32 INFO - "repository": "", 16:09:32 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 16:09:32 INFO - "buildid": "20161122145719", 16:09:32 INFO - "pgo_build": "False", 16:09:32 INFO - "basedir": "/builds/slave/test", 16:09:32 INFO - "buildnumber": 3, 16:09:32 INFO - "slavename": "t-yosemite-r7-0327", 16:09:32 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 16:09:32 INFO - "platform": "yosemite_r7", 16:09:32 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 16:09:32 INFO - "branch": "mozilla-aurora", 16:09:32 INFO - "repo_path": "releases/mozilla-aurora", 16:09:32 INFO - "revision": "5468cd9ed8e4a4cc32d37135842c04a7df8a58ee", 16:09:32 INFO - "stage_platform": "macosx64", 16:09:32 INFO - "builduid": "a5135d4e4298484e8ca5c03daec65fef", 16:09:32 INFO - "slavebuilddir": "test" 16:09:32 INFO - } 16:09:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/J0hs4HbOTDeILN4mAGEWeA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 16:09:32 INFO - Running post-action listener: _resource_record_post_action 16:09:32 INFO - [mozharness: 2016-11-23 00:09:32.238623Z] Finished read-buildbot-config step (success) 16:09:32 INFO - [mozharness: 2016-11-23 00:09:32.238756Z] Running download-and-extract step. 16:09:32 INFO - Running pre-action listener: _resource_record_pre_action 16:09:32 INFO - Running main action method: download_and_extract 16:09:32 INFO - mkdir: /builds/slave/test/build/tests 16:09:32 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')]} 16:09:32 INFO - trying https://queue.taskcluster.net/v1/task/J0hs4HbOTDeILN4mAGEWeA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 16:09:32 INFO - Downloading https://queue.taskcluster.net/v1/task/J0hs4HbOTDeILN4mAGEWeA/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 16:09:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J0hs4HbOTDeILN4mAGEWeA/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 16:09:33 INFO - Downloaded 1199 bytes. 16:09:33 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 16:09:33 INFO - Using the following test package requirements: 16:09:33 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 16:09:33 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:09:33 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 16:09:33 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:09:33 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 16:09:33 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:09:33 INFO - u'jsshell-mac.zip'], 16:09:33 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:09:33 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 16:09:33 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 16:09:33 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:09:33 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 16:09:33 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:09:33 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 16:09:33 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:09:33 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 16:09:33 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 16:09:33 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 16:09:33 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 16:09:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J0hs4HbOTDeILN4mAGEWeA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 16:09:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J0hs4HbOTDeILN4mAGEWeA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 16:09:33 INFO - Fetch https://queue.taskcluster.net/v1/task/J0hs4HbOTDeILN4mAGEWeA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 16:09:33 INFO - Http code: 200 16:09:33 INFO - via: 1.1 e30ae5b3d9f6779a9b8bc992faad0b09.cloudfront.net (CloudFront) 16:09:33 INFO - x-amz-cf-id: znkWFmJQLmep11dlqiQumCZ1aTGik1H5LT5iElLuDeS5EgANSMcW-Q== 16:09:33 INFO - x-amz-version-id: fwVBu_3UojICNabUEKItiyKM9yT24zjr 16:09:34 INFO - Expected file size: 16277479 16:09:34 INFO - Obtained file size: 16277479 16:09:40 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 16:09:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J0hs4HbOTDeILN4mAGEWeA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 16:09:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J0hs4HbOTDeILN4mAGEWeA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 16:09:40 INFO - Fetch https://queue.taskcluster.net/v1/task/J0hs4HbOTDeILN4mAGEWeA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 16:09:41 INFO - Http code: 200 16:09:41 INFO - via: 1.1 9552af4ceb2e0dd55964a80d1e549fd5.cloudfront.net (CloudFront) 16:09:41 INFO - x-amz-cf-id: _uXqUd0dyKuA-HglUOnP4yHUZ1RGjY03u908PilxpsAl8xxNp2AvDg== 16:09:41 INFO - x-amz-version-id: fwVBu_3UojICNabUEKItiyKM9yT24zjr 16:09:41 INFO - Expected file size: 16277479 16:09:41 INFO - Obtained file size: 16277479 16:09:47 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/J0hs4HbOTDeILN4mAGEWeA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 16:09:47 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/J0hs4HbOTDeILN4mAGEWeA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 16:09:47 INFO - Fetch https://queue.taskcluster.net/v1/task/J0hs4HbOTDeILN4mAGEWeA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 16:09:48 INFO - Http code: 200 16:09:48 INFO - via: 1.1 ba26a6eef2785fa2e20e601890b882b2.cloudfront.net (CloudFront) 16:09:48 INFO - x-amz-cf-id: GK4J2bMxDPWlDJo0uggW-iUPgwLYZkc-N_YAhV1k_WRVLVY5VCZNXw== 16:09:48 INFO - x-amz-version-id: IW5Lw.QPK5oKmSEtXjpOZ9G_o4ymIIyN 16:09:48 INFO - Expected file size: 14409038 16:09:48 INFO - Obtained file size: 14409038 16:09:48 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')]} 16:09:48 INFO - trying https://queue.taskcluster.net/v1/task/J0hs4HbOTDeILN4mAGEWeA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 16:09:48 INFO - Downloading https://queue.taskcluster.net/v1/task/J0hs4HbOTDeILN4mAGEWeA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 16:09:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/J0hs4HbOTDeILN4mAGEWeA/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 16:09:51 INFO - Downloaded 109673273 bytes. 16:09:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/J0hs4HbOTDeILN4mAGEWeA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 16:09:51 INFO - mkdir: /builds/slave/test/properties 16:09:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:09:51 INFO - Writing to file /builds/slave/test/properties/build_url 16:09:51 INFO - Contents: 16:09:51 INFO - build_url:https://queue.taskcluster.net/v1/task/J0hs4HbOTDeILN4mAGEWeA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 16:09:52 INFO - Running post-action listener: _resource_record_post_action 16:09:52 INFO - Running post-action listener: set_extra_try_arguments 16:09:52 INFO - [mozharness: 2016-11-23 00:09:52.338010Z] Finished download-and-extract step (success) 16:09:52 INFO - [mozharness: 2016-11-23 00:09:52.338147Z] Running populate-webroot step. 16:09:52 INFO - Running pre-action listener: _resource_record_pre_action 16:09:52 INFO - Running main action method: populate_webroot 16:09:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:09:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:09:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:09:52 INFO - '--tppagecycles', 16:09:52 INFO - '7'], 16:09:52 INFO - 'tests': ['tsvgm']}}, 16:09:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:09:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:09:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:09:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:09:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:09:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:09:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:52 INFO - 'talos_options': ['--disable-e10s'], 16:09:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:09:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:09:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:52 INFO - 'talos_options': ['--disable-e10s'], 16:09:52 INFO - 'tests': ['damp', 'tps']}, 16:09:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:52 INFO - 'tests': ['damp', 'tps']}, 16:09:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:09:52 INFO - 'tests': ['dromaeo_dom']}, 16:09:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:09:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:09:52 INFO - 'tests': ['basic_compositor_video']}, 16:09:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:09:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:09:52 INFO - 'tests': ['a11yr', 16:09:52 INFO - 'ts_paint', 16:09:52 INFO - 'tpaint', 16:09:52 INFO - 'sessionrestore', 16:09:52 INFO - 'sessionrestore_no_auto_restore', 16:09:52 INFO - 'tabpaint']}, 16:09:52 INFO - 'other-e10s': {'tests': ['a11yr', 16:09:52 INFO - 'ts_paint', 16:09:52 INFO - 'tpaint', 16:09:52 INFO - 'sessionrestore', 16:09:52 INFO - 'sessionrestore_no_auto_restore', 16:09:52 INFO - 'tabpaint']}, 16:09:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:09:52 INFO - 'ts_paint', 16:09:52 INFO - 'tpaint', 16:09:52 INFO - 'sessionrestore', 16:09:52 INFO - 'sessionrestore_no_auto_restore', 16:09:52 INFO - 'tabpaint']}, 16:09:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:09:52 INFO - 'ts_paint', 16:09:52 INFO - 'tpaint', 16:09:52 INFO - 'sessionrestore', 16:09:52 INFO - 'sessionrestore_no_auto_restore', 16:09:52 INFO - 'tabpaint']}, 16:09:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:09:52 INFO - 'tests': ['a11yr', 16:09:52 INFO - 'ts_paint', 16:09:52 INFO - 'tpaint', 16:09:52 INFO - 'sessionrestore', 16:09:52 INFO - 'sessionrestore_no_auto_restore', 16:09:52 INFO - 'tabpaint']}, 16:09:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:09:52 INFO - 'tests': ['a11yr', 16:09:52 INFO - 'ts_paint', 16:09:52 INFO - 'tpaint', 16:09:52 INFO - 'sessionrestore', 16:09:52 INFO - 'sessionrestore_no_auto_restore', 16:09:52 INFO - 'tabpaint']}, 16:09:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:09:52 INFO - 'tests': ['tsvgx', 16:09:52 INFO - 'tsvgr_opacity', 16:09:52 INFO - 'tart', 16:09:52 INFO - 'tscrollx', 16:09:52 INFO - 'cart']}, 16:09:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:09:52 INFO - 'tsvgr_opacity', 16:09:52 INFO - 'tart', 16:09:52 INFO - 'tscrollx', 16:09:52 INFO - 'cart']}, 16:09:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:52 INFO - 'talos_options': ['--disable-e10s'], 16:09:52 INFO - 'tests': ['tp5o']}, 16:09:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:52 INFO - 'tests': ['tp5o']}, 16:09:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:52 INFO - 'talos_options': ['--disable-e10s', 16:09:52 INFO - '--xperf_path', 16:09:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:09:52 INFO - 'tests': ['tp5n']}, 16:09:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:09:52 INFO - 'talos_options': ['--xperf_path', 16:09:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:09:52 INFO - 'tests': ['tp5n']}}, 16:09:52 INFO - 'talos.zip': {'path': '', 16:09:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:09:52 INFO - Running post-action listener: _resource_record_post_action 16:09:52 INFO - [mozharness: 2016-11-23 00:09:52.356671Z] Finished populate-webroot step (success) 16:09:52 INFO - [mozharness: 2016-11-23 00:09:52.356786Z] Running create-virtualenv step. 16:09:52 INFO - Running pre-action listener: _resource_record_pre_action 16:09:52 INFO - Running main action method: create_virtualenv 16:09:52 INFO - Creating virtualenv /builds/slave/test/build/venv 16:09:52 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 16:09:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:09:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:09:52 INFO - Using real prefix '/tools/python27' 16:09:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:09:53 INFO - Installing distribute.............................................................................................................................................................................................done. 16:09:56 INFO - Installing pip.................done. 16:09:56 INFO - Return code: 0 16:09:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:09:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:09:56 INFO - Reading from file tmpfile_stdout 16:09:56 INFO - Output received: 16:09:56 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) 16:09:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:09:56 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')]} 16:09:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:09:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:56 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')]} 16:09:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:09:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:56 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 0x10e410490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e386570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc262571ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e224d30>, '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 0x10e08adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e21cb20>, '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.KxnlVYIBXz/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.gNwKrvOas0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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 16:09:56 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 16:09:56 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 16:09:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KxnlVYIBXz/Render', 16:09:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:09:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:09:56 INFO - 'HOME': '/Users/cltbld', 16:09:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:09:56 INFO - 'LOGNAME': 'cltbld', 16:09:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:56 INFO - 'MOZ_NO_REMOTE': '1', 16:09:56 INFO - 'NO_EM_RESTART': '1', 16:09:56 INFO - 'PAGER': '/bin/cat', 16:09:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:09:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:56 INFO - 'PWD': '/builds/slave/test', 16:09:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:09:56 INFO - 'SHELL': '/bin/bash', 16:09:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gNwKrvOas0/Listeners', 16:09:56 INFO - 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', 16:09:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:09:56 INFO - 'USER': 'cltbld', 16:09:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:09:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:09:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:56 INFO - 'XPC_FLAGS': '0x0', 16:09:56 INFO - 'XPC_SERVICE_NAME': '0', 16:09:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:09:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:09:56 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 16:09:56 INFO - Cleaning up... 16:09:56 INFO - Return code: 0 16:09:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:09:56 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')]} 16:09:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:09:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:56 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')]} 16:09:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:09:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:09:56 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 0x10e410490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e386570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc262571ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e224d30>, '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 0x10e08adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e21cb20>, '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.KxnlVYIBXz/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.gNwKrvOas0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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 16:09:56 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 16:09:56 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 16:09:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KxnlVYIBXz/Render', 16:09:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:09:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:09:56 INFO - 'HOME': '/Users/cltbld', 16:09:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:09:56 INFO - 'LOGNAME': 'cltbld', 16:09:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:09:56 INFO - 'MOZ_NO_REMOTE': '1', 16:09:56 INFO - 'NO_EM_RESTART': '1', 16:09:56 INFO - 'PAGER': '/bin/cat', 16:09:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:09:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:09:56 INFO - 'PWD': '/builds/slave/test', 16:09:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:09:56 INFO - 'SHELL': '/bin/bash', 16:09:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gNwKrvOas0/Listeners', 16:09:56 INFO - 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', 16:09:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:09:56 INFO - 'USER': 'cltbld', 16:09:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:09:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:09:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:09:56 INFO - 'XPC_FLAGS': '0x0', 16:09:56 INFO - 'XPC_SERVICE_NAME': '0', 16:09:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:09:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:09:56 INFO - Downloading/unpacking psutil>=3.1.1 16:09:56 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 16:09:56 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 16:09:56 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 16:09:56 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 16:09:56 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 16:09:56 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 16:10:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:10:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:10:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:10:00 INFO - Installing collected packages: psutil 16:10:00 INFO - Running setup.py install for psutil 16:10:00 INFO - building 'psutil._psutil_osx' extension 16:10:00 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 16:10:00 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 16:10:00 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 16:10:01 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 16:10:01 INFO - building 'psutil._psutil_posix' extension 16:10:01 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 16:10:01 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:10:01 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:10:01 INFO - ^ 16:10:01 INFO - 1 warning generated. 16:10:01 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 16:10:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:10:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:10:01 INFO - Successfully installed psutil 16:10:01 INFO - Cleaning up... 16:10:01 INFO - Return code: 0 16:10:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:10:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:10:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:10:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e410490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e386570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc262571ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e224d30>, '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 0x10e08adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e21cb20>, '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.KxnlVYIBXz/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.gNwKrvOas0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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 16:10:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:10:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:10:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KxnlVYIBXz/Render', 16:10:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:01 INFO - 'HOME': '/Users/cltbld', 16:10:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:01 INFO - 'LOGNAME': 'cltbld', 16:10:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:01 INFO - 'MOZ_NO_REMOTE': '1', 16:10:01 INFO - 'NO_EM_RESTART': '1', 16:10:01 INFO - 'PAGER': '/bin/cat', 16:10:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:01 INFO - 'PWD': '/builds/slave/test', 16:10:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:01 INFO - 'SHELL': '/bin/bash', 16:10:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gNwKrvOas0/Listeners', 16:10:01 INFO - 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', 16:10:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:01 INFO - 'USER': 'cltbld', 16:10:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:01 INFO - 'XPC_FLAGS': '0x0', 16:10:01 INFO - 'XPC_SERVICE_NAME': '0', 16:10:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:10:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:10:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:10:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:10:04 INFO - Installing collected packages: mozsystemmonitor 16:10:04 INFO - Running setup.py install for mozsystemmonitor 16:10:04 INFO - Successfully installed mozsystemmonitor 16:10:04 INFO - Cleaning up... 16:10:04 INFO - Return code: 0 16:10:04 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:10:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:10:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e410490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e386570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc262571ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e224d30>, '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 0x10e08adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e21cb20>, '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.KxnlVYIBXz/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.gNwKrvOas0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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 16:10:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:10:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:10:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KxnlVYIBXz/Render', 16:10:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:04 INFO - 'HOME': '/Users/cltbld', 16:10:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:04 INFO - 'LOGNAME': 'cltbld', 16:10:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:04 INFO - 'MOZ_NO_REMOTE': '1', 16:10:04 INFO - 'NO_EM_RESTART': '1', 16:10:04 INFO - 'PAGER': '/bin/cat', 16:10:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:04 INFO - 'PWD': '/builds/slave/test', 16:10:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:04 INFO - 'SHELL': '/bin/bash', 16:10:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gNwKrvOas0/Listeners', 16:10:04 INFO - 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', 16:10:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:04 INFO - 'USER': 'cltbld', 16:10:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:04 INFO - 'XPC_FLAGS': '0x0', 16:10:04 INFO - 'XPC_SERVICE_NAME': '0', 16:10:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:04 INFO - Downloading/unpacking jsonschema==2.5.1 16:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:10:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:10:07 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 16:10:07 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 16:10:07 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 16:10:07 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 16:10:07 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 16:10:07 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 16:10:07 INFO - Downloading functools32-3.2.3-2.tar.gz 16:10:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:10:07 INFO - warning: no files found matching '*.txt' 16:10:07 INFO - no previously-included directories found matching 'build' 16:10:07 INFO - no previously-included directories found matching 'dist' 16:10:07 INFO - no previously-included directories found matching '.git*' 16:10:07 INFO - Installing collected packages: jsonschema, functools32 16:10:07 INFO - Running setup.py install for functools32 16:10:08 INFO - warning: no files found matching '*.txt' 16:10:08 INFO - no previously-included directories found matching 'build' 16:10:08 INFO - no previously-included directories found matching 'dist' 16:10:08 INFO - no previously-included directories found matching '.git*' 16:10:08 INFO - Successfully installed jsonschema functools32 16:10:08 INFO - Cleaning up... 16:10:08 INFO - Return code: 0 16:10:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:10:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:10:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:10:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e410490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e386570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc262571ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e224d30>, '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 0x10e08adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e21cb20>, '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.KxnlVYIBXz/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.gNwKrvOas0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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 16:10:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 16:10:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 16:10:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KxnlVYIBXz/Render', 16:10:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:08 INFO - 'HOME': '/Users/cltbld', 16:10:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:08 INFO - 'LOGNAME': 'cltbld', 16:10:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:08 INFO - 'MOZ_NO_REMOTE': '1', 16:10:08 INFO - 'NO_EM_RESTART': '1', 16:10:08 INFO - 'PAGER': '/bin/cat', 16:10:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:08 INFO - 'PWD': '/builds/slave/test', 16:10:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:08 INFO - 'SHELL': '/bin/bash', 16:10:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gNwKrvOas0/Listeners', 16:10:08 INFO - 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', 16:10:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:08 INFO - 'USER': 'cltbld', 16:10:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:08 INFO - 'XPC_FLAGS': '0x0', 16:10:08 INFO - 'XPC_SERVICE_NAME': '0', 16:10:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:10:08 INFO - Cleaning up... 16:10:08 INFO - Return code: 0 16:10:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:10:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:10:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:10:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10e410490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e386570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc262571ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e224d30>, '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 0x10e08adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e21cb20>, '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.KxnlVYIBXz/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.gNwKrvOas0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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 16:10:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 16:10:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 16:10:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KxnlVYIBXz/Render', 16:10:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:08 INFO - 'HOME': '/Users/cltbld', 16:10:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:08 INFO - 'LOGNAME': 'cltbld', 16:10:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:08 INFO - 'MOZ_NO_REMOTE': '1', 16:10:08 INFO - 'NO_EM_RESTART': '1', 16:10:08 INFO - 'PAGER': '/bin/cat', 16:10:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:08 INFO - 'PWD': '/builds/slave/test', 16:10:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:08 INFO - 'SHELL': '/bin/bash', 16:10:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gNwKrvOas0/Listeners', 16:10:08 INFO - 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', 16:10:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:08 INFO - 'USER': 'cltbld', 16:10:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:08 INFO - 'XPC_FLAGS': '0x0', 16:10:08 INFO - 'XPC_SERVICE_NAME': '0', 16:10:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:08 INFO - Downloading/unpacking blobuploader==1.2.4 16:10:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:11 INFO - Downloading blobuploader-1.2.4.tar.gz 16:10:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:10:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:10:11 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 16:10:11 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 16:10:11 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 16:10:11 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 16:10:11 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 16:10:11 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 16:10:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:10:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:10:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:12 INFO - Downloading docopt-0.6.1.tar.gz 16:10:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:10:12 INFO - Installing collected packages: blobuploader, requests, docopt 16:10:12 INFO - Running setup.py install for blobuploader 16:10:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:10:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:10:12 INFO - Running setup.py install for requests 16:10:13 INFO - Running setup.py install for docopt 16:10:13 INFO - Successfully installed blobuploader requests docopt 16:10:13 INFO - Cleaning up... 16:10:13 INFO - Return code: 0 16:10:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:10:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:10:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:10:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:13 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 0x10e410490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e386570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc262571ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e224d30>, '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 0x10e08adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e21cb20>, '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.KxnlVYIBXz/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.gNwKrvOas0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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 16:10:13 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 16:10:13 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 16:10:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KxnlVYIBXz/Render', 16:10:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:13 INFO - 'HOME': '/Users/cltbld', 16:10:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:13 INFO - 'LOGNAME': 'cltbld', 16:10:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:13 INFO - 'MOZ_NO_REMOTE': '1', 16:10:13 INFO - 'NO_EM_RESTART': '1', 16:10:13 INFO - 'PAGER': '/bin/cat', 16:10:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:13 INFO - 'PWD': '/builds/slave/test', 16:10:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:13 INFO - 'SHELL': '/bin/bash', 16:10:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gNwKrvOas0/Listeners', 16:10:13 INFO - 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', 16:10:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:13 INFO - 'USER': 'cltbld', 16:10:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:13 INFO - 'XPC_FLAGS': '0x0', 16:10:13 INFO - 'XPC_SERVICE_NAME': '0', 16:10:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:10:13 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-jDxfFT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:10:14 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-k2Azi4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:10:14 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-17lLPl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:10:14 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-VqBYJc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:10:14 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-hGMil5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:10:14 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-br2eM6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:10:14 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-0b10DF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:10:14 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-dgWB6q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:10:14 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-wGlJK9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:10:14 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-OgoAsM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:10:14 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-5ngdyu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:10:15 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-W1SX7q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:10:15 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-_vN3or-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:10:15 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-gCgWiJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:10:15 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-UHN6yV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:10:15 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-LNZxtg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:10:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:10:15 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-8s_9nL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:10:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:10:15 INFO - Running setup.py install for manifestparser 16:10:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:10:15 INFO - Running setup.py install for mozcrash 16:10:15 INFO - Running setup.py install for mozdebug 16:10:16 INFO - Running setup.py install for mozdevice 16:10:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:10:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:10:16 INFO - Running setup.py install for mozfile 16:10:16 INFO - Running setup.py install for mozhttpd 16:10:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:10:16 INFO - Running setup.py install for mozinfo 16:10:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:10:16 INFO - Running setup.py install for mozInstall 16:10:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:10:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:10:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:10:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:10:16 INFO - Running setup.py install for mozleak 16:10:16 INFO - Running setup.py install for mozlog 16:10:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:10:17 INFO - Running setup.py install for moznetwork 16:10:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:10:17 INFO - Running setup.py install for mozprocess 16:10:17 INFO - Running setup.py install for mozprofile 16:10:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:10:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:10:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:10:17 INFO - Running setup.py install for mozrunner 16:10:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:10:17 INFO - Running setup.py install for mozscreenshot 16:10:17 INFO - Running setup.py install for moztest 16:10:18 INFO - Running setup.py install for mozversion 16:10:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:10:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:10:18 INFO - Cleaning up... 16:10:18 INFO - Return code: 0 16:10:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:10:18 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')]} 16:10:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:18 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')]} 16:10:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:18 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 0x10e410490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e386570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc262571ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e224d30>, '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 0x10e08adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e21cb20>, '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.KxnlVYIBXz/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.gNwKrvOas0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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 16:10:18 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 16:10:18 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 16:10:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KxnlVYIBXz/Render', 16:10:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:18 INFO - 'HOME': '/Users/cltbld', 16:10:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:18 INFO - 'LOGNAME': 'cltbld', 16:10:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:18 INFO - 'MOZ_NO_REMOTE': '1', 16:10:18 INFO - 'NO_EM_RESTART': '1', 16:10:18 INFO - 'PAGER': '/bin/cat', 16:10:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:18 INFO - 'PWD': '/builds/slave/test', 16:10:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:18 INFO - 'SHELL': '/bin/bash', 16:10:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gNwKrvOas0/Listeners', 16:10:18 INFO - 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', 16:10:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:18 INFO - 'USER': 'cltbld', 16:10:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:18 INFO - 'XPC_FLAGS': '0x0', 16:10:18 INFO - 'XPC_SERVICE_NAME': '0', 16:10:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:10:18 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-5A7L27-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:10:18 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)) 16:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:10:18 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-HRms32-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:10:18 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)) 16:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:10:18 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-Ez4yM2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:10:18 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)) 16:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:10:18 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-2keekL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:10:18 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)) 16:10:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:10:18 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-e6shzw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:10:19 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)) 16:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:10:19 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-8BjR6S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:10:19 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)) 16:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:10:19 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-EVSyxc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:10:19 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)) 16:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:10:19 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-kfV6GO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:10:19 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)) 16:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:10:19 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-ygFutN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:10:19 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)) 16:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:10:19 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-m7bcmD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:10:19 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)) 16:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:10:19 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-UEVxAQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:10:19 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)) 16:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:10:19 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-6QVkif-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:10:19 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)) 16:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:10:19 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-H7JgSJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:10:19 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)) 16:10:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:10:19 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-dGItHX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:10:20 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)) 16:10:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:10:20 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-4mv0Ds-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:10:20 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)) 16:10:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:10:20 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-pHimwp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:10:20 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)) 16:10:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:10:20 INFO - Running setup.py (path:/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/pip-GpzBmy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:10:20 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)) 16:10:20 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)) 16:10:20 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)) 16:10:20 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)) 16:10:20 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)) 16:10:20 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)) 16:10:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:10:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:10:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:10:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:10:22 INFO - Downloading blessings-1.6.tar.gz 16:10:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:10:22 INFO - Installing collected packages: blessings 16:10:22 INFO - Running setup.py install for blessings 16:10:23 INFO - Successfully installed blessings 16:10:23 INFO - Cleaning up... 16:10:23 INFO - Return code: 0 16:10:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:10:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:10:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:10:23 INFO - Reading from file tmpfile_stdout 16:10:23 INFO - Current package versions: 16:10:23 INFO - blessings == 1.6 16:10:23 INFO - blobuploader == 1.2.4 16:10:23 INFO - docopt == 0.6.1 16:10:23 INFO - functools32 == 3.2.3-2 16:10:23 INFO - jsonschema == 2.5.1 16:10:23 INFO - manifestparser == 1.1 16:10:23 INFO - mozInstall == 1.12 16:10:23 INFO - mozcrash == 1.0 16:10:23 INFO - mozdebug == 0.1 16:10:23 INFO - mozdevice == 0.48 16:10:23 INFO - mozfile == 1.2 16:10:23 INFO - mozhttpd == 0.7 16:10:23 INFO - mozinfo == 0.9 16:10:23 INFO - mozleak == 0.1 16:10:23 INFO - mozlog == 3.4 16:10:23 INFO - moznetwork == 0.27 16:10:23 INFO - mozprocess == 0.23 16:10:23 INFO - mozprofile == 0.28 16:10:23 INFO - mozrunner == 6.13 16:10:23 INFO - mozscreenshot == 0.1 16:10:23 INFO - mozsystemmonitor == 0.3 16:10:23 INFO - moztest == 0.7 16:10:23 INFO - mozversion == 1.4 16:10:23 INFO - psutil == 3.1.1 16:10:23 INFO - requests == 1.2.3 16:10:23 INFO - wsgiref == 0.1.2 16:10:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:10:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:10:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:10:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:24 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 0x10e410490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e386570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc262571ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e224d30>, '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 0x10e08adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e21cb20>, '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.KxnlVYIBXz/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.gNwKrvOas0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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 16:10:24 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 16:10:24 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 16:10:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KxnlVYIBXz/Render', 16:10:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:24 INFO - 'HOME': '/Users/cltbld', 16:10:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:24 INFO - 'LOGNAME': 'cltbld', 16:10:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:24 INFO - 'MOZ_NO_REMOTE': '1', 16:10:24 INFO - 'NO_EM_RESTART': '1', 16:10:24 INFO - 'PAGER': '/bin/cat', 16:10:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:24 INFO - 'PWD': '/builds/slave/test', 16:10:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:24 INFO - 'SHELL': '/bin/bash', 16:10:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gNwKrvOas0/Listeners', 16:10:24 INFO - 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', 16:10:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:24 INFO - 'USER': 'cltbld', 16:10:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:24 INFO - 'XPC_FLAGS': '0x0', 16:10:24 INFO - 'XPC_SERVICE_NAME': '0', 16:10:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:24 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)) 16:10:24 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)) 16:10:24 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)) 16:10:24 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)) 16:10:24 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)) 16:10:24 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)) 16:10:24 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)) 16:10:24 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)) 16:10:24 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)) 16:10:24 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)) 16:10:24 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)) 16:10:24 INFO - Cleaning up... 16:10:24 INFO - Return code: 0 16:10:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:10:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:10:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:10:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:10:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:10:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:10:24 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 0x10e410490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e386570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc262571ff0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e224d30>, '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 0x10e08adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e21cb20>, '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.KxnlVYIBXz/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.gNwKrvOas0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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 16:10:24 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 16:10:24 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 16:10:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KxnlVYIBXz/Render', 16:10:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:24 INFO - 'HOME': '/Users/cltbld', 16:10:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:24 INFO - 'LOGNAME': 'cltbld', 16:10:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:24 INFO - 'MOZ_NO_REMOTE': '1', 16:10:24 INFO - 'NO_EM_RESTART': '1', 16:10:24 INFO - 'PAGER': '/bin/cat', 16:10:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:24 INFO - 'PWD': '/builds/slave/test', 16:10:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:24 INFO - 'SHELL': '/bin/bash', 16:10:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gNwKrvOas0/Listeners', 16:10:24 INFO - 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', 16:10:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:24 INFO - 'USER': 'cltbld', 16:10:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:24 INFO - 'XPC_FLAGS': '0x0', 16:10:24 INFO - 'XPC_SERVICE_NAME': '0', 16:10:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:10:25 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:10:25 INFO - Cleaning up... 16:10:25 INFO - Return code: 0 16:10:25 INFO - Running post-action listener: _resource_record_post_action 16:10:25 INFO - Running post-action listener: _start_resource_monitoring 16:10:25 INFO - Starting resource monitoring. 16:10:25 INFO - [mozharness: 2016-11-23 00:10:25.190589Z] Finished create-virtualenv step (success) 16:10:25 INFO - [mozharness: 2016-11-23 00:10:25.191574Z] Running install step. 16:10:25 INFO - Running pre-action listener: _resource_record_pre_action 16:10:25 INFO - Running main action method: install 16:10:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:10:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:10:25 INFO - Reading from file tmpfile_stdout 16:10:25 INFO - Detecting whether we're running mozinstall >=1.0... 16:10:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:10:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:10:25 INFO - Reading from file tmpfile_stdout 16:10:25 INFO - Output received: 16:10:25 INFO - Usage: mozinstall [options] installer 16:10:25 INFO - Options: 16:10:25 INFO - -h, --help show this help message and exit 16:10:25 INFO - -d DEST, --destination=DEST 16:10:25 INFO - Directory to install application into. [default: 16:10:25 INFO - "/builds/slave/test"] 16:10:25 INFO - --app=APP Application being installed. [default: firefox] 16:10:25 INFO - mkdir: /builds/slave/test/build/application 16:10:25 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'] 16:10:25 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 16:10:56 INFO - Reading from file tmpfile_stdout 16:10:56 INFO - Output received: 16:10:56 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 16:10:56 INFO - Running post-action listener: _resource_record_post_action 16:10:56 INFO - [mozharness: 2016-11-23 00:10:56.800189Z] Finished install step (success) 16:10:56 INFO - [mozharness: 2016-11-23 00:10:56.800357Z] Running run-tests step. 16:10:56 INFO - Running pre-action listener: _resource_record_pre_action 16:10:56 INFO - Running main action method: run_tests 16:10:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:10:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:10:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:10:56 INFO - 2016-11-22 16:10:56.814 screenresolution[1622:7402] starting screenresolution argv=screenresolution get 16:10:56 INFO - 2016-11-22 16:10:56.817 screenresolution[1622:7402] Display 0: 1600x1200x32@60 16:10:56 INFO - 2016-11-22 16:10:56.823 screenresolution[1623:7405] starting screenresolution argv=screenresolution list 16:10:56 INFO - Available Modes on Display 0 16:10:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:10:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:10:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:10:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:10:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:10:57 INFO - Intel Iris: 16:10:57 INFO - Chipset Model: Intel Iris 16:10:57 INFO - Type: GPU 16:10:57 INFO - Bus: Built-In 16:10:57 INFO - VRAM (Dynamic, Max): 1536 MB 16:10:57 INFO - Vendor: Intel (0x8086) 16:10:57 INFO - Device ID: 0x0a2e 16:10:57 INFO - Revision ID: 0x0009 16:10:57 INFO - Displays: 16:10:57 INFO - Display: 16:10:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:10:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:10:57 INFO - Display Serial Number: 1600x1200 60 16:10:57 INFO - Main Display: Yes 16:10:57 INFO - Mirror: Off 16:10:57 INFO - Online: Yes 16:10:57 INFO - Rotation: Supported 16:10:57 INFO - Return code: 0 16:10:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:10:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:10:57 INFO - Python 2.7.3 16:10:57 INFO - Return code: 0 16:10:57 INFO - grabbing minidump binary from tooltool 16:10:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:10:57 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 0x10e224d30>, '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 0x10e08adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10e21cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:10:57 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 16:10:57 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 16:10:57 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 16:10:57 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:10:57 INFO - Return code: 0 16:10:57 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:10:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:10:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:10:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:10:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:10:57 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/J0hs4HbOTDeILN4mAGEWeA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0327', '--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 16:10:57 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/J0hs4HbOTDeILN4mAGEWeA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0327 --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 16:10:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KxnlVYIBXz/Render', 16:10:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:10:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:10:57 INFO - 'HOME': '/Users/cltbld', 16:10:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:10:57 INFO - 'LOGNAME': 'cltbld', 16:10:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:10:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:10:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:10:57 INFO - 'MOZ_NO_REMOTE': '1', 16:10:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:10:57 INFO - 'NO_EM_RESTART': '1', 16:10:57 INFO - 'PAGER': '/bin/cat', 16:10:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:10:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:10:57 INFO - 'PWD': '/builds/slave/test', 16:10:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:10:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:10:57 INFO - 'SHELL': '/bin/bash', 16:10:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gNwKrvOas0/Listeners', 16:10:57 INFO - 'TMPDIR': '/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/', 16:10:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:10:57 INFO - 'USER': 'cltbld', 16:10:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:10:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:10:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:10:57 INFO - 'XPC_FLAGS': '0x0', 16:10:57 INFO - 'XPC_SERVICE_NAME': '0', 16:10:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:10:57 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/J0hs4HbOTDeILN4mAGEWeA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0327', '--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 16:10:57 INFO - mozversion application_buildid: 20161122145719 16:10:57 INFO - mozversion application_changeset: 5468cd9ed8e4a4cc32d37135842c04a7df8a58ee 16:10:57 INFO - mozversion application_display_name: FirefoxDeveloperEdition 16:10:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:10:57 INFO - mozversion application_name: Firefox 16:10:57 INFO - mozversion application_remotingname: firefox-dev 16:10:57 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 16:10:57 INFO - mozversion application_vendor: Mozilla 16:10:57 INFO - mozversion application_version: 52.0a2 16:10:57 INFO - mozversion platform_buildid: 20161122145719 16:10:57 INFO - mozversion platform_changeset: 5468cd9ed8e4a4cc32d37135842c04a7df8a58ee 16:10:57 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 16:10:57 INFO - mozversion platform_version: 52.0a2 16:10:57 INFO - using testdate: 1479859857 16:10:57 INFO - actual date: 1479859857 16:10:57 INFO - starting webserver on 'localhost:49235' 16:10:57 INFO - SUITE-START | Running 2 tests 16:10:57 INFO - TEST-START | dromaeo_css 16:10:57 INFO - operating with platform_type : mac_ 16:10:57 INFO - Initialising browser for dromaeo_css test... 16:10:57 INFO - TEST-INFO | started process 1633 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/tmpGCP6Ni/profile http://localhost:49235/getInfo.html) 16:10:59 ERROR - PROCESS | 1633 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5373: TypeError: parent.firstChild is null 16:11:00 INFO - PROCESS | 1633 | __metrics Screen width/height:1600/1200 16:11:00 INFO - PROCESS | 1633 | colorDepth:24 16:11:00 INFO - PROCESS | 1633 | Browser inner width/height: 1024/689 16:11:00 INFO - PROCESS | 1633 | __metrics 16:11:00 INFO - PROCESS | 1633 | 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" 16:11:00 INFO - PROCESS | 1633 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:11:01 INFO - TEST-INFO | 1633: exit 0 16:11:01 INFO - Browser initialized. 16:11:01 INFO - Running cycle 1/1 for dromaeo_css test... 16:11:01 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/tmpGCP6Ni/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:11:13 INFO - PROCESS | 1635 | RSS: Main: 222834688 16:11:13 INFO - PROCESS | 1635 | 16:12:04 INFO - PROCESS | 1635 | 1479859924299 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] 16:14:19 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49235/tests/dromaeo/cssquery-ext.html) 16:14:21 INFO - PROCESS | 1635 | RSS: Main: 335024128 16:14:21 INFO - PROCESS | 1635 | 16:17:23 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-ext.html (next: http://localhost:49235/tests/dromaeo/cssquery-jquery.html) 16:17:24 INFO - PROCESS | 1635 | RSS: Main: 348303360 16:17:24 INFO - PROCESS | 1635 | 16:20:26 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49235/tests/dromaeo/cssquery-mootools.html) 16:20:27 INFO - PROCESS | 1635 | RSS: Main: 337215488 16:20:27 INFO - PROCESS | 1635 | 16:23:29 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49235/tests/dromaeo/cssquery-prototype.html) 16:23:30 INFO - PROCESS | 1635 | RSS: Main: 315453440 16:23:30 INFO - PROCESS | 1635 | 16:26:33 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49235/tests/dromaeo/cssquery-yui.html) 16:26:34 INFO - PROCESS | 1635 | RSS: Main: 308080640 16:26:34 INFO - PROCESS | 1635 | 16:29:37 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-yui.html (next: http://localhost:49235/tests/dromaeo/cssquery-dojo.html) 16:29:37 INFO - PROCESS | 1635 | RSS: Main: 400130048 16:29:37 INFO - PROCESS | 1635 | 16:29:37 INFO - PROCESS | 1635 | __start_tp_report 16:29:37 INFO - PROCESS | 1635 | _x_x_mozilla_page_load 16:29:37 INFO - PROCESS | 1635 | _x_x_mozilla_page_load_details 16:29:37 INFO - PROCESS | 1635 | |i|pagename|runs| 16:29:37 INFO - PROCESS | 1635 | |0;dojo.html;2846.5018621741197;3362.764606477546;3378.5607870976783;3402.1494626343415;3423.4693622488508;4729.456112547058;4954.7274899880495;5195.896082078356;5669.9433005669935;5765.423457654232;5798.9130163047585;5808.186853840455;5814.970925145379;5825.271841019872;5836.270466191726;5212.087884620195;5233.633645644806;5257.001169777749;5273.868153296179;5309.628326017161;5202.505761952609;5203.063448579254;5224.869378265555;5338.919916201241;5359.16932875403;5266.920996185041;5288.233206185101;5291.2327712481665;5292.311999440087;5294.52349288564;5286.97356513215;5287.814926477574;5302.310699609065;5309.734513274359;5313.867153321159;4297.269464191095;4380.912381752379;5138.254953031789;5259.579233661298;5260.473952604709;5788.88422231553;5908.379620139866;5909.995300798874;5916.4675179233645;5917.14201440793;6118.963808992752;6132.080177995925;6134.785582005955;6140.078988151813;6154.26148862135;6106.339326077157;6121.94077276304;6141.407276743066;6170;6247.312795592481;5906.940930590682;5945.375735547748;5963.940360596382;5965.940340596582;5978.432048955342;6135.600157732601;6325.462335701425;6351.682415879234;6368.586041907308;6373.362663733589;5725.141228815628;5747.224124743107;6116.847078823065;6126.559057237747;6127.896978543816;6146.446819786241;6202.1937148171355;6232.781852635136;6236.75052997875;6236.875262494725;6119.663418511936;6122.969385153135;6130.214697154755;6131.693415329305;6142.477889379364;6093.482054025458;6105.800759126419;6118.632882027093;6128.483736330546;6142.662153581507;4935.531124543163;4939.580135688507;4943.925841112315;4947.925781113287;4968.428630707443;4342.913141737147;4369.060651959844;4459.866204013885;4460.464744230713;4479.70881892676;5704.543636509069;5752.884942301131;5762.423757624204;5785.103308987113;5803.593748437654;6109.2528441424865;6121.87979361315;6168.352323006109;6185.783497577563;6189.04069869171;5858.619189752652;5865.178874957509;5875;5888.587798854039;5894.764209431661;5381;5423.159410291411;5427.267318911896;5434.103372943499;5438.9937861494955;6287.591306565058;6287.811365659038;6289.213848268966;6296.150019747275;6309.305976342599;6112.807455516588;6147.354527774519;6207.96896015517;6211.875762484816;6243.219597550306;6454.741810327534;6454.774082907264;6461.903071454026;6462.547621666438;6466.482681385572;6144.324124346408;6156.353582873734;6167.629942203482;6169.413905678954;6176.938230617816;4929.9014019720125;5901.1443340714495;6111.04482360895;6138.3883983858805;6158.199434073542;5077.543021128116;6132.141360525334;6151.538634602333;6167.105769663463;6172.135900973777;6136.110264011604;6141.662208578571;6143.569950103629;6144.047672610753;6157.815265542041;5816.2231611198595;6001.879962400641;6072.605280656743;6079.891469697885;6132.601380910227;5801.100829371454;5820.097884827858;5835.387284335254;5896.734646940812;6142.293636231891;4765.928511072268;5703.942960570341;5713.257276554022;5737.139429085671;5747.597668163188 16:29:37 INFO - PROCESS | 1635 | |1;ext.html;485.04688287515046;506.4808571214505;529.0477141145936;537.9838604841855;555.3169601390292;38986;39967.40048899269;40005.79997100014;40020.39969400461;41818.790906045426;41324.79337603308;41698.7915060425;41772;41805.58194418055;41880.581194188046;9270.860937085967;9449.14957653811;9455.527223638825;9457.148856602904;9457.19613832825;9271.536423178813;9366;9366.672166474174;9381.15569598736;9391.624335026625;9398.765030874249;9422.3875448096;9440;9457.871978804747;9478.431294122374;9353.15821576058;9406.341556091076;9411.20004799586;9413.011633778424;9416.246700263997;9294.953525232399;9386.718398448058;9387.577559009793;9388.577514011817;9407.43555386679;38995.805020975;39309.21381572381;39347.40978885319;39354.40968385476;39439;3104.7671424643017;3240.287136829894;3380.6112297085906;3624;3735.514383130203;29820.10539683813;29946;30052.398952021056;30222.395552088834;30532.931347402733;3106.176863131272;3170.8414579271175;3173.730232930204;3245.740340772756;3253.8535765890592;490918;494074;496454;496823.9999999964;507116;55786.44213557812;56001.15998260029;56087;56126.15810762842;56226;33717;34140.65859341375;34373.79691710828;35581;35608.64391356053;15892.443764468188;16022.19889005545;16033.839661603468;16047.839521604868;16061.839381606034;12286.569970051005;12375.381230938598;12393.256404615751;12406.193597416139;12406.193597416139;18586.44240672782;18602.44192674222;18608.81391186098;18612.44162675122;18682.87902725841;6570.178727659043;7288.271172882775;7381.889271660822;7501.699932002659;7595.6961721531625;801.4750338528273;892.2282225874571;918.3755046568398;954.9283803714749;974.2741657465234;23771.88873496294;29643.40713185767;29689.703102969124;29863.701362986525;30090.186411649847;1008.4000019988242;1021.7752094539189;1031.1905154453868;1051.405955635079;1074.9355038697547;15233.390664373523;15353.385864565293;15444.45944391941;15777.526674199793;15804.209789510709;524974;580094;666200;668667;669981;56454;56861.715691422105;56882.7155864218;56925.14612280819;57024;1178.699431644925;1227.9079069069855;1235.1909499277988;1322.3850909327016;1331.4075236519657;29056.564151538165;30480;30486.84756576203;30730.231744206572;32354.514682279314;54402;55148.448515514334;55291.44708552863;55305;55413.722931385084;583810;587610;588557;589670;590274;40200.994975125854;41401.585984139776;41566.16867662569;41590;41617.58382416258;44863.10273794571;45282.094358111994;45711.08577828358;45713.542864570925;45769.313460298785;37889.93988817066;37904.62095379011;38334.6166538331;39152.608473916036;39598.24821021496;37714.622853771114;37894.7263164211;38917.6108238914;39385.606143938196;39387.01532461711;36481.81759091187;36646.816765916;36754.897353078384;36959.260814784095;37022.20713344212;32195;32446.513302300013;33983.49024764581;34543.30913381668;34702.652973470955;21998.89000554987;24854.751452485245;25308.49383012366;25467.23598292054;25805.3548661285 16:29:37 INFO - PROCESS | 1635 | |2;jquery.html;346.27282706316754;362.61562743491885;367.7591177778555;377.6751993285774;396.9702272329576;14598.854011459882;15175.620609484768;15465.76801347978;19504.82971021736;20165.899170504126;19566.804331956675;19614.60770784587;20148.39554813358;20252;20430.89784551079;4481.7983190756495;5910.822675319747;5911.586188966774;5917.674527900964;5919.970400148015;4873.951260487385;5348;5772.971135144318;5822.214001109838;5844.970775146139;5631.267935168443;5780.104083866986;5780.4797568218855;5782.595218334718;5793.304803423595;5493.945060549383;5720;5735.0250457422335;5736.684482353469;5742.770289188448;4852.1508735971065;4950.2079667253065;5016.44819069902;5019.774110165052;5030.723310217936;23888.880555597283;24680.629790553157;24968.5006299873;25142.748572514225;25201.24396268115;2441.6825812644242;2814.8459131756085;2954.8374839383823;3010.8494575271366;3036.5748795168474;14983.775243371358;15001.924990374979;15028.023178493362;15452.995555288982;15740.370385184578;1765.5497848048665;1800.97298540522;1824.5803465203078;1875.5873707784265;1890.1116475256608;121828.78171218262;122081;125655;126993;128972.35513822465;9367.344285900057;9949.602015919427;10591.134236003803;10607.94696026515;10781;11813.173077884465;12991.805122923164;13005.544805931748;13011.609651710463;13016.479340826261;6957.65211739411;7600.50596711212;8285.668573257002;8372.958135209285;8491.405601607952;8446.662133514714;8465.32277417805;8467.745967620982;8563.100874408223;8606.547134066761;6193.566450348432;6382.170317858649;6388.488920886318;6391.201099862517;6392.328805475358;1594.0595048921193;1722.3541172060477;1826.2512369928265;1830.1855674224842;1837.2375464182483;681.2914568848441;874.4709450782312;955.2978560757838;1009.1170226052204;1022.2333250062453;20066.297679581145;21246.681299780517;21264.36206913795;21279.574408511744;21327.36017919465;941.7268991992382;968.5205823117461;983.9901600984042;998.6953979036318;1022.0443884967431;10588.381887507134;11187.440627968568;11336.376499292619;11347.432628368548;11373.886261137448;605712;611044;617289;617333;620300;117938;118355.40822296006;125212;125504.37247813703;127557;975.6926568131016;990.4503000834605;995.6365926436944;998.1814911772277;998.3211416236884;18667.346642867433;18841.434756957315;19217.23131074797;19253.518662033563;19310.420687379403;49353.99999999857;50214;50931.74534127454;52013;52403.475965241385;69342;71014.64492677503;71266;71425.64287178531;73494.53010939644;6340.70015646782;7478.2521747824785;7602.543847369176;7914;7957.323627491728;10558.683239502827;10772.407517586607;11340;11925;12366.19619724715;10442.72523084602;10597.364158150536;10939.343639381663;10943.45282735844;10974.835377469504;9953.452560109263;10047.648332308334;10318.638847640297;10344.431056291976;10373.222008349254;10682.359058456206;10862.239643224899;10986.615468458565;11170.329780213213;11257.774844502901;7567.091948966159;7579.886301705368;7605.6577454013595;7800.219978002381;7808.492447990862;10997;11126.721831954266;11286.774264514499;11304.378259195495;11403.381630439686 16:29:37 INFO - PROCESS | 1635 | |3;mootools.html;1320.6104199261217;1358.5448874627;1471.0879254861984;1590.149270140475;1785.410814431237;7666.769996900088;8095.91904080959;8249.051359093706;8409.453385529936;8672.653093876237;8531.616077276522;8618.784530386743;8618.870716939235;8624.741257762276;8635.266002389804;2358.492924021331;2390.043982407042;2393.44950661348;2396.101461951768;2398.46034642205;2363.302825666424;2411.4212588978694;2412.3124909400735;2412.3848418653306;2413.046846495633;2343.7304709958403;2352.5882970480184;2372.95254094918;2381.892814823337;2382.5592265430864;2301.1945818963395;2328.7904088632104;2368.3723813189517;2368.786809187173;2370.5258948210253;2348.330725743155;2363.2083252110565;2385.4990452004927;2386.809055275574;2390.53384590005;8387.203215694499;8499.277561407287;8531.872021919615;8587.270082043035;8593.699220527315;5191.0656081905245;5388.973055134738;5402.270693456412;5403.189521571757;5404.189371594253;3491.9825400873087;3599.94600080999;3651.123730304708;3691.261747650454;3695.519582454264;1066.7439814444479;1072.4369705904385;1086.5490821309147;1114.4594871487323;1114.849495318138;11359.148063895158;11393.943030284878;11445.771084578262;11465.885341146648;11504.367259800707;4372.628326592276;4531.864044078683;4633.050224703928;4639.768011599406;4651.279051746985;8985.910140898639;8986.55067246635;8996.235319997873;8997.370184087182;9015.684451044182;5240.030594340059;5246.501582349671;5247;5247.055530004636;5250.422453530108;4387.232234358974;4530.275155975094;4727.220008698595;4858.708477491291;4912.263160525878;3708.721845861571;3744.2324323513612;3791.052236940765;3843.0584506796013;3850.730448868608;984.0761311635878;1156.283104475214;1158.2124155574122;1174.6593487888588;1176.3882780953857;758.1849511774753;842.7893026743315;891.9509426981449;900.5092224737534;920.1810388754014;5621.831345059655;5635.295588051494;5720.513756330759;5957.391504293903;6092.878142437126;847.093609837468;858.6565373850385;906.7959709065408;912.9680461183826;956.5217391304348;3846.4999550058874;3930.1746633207345;3941.231459865356;3947.1513624570857;4055.2903241932545;10177.592896284214;10582.841257381146;10583.576656933636;10586.364818110938;10596.523156457977;10336;10449.00734430228;10480.423576703355;10481.737956550995;10576.788464230673;7689.923100769032;8060.193980601893;8089.352851771844;8098.716544921017;8233;4975.577075948585;5595.664260144405;5744.856378590402;5844.327902291121;6023.216981792339;9574.425534467955;9606.087421694929;9655.517224138905;9835.459049752046;9934.155596774355;9902.653407131003;9961.103500684972;10039.247056470647;10069.395836249849;10070.43203983009;6826.351496607815;7011.4390848733;7060.8940865886025;7396.889046664412;7582.317591416579;8054.798630034296;8344.582770861554;8345.624446899783;8356.707515236938;8357.582120894052;7317.231690672403;7381.1511676157925;7381.963090184515;7557.5465472071855;7565;7181.425485961215;7285.599292038777;7336.156342020522;7350.007748954036;7453.993710849183;5761.48146666802;6755.189377274562;6939.4448444125355;6945.548539344926;6945.617991010543;4860.513948605253;4920;4927.507249275044;4951.925721114114;4994.001199760135;4917.852464426073;4941.8272644661765;5034.320366750589;5078.984203159309;5112.309838171799 16:29:37 INFO - PROCESS | 1635 | |4;prototype.html;299.7092819964634;302.2775566396313;308.13875218763553;313.1966505912335;315.52986050784307;4195.85314513992;4271.658267338613;4412.492563355215;5203.245529398235;5206.7656955437005;3968.2658708139015;4896.265560165977;4997.875053123674;5010.974945125269;5113.309703190068;3462.4979377990176;3998.9400158997637;4012.2577323195155;4022.879313620596;4052.7163098583037;3404.5063465797443;3878.864239751609;3925.665273806905;3994.720369574131;4009.278329900617;3415.248645298043;3690.833912473932;3942.56631770505;3963.425303331015;4015.955851478622;3965.9405108923247;3970.8610198643196;3976.423418604315;3992.2215168042244;3992.4210989406665;3477.4957631143598;3485.9128521786906;3504.5969713483023;3512.9788381991466;3514.7188224941942;3868.400397938324;4253.617174454283;4917.532834380728;4969.602431805447;5035;4340.8697739067875;4525.275955847049;4540.387047748578;4540.500544940054;4575.862724118282;4442.1565050504005;5156.04613146569;5342.572594192494;5396.649217800869;5416.945830541723;3066.632004159515;3764.416515440111;3821.426785982097;3871.361225397806;3891.630295121959;4518.728876267434;5614.775408983635;5617.719114044281;5665.688387138705;5697.6011679182475;2973.44991176633;3386.813725245086;3764.8494060237285;3820.8280627371837;3868.9806550967064;3530.1351168963256;3921;3926.450296958428;3931.253061918226;3945.546262179829;3413.6415676354122;4307.440032795716;4324.978375108104;4442.200403927324;4480.350349199413;4891.926621100686;5081.949180508222;5212.5569326607665;5430.592705547021;5438.830651409966;1151.7235863392725;1183.3314177489863;1201.9399030048462;1239.2874097394035;1265.240855486719;783.7648705388475;853.3514528958036;865.6970060478782;866.9263112635371;871.6556960000764;4154.231467178582;4863.5622793948005;4885.975570122127;4890.58430033444;4893.947801222755;4502.864914052584;5220.92168617471;5269.420363759983;5314.256004159421;5316.617679403383;4232.513260974965;5166.793328266903;5183.922241166386;5249.766304918341;5312.282841816383;2459.803215742772;2569.79441644668;2756.021612327595;2765.25338158696;2777.6250206221903;4854.577522846384;5341.385740639798;5396.163594642837;5505.907755053135;5513.476219759116;5721.39925307845;5724.570657200643;5734.741936612862;5746.540276777848;5751.4758588973955;4614.538546145359;5674.177244299553;5708.65748055118;5777.884442311214;5781.53747700183;4324.956750432455;5464.125739881759;5573.637713548606;5585.1901474286815;5593.244911936837;4656.185167595725;4871.391076115486;5122.410922743932;5293.861819708781;5582.413846546055;5641.815218804097;5861.706914654165;5879.647221166743;5886.646801191942;5894.6736984178215;3429.42792905321;3622.3660859350034;3672.2839046386766;3775.5091838060875;3796.772193668389;4236;5316.388615309289;5493.23094766725;5586.938481688466;5657.274531268003;5081.466446023114;5304.840854774363;5489.698066606375;5539.639923404966;5553.362152977465;4690.460597031249;5188.910328830837;5472.863178420572;5504.889902202013;5572.804951826829;4094.8157332920687;4922.65541412112;5087.982403519385;5096.935741024891;5343.599230057684;4554.405957914703;5120.231965205249;5321.255024296524;5460.399356070909;5482.972585137049;4793.544613261734;5292.920606190987;5304.389995150607;5324.402679196303;5353.464653534771 16:29:37 INFO - PROCESS | 1635 | |5;yui.html;667.8463953290743;711.3704371631109;766.3524321947953;776.0919723923009;778.4239662649641;14032.158070515763;14082.94377921656;14581.416743330268;15774.684506309866;16612.58468538287;14744.705105897876;14943.028703134289;15189.164595947219;15246;15290.617734556641;791.5527726834334;814.7148498025673;815.0708192660372;815.0789607743242;815.4943934760428;592.3720855892745;642.9903551446732;651.8337823854911;788.1921030943272;820.6963423533301;861.3970220845401;871.4727589808169;871.5555066915855;872.1583671756754;874.2219424712011;861.5046348349692;862.4911302331592;862.6161358195606;863.3956230638578;864.2092485375937;500.1647249094022;500.83222120589284;513.958883289336;639.0159154901446;641.4836056974134;12131.696707582292;12186.51253949841;14075.85924140766;14634.780478292834;14644.560663180122;12844.165129266568;13054;13161.539346122936;13209.603711888658;13309;11982.580609678707;12248.693782655415;12305.138640295183;12441.3779311035;12605.93697031509;4366.214081465335;4396.824127034947;4489.438820147481;4550.203714349976;4557.475890272628;95795.04204957931;97907.51046244794;98261;100540.49729751305;101280.49359753227;8706.347023973227;10078.193744500422;10345.12066474343;10430.37417754922;10641.680749577525;19562.804371956383;19659.705104423447;19701.14333421646;19723;20003.59992800136;7395.4083673306;7499.550026998398;7664.885026724603;7685.961570192113;7966.123726390092;4199.160167966419;4235.597618226264;4313.094250207429;4321.330193819963;4334.913301733947;3419.726421886293;3547.2018795770746;3547.7516573839584;3552.7335449841366;3553.7690050146657;587.8360845525883;630.2059405149545;645.3030726815028;653.2063542795527;653.9411452969256;539.9325084364455;557.3117200257678;579.9739011744482;647.7052940911968;663.9535232533773;8499.575021248913;8960.924689037358;9249.953750231205;9284.210842078364;9412.529373531295;552.95299899509;559.5383808358121;617.9309794056308;626.3266987987931;672.8527859998691;3767.7174211934216;4377.409049778239;4489.88775280614;4508.256137737302;4535.070310586388;79954.62325359246;104891;121827;125919;129257.35371323083;69125.3087469129;103737;103878;105788;109532;86264.13735862686;93803;94094.0590594099;96576.51711741397;98474;7131.750388736369;7351.301626345488;8909.331800114887;8917.509536975529;9132.269418446376;57465.42534574768;57871;58662.70668646629;59890;61167.69416152891;57791.84416311566;69623;73412;73803;75871;10578.523966421375;12110.091743119478;12337.876621233672;12533;12852.807207891701;13250.801237981244;13420.530281440102;14004.859951400764;14587.85412145865;14759.188244646648;12911.870881291066;13225.272610006543;13535.187888726708;13609.387577558835;14052.297385130498;12607.180533265308;12934.050336845157;12956.157849739737;13061.934690326867;13158.60524184276;12466.376681165724;12724.681882953;12738.808917866425;13042.869571304165;13510.256935868621;10811.783764324513;10836.620718274822;11299.09607231436;11313.71715707081;11693.88306116928;11326.660200194008;11408.144389170679;11554.191206615456;11613.245139065933;11617.941910290394 16:29:37 INFO - PROCESS | 1635 | __end_tp_report 16:29:37 INFO - PROCESS | 1635 | __start_cc_report 16:29:37 INFO - PROCESS | 1635 | _x_x_mozilla_cycle_collect,1924 16:29:37 INFO - PROCESS | 1635 | __end_cc_report 16:29:37 INFO - PROCESS | 1635 | __startTimestamp1479860977485__endTimestamp 16:29:37 INFO - PROCESS | 1635 | 16:29:37 INFO - PROCESS | 1635 | ------- Summary: start ------- 16:29:37 INFO - PROCESS | 1635 | Number of tests: 6 16:29:37 INFO - PROCESS | 1635 | 16:29:37 INFO - PROCESS | 1635 | [#0] dojo.html Cycles:165 Average:5705.52 Median:5908.38 stddev:662.81 (11.2%) stddev-sans-first:625.75 16:29:37 INFO - PROCESS | 1635 | Values: 2846.5 3362.8 3378.6 3402.1 3423.5 4729.5 4954.7 5195.9 5669.9 5765.4 5798.9 5808.2 5815.0 5825.3 5836.3 5212.1 5233.6 5257.0 5273.9 5309.6 5202.5 5203.1 5224.9 5338.9 5359.2 5266.9 5288.2 5291.2 5292.3 5294.5 5287.0 5287.8 5302.3 5309.7 5313.9 4297.3 4380.9 5138.3 5259.6 5260.5 5788.9 5908.4 5910.0 5916.5 5917.1 6119.0 6132.1 6134.8 6140.1 6154.3 6106.3 6121.9 6141.4 6170.0 6247.3 5906.9 5945.4 5963.9 5965.9 5978.4 6135.6 6325.5 6351.7 6368.6 6373.4 5725.1 5747.2 6116.8 6126.6 6127.9 6146.4 6202.2 6232.8 6236.8 6236.9 6119.7 6123.0 6130.2 6131.7 6142.5 6093.5 6105.8 6118.6 6128.5 6142.7 4935.5 4939.6 4943.9 4947.9 4968.4 4342.9 4369.1 4459.9 4460.5 4479.7 5704.5 5752.9 5762.4 5785.1 5803.6 6109.3 6121.9 6168.4 6185.8 6189.0 5858.6 5865.2 5875.0 5888.6 5894.8 5381.0 5423.2 5427.3 5434.1 5439.0 6287.6 6287.8 6289.2 6296.2 6309.3 6112.8 6147.4 6208.0 6211.9 6243.2 6454.7 6454.8 6461.9 6462.5 6466.5 6144.3 6156.4 6167.6 6169.4 6176.9 4929.9 5901.1 6111.0 6138.4 6158.2 5077.5 6132.1 6151.5 6167.1 6172.1 6136.1 6141.7 6143.6 6144.0 6157.8 5816.2 6001.9 6072.6 6079.9 6132.6 5801.1 5820.1 5835.4 5896.7 6142.3 4765.9 5703.9 5713.3 5737.1 5747.6 16:29:37 INFO - PROCESS | 1635 | 16:29:37 INFO - PROCESS | 1635 | [#1] ext.html Cycles:180 Average:69620.47 Median:39782.82 stddev:153021.69 (384.6%) stddev-sans-first:153362.92 16:29:37 INFO - PROCESS | 1635 | Values: 485.0 506.5 529.0 538.0 555.3 38986.0 39967.4 40005.8 40020.4 41818.8 41324.8 41698.8 41772.0 41805.6 41880.6 9270.9 9449.1 9455.5 9457.1 9457.2 9271.5 9366.0 9366.7 9381.2 9391.6 9398.8 9422.4 9440.0 9457.9 9478.4 9353.2 9406.3 9411.2 9413.0 9416.2 9295.0 9386.7 9387.6 9388.6 9407.4 38995.8 39309.2 39347.4 39354.4 39439.0 3104.8 3240.3 3380.6 3624.0 3735.5 29820.1 29946.0 30052.4 30222.4 30532.9 3106.2 3170.8 3173.7 3245.7 3253.9 490918.0 494074.0 496454.0 496824.0 507116.0 55786.4 56001.2 56087.0 56126.2 56226.0 33717.0 34140.7 34373.8 35581.0 35608.6 15892.4 16022.2 16033.8 16047.8 16061.8 12286.6 12375.4 12393.3 12406.2 12406.2 18586.4 18602.4 18608.8 18612.4 18682.9 6570.2 7288.3 7381.9 7501.7 7595.7 801.5 892.2 918.4 954.9 974.3 23771.9 29643.4 29689.7 29863.7 30090.2 1008.4 1021.8 1031.2 1051.4 1074.9 15233.4 15353.4 15444.5 15777.5 15804.2 524974.0 580094.0 666200.0 668667.0 669981.0 56454.0 56861.7 56882.7 56925.1 57024.0 1178.7 1227.9 1235.2 1322.4 1331.4 29056.6 30480.0 30486.8 30730.2 32354.5 54402.0 55148.4 55291.4 55305.0 55413.7 583810.0 587610.0 588557.0 589670.0 590274.0 40201.0 41401.6 41566.2 41590.0 41617.6 44863.1 45282.1 45711.1 45713.5 45769.3 37889.9 37904.6 38334.6 39152.6 39598.2 37714.6 37894.7 38917.6 39385.6 39387.0 36481.8 36646.8 36754.9 36959.3 37022.2 32195.0 32446.5 33983.5 34543.3 34702.7 21998.9 24854.8 25308.5 25467.2 25805.4 16:29:37 INFO - PROCESS | 1635 | 16:29:37 INFO - PROCESS | 1635 | [#2] jquery.html Cycles:180 Average:35159.37 Median:14008.04 stddev:102374.43 (730.8%) stddev-sans-first:102628.25 16:29:37 INFO - PROCESS | 1635 | Values: 346.3 362.6 367.8 377.7 397.0 14598.9 15175.6 15465.8 19504.8 20165.9 19566.8 19614.6 20148.4 20252.0 20430.9 4481.8 5910.8 5911.6 5917.7 5920.0 4874.0 5348.0 5773.0 5822.2 5845.0 5631.3 5780.1 5780.5 5782.6 5793.3 5493.9 5720.0 5735.0 5736.7 5742.8 4852.2 4950.2 5016.4 5019.8 5030.7 23888.9 24680.6 24968.5 25142.7 25201.2 2441.7 2814.8 2954.8 3010.8 3036.6 14983.8 15001.9 15028.0 15453.0 15740.4 1765.5 1801.0 1824.6 1875.6 1890.1 121828.8 122081.0 125655.0 126993.0 128972.4 9367.3 9949.6 10591.1 10607.9 10781.0 11813.2 12991.8 13005.5 13011.6 13016.5 6957.7 7600.5 8285.7 8373.0 8491.4 8446.7 8465.3 8467.7 8563.1 8606.5 6193.6 6382.2 6388.5 6391.2 6392.3 1594.1 1722.4 1826.3 1830.2 1837.2 681.3 874.5 955.3 1009.1 1022.2 20066.3 21246.7 21264.4 21279.6 21327.4 941.7 968.5 984.0 998.7 1022.0 10588.4 11187.4 11336.4 11347.4 11373.9 605712.0 611044.0 617289.0 617333.0 620300.0 117938.0 118355.4 125212.0 125504.4 127557.0 975.7 990.5 995.6 998.2 998.3 18667.3 18841.4 19217.2 19253.5 19310.4 49354.0 50214.0 50931.7 52013.0 52403.5 69342.0 71014.6 71266.0 71425.6 73494.5 6340.7 7478.3 7602.5 7914.0 7957.3 10558.7 10772.4 11340.0 11925.0 12366.2 10442.7 10597.4 10939.3 10943.5 10974.8 9953.5 10047.6 10318.6 10344.4 10373.2 10682.4 10862.2 10986.6 11170.3 11257.8 7567.1 7579.9 7605.7 7800.2 7808.5 10997.0 11126.7 11286.8 11304.4 11403.4 16:29:37 INFO - PROCESS | 1635 | 16:29:37 INFO - PROCESS | 1635 | [#3] mootools.html Cycles:180 Average:5593.68 Median:5056.65 stddev:3102.40 (61.4%) stddev-sans-first:3094.48 16:29:37 INFO - PROCESS | 1635 | Values: 1320.6 1358.5 1471.1 1590.1 1785.4 7666.8 8095.9 8249.1 8409.5 8672.7 8531.6 8618.8 8618.9 8624.7 8635.3 2358.5 2390.0 2393.4 2396.1 2398.5 2363.3 2411.4 2412.3 2412.4 2413.0 2343.7 2352.6 2373.0 2381.9 2382.6 2301.2 2328.8 2368.4 2368.8 2370.5 2348.3 2363.2 2385.5 2386.8 2390.5 8387.2 8499.3 8531.9 8587.3 8593.7 5191.1 5389.0 5402.3 5403.2 5404.2 3492.0 3599.9 3651.1 3691.3 3695.5 1066.7 1072.4 1086.5 1114.5 1114.8 11359.1 11393.9 11445.8 11465.9 11504.4 4372.6 4531.9 4633.1 4639.8 4651.3 8985.9 8986.6 8996.2 8997.4 9015.7 5240.0 5246.5 5247.0 5247.1 5250.4 4387.2 4530.3 4727.2 4858.7 4912.3 3708.7 3744.2 3791.1 3843.1 3850.7 984.1 1156.3 1158.2 1174.7 1176.4 758.2 842.8 892.0 900.5 920.2 5621.8 5635.3 5720.5 5957.4 6092.9 847.1 858.7 906.8 913.0 956.5 3846.5 3930.2 3941.2 3947.2 4055.3 10177.6 10582.8 10583.6 10586.4 10596.5 10336.0 10449.0 10480.4 10481.7 10576.8 7689.9 8060.2 8089.4 8098.7 8233.0 4975.6 5595.7 5744.9 5844.3 6023.2 9574.4 9606.1 9655.5 9835.5 9934.2 9902.7 9961.1 10039.2 10069.4 10070.4 6826.4 7011.4 7060.9 7396.9 7582.3 8054.8 8344.6 8345.6 8356.7 8357.6 7317.2 7381.2 7382.0 7557.5 7565.0 7181.4 7285.6 7336.2 7350.0 7454.0 5761.5 6755.2 6939.4 6945.5 6945.6 4860.5 4920.0 4927.5 4951.9 4994.0 4917.9 4941.8 5034.3 5079.0 5112.3 16:29:37 INFO - PROCESS | 1635 | 16:29:37 INFO - PROCESS | 1635 | [#4] prototype.html Cycles:180 Average:4342.40 Median:4867.48 stddev:1343.37 (27.6%) stddev-sans-first:1312.42 16:29:37 INFO - PROCESS | 1635 | Values: 299.7 302.3 308.1 313.2 315.5 4195.9 4271.7 4412.5 5203.2 5206.8 3968.3 4896.3 4997.9 5011.0 5113.3 3462.5 3998.9 4012.3 4022.9 4052.7 3404.5 3878.9 3925.7 3994.7 4009.3 3415.2 3690.8 3942.6 3963.4 4016.0 3965.9 3970.9 3976.4 3992.2 3992.4 3477.5 3485.9 3504.6 3513.0 3514.7 3868.4 4253.6 4917.5 4969.6 5035.0 4340.9 4525.3 4540.4 4540.5 4575.9 4442.2 5156.0 5342.6 5396.6 5416.9 3066.6 3764.4 3821.4 3871.4 3891.6 4518.7 5614.8 5617.7 5665.7 5697.6 2973.4 3386.8 3764.8 3820.8 3869.0 3530.1 3921.0 3926.5 3931.3 3945.5 3413.6 4307.4 4325.0 4442.2 4480.4 4891.9 5081.9 5212.6 5430.6 5438.8 1151.7 1183.3 1201.9 1239.3 1265.2 783.8 853.4 865.7 866.9 871.7 4154.2 4863.6 4886.0 4890.6 4893.9 4502.9 5220.9 5269.4 5314.3 5316.6 4232.5 5166.8 5183.9 5249.8 5312.3 2459.8 2569.8 2756.0 2765.3 2777.6 4854.6 5341.4 5396.2 5505.9 5513.5 5721.4 5724.6 5734.7 5746.5 5751.5 4614.5 5674.2 5708.7 5777.9 5781.5 4325.0 5464.1 5573.6 5585.2 5593.2 4656.2 4871.4 5122.4 5293.9 5582.4 5641.8 5861.7 5879.6 5886.6 5894.7 3429.4 3622.4 3672.3 3775.5 3796.8 4236.0 5316.4 5493.2 5586.9 5657.3 5081.5 5304.8 5489.7 5539.6 5553.4 4690.5 5188.9 5472.9 5504.9 5572.8 4094.8 4922.7 5088.0 5096.9 5343.6 4554.4 5120.2 5321.3 5460.4 5483.0 4793.5 5292.9 5304.4 5324.4 5353.5 16:29:37 INFO - PROCESS | 1635 | 16:29:37 INFO - PROCESS | 1635 | [#5] yui.html Cycles:180 Average:21317.31 Median:4371.81 stddev:31877.43 (729.2%) stddev-sans-first:31929.15 16:29:37 INFO - PROCESS | 1635 | Values: 667.8 711.4 766.4 776.1 778.4 14032.2 14082.9 14581.4 15774.7 16612.6 14744.7 14943.0 15189.2 15246.0 15290.6 791.6 814.7 815.1 815.1 815.5 592.4 643.0 651.8 788.2 820.7 861.4 871.5 871.6 872.2 874.2 861.5 862.5 862.6 863.4 864.2 500.2 500.8 514.0 639.0 641.5 12131.7 12186.5 14075.9 14634.8 14644.6 12844.2 13054.0 13161.5 13209.6 13309.0 11982.6 12248.7 12305.1 12441.4 12605.9 4366.2 4396.8 4489.4 4550.2 4557.5 95795.0 97907.5 98261.0 100540.5 101280.5 8706.3 10078.2 10345.1 10430.4 10641.7 19562.8 19659.7 19701.1 19723.0 20003.6 7395.4 7499.6 7664.9 7686.0 7966.1 4199.2 4235.6 4313.1 4321.3 4334.9 3419.7 3547.2 3547.8 3552.7 3553.8 587.8 630.2 645.3 653.2 653.9 539.9 557.3 580.0 647.7 664.0 8499.6 8960.9 9250.0 9284.2 9412.5 553.0 559.5 617.9 626.3 672.9 3767.7 4377.4 4489.9 4508.3 4535.1 79954.6 104891.0 121827.0 125919.0 129257.4 69125.3 103737.0 103878.0 105788.0 109532.0 86264.1 93803.0 94094.1 96576.5 98474.0 7131.8 7351.3 8909.3 8917.5 9132.3 57465.4 57871.0 58662.7 59890.0 61167.7 57791.8 69623.0 73412.0 73803.0 75871.0 10578.5 12110.1 12337.9 12533.0 12852.8 13250.8 13420.5 14004.9 14587.9 14759.2 12911.9 13225.3 13535.2 13609.4 14052.3 12607.2 12934.1 12956.2 13061.9 13158.6 12466.4 12724.7 12738.8 13042.9 13510.3 10811.8 10836.6 11299.1 11313.7 11693.9 11326.7 11408.1 11554.2 11613.2 11617.9 16:29:37 INFO - PROCESS | 1635 | -------- Summary: end -------- 16:29:37 INFO - PROCESS | 1635 | 16:29:38 INFO - TEST-INFO | 1635: exit 0 16:29:38 INFO - TEST-OK | dromaeo_css | took 1120776ms 16:29:38 INFO - TEST-START | kraken 16:29:38 INFO - operating with platform_type : mac_ 16:29:38 INFO - Initialising browser for kraken test... 16:29:38 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/tmpgyaDwG/profile http://localhost:49235/getInfo.html) 16:29:40 ERROR - PROCESS | 1644 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5373: TypeError: parent.firstChild is null 16:29:40 INFO - PROCESS | 1644 | __metrics Screen width/height:1600/1200 16:29:40 INFO - PROCESS | 1644 | colorDepth:24 16:29:40 INFO - PROCESS | 1644 | Browser inner width/height: 1024/689 16:29:40 INFO - PROCESS | 1644 | __metrics 16:29:41 INFO - PROCESS | 1644 | 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" 16:29:41 INFO - PROCESS | 1644 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:29:41 INFO - TEST-INFO | 1644: exit 0 16:29:41 INFO - Browser initialized. 16:29:41 INFO - Running cycle 1/1 for kraken test... 16:29:41 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/T/tmpgyaDwG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:29:54 INFO - PROCESS | 1645 | RSS: Main: 222982144 16:29:54 INFO - PROCESS | 1645 | 16:29:57 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection) 16:29:57 INFO - PROCESS | 1645 | RSS: Main: 270553088 16:29:57 INFO - PROCESS | 1645 | 16:30:00 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-dft) 16:30:01 INFO - PROCESS | 1645 | RSS: Main: 283262976 16:30:01 INFO - PROCESS | 1645 | 16:30:04 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-fft) 16:30:04 INFO - PROCESS | 1645 | RSS: Main: 379596800 16:30:04 INFO - PROCESS | 1645 | 16:30:07 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator) 16:30:07 INFO - PROCESS | 1645 | RSS: Main: 284700672 16:30:07 INFO - PROCESS | 1645 | 16:30:09 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:30:10 INFO - PROCESS | 1645 | RSS: Main: 286449664 16:30:10 INFO - PROCESS | 1645 | 16:30:13 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom) 16:30:13 INFO - PROCESS | 1645 | RSS: Main: 326807552 16:30:13 INFO - PROCESS | 1645 | 16:30:16 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate) 16:30:17 INFO - PROCESS | 1645 | RSS: Main: 324919296 16:30:17 INFO - PROCESS | 1645 | 16:30:20 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial) 16:30:20 INFO - PROCESS | 1645 | RSS: Main: 322781184 16:30:20 INFO - PROCESS | 1645 | 16:30:22 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:30:23 INFO - PROCESS | 1645 | RSS: Main: 300703744 16:30:23 INFO - PROCESS | 1645 | 16:30:24 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:30:25 INFO - PROCESS | 1645 | RSS: Main: 300687360 16:30:25 INFO - PROCESS | 1645 | 16:30:27 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:30:28 INFO - PROCESS | 1645 | RSS: Main: 303411200 16:30:28 INFO - PROCESS | 1645 | 16:30:31 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:30:32 INFO - PROCESS | 1645 | RSS: Main: 314691584 16:30:32 INFO - PROCESS | 1645 | 16:30:35 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:30:35 INFO - PROCESS | 1645 | RSS: Main: 314281984 16:30:35 INFO - PROCESS | 1645 | 16:30:37 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49235/tests/kraken/driver.html?testName=ai-astar) 16:30:37 INFO - PROCESS | 1645 | RSS: Main: 358043648 16:30:37 INFO - PROCESS | 1645 | 16:30:37 INFO - PROCESS | 1645 | __start_tp_report 16:30:37 INFO - PROCESS | 1645 | _x_x_mozilla_page_load 16:30:37 INFO - PROCESS | 1645 | _x_x_mozilla_page_load_details 16:30:37 INFO - PROCESS | 1645 | |i|pagename|runs| 16:30:37 INFO - PROCESS | 1645 | |0;ai-astar;105;115;113;114;115;114;113;114;114;114 16:30:37 INFO - PROCESS | 1645 | |1;audio-beat-detection;152;113;119;181;141;113;116;114;143;112 16:30:37 INFO - PROCESS | 1645 | |2;audio-dft;179;181;183;182;187;185;181;182;184;186 16:30:37 INFO - PROCESS | 1645 | |3;audio-fft;81;61;74;113;86;53;53;75;108;80 16:30:37 INFO - PROCESS | 1645 | |4;audio-oscillator;88;73;122;100;97;68;89;68;69;92 16:30:37 INFO - PROCESS | 1645 | |5;imaging-gaussian-blur;88;85;88;85;88;86;88;85;89;84 16:30:37 INFO - PROCESS | 1645 | |6;imaging-darkroom;95;95;96;95;94;95;116;112;95;94 16:30:37 INFO - PROCESS | 1645 | |7;imaging-desaturate;85;82;81;79;81;80;85;89;124;123 16:30:37 INFO - PROCESS | 1645 | |8;json-parse-financial;53;54;53;54;57;58;55;54;54;78 16:30:37 INFO - PROCESS | 1645 | |9;json-stringify-tinderbox;77;74;65;76;73;74;70;72;70;71 16:30:37 INFO - PROCESS | 1645 | |10;stanford-crypto-aes;86;82;85;89;89;87;95;102;105;107 16:30:37 INFO - PROCESS | 1645 | |11;stanford-crypto-ccm;161;159;159;165;160;166;157;147;188;146 16:30:37 INFO - PROCESS | 1645 | |12;stanford-crypto-pbkdf2;165;169;169;169;169;171;170;170;167;167 16:30:37 INFO - PROCESS | 1645 | |13;stanford-crypto-sha256-iterative;67;67;67;68;67;65;66;66;66;66 16:30:37 INFO - PROCESS | 1645 | __end_tp_report 16:30:37 INFO - PROCESS | 1645 | __start_cc_report 16:30:37 INFO - PROCESS | 1645 | _x_x_mozilla_cycle_collect,1972 16:30:37 INFO - PROCESS | 1645 | __end_cc_report 16:30:37 INFO - PROCESS | 1645 | __startTimestamp1479861037754__endTimestamp 16:30:37 INFO - PROCESS | 1645 | 16:30:37 INFO - PROCESS | 1645 | ------- Summary: start ------- 16:30:37 INFO - PROCESS | 1645 | Number of tests: 14 16:30:37 INFO - PROCESS | 1645 | 16:30:37 INFO - PROCESS | 1645 | [#0] ai-astar Cycles:10 Average:113.10 Median:114.00 stddev:2.92 (2.6%) stddev-sans-first:0.71 16:30:37 INFO - PROCESS | 1645 | Values: 105.0 115.0 113.0 114.0 115.0 114.0 113.0 114.0 114.0 114.0 16:30:37 INFO - PROCESS | 1645 | 16:30:37 INFO - PROCESS | 1645 | [#1] audio-beat-detection Cycles:10 Average:130.40 Median:130.00 stddev:23.21 (17.9%) stddev-sans-first:23.26 16:30:37 INFO - PROCESS | 1645 | Values: 152.0 113.0 119.0 181.0 141.0 113.0 116.0 114.0 143.0 112.0 16:30:37 INFO - PROCESS | 1645 | 16:30:37 INFO - PROCESS | 1645 | [#2] audio-dft Cycles:10 Average:183.00 Median:183.50 stddev:2.49 (1.4%) stddev-sans-first:2.19 16:30:37 INFO - PROCESS | 1645 | Values: 179.0 181.0 183.0 182.0 187.0 185.0 181.0 182.0 184.0 186.0 16:30:37 INFO - PROCESS | 1645 | 16:30:37 INFO - PROCESS | 1645 | [#3] audio-fft Cycles:10 Average:78.40 Median:74.50 stddev:20.45 (27.5%) stddev-sans-first:21.67 16:30:37 INFO - PROCESS | 1645 | Values: 81.0 61.0 74.0 113.0 86.0 53.0 53.0 75.0 108.0 80.0 16:30:37 INFO - PROCESS | 1645 | 16:30:37 INFO - PROCESS | 1645 | [#4] audio-oscillator Cycles:10 Average:86.60 Median:80.50 stddev:17.53 (21.8%) stddev-sans-first:18.58 16:30:37 INFO - PROCESS | 1645 | Values: 88.0 73.0 122.0 100.0 97.0 68.0 89.0 68.0 69.0 92.0 16:30:37 INFO - PROCESS | 1645 | 16:30:37 INFO - PROCESS | 1645 | [#5] imaging-gaussian-blur Cycles:10 Average:86.60 Median:88.00 stddev:1.78 (2.0%) stddev-sans-first:1.81 16:30:37 INFO - PROCESS | 1645 | Values: 88.0 85.0 88.0 85.0 88.0 86.0 88.0 85.0 89.0 84.0 16:30:37 INFO - PROCESS | 1645 | 16:30:37 INFO - PROCESS | 1645 | [#6] imaging-darkroom Cycles:10 Average:98.70 Median:95.00 stddev:8.14 (8.6%) stddev-sans-first:8.52 16:30:37 INFO - PROCESS | 1645 | Values: 95.0 95.0 96.0 95.0 94.0 95.0 116.0 112.0 95.0 94.0 16:30:37 INFO - PROCESS | 1645 | 16:30:37 INFO - PROCESS | 1645 | [#7] imaging-desaturate Cycles:10 Average:90.90 Median:81.50 stddev:17.43 (21.4%) stddev-sans-first:18.36 16:30:37 INFO - PROCESS | 1645 | Values: 85.0 82.0 81.0 79.0 81.0 80.0 85.0 89.0 124.0 123.0 16:30:37 INFO - PROCESS | 1645 | 16:30:37 INFO - PROCESS | 1645 | [#8] json-parse-financial Cycles:10 Average:57.00 Median:54.50 stddev:7.56 (13.9%) stddev-sans-first:7.88 16:30:37 INFO - PROCESS | 1645 | Values: 53.0 54.0 53.0 54.0 57.0 58.0 55.0 54.0 54.0 78.0 16:30:37 INFO - PROCESS | 1645 | 16:30:37 INFO - PROCESS | 1645 | [#9] json-stringify-tinderbox Cycles:10 Average:72.20 Median:73.50 stddev:3.46 (4.7%) stddev-sans-first:3.20 16:30:37 INFO - PROCESS | 1645 | Values: 77.0 74.0 65.0 76.0 73.0 74.0 70.0 72.0 70.0 71.0 16:30:37 INFO - PROCESS | 1645 | 16:30:37 INFO - PROCESS | 1645 | [#10] stanford-crypto-aes Cycles:10 Average:92.70 Median:86.50 stddev:8.98 (10.4%) stddev-sans-first:9.19 16:30:37 INFO - PROCESS | 1645 | Values: 86.0 82.0 85.0 89.0 89.0 87.0 95.0 102.0 105.0 107.0 16:30:37 INFO - PROCESS | 1645 | 16:30:37 INFO - PROCESS | 1645 | [#11] stanford-crypto-ccm Cycles:10 Average:160.80 Median:160.50 stddev:11.62 (7.2%) stddev-sans-first:12.33 16:30:37 INFO - PROCESS | 1645 | Values: 161.0 159.0 159.0 165.0 160.0 166.0 157.0 147.0 188.0 146.0 16:30:37 INFO - PROCESS | 1645 | 16:30:37 INFO - PROCESS | 1645 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.60 Median:169.00 stddev:1.78 (1.1%) stddev-sans-first:1.32 16:30:37 INFO - PROCESS | 1645 | Values: 165.0 169.0 169.0 169.0 169.0 171.0 170.0 170.0 167.0 167.0 16:30:37 INFO - PROCESS | 1645 | 16:30:37 INFO - PROCESS | 1645 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.50 Median:67.00 stddev:0.85 (1.3%) stddev-sans-first:0.88 16:30:37 INFO - PROCESS | 1645 | Values: 67.0 67.0 67.0 68.0 67.0 65.0 66.0 66.0 66.0 66.0 16:30:37 INFO - PROCESS | 1645 | -------- Summary: end -------- 16:30:37 INFO - PROCESS | 1645 | 16:30:38 INFO - TEST-INFO | 1645: exit 0 16:30:38 INFO - TEST-OK | kraken | took 60671ms 16:30:38 INFO - SUITE-END | took 1181s 16:30:38 INFO - Completed test suite (00:19:41) 16:30:38 INFO - javascript benchmark 16:30:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2846.5018621741197, 3362.764606477546, 3378.5607870976783, 3402.1494626343415, 3423.4693622488508, 4729.456112547058, 4954.7274899880495, 5195.896082078356, 5669.9433005669935, 5765.423457654232, 5798.9130163047585, 5808.186853840455, 5814.970925145379, 5825.271841019872, 5836.270466191726, 5212.087884620195, 5233.633645644806, 5257.001169777749, 5273.868153296179, 5309.628326017161, 5202.505761952609, 5203.063448579254, 5224.869378265555, 5338.919916201241, 5359.16932875403, 5266.920996185041, 5288.233206185101, 5291.2327712481665, 5292.311999440087, 5294.52349288564, 5286.97356513215, 5287.814926477574, 5302.310699609065, 5309.734513274359, 5313.867153321159, 4297.269464191095, 4380.912381752379, 5138.254953031789, 5259.579233661298, 5260.473952604709, 5788.88422231553, 5908.379620139866, 5909.995300798874, 5916.4675179233645, 5917.14201440793, 6118.963808992752, 6132.080177995925, 6134.785582005955, 6140.078988151813, 6154.26148862135, 6106.339326077157, 6121.94077276304, 6141.407276743066, 6170.0, 6247.312795592481, 5906.940930590682, 5945.375735547748, 5963.940360596382, 5965.940340596582, 5978.432048955342, 6135.600157732601, 6325.462335701425, 6351.682415879234, 6368.586041907308, 6373.362663733589, 5725.141228815628, 5747.224124743107, 6116.847078823065, 6126.559057237747, 6127.896978543816, 6146.446819786241, 6202.1937148171355, 6232.781852635136, 6236.75052997875, 6236.875262494725, 6119.663418511936, 6122.969385153135, 6130.214697154755, 6131.693415329305, 6142.477889379364, 6093.482054025458, 6105.800759126419, 6118.632882027093, 6128.483736330546, 6142.662153581507, 4935.531124543163, 4939.580135688507, 4943.925841112315, 4947.925781113287, 4968.428630707443, 4342.913141737147, 4369.060651959844, 4459.866204013885, 4460.464744230713, 4479.70881892676, 5704.543636509069, 5752.884942301131, 5762.423757624204, 5785.103308987113, 5803.593748437654, 6109.2528441424865, 6121.87979361315, 6168.352323006109, 6185.783497577563, 6189.04069869171, 5858.619189752652, 5865.178874957509, 5875.0, 5888.587798854039, 5894.764209431661, 5381.0, 5423.159410291411, 5427.267318911896, 5434.103372943499, 5438.9937861494955, 6287.591306565058, 6287.811365659038, 6289.213848268966, 6296.150019747275, 6309.305976342599, 6112.807455516588, 6147.354527774519, 6207.96896015517, 6211.875762484816, 6243.219597550306, 6454.741810327534, 6454.774082907264, 6461.903071454026, 6462.547621666438, 6466.482681385572, 6144.324124346408, 6156.353582873734, 6167.629942203482, 6169.413905678954, 6176.938230617816, 4929.9014019720125, 5901.1443340714495, 6111.04482360895, 6138.3883983858805, 6158.199434073542, 5077.543021128116, 6132.141360525334, 6151.538634602333, 6167.105769663463, 6172.135900973777, 6136.110264011604, 6141.662208578571, 6143.569950103629, 6144.047672610753, 6157.815265542041, 5816.2231611198595, 6001.879962400641, 6072.605280656743, 6079.891469697885, 6132.601380910227, 5801.100829371454, 5820.097884827858, 5835.387284335254, 5896.734646940812, 6142.293636231891, 4765.928511072268, 5703.942960570341, 5713.257276554022, 5737.139429085671, 5747.597668163188], "value": 5662.857928509166, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [485.04688287515046, 506.4808571214505, 529.0477141145936, 537.9838604841855, 555.3169601390292, 38986.0, 39967.40048899269, 40005.79997100014, 40020.39969400461, 41818.790906045426, 41324.79337603308, 41698.7915060425, 41772.0, 41805.58194418055, 41880.581194188046, 9270.860937085967, 9449.14957653811, 9455.527223638825, 9457.148856602904, 9457.19613832825, 9271.536423178813, 9366.0, 9366.672166474174, 9381.15569598736, 9391.624335026625, 9398.765030874249, 9422.3875448096, 9440.0, 9457.871978804747, 9478.431294122374, 9353.15821576058, 9406.341556091076, 9411.20004799586, 9413.011633778424, 9416.246700263997, 9294.953525232399, 9386.718398448058, 9387.577559009793, 9388.577514011817, 9407.43555386679, 38995.805020975, 39309.21381572381, 39347.40978885319, 39354.40968385476, 39439.0, 3104.7671424643017, 3240.287136829894, 3380.6112297085906, 3624.0, 3735.514383130203, 29820.10539683813, 29946.0, 30052.398952021056, 30222.395552088834, 30532.931347402733, 3106.176863131272, 3170.8414579271175, 3173.730232930204, 3245.740340772756, 3253.8535765890592, 490918.0, 494074.0, 496454.0, 496823.9999999964, 507116.0, 55786.44213557812, 56001.15998260029, 56087.0, 56126.15810762842, 56226.0, 33717.0, 34140.65859341375, 34373.79691710828, 35581.0, 35608.64391356053, 15892.443764468188, 16022.19889005545, 16033.839661603468, 16047.839521604868, 16061.839381606034, 12286.569970051005, 12375.381230938598, 12393.256404615751, 12406.193597416139, 12406.193597416139, 18586.44240672782, 18602.44192674222, 18608.81391186098, 18612.44162675122, 18682.87902725841, 6570.178727659043, 7288.271172882775, 7381.889271660822, 7501.699932002659, 7595.6961721531625, 801.4750338528273, 892.2282225874571, 918.3755046568398, 954.9283803714749, 974.2741657465234, 23771.88873496294, 29643.40713185767, 29689.703102969124, 29863.701362986525, 30090.186411649847, 1008.4000019988242, 1021.7752094539189, 1031.1905154453868, 1051.405955635079, 1074.9355038697547, 15233.390664373523, 15353.385864565293, 15444.45944391941, 15777.526674199793, 15804.209789510709, 524974.0, 580094.0, 666200.0, 668667.0, 669981.0, 56454.0, 56861.715691422105, 56882.7155864218, 56925.14612280819, 57024.0, 1178.699431644925, 1227.9079069069855, 1235.1909499277988, 1322.3850909327016, 1331.4075236519657, 29056.564151538165, 30480.0, 30486.84756576203, 30730.231744206572, 32354.514682279314, 54402.0, 55148.448515514334, 55291.44708552863, 55305.0, 55413.722931385084, 583810.0, 587610.0, 588557.0, 589670.0, 590274.0, 40200.994975125854, 41401.585984139776, 41566.16867662569, 41590.0, 41617.58382416258, 44863.10273794571, 45282.094358111994, 45711.08577828358, 45713.542864570925, 45769.313460298785, 37889.93988817066, 37904.62095379011, 38334.6166538331, 39152.608473916036, 39598.24821021496, 37714.622853771114, 37894.7263164211, 38917.6108238914, 39385.606143938196, 39387.01532461711, 36481.81759091187, 36646.816765916, 36754.897353078384, 36959.260814784095, 37022.20713344212, 32195.0, 32446.513302300013, 33983.49024764581, 34543.30913381668, 34702.652973470955, 21998.89000554987, 24854.751452485245, 25308.49383012366, 25467.23598292054, 25805.3548661285], "value": 19612.189941989127, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.27282706316754, 362.61562743491885, 367.7591177778555, 377.6751993285774, 396.9702272329576, 14598.854011459882, 15175.620609484768, 15465.76801347978, 19504.82971021736, 20165.899170504126, 19566.804331956675, 19614.60770784587, 20148.39554813358, 20252.0, 20430.89784551079, 4481.7983190756495, 5910.822675319747, 5911.586188966774, 5917.674527900964, 5919.970400148015, 4873.951260487385, 5348.0, 5772.971135144318, 5822.214001109838, 5844.970775146139, 5631.267935168443, 5780.104083866986, 5780.4797568218855, 5782.595218334718, 5793.304803423595, 5493.945060549383, 5720.0, 5735.0250457422335, 5736.684482353469, 5742.770289188448, 4852.1508735971065, 4950.2079667253065, 5016.44819069902, 5019.774110165052, 5030.723310217936, 23888.880555597283, 24680.629790553157, 24968.5006299873, 25142.748572514225, 25201.24396268115, 2441.6825812644242, 2814.8459131756085, 2954.8374839383823, 3010.8494575271366, 3036.5748795168474, 14983.775243371358, 15001.924990374979, 15028.023178493362, 15452.995555288982, 15740.370385184578, 1765.5497848048665, 1800.97298540522, 1824.5803465203078, 1875.5873707784265, 1890.1116475256608, 121828.78171218262, 122081.0, 125655.0, 126993.0, 128972.35513822465, 9367.344285900057, 9949.602015919427, 10591.134236003803, 10607.94696026515, 10781.0, 11813.173077884465, 12991.805122923164, 13005.544805931748, 13011.609651710463, 13016.479340826261, 6957.65211739411, 7600.50596711212, 8285.668573257002, 8372.958135209285, 8491.405601607952, 8446.662133514714, 8465.32277417805, 8467.745967620982, 8563.100874408223, 8606.547134066761, 6193.566450348432, 6382.170317858649, 6388.488920886318, 6391.201099862517, 6392.328805475358, 1594.0595048921193, 1722.3541172060477, 1826.2512369928265, 1830.1855674224842, 1837.2375464182483, 681.2914568848441, 874.4709450782312, 955.2978560757838, 1009.1170226052204, 1022.2333250062453, 20066.297679581145, 21246.681299780517, 21264.36206913795, 21279.574408511744, 21327.36017919465, 941.7268991992382, 968.5205823117461, 983.9901600984042, 998.6953979036318, 1022.0443884967431, 10588.381887507134, 11187.440627968568, 11336.376499292619, 11347.432628368548, 11373.886261137448, 605712.0, 611044.0, 617289.0, 617333.0, 620300.0, 117938.0, 118355.40822296006, 125212.0, 125504.37247813703, 127557.0, 975.6926568131016, 990.4503000834605, 995.6365926436944, 998.1814911772277, 998.3211416236884, 18667.346642867433, 18841.434756957315, 19217.23131074797, 19253.518662033563, 19310.420687379403, 49353.99999999857, 50214.0, 50931.74534127454, 52013.0, 52403.475965241385, 69342.0, 71014.64492677503, 71266.0, 71425.64287178531, 73494.53010939644, 6340.70015646782, 7478.2521747824785, 7602.543847369176, 7914.0, 7957.323627491728, 10558.683239502827, 10772.407517586607, 11340.0, 11925.0, 12366.19619724715, 10442.72523084602, 10597.364158150536, 10939.343639381663, 10943.45282735844, 10974.835377469504, 9953.452560109263, 10047.648332308334, 10318.638847640297, 10344.431056291976, 10373.222008349254, 10682.359058456206, 10862.239643224899, 10986.615468458565, 11170.329780213213, 11257.774844502901, 7567.091948966159, 7579.886301705368, 7605.6577454013595, 7800.219978002381, 7808.492447990862, 10997.0, 11126.721831954266, 11286.774264514499, 11304.378259195495, 11403.381630439686], "value": 9488.265275433816, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1320.6104199261217, 1358.5448874627, 1471.0879254861984, 1590.149270140475, 1785.410814431237, 7666.769996900088, 8095.91904080959, 8249.051359093706, 8409.453385529936, 8672.653093876237, 8531.616077276522, 8618.784530386743, 8618.870716939235, 8624.741257762276, 8635.266002389804, 2358.492924021331, 2390.043982407042, 2393.44950661348, 2396.101461951768, 2398.46034642205, 2363.302825666424, 2411.4212588978694, 2412.3124909400735, 2412.3848418653306, 2413.046846495633, 2343.7304709958403, 2352.5882970480184, 2372.95254094918, 2381.892814823337, 2382.5592265430864, 2301.1945818963395, 2328.7904088632104, 2368.3723813189517, 2368.786809187173, 2370.5258948210253, 2348.330725743155, 2363.2083252110565, 2385.4990452004927, 2386.809055275574, 2390.53384590005, 8387.203215694499, 8499.277561407287, 8531.872021919615, 8587.270082043035, 8593.699220527315, 5191.0656081905245, 5388.973055134738, 5402.270693456412, 5403.189521571757, 5404.189371594253, 3491.9825400873087, 3599.94600080999, 3651.123730304708, 3691.261747650454, 3695.519582454264, 1066.7439814444479, 1072.4369705904385, 1086.5490821309147, 1114.4594871487323, 1114.849495318138, 11359.148063895158, 11393.943030284878, 11445.771084578262, 11465.885341146648, 11504.367259800707, 4372.628326592276, 4531.864044078683, 4633.050224703928, 4639.768011599406, 4651.279051746985, 8985.910140898639, 8986.55067246635, 8996.235319997873, 8997.370184087182, 9015.684451044182, 5240.030594340059, 5246.501582349671, 5247.0, 5247.055530004636, 5250.422453530108, 4387.232234358974, 4530.275155975094, 4727.220008698595, 4858.708477491291, 4912.263160525878, 3708.721845861571, 3744.2324323513612, 3791.052236940765, 3843.0584506796013, 3850.730448868608, 984.0761311635878, 1156.283104475214, 1158.2124155574122, 1174.6593487888588, 1176.3882780953857, 758.1849511774753, 842.7893026743315, 891.9509426981449, 900.5092224737534, 920.1810388754014, 5621.831345059655, 5635.295588051494, 5720.513756330759, 5957.391504293903, 6092.878142437126, 847.093609837468, 858.6565373850385, 906.7959709065408, 912.9680461183826, 956.5217391304348, 3846.4999550058874, 3930.1746633207345, 3941.231459865356, 3947.1513624570857, 4055.2903241932545, 10177.592896284214, 10582.841257381146, 10583.576656933636, 10586.364818110938, 10596.523156457977, 10336.0, 10449.00734430228, 10480.423576703355, 10481.737956550995, 10576.788464230673, 7689.923100769032, 8060.193980601893, 8089.352851771844, 8098.716544921017, 8233.0, 4975.577075948585, 5595.664260144405, 5744.856378590402, 5844.327902291121, 6023.216981792339, 9574.425534467955, 9606.087421694929, 9655.517224138905, 9835.459049752046, 9934.155596774355, 9902.653407131003, 9961.103500684972, 10039.247056470647, 10069.395836249849, 10070.43203983009, 6826.351496607815, 7011.4390848733, 7060.8940865886025, 7396.889046664412, 7582.317591416579, 8054.798630034296, 8344.582770861554, 8345.624446899783, 8356.707515236938, 8357.582120894052, 7317.231690672403, 7381.1511676157925, 7381.963090184515, 7557.5465472071855, 7565.0, 7181.425485961215, 7285.599292038777, 7336.156342020522, 7350.007748954036, 7453.993710849183, 5761.48146666802, 6755.189377274562, 6939.4448444125355, 6945.548539344926, 6945.617991010543, 4860.513948605253, 4920.0, 4927.507249275044, 4951.925721114114, 4994.001199760135, 4917.852464426073, 4941.8272644661765, 5034.320366750589, 5078.984203159309, 5112.309838171799], "value": 4501.027109216675, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.7092819964634, 302.2775566396313, 308.13875218763553, 313.1966505912335, 315.52986050784307, 4195.85314513992, 4271.658267338613, 4412.492563355215, 5203.245529398235, 5206.7656955437005, 3968.2658708139015, 4896.265560165977, 4997.875053123674, 5010.974945125269, 5113.309703190068, 3462.4979377990176, 3998.9400158997637, 4012.2577323195155, 4022.879313620596, 4052.7163098583037, 3404.5063465797443, 3878.864239751609, 3925.665273806905, 3994.720369574131, 4009.278329900617, 3415.248645298043, 3690.833912473932, 3942.56631770505, 3963.425303331015, 4015.955851478622, 3965.9405108923247, 3970.8610198643196, 3976.423418604315, 3992.2215168042244, 3992.4210989406665, 3477.4957631143598, 3485.9128521786906, 3504.5969713483023, 3512.9788381991466, 3514.7188224941942, 3868.400397938324, 4253.617174454283, 4917.532834380728, 4969.602431805447, 5035.0, 4340.8697739067875, 4525.275955847049, 4540.387047748578, 4540.500544940054, 4575.862724118282, 4442.1565050504005, 5156.04613146569, 5342.572594192494, 5396.649217800869, 5416.945830541723, 3066.632004159515, 3764.416515440111, 3821.426785982097, 3871.361225397806, 3891.630295121959, 4518.728876267434, 5614.775408983635, 5617.719114044281, 5665.688387138705, 5697.6011679182475, 2973.44991176633, 3386.813725245086, 3764.8494060237285, 3820.8280627371837, 3868.9806550967064, 3530.1351168963256, 3921.0, 3926.450296958428, 3931.253061918226, 3945.546262179829, 3413.6415676354122, 4307.440032795716, 4324.978375108104, 4442.200403927324, 4480.350349199413, 4891.926621100686, 5081.949180508222, 5212.5569326607665, 5430.592705547021, 5438.830651409966, 1151.7235863392725, 1183.3314177489863, 1201.9399030048462, 1239.2874097394035, 1265.240855486719, 783.7648705388475, 853.3514528958036, 865.6970060478782, 866.9263112635371, 871.6556960000764, 4154.231467178582, 4863.5622793948005, 4885.975570122127, 4890.58430033444, 4893.947801222755, 4502.864914052584, 5220.92168617471, 5269.420363759983, 5314.256004159421, 5316.617679403383, 4232.513260974965, 5166.793328266903, 5183.922241166386, 5249.766304918341, 5312.282841816383, 2459.803215742772, 2569.79441644668, 2756.021612327595, 2765.25338158696, 2777.6250206221903, 4854.577522846384, 5341.385740639798, 5396.163594642837, 5505.907755053135, 5513.476219759116, 5721.39925307845, 5724.570657200643, 5734.741936612862, 5746.540276777848, 5751.4758588973955, 4614.538546145359, 5674.177244299553, 5708.65748055118, 5777.884442311214, 5781.53747700183, 4324.956750432455, 5464.125739881759, 5573.637713548606, 5585.1901474286815, 5593.244911936837, 4656.185167595725, 4871.391076115486, 5122.410922743932, 5293.861819708781, 5582.413846546055, 5641.815218804097, 5861.706914654165, 5879.647221166743, 5886.646801191942, 5894.6736984178215, 3429.42792905321, 3622.3660859350034, 3672.2839046386766, 3775.5091838060875, 3796.772193668389, 4236.0, 5316.388615309289, 5493.23094766725, 5586.938481688466, 5657.274531268003, 5081.466446023114, 5304.840854774363, 5489.698066606375, 5539.639923404966, 5553.362152977465, 4690.460597031249, 5188.910328830837, 5472.863178420572, 5504.889902202013, 5572.804951826829, 4094.8157332920687, 4922.65541412112, 5087.982403519385, 5096.935741024891, 5343.599230057684, 4554.405957914703, 5120.231965205249, 5321.255024296524, 5460.399356070909, 5482.972585137049, 4793.544613261734, 5292.920606190987, 5304.389995150607, 5324.402679196303, 5353.464653534771], "value": 3919.627854976157, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [667.8463953290743, 711.3704371631109, 766.3524321947953, 776.0919723923009, 778.4239662649641, 14032.158070515763, 14082.94377921656, 14581.416743330268, 15774.684506309866, 16612.58468538287, 14744.705105897876, 14943.028703134289, 15189.164595947219, 15246.0, 15290.617734556641, 791.5527726834334, 814.7148498025673, 815.0708192660372, 815.0789607743242, 815.4943934760428, 592.3720855892745, 642.9903551446732, 651.8337823854911, 788.1921030943272, 820.6963423533301, 861.3970220845401, 871.4727589808169, 871.5555066915855, 872.1583671756754, 874.2219424712011, 861.5046348349692, 862.4911302331592, 862.6161358195606, 863.3956230638578, 864.2092485375937, 500.1647249094022, 500.83222120589284, 513.958883289336, 639.0159154901446, 641.4836056974134, 12131.696707582292, 12186.51253949841, 14075.85924140766, 14634.780478292834, 14644.560663180122, 12844.165129266568, 13054.0, 13161.539346122936, 13209.603711888658, 13309.0, 11982.580609678707, 12248.693782655415, 12305.138640295183, 12441.3779311035, 12605.93697031509, 4366.214081465335, 4396.824127034947, 4489.438820147481, 4550.203714349976, 4557.475890272628, 95795.04204957931, 97907.51046244794, 98261.0, 100540.49729751305, 101280.49359753227, 8706.347023973227, 10078.193744500422, 10345.12066474343, 10430.37417754922, 10641.680749577525, 19562.804371956383, 19659.705104423447, 19701.14333421646, 19723.0, 20003.59992800136, 7395.4083673306, 7499.550026998398, 7664.885026724603, 7685.961570192113, 7966.123726390092, 4199.160167966419, 4235.597618226264, 4313.094250207429, 4321.330193819963, 4334.913301733947, 3419.726421886293, 3547.2018795770746, 3547.7516573839584, 3552.7335449841366, 3553.7690050146657, 587.8360845525883, 630.2059405149545, 645.3030726815028, 653.2063542795527, 653.9411452969256, 539.9325084364455, 557.3117200257678, 579.9739011744482, 647.7052940911968, 663.9535232533773, 8499.575021248913, 8960.924689037358, 9249.953750231205, 9284.210842078364, 9412.529373531295, 552.95299899509, 559.5383808358121, 617.9309794056308, 626.3266987987931, 672.8527859998691, 3767.7174211934216, 4377.409049778239, 4489.88775280614, 4508.256137737302, 4535.070310586388, 79954.62325359246, 104891.0, 121827.0, 125919.0, 129257.35371323083, 69125.3087469129, 103737.0, 103878.0, 105788.0, 109532.0, 86264.13735862686, 93803.0, 94094.0590594099, 96576.51711741397, 98474.0, 7131.750388736369, 7351.301626345488, 8909.331800114887, 8917.509536975529, 9132.269418446376, 57465.42534574768, 57871.0, 58662.70668646629, 59890.0, 61167.69416152891, 57791.84416311566, 69623.0, 73412.0, 73803.0, 75871.0, 10578.523966421375, 12110.091743119478, 12337.876621233672, 12533.0, 12852.807207891701, 13250.801237981244, 13420.530281440102, 14004.859951400764, 14587.85412145865, 14759.188244646648, 12911.870881291066, 13225.272610006543, 13535.187888726708, 13609.387577558835, 14052.297385130498, 12607.180533265308, 12934.050336845157, 12956.157849739737, 13061.934690326867, 13158.60524184276, 12466.376681165724, 12724.681882953, 12738.808917866425, 13042.869571304165, 13510.256935868621, 10811.783764324513, 10836.620718274822, 11299.09607231436, 11313.71715707081, 11693.88306116928, 11326.660200194008, 11408.144389170679, 11554.191206615456, 11613.245139065933, 11617.941910290394], "value": 7331.14957081224, "unit": "score"}], "name": "dromaeo_css", "value": 7173.881002904684, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 115.0, 113.0, 114.0, 115.0, 114.0, 113.0, 114.0, 114.0, 114.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 113.0, 119.0, 181.0, 141.0, 113.0, 116.0, 114.0, 143.0, 112.0], "value": 130.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 181.0, 183.0, 182.0, 187.0, 185.0, 181.0, 182.0, 184.0, 186.0], "value": 183.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 61.0, 74.0, 113.0, 86.0, 53.0, 53.0, 75.0, 108.0, 80.0], "value": 78.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 73.0, 122.0, 100.0, 97.0, 68.0, 89.0, 68.0, 69.0, 92.0], "value": 86.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 85.0, 88.0, 86.0, 88.0, 85.0, 89.0, 84.0], "value": 86.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 96.0, 95.0, 94.0, 95.0, 116.0, 112.0, 95.0, 94.0], "value": 98.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 82.0, 81.0, 79.0, 81.0, 80.0, 85.0, 89.0, 124.0, 123.0], "value": 90.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 53.0, 54.0, 57.0, 58.0, 55.0, 54.0, 54.0, 78.0], "value": 57.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 74.0, 65.0, 76.0, 73.0, 74.0, 70.0, 72.0, 70.0, 71.0], "value": 72.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 82.0, 85.0, 89.0, 89.0, 87.0, 95.0, 102.0, 105.0, 107.0], "value": 92.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 159.0, 159.0, 165.0, 160.0, 166.0, 157.0, 147.0, 188.0, 146.0], "value": 160.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 169.0, 169.0, 169.0, 169.0, 171.0, 170.0, 170.0, 167.0, 167.0], "value": 168.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 68.0, 67.0, 65.0, 66.0, 66.0, 66.0, 66.0], "value": 66.5, "unit": "score"}], "name": "kraken", "value": 1485.5, "alertThreshold": 2.0}]} 16:30:38 INFO - Return code: 0 16:30:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:30:39 ERROR - # TBPL SUCCESS # 16:30:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:30:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:30:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:30:39 INFO - 2016-11-22 16:30:39.061 screenresolution[1650:9862] starting screenresolution argv=screenresolution get 16:30:39 INFO - 2016-11-22 16:30:39.064 screenresolution[1650:9862] Display 0: 1600x1200x32@60 16:30:39 INFO - 2016-11-22 16:30:39.071 screenresolution[1651:9865] starting screenresolution argv=screenresolution list 16:30:39 INFO - Available Modes on Display 0 16:30:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:30:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:30:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:30:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:30:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:30:39 INFO - Intel Iris: 16:30:39 INFO - Chipset Model: Intel Iris 16:30:39 INFO - Type: GPU 16:30:39 INFO - Bus: Built-In 16:30:39 INFO - VRAM (Dynamic, Max): 1536 MB 16:30:39 INFO - Vendor: Intel (0x8086) 16:30:39 INFO - Device ID: 0x0a2e 16:30:39 INFO - Revision ID: 0x0009 16:30:39 INFO - Displays: 16:30:39 INFO - Display: 16:30:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:30:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:30:39 INFO - Display Serial Number: 1600x1200 60 16:30:39 INFO - Main Display: Yes 16:30:39 INFO - Mirror: Off 16:30:39 INFO - Online: Yes 16:30:39 INFO - Rotation: Supported 16:30:39 INFO - Return code: 0 16:30:39 INFO - Running post-action listener: _resource_record_post_action 16:30:39 INFO - [mozharness: 2016-11-23 00:30:39.317559Z] Finished run-tests step (success) 16:30:39 INFO - Running post-run listener: _resource_record_post_run 16:30:39 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 36353024; Write bytes: 654773248; Read time: 235; Write time: 1894 16:30:39 INFO - TinderboxPrint: CPU usage
53.3% 16:30:39 INFO - TinderboxPrint: I/O read bytes / time
36,353,024 / 235 16:30:39 INFO - TinderboxPrint: I/O write bytes / time
654,773,248 / 1,894 16:30:39 INFO - TinderboxPrint: CPU idle
2,265.3 (46.7%) 16:30:39 INFO - TinderboxPrint: CPU system
971.4 (20.0%) 16:30:39 INFO - TinderboxPrint: CPU user
1,616.9 (33.3%) 16:30:39 INFO - TinderboxPrint: Swap in / out
384,901,120 / 0 16:30:39 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 343515648; Write bytes: 347031552; Read time: 26884; Write time: 753 16:30:39 INFO - run-tests - Wall time: 1183s; CPU: 53.0%; Read bytes: 34567168; Write bytes: 300835840; Read time: 220; Write time: 1113 16:30:39 INFO - Running post-run listener: _upload_blobber_files 16:30:39 INFO - Blob upload gear active. 16:30:39 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:30: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 16:30: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'] 16:30: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 16:30:40 INFO - (blobuploader) - INFO - Open directory for files ... 16:30:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:30:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:30:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:30:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:30:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:30:41 INFO - (blobuploader) - INFO - Done attempting. 16:30:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:30:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:30:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:30:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:30:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:30:42 INFO - (blobuploader) - INFO - Done attempting. 16:30:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:30:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:30:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:30:44 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:30:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:30:44 INFO - (blobuploader) - INFO - Done attempting. 16:30:44 INFO - (blobuploader) - INFO - Iteration through files over. 16:30:44 INFO - Return code: 0 16:30:44 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:30:44 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:30:44 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/abfd03715698075dc4ba87cf931804d78003486afd740975015e5ff6bb6258184ba7539485faf9c715b6cefc576c462bd5ba635184b472143ee0381e47a4f734", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/40ba74bea1a71d156eeabee0205a4c1e1ebf23e7f093d5c4e20de249e712c848253f4bcee69316167e08dbaa74652ff937d072cbf57de3429912d88cabd12703", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6a49e724aaa8d944275738b642fe1f448a145ea7a42fcb799cc250cc18b7aebfdbe82a8e572f6e72f5b99beb05e94f0d9c0d3ba5f6bb3dbb650dbfec73cd6e95"} 16:30:44 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:30:44 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:30:44 INFO - Contents: 16:30:44 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/abfd03715698075dc4ba87cf931804d78003486afd740975015e5ff6bb6258184ba7539485faf9c715b6cefc576c462bd5ba635184b472143ee0381e47a4f734", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/40ba74bea1a71d156eeabee0205a4c1e1ebf23e7f093d5c4e20de249e712c848253f4bcee69316167e08dbaa74652ff937d072cbf57de3429912d88cabd12703", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6a49e724aaa8d944275738b642fe1f448a145ea7a42fcb799cc250cc18b7aebfdbe82a8e572f6e72f5b99beb05e94f0d9c0d3ba5f6bb3dbb650dbfec73cd6e95"} 16:30:44 INFO - Running post-run listener: copy_logs_to_upload_dir 16:30:44 INFO - Copying logs to upload dir... 16:30:44 INFO - mkdir: /builds/slave/test/build/upload/logs 16:30:44 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.049828 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-11-22 16:30:44.379411) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-22 16:30:44.382872) ========= 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.KxnlVYIBXz/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.gNwKrvOas0/Listeners TMPDIR=/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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/abfd03715698075dc4ba87cf931804d78003486afd740975015e5ff6bb6258184ba7539485faf9c715b6cefc576c462bd5ba635184b472143ee0381e47a4f734", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/40ba74bea1a71d156eeabee0205a4c1e1ebf23e7f093d5c4e20de249e712c848253f4bcee69316167e08dbaa74652ff937d072cbf57de3429912d88cabd12703", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6a49e724aaa8d944275738b642fe1f448a145ea7a42fcb799cc250cc18b7aebfdbe82a8e572f6e72f5b99beb05e94f0d9c0d3ba5f6bb3dbb650dbfec73cd6e95"} build_url:https://queue.taskcluster.net/v1/task/J0hs4HbOTDeILN4mAGEWeA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011156 build_url: 'https://queue.taskcluster.net/v1/task/J0hs4HbOTDeILN4mAGEWeA/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/abfd03715698075dc4ba87cf931804d78003486afd740975015e5ff6bb6258184ba7539485faf9c715b6cefc576c462bd5ba635184b472143ee0381e47a4f734", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/40ba74bea1a71d156eeabee0205a4c1e1ebf23e7f093d5c4e20de249e712c848253f4bcee69316167e08dbaa74652ff937d072cbf57de3429912d88cabd12703", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6a49e724aaa8d944275738b642fe1f448a145ea7a42fcb799cc250cc18b7aebfdbe82a8e572f6e72f5b99beb05e94f0d9c0d3ba5f6bb3dbb650dbfec73cd6e95"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-22 16:30:44.417899) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 16:30:44.418212) ========= 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.KxnlVYIBXz/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.gNwKrvOas0/Listeners TMPDIR=/var/folders/1b/b370xjvx7_vbkyyr8hgyjzcm00000w/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.005083 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-22 16:30:44.466750) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-22 16:30:44.467089) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-22 16:30:44.467432) ========= ========= Total master_lag: 0.18 =========