builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0077 starttime: 1482423710.23 results: success (0) buildid: 20161222064453 builduid: 5cc3aee5628a433c8971e7e733605130 revision: a368c15c83ace70e2e9a95a308a876e7689b4a8c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-22 08:21:50.226696) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-22 08:21:50.227157) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-22 08:21:50.227430) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-22 08:21:50.251681) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 08:21:50.252223) ========= 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.OnNeXKEvGn/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.i9FjvePrvC/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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-12-22 08:21:50-- 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% 43.7M=0s 2016-12-22 08:21:50 (43.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.148371 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 08:21:50.424712) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 08:21:50.425126) ========= 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.OnNeXKEvGn/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.i9FjvePrvC/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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.019604 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 08:21:50.473890) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-12-22 08:21:50.474249) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev a368c15c83ace70e2e9a95a308a876e7689b4a8c --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 a368c15c83ace70e2e9a95a308a876e7689b4a8c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.OnNeXKEvGn/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.i9FjvePrvC/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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-12-22 08:21:50,549 truncating revision to first 12 chars 2016-12-22 08:21:50,550 Setting DEBUG logging. 2016-12-22 08:21:50,550 attempt 1/10 2016-12-22 08:21:50,550 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/a368c15c83ac?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-22 08:21:51,630 unpacking tar archive at: mozilla-aurora-a368c15c83ac/testing/mozharness/ program finished with exit code 0 elapsedTime=1.345797 ========= master_lag: 2.11 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-12-22 08:21:53.928878) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-22 08:21:53.929228) ========= script_repo_revision: a368c15c83ace70e2e9a95a308a876e7689b4a8c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-22 08:21:53.929620) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-22 08:21:53.929887) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-22 08:21:53.952599) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-12-22 08:21:53.952957) ========= /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.OnNeXKEvGn/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.i9FjvePrvC/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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 08:21:54 INFO - MultiFileLogger online at 20161222 08:21:54 in /builds/slave/test 08:21:54 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 08:21:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:21:54 INFO - {'append_to_log': False, 08:21:54 INFO - 'base_work_dir': '/builds/slave/test', 08:21:54 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:21:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:21:54 INFO - 'branch': 'Mozilla-Aurora', 08:21:54 INFO - 'buildbot_json_path': 'buildprops.json', 08:21:54 INFO - 'config_files': ('talos/mac_config.py',), 08:21:54 INFO - 'default_actions': ('clobber', 08:21:54 INFO - 'read-buildbot-config', 08:21:54 INFO - 'download-and-extract', 08:21:54 INFO - 'populate-webroot', 08:21:54 INFO - 'create-virtualenv', 08:21:54 INFO - 'install', 08:21:54 INFO - 'run-tests'), 08:21:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:21:54 INFO - 'download_minidump_stackwalk': True, 08:21:54 INFO - 'download_symbols': 'ondemand', 08:21:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:21:54 INFO - 'tooltool.py': '/tools/tooltool.py', 08:21:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:21:54 INFO - '/tools/misc-python/virtualenv.py')}, 08:21:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:21:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:21:54 INFO - 'installer_path': 'installer.exe', 08:21:54 INFO - 'log_level': 'info', 08:21:54 INFO - 'log_name': 'talos', 08:21:54 INFO - 'log_to_console': True, 08:21:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:21:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:21:54 INFO - 'opt_config_files': (), 08:21:54 INFO - 'pip_index': False, 08:21:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:21:54 INFO - 'cmd': ('bash', 08:21:54 INFO - '-c', 08:21:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:21:54 INFO - 'enabled': True, 08:21:54 INFO - 'halt_on_failure': False, 08:21:54 INFO - 'name': 'check_screen_resolution'},), 08:21:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:21:54 INFO - 'cmd': ('bash', 08:21:54 INFO - '-c', 08:21:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:21:54 INFO - 'enabled': True, 08:21:54 INFO - 'halt_on_failure': False, 08:21:54 INFO - 'name': 'check_screen_resolution'},), 08:21:54 INFO - 'run_cmd_checks_enabled': True, 08:21:54 INFO - 'sps_profile': False, 08:21:54 INFO - 'sps_profile_interval': 0, 08:21:54 INFO - 'suite': 'dromaeojs', 08:21:54 INFO - 'system_bits': '32', 08:21:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:21:54 INFO - 'title': 't-yosemite-r7-0077', 08:21:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:21:54 INFO - 'use_talos_json': True, 08:21:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:21:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:21:54 INFO - 'work_dir': 'build'} 08:21:54 INFO - [mozharness: 2016-12-22 16:21:54.151672Z] Running clobber step. 08:21:54 INFO - Running pre-action listener: _resource_record_pre_action 08:21:54 INFO - Running main action method: clobber 08:21:54 INFO - rmtree: /builds/slave/test/build 08:21:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:21:56 INFO - Running post-action listener: _resource_record_post_action 08:21:56 INFO - [mozharness: 2016-12-22 16:21:56.231008Z] Finished clobber step (success) 08:21:56 INFO - [mozharness: 2016-12-22 16:21:56.231287Z] Running read-buildbot-config step. 08:21:56 INFO - Running pre-action listener: _resource_record_pre_action 08:21:56 INFO - Running main action method: read_buildbot_config 08:21:56 INFO - Using buildbot properties: 08:21:56 INFO - { 08:21:56 INFO - "project": "", 08:21:56 INFO - "product": "firefox", 08:21:56 INFO - "script_repo_revision": "production", 08:21:56 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 08:21:56 INFO - "repository": "", 08:21:56 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 08:21:56 INFO - "buildid": "20161222064453", 08:21:56 INFO - "pgo_build": "False", 08:21:56 INFO - "basedir": "/builds/slave/test", 08:21:56 INFO - "buildnumber": 0, 08:21:56 INFO - "slavename": "t-yosemite-r7-0077", 08:21:56 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 08:21:56 INFO - "platform": "yosemite_r7", 08:21:56 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:21:56 INFO - "branch": "mozilla-aurora", 08:21:56 INFO - "repo_path": "releases/mozilla-aurora", 08:21:56 INFO - "revision": "a368c15c83ace70e2e9a95a308a876e7689b4a8c", 08:21:56 INFO - "stage_platform": "macosx64", 08:21:56 INFO - "builduid": "5cc3aee5628a433c8971e7e733605130", 08:21:56 INFO - "slavebuilddir": "test" 08:21:56 INFO - } 08:21:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/AQmIoI9GT3GGWNJAfdvCbA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 08:21:56 INFO - Running post-action listener: _resource_record_post_action 08:21:56 INFO - [mozharness: 2016-12-22 16:21:56.236107Z] Finished read-buildbot-config step (success) 08:21:56 INFO - [mozharness: 2016-12-22 16:21:56.236207Z] Running download-and-extract step. 08:21:56 INFO - Running pre-action listener: _resource_record_pre_action 08:21:56 INFO - Running main action method: download_and_extract 08:21:56 INFO - mkdir: /builds/slave/test/build/tests 08:21: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')]} 08:21:56 INFO - trying https://queue.taskcluster.net/v1/task/AQmIoI9GT3GGWNJAfdvCbA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 08:21:56 INFO - Downloading https://queue.taskcluster.net/v1/task/AQmIoI9GT3GGWNJAfdvCbA/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 08:21:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AQmIoI9GT3GGWNJAfdvCbA/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 08:21:57 INFO - Downloaded 1199 bytes. 08:21:57 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:21:57 INFO - Using the following test package requirements: 08:21:57 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:21:57 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:21:57 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 08:21:57 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:21:57 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 08:21:57 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:21:57 INFO - u'jsshell-mac.zip'], 08:21:57 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:21:57 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 08:21:57 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:21:57 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:21:57 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 08:21:57 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:21:57 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 08:21:57 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:21:57 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 08:21:57 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:21:57 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 08:21:57 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:21:57 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AQmIoI9GT3GGWNJAfdvCbA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:21:57 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AQmIoI9GT3GGWNJAfdvCbA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:21:57 INFO - Fetch https://queue.taskcluster.net/v1/task/AQmIoI9GT3GGWNJAfdvCbA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:21:57 INFO - Http code: 200 08:21:57 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront) 08:21:57 INFO - x-amz-cf-id: 4QBoBKF_tD0yJXYiQjQkoWPSfADrPE8tT_hsH38BW1Rs1oxO3gpjEQ== 08:21:57 INFO - x-amz-version-id: bnhGpajMPy1FloDI5b6Fbm7CN_Mw.hMT 08:21:58 INFO - Expected file size: 16270630 08:21:58 INFO - Obtained file size: 16270630 08:22:04 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 08:22:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AQmIoI9GT3GGWNJAfdvCbA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:22:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AQmIoI9GT3GGWNJAfdvCbA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:22:04 INFO - Fetch https://queue.taskcluster.net/v1/task/AQmIoI9GT3GGWNJAfdvCbA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:22:05 INFO - Http code: 200 08:22:05 INFO - via: 1.1 93ab344e1a3fa44edbf3f1bc5729427a.cloudfront.net (CloudFront) 08:22:05 INFO - x-amz-cf-id: oQdeKwz-fcQx1X0zWQN6_4PpKw4inSlZq1sptZAhZM2W7CW556NbyQ== 08:22:05 INFO - x-amz-version-id: bnhGpajMPy1FloDI5b6Fbm7CN_Mw.hMT 08:22:05 INFO - Expected file size: 16270630 08:22:05 INFO - Obtained file size: 16270630 08:22:11 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/AQmIoI9GT3GGWNJAfdvCbA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 08:22:11 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/AQmIoI9GT3GGWNJAfdvCbA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:22:11 INFO - Fetch https://queue.taskcluster.net/v1/task/AQmIoI9GT3GGWNJAfdvCbA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 08:22:12 INFO - Http code: 200 08:22:12 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 08:22:12 INFO - x-amz-cf-id: Ok5wdtlgEP5FNnQ7-PQYTUMg3w3z8wmswODtiTYKOqF0okbB0mrYaQ== 08:22:12 INFO - x-amz-version-id: VZpN84I6T9aLpaibGX5M4dfJgIYPpK5k 08:22:12 INFO - Expected file size: 14409038 08:22:12 INFO - Obtained file size: 14409038 08:22: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')]} 08:22:13 INFO - trying https://queue.taskcluster.net/v1/task/AQmIoI9GT3GGWNJAfdvCbA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:22:13 INFO - Downloading https://queue.taskcluster.net/v1/task/AQmIoI9GT3GGWNJAfdvCbA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 08:22:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/AQmIoI9GT3GGWNJAfdvCbA/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 08:22:15 INFO - Downloaded 109761738 bytes. 08:22:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/AQmIoI9GT3GGWNJAfdvCbA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:22:15 INFO - mkdir: /builds/slave/test/properties 08:22:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:22:15 INFO - Writing to file /builds/slave/test/properties/build_url 08:22:15 INFO - Contents: 08:22:15 INFO - build_url:https://queue.taskcluster.net/v1/task/AQmIoI9GT3GGWNJAfdvCbA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:22:17 INFO - Running post-action listener: _resource_record_post_action 08:22:17 INFO - Running post-action listener: set_extra_try_arguments 08:22:17 INFO - [mozharness: 2016-12-22 16:22:17.056997Z] Finished download-and-extract step (success) 08:22:17 INFO - [mozharness: 2016-12-22 16:22:17.057140Z] Running populate-webroot step. 08:22:17 INFO - Running pre-action listener: _resource_record_pre_action 08:22:17 INFO - Running main action method: populate_webroot 08:22:17 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:22:17 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:22:17 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:22:17 INFO - '--tppagecycles', 08:22:17 INFO - '7'], 08:22:17 INFO - 'tests': ['tsvgm']}}, 08:22:17 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:22:17 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:22:17 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:22:17 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:22:17 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:22:17 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:22:17 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:22:17 INFO - 'talos_options': ['--disable-e10s'], 08:22:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:22:17 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:22:17 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:22:17 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:22:17 INFO - 'talos_options': ['--disable-e10s'], 08:22:17 INFO - 'tests': ['damp', 'tps']}, 08:22:17 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:22:17 INFO - 'tests': ['damp', 'tps']}, 08:22:17 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:22:17 INFO - 'tests': ['dromaeo_dom']}, 08:22:17 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:22:17 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:22:17 INFO - 'tests': ['basic_compositor_video']}, 08:22:17 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:22:17 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:22:17 INFO - 'tests': ['a11yr', 08:22:17 INFO - 'ts_paint', 08:22:17 INFO - 'tpaint', 08:22:17 INFO - 'sessionrestore', 08:22:17 INFO - 'sessionrestore_no_auto_restore', 08:22:17 INFO - 'tabpaint']}, 08:22:17 INFO - 'other-e10s': {'tests': ['a11yr', 08:22:17 INFO - 'ts_paint', 08:22:17 INFO - 'tpaint', 08:22:17 INFO - 'sessionrestore', 08:22:17 INFO - 'sessionrestore_no_auto_restore', 08:22:17 INFO - 'tabpaint']}, 08:22:17 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:22:17 INFO - 'ts_paint', 08:22:17 INFO - 'tpaint', 08:22:17 INFO - 'sessionrestore', 08:22:17 INFO - 'sessionrestore_no_auto_restore', 08:22:17 INFO - 'tabpaint']}, 08:22:17 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:22:17 INFO - 'ts_paint', 08:22:17 INFO - 'tpaint', 08:22:17 INFO - 'sessionrestore', 08:22:17 INFO - 'sessionrestore_no_auto_restore', 08:22:17 INFO - 'tabpaint']}, 08:22:17 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:22:17 INFO - 'tests': ['a11yr', 08:22:17 INFO - 'ts_paint', 08:22:17 INFO - 'tpaint', 08:22:17 INFO - 'sessionrestore', 08:22:17 INFO - 'sessionrestore_no_auto_restore', 08:22:17 INFO - 'tabpaint']}, 08:22:17 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:22:17 INFO - 'tests': ['a11yr', 08:22:17 INFO - 'ts_paint', 08:22:17 INFO - 'tpaint', 08:22:17 INFO - 'sessionrestore', 08:22:17 INFO - 'sessionrestore_no_auto_restore', 08:22:17 INFO - 'tabpaint']}, 08:22:17 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:22:17 INFO - 'tests': ['tsvgx', 08:22:17 INFO - 'tsvgr_opacity', 08:22:17 INFO - 'tart', 08:22:17 INFO - 'tscrollx', 08:22:17 INFO - 'cart']}, 08:22:17 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:22:17 INFO - 'tsvgr_opacity', 08:22:17 INFO - 'tart', 08:22:17 INFO - 'tscrollx', 08:22:17 INFO - 'cart']}, 08:22:17 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:22:17 INFO - 'talos_options': ['--disable-e10s'], 08:22:17 INFO - 'tests': ['tp5o']}, 08:22:17 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:22:17 INFO - 'tests': ['tp5o']}, 08:22:17 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:22:17 INFO - 'talos_options': ['--disable-e10s', 08:22:17 INFO - '--xperf_path', 08:22:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:22:17 INFO - 'tests': ['tp5n']}, 08:22:17 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:22:17 INFO - 'talos_options': ['--xperf_path', 08:22:17 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:22:17 INFO - 'tests': ['tp5n']}}, 08:22:17 INFO - 'talos.zip': {'path': '', 08:22:17 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:22:17 INFO - Running post-action listener: _resource_record_post_action 08:22:17 INFO - [mozharness: 2016-12-22 16:22:17.076075Z] Finished populate-webroot step (success) 08:22:17 INFO - [mozharness: 2016-12-22 16:22:17.076175Z] Running create-virtualenv step. 08:22:17 INFO - Running pre-action listener: _resource_record_pre_action 08:22:17 INFO - Running main action method: create_virtualenv 08:22:17 INFO - Creating virtualenv /builds/slave/test/build/venv 08:22:17 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 08:22:17 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:22:17 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:22:17 INFO - Using real prefix '/tools/python27' 08:22:17 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:22:17 INFO - Installing distribute.............................................................................................................................................................................................done. 08:22:20 INFO - Installing pip.................done. 08:22:20 INFO - Return code: 0 08:22:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:22:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:22:21 INFO - Reading from file tmpfile_stdout 08:22:21 INFO - Output received: 08:22:21 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) 08:22:21 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:22:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:22:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:22:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:22:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:22:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:21 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 0x1040bc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104032570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd11bca2590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ed0d30>, '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 0x103d36db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ec8b20>, '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.OnNeXKEvGn/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.i9FjvePrvC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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 08:22:21 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 08:22:21 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 08:22:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OnNeXKEvGn/Render', 08:22:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:22:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:22:21 INFO - 'HOME': '/Users/cltbld', 08:22:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:22:21 INFO - 'LOGNAME': 'cltbld', 08:22:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:22:21 INFO - 'MOZ_NO_REMOTE': '1', 08:22:21 INFO - 'NO_EM_RESTART': '1', 08:22:21 INFO - 'PAGER': '/bin/cat', 08:22:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:22:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:22:21 INFO - 'PWD': '/builds/slave/test', 08:22:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:22:21 INFO - 'SHELL': '/bin/bash', 08:22:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i9FjvePrvC/Listeners', 08:22:21 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 08:22:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:22:21 INFO - 'USER': 'cltbld', 08:22:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:22:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:22:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:22:21 INFO - 'XPC_FLAGS': '0x0', 08:22:21 INFO - 'XPC_SERVICE_NAME': '0', 08:22:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:22:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:22:21 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 08:22:21 INFO - Cleaning up... 08:22:21 INFO - Return code: 0 08:22:21 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:22:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:22:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:22:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:22:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:22:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:21 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 0x1040bc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104032570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd11bca2590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ed0d30>, '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 0x103d36db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ec8b20>, '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.OnNeXKEvGn/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.i9FjvePrvC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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 08:22:21 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 08:22:21 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 08:22:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OnNeXKEvGn/Render', 08:22:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:22:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:22:21 INFO - 'HOME': '/Users/cltbld', 08:22:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:22:21 INFO - 'LOGNAME': 'cltbld', 08:22:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:22:21 INFO - 'MOZ_NO_REMOTE': '1', 08:22:21 INFO - 'NO_EM_RESTART': '1', 08:22:21 INFO - 'PAGER': '/bin/cat', 08:22:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:22:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:22:21 INFO - 'PWD': '/builds/slave/test', 08:22:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:22:21 INFO - 'SHELL': '/bin/bash', 08:22:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i9FjvePrvC/Listeners', 08:22:21 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 08:22:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:22:21 INFO - 'USER': 'cltbld', 08:22:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:22:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:22:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:22:21 INFO - 'XPC_FLAGS': '0x0', 08:22:21 INFO - 'XPC_SERVICE_NAME': '0', 08:22:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:22:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:22:21 INFO - Downloading/unpacking psutil>=3.1.1 08:22:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:22:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:22:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:22:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:22:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:22:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:22:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:22:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:22:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:22:25 INFO - Installing collected packages: psutil 08:22:25 INFO - Running setup.py install for psutil 08:22:25 INFO - building 'psutil._psutil_osx' extension 08:22:25 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 08:22:25 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 08:22:25 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 08:22:25 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 08:22:25 INFO - building 'psutil._psutil_posix' extension 08:22:25 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 08:22:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:22:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:22:25 INFO - ^ 08:22:25 INFO - 1 warning generated. 08:22:25 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 08:22:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:22:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:22:25 INFO - Successfully installed psutil 08:22:25 INFO - Cleaning up... 08:22:26 INFO - Return code: 0 08:22:26 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:22:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:22:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:22:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:22:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:22:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1040bc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104032570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd11bca2590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ed0d30>, '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 0x103d36db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ec8b20>, '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.OnNeXKEvGn/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.i9FjvePrvC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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 08:22:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:22:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:22:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OnNeXKEvGn/Render', 08:22:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:22:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:22:26 INFO - 'HOME': '/Users/cltbld', 08:22:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:22:26 INFO - 'LOGNAME': 'cltbld', 08:22:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:22:26 INFO - 'MOZ_NO_REMOTE': '1', 08:22:26 INFO - 'NO_EM_RESTART': '1', 08:22:26 INFO - 'PAGER': '/bin/cat', 08:22:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:22:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:22:26 INFO - 'PWD': '/builds/slave/test', 08:22:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:22:26 INFO - 'SHELL': '/bin/bash', 08:22:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i9FjvePrvC/Listeners', 08:22:26 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 08:22:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:22:26 INFO - 'USER': 'cltbld', 08:22:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:22:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:22:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:22:26 INFO - 'XPC_FLAGS': '0x0', 08:22:26 INFO - 'XPC_SERVICE_NAME': '0', 08:22:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:22:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:22:26 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:22:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:22:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:22:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:22:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:22:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:22:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:22:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:22:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:22:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:22:29 INFO - Installing collected packages: mozsystemmonitor 08:22:29 INFO - Running setup.py install for mozsystemmonitor 08:22:29 INFO - Successfully installed mozsystemmonitor 08:22:29 INFO - Cleaning up... 08:22:29 INFO - Return code: 0 08:22:29 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:22:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:22:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:22:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:22:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:22:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1040bc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104032570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd11bca2590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ed0d30>, '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 0x103d36db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ec8b20>, '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.OnNeXKEvGn/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.i9FjvePrvC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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 08:22:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:22:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:22:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OnNeXKEvGn/Render', 08:22:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:22:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:22:29 INFO - 'HOME': '/Users/cltbld', 08:22:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:22:29 INFO - 'LOGNAME': 'cltbld', 08:22:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:22:29 INFO - 'MOZ_NO_REMOTE': '1', 08:22:29 INFO - 'NO_EM_RESTART': '1', 08:22:29 INFO - 'PAGER': '/bin/cat', 08:22:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:22:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:22:29 INFO - 'PWD': '/builds/slave/test', 08:22:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:22:29 INFO - 'SHELL': '/bin/bash', 08:22:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i9FjvePrvC/Listeners', 08:22:29 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 08:22:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:22:29 INFO - 'USER': 'cltbld', 08:22:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:22:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:22:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:22:29 INFO - 'XPC_FLAGS': '0x0', 08:22:29 INFO - 'XPC_SERVICE_NAME': '0', 08:22:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:22:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:22:29 INFO - Downloading/unpacking jsonschema==2.5.1 08:22:29 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 08:22:29 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 08:22:29 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 08:22:29 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 08:22:29 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 08:22:29 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 08:22:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:22:32 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:22:32 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 08:22:32 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 08:22:32 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 08:22:32 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 08:22:32 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 08:22:32 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 08:22:32 INFO - Downloading functools32-3.2.3-2.tar.gz 08:22:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:22:32 INFO - warning: no files found matching '*.txt' 08:22:32 INFO - no previously-included directories found matching 'build' 08:22:32 INFO - no previously-included directories found matching 'dist' 08:22:32 INFO - no previously-included directories found matching '.git*' 08:22:32 INFO - Installing collected packages: jsonschema, functools32 08:22:32 INFO - Running setup.py install for functools32 08:22:33 INFO - warning: no files found matching '*.txt' 08:22:33 INFO - no previously-included directories found matching 'build' 08:22:33 INFO - no previously-included directories found matching 'dist' 08:22:33 INFO - no previously-included directories found matching '.git*' 08:22:33 INFO - Successfully installed jsonschema functools32 08:22:33 INFO - Cleaning up... 08:22:33 INFO - Return code: 0 08:22:33 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:22:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:22:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:22:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:22:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:22:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:33 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 0x1040bc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104032570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd11bca2590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ed0d30>, '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 0x103d36db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ec8b20>, '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.OnNeXKEvGn/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.i9FjvePrvC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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 08:22:33 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 08:22:33 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 08:22:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OnNeXKEvGn/Render', 08:22:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:22:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:22:33 INFO - 'HOME': '/Users/cltbld', 08:22:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:22:33 INFO - 'LOGNAME': 'cltbld', 08:22:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:22:33 INFO - 'MOZ_NO_REMOTE': '1', 08:22:33 INFO - 'NO_EM_RESTART': '1', 08:22:33 INFO - 'PAGER': '/bin/cat', 08:22:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:22:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:22:33 INFO - 'PWD': '/builds/slave/test', 08:22:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:22:33 INFO - 'SHELL': '/bin/bash', 08:22:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i9FjvePrvC/Listeners', 08:22:33 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 08:22:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:22:33 INFO - 'USER': 'cltbld', 08:22:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:22:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:22:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:22:33 INFO - 'XPC_FLAGS': '0x0', 08:22:33 INFO - 'XPC_SERVICE_NAME': '0', 08:22:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:22:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:22:33 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:22:33 INFO - Cleaning up... 08:22:33 INFO - Return code: 0 08:22:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:22:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:22:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:22:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:22:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:22:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:33 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 0x1040bc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104032570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd11bca2590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ed0d30>, '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 0x103d36db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ec8b20>, '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.OnNeXKEvGn/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.i9FjvePrvC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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 08:22:33 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 08:22:33 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 08:22:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OnNeXKEvGn/Render', 08:22:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:22:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:22:33 INFO - 'HOME': '/Users/cltbld', 08:22:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:22:33 INFO - 'LOGNAME': 'cltbld', 08:22:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:22:33 INFO - 'MOZ_NO_REMOTE': '1', 08:22:33 INFO - 'NO_EM_RESTART': '1', 08:22:33 INFO - 'PAGER': '/bin/cat', 08:22:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:22:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:22:33 INFO - 'PWD': '/builds/slave/test', 08:22:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:22:33 INFO - 'SHELL': '/bin/bash', 08:22:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i9FjvePrvC/Listeners', 08:22:33 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 08:22:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:22:33 INFO - 'USER': 'cltbld', 08:22:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:22:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:22:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:22:33 INFO - 'XPC_FLAGS': '0x0', 08:22:33 INFO - 'XPC_SERVICE_NAME': '0', 08:22:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:22:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:22:33 INFO - Downloading/unpacking blobuploader==1.2.4 08:22:33 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 08:22:33 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 08:22:33 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 08:22:33 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 08:22:33 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 08:22:33 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 08:22:36 INFO - Downloading blobuploader-1.2.4.tar.gz 08:22:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:22:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:22:36 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 08:22:36 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 08:22:36 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 08:22:36 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 08:22:36 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 08:22:36 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 08:22:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:22:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:22:37 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 08:22:37 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 08:22:37 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 08:22:37 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 08:22:37 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 08:22:37 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 08:22:37 INFO - Downloading docopt-0.6.1.tar.gz 08:22:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:22:37 INFO - Installing collected packages: blobuploader, requests, docopt 08:22:37 INFO - Running setup.py install for blobuploader 08:22:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:22:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:22:37 INFO - Running setup.py install for requests 08:22:38 INFO - Running setup.py install for docopt 08:22:38 INFO - Successfully installed blobuploader requests docopt 08:22:38 INFO - Cleaning up... 08:22:38 INFO - Return code: 0 08:22:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:22:38 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')]} 08:22:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:22:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:38 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')]} 08:22:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:22:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:38 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 0x1040bc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104032570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd11bca2590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ed0d30>, '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 0x103d36db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ec8b20>, '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.OnNeXKEvGn/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.i9FjvePrvC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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 08:22:38 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 08:22:38 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 08:22:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OnNeXKEvGn/Render', 08:22:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:22:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:22:38 INFO - 'HOME': '/Users/cltbld', 08:22:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:22:38 INFO - 'LOGNAME': 'cltbld', 08:22:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:22:38 INFO - 'MOZ_NO_REMOTE': '1', 08:22:38 INFO - 'NO_EM_RESTART': '1', 08:22:38 INFO - 'PAGER': '/bin/cat', 08:22:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:22:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:22:38 INFO - 'PWD': '/builds/slave/test', 08:22:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:22:38 INFO - 'SHELL': '/bin/bash', 08:22:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i9FjvePrvC/Listeners', 08:22:38 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 08:22:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:22:38 INFO - 'USER': 'cltbld', 08:22:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:22:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:22:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:22:38 INFO - 'XPC_FLAGS': '0x0', 08:22:38 INFO - 'XPC_SERVICE_NAME': '0', 08:22:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:22:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:22:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:22:38 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-t9bZUM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:22:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:22:38 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-l5t18K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:22:39 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-u6Mogp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:22:39 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-wAQugn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:22:39 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-JN06Ov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:22:39 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-H5h_xM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:22:39 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-_DueE1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:22:39 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-wynzf0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:22:39 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-uErS41-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:22:39 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-WFhL5Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:22:39 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-TN6XSB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:22:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:22:39 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-gJfVOy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:22:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:22:40 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-5zKsOG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:22:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:22:40 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-c4Qdtq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:22:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:22:40 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-D2qokz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:22:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:22:40 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-yu1h1p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:22:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:22:40 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-oLz4T8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:22:40 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:22:40 INFO - Running setup.py install for manifestparser 08:22:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:22:40 INFO - Running setup.py install for mozcrash 08:22:40 INFO - Running setup.py install for mozdebug 08:22:41 INFO - Running setup.py install for mozdevice 08:22:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:22:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:22:41 INFO - Running setup.py install for mozfile 08:22:41 INFO - Running setup.py install for mozhttpd 08:22:41 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:22:41 INFO - Running setup.py install for mozinfo 08:22:41 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:22:41 INFO - Running setup.py install for mozInstall 08:22:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:22:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:22:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:22:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:22:41 INFO - Running setup.py install for mozleak 08:22:41 INFO - Running setup.py install for mozlog 08:22:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:22:42 INFO - Running setup.py install for moznetwork 08:22:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:22:42 INFO - Running setup.py install for mozprocess 08:22:42 INFO - Running setup.py install for mozprofile 08:22:42 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:22:42 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:22:42 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:22:42 INFO - Running setup.py install for mozrunner 08:22:42 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:22:42 INFO - Running setup.py install for mozscreenshot 08:22:42 INFO - Running setup.py install for moztest 08:22:43 INFO - Running setup.py install for mozversion 08:22:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:22:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:22:43 INFO - Cleaning up... 08:22:43 INFO - Return code: 0 08:22:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:22:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:22:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:22:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:22:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:22:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:43 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 0x1040bc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104032570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd11bca2590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ed0d30>, '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 0x103d36db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ec8b20>, '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.OnNeXKEvGn/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.i9FjvePrvC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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 08:22:43 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 08:22:43 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 08:22:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OnNeXKEvGn/Render', 08:22:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:22:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:22:43 INFO - 'HOME': '/Users/cltbld', 08:22:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:22:43 INFO - 'LOGNAME': 'cltbld', 08:22:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:22:43 INFO - 'MOZ_NO_REMOTE': '1', 08:22:43 INFO - 'NO_EM_RESTART': '1', 08:22:43 INFO - 'PAGER': '/bin/cat', 08:22:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:22:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:22:43 INFO - 'PWD': '/builds/slave/test', 08:22:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:22:43 INFO - 'SHELL': '/bin/bash', 08:22:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i9FjvePrvC/Listeners', 08:22:43 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 08:22:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:22:43 INFO - 'USER': 'cltbld', 08:22:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:22:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:22:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:22:43 INFO - 'XPC_FLAGS': '0x0', 08:22:43 INFO - 'XPC_SERVICE_NAME': '0', 08:22:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:22:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:22:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:22:43 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-zmoPmm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:22:43 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)) 08:22:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:22:43 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-Ds90fZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:22:43 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)) 08:22:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:22:43 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-1L4Tol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:22:43 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)) 08:22:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:22:43 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-3DLXQ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:22:43 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)) 08:22:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:22:43 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-uYCAJw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:22:44 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)) 08:22:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:22:44 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-pPM1fd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:22:44 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)) 08:22:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:22:44 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-uuU2Xp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:22:44 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)) 08:22:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:22:44 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-R_H7rb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:22:44 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)) 08:22:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:22:44 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-gN_MH3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:22:44 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)) 08:22:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:22:44 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-6YhPwV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:22:44 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)) 08:22:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:22:44 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-I4rKci-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:22:44 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)) 08:22:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:22:44 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-_8fJyo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:22:44 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)) 08:22:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:22:44 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-x0R6pm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:22:44 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)) 08:22:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:22:44 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-txbAiZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:22:45 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)) 08:22:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:22:45 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-XWFQvz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:22:45 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)) 08:22:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:22:45 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-2jKiZN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:22:45 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)) 08:22:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:22:45 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-4rmKeY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:22:45 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)) 08:22:45 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)) 08:22:45 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)) 08:22:45 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)) 08:22:45 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)) 08:22:45 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)) 08:22:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:22:45 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 08:22:45 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 08:22:45 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 08:22:45 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 08:22:45 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 08:22:45 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 08:22:47 INFO - Downloading blessings-1.6.tar.gz 08:22:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:22:47 INFO - Installing collected packages: blessings 08:22:47 INFO - Running setup.py install for blessings 08:22:48 INFO - Successfully installed blessings 08:22:48 INFO - Cleaning up... 08:22:48 INFO - Return code: 0 08:22:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:22:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:22:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:22:48 INFO - Reading from file tmpfile_stdout 08:22:48 INFO - Current package versions: 08:22:48 INFO - blessings == 1.6 08:22:48 INFO - blobuploader == 1.2.4 08:22:48 INFO - docopt == 0.6.1 08:22:48 INFO - functools32 == 3.2.3-2 08:22:48 INFO - jsonschema == 2.5.1 08:22:48 INFO - manifestparser == 1.1 08:22:48 INFO - mozInstall == 1.12 08:22:48 INFO - mozcrash == 1.0 08:22:48 INFO - mozdebug == 0.1 08:22:48 INFO - mozdevice == 0.48 08:22:48 INFO - mozfile == 1.2 08:22:48 INFO - mozhttpd == 0.7 08:22:48 INFO - mozinfo == 0.9 08:22:48 INFO - mozleak == 0.1 08:22:48 INFO - mozlog == 3.4 08:22:48 INFO - moznetwork == 0.27 08:22:48 INFO - mozprocess == 0.23 08:22:48 INFO - mozprofile == 0.28 08:22:48 INFO - mozrunner == 6.13 08:22:48 INFO - mozscreenshot == 0.1 08:22:48 INFO - mozsystemmonitor == 0.3 08:22:48 INFO - moztest == 0.7 08:22:48 INFO - mozversion == 1.4 08:22:48 INFO - psutil == 3.1.1 08:22:48 INFO - requests == 1.2.3 08:22:48 INFO - wsgiref == 0.1.2 08:22:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:22: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')]} 08:22:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:22:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:22:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:22:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:49 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 0x1040bc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104032570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd11bca2590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ed0d30>, '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 0x103d36db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ec8b20>, '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.OnNeXKEvGn/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.i9FjvePrvC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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 08:22:49 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 08:22:49 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 08:22:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OnNeXKEvGn/Render', 08:22:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:22:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:22:49 INFO - 'HOME': '/Users/cltbld', 08:22:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:22:49 INFO - 'LOGNAME': 'cltbld', 08:22:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:22:49 INFO - 'MOZ_NO_REMOTE': '1', 08:22:49 INFO - 'NO_EM_RESTART': '1', 08:22:49 INFO - 'PAGER': '/bin/cat', 08:22:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:22:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:22:49 INFO - 'PWD': '/builds/slave/test', 08:22:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:22:49 INFO - 'SHELL': '/bin/bash', 08:22:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i9FjvePrvC/Listeners', 08:22:49 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 08:22:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:22:49 INFO - 'USER': 'cltbld', 08:22:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:22:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:22:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:22:49 INFO - 'XPC_FLAGS': '0x0', 08:22:49 INFO - 'XPC_SERVICE_NAME': '0', 08:22:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:22:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:22:49 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)) 08:22:49 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)) 08:22:49 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)) 08:22:49 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)) 08:22:49 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)) 08:22:49 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)) 08:22:49 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)) 08:22:49 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)) 08:22:49 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)) 08:22:49 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)) 08:22:49 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)) 08:22:49 INFO - Cleaning up... 08:22:49 INFO - Return code: 0 08:22:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:22:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:22:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:22:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:22:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:22:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:22:50 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 0x1040bc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104032570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd11bca2590>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ed0d30>, '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 0x103d36db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ec8b20>, '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.OnNeXKEvGn/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.i9FjvePrvC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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 08:22:50 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 08:22:50 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 08:22:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OnNeXKEvGn/Render', 08:22:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:22:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:22:50 INFO - 'HOME': '/Users/cltbld', 08:22:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:22:50 INFO - 'LOGNAME': 'cltbld', 08:22:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:22:50 INFO - 'MOZ_NO_REMOTE': '1', 08:22:50 INFO - 'NO_EM_RESTART': '1', 08:22:50 INFO - 'PAGER': '/bin/cat', 08:22:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:22:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:22:50 INFO - 'PWD': '/builds/slave/test', 08:22:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:22:50 INFO - 'SHELL': '/bin/bash', 08:22:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i9FjvePrvC/Listeners', 08:22:50 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 08:22:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:22:50 INFO - 'USER': 'cltbld', 08:22:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:22:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:22:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:22:50 INFO - 'XPC_FLAGS': '0x0', 08:22:50 INFO - 'XPC_SERVICE_NAME': '0', 08:22:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:22:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:22:50 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:22:50 INFO - Cleaning up... 08:22:50 INFO - Return code: 0 08:22:50 INFO - Running post-action listener: _resource_record_post_action 08:22:50 INFO - Running post-action listener: _start_resource_monitoring 08:22:50 INFO - Starting resource monitoring. 08:22:50 INFO - [mozharness: 2016-12-22 16:22:50.427288Z] Finished create-virtualenv step (success) 08:22:50 INFO - [mozharness: 2016-12-22 16:22:50.428306Z] Running install step. 08:22:50 INFO - Running pre-action listener: _resource_record_pre_action 08:22:50 INFO - Running main action method: install 08:22:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:22:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:22:50 INFO - Reading from file tmpfile_stdout 08:22:50 INFO - Detecting whether we're running mozinstall >=1.0... 08:22:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:22:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:22:50 INFO - Reading from file tmpfile_stdout 08:22:50 INFO - Output received: 08:22:50 INFO - Usage: mozinstall [options] installer 08:22:50 INFO - Options: 08:22:50 INFO - -h, --help show this help message and exit 08:22:50 INFO - -d DEST, --destination=DEST 08:22:50 INFO - Directory to install application into. [default: 08:22:50 INFO - "/builds/slave/test"] 08:22:50 INFO - --app=APP Application being installed. [default: firefox] 08:22:50 INFO - mkdir: /builds/slave/test/build/application 08:22:50 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'] 08:22:50 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 08:23:21 INFO - Reading from file tmpfile_stdout 08:23:21 INFO - Output received: 08:23:21 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:23:21 INFO - Running post-action listener: _resource_record_post_action 08:23:21 INFO - [mozharness: 2016-12-22 16:23:21.470390Z] Finished install step (success) 08:23:21 INFO - [mozharness: 2016-12-22 16:23:21.470515Z] Running run-tests step. 08:23:21 INFO - Running pre-action listener: _resource_record_pre_action 08:23:21 INFO - Running main action method: run_tests 08:23:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:23:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:23:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:23:21 INFO - 2016-12-22 08:23:21.485 screenresolution[1904:63643] starting screenresolution argv=screenresolution get 08:23:21 INFO - 2016-12-22 08:23:21.487 screenresolution[1904:63643] Display 0: 1600x1200x32@60 08:23:21 INFO - 2016-12-22 08:23:21.493 screenresolution[1905:63645] starting screenresolution argv=screenresolution list 08:23:21 INFO - Available Modes on Display 0 08:23:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:23:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:23:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:23:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:23:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:23:21 INFO - Intel Iris: 08:23:21 INFO - Chipset Model: Intel Iris 08:23:21 INFO - Type: GPU 08:23:21 INFO - Bus: Built-In 08:23:21 INFO - VRAM (Dynamic, Max): 1536 MB 08:23:21 INFO - Vendor: Intel (0x8086) 08:23:21 INFO - Device ID: 0x0a2e 08:23:21 INFO - Revision ID: 0x0009 08:23:21 INFO - Displays: 08:23:21 INFO - Display: 08:23:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:23:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:23:21 INFO - Display Serial Number: 1600x1200 60 08:23:21 INFO - Main Display: Yes 08:23:21 INFO - Mirror: Off 08:23:21 INFO - Online: Yes 08:23:21 INFO - Rotation: Supported 08:23:21 INFO - Return code: 0 08:23:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:23:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:23:21 INFO - Python 2.7.3 08:23:21 INFO - Return code: 0 08:23:21 INFO - grabbing minidump binary from tooltool 08:23:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:23:21 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 0x103ed0d30>, '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 0x103d36db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ec8b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:23:21 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 08:23:21 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 08:23:21 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 08:23:21 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:23:21 INFO - Return code: 0 08:23:21 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:23:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:23:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:23:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:23:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:23:21 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/AQmIoI9GT3GGWNJAfdvCbA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0077', '--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 08:23:21 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/AQmIoI9GT3GGWNJAfdvCbA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0077 --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 08:23:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.OnNeXKEvGn/Render', 08:23:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:23:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:23:21 INFO - 'HOME': '/Users/cltbld', 08:23:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:23:21 INFO - 'LOGNAME': 'cltbld', 08:23:21 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:23:21 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:23:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:23:21 INFO - 'MOZ_NO_REMOTE': '1', 08:23:21 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:23:21 INFO - 'NO_EM_RESTART': '1', 08:23:21 INFO - 'PAGER': '/bin/cat', 08:23:21 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:23:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:23:21 INFO - 'PWD': '/builds/slave/test', 08:23:21 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:23:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:23:21 INFO - 'SHELL': '/bin/bash', 08:23:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.i9FjvePrvC/Listeners', 08:23:21 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 08:23:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:23:21 INFO - 'USER': 'cltbld', 08:23:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:23:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:23:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:23:21 INFO - 'XPC_FLAGS': '0x0', 08:23:21 INFO - 'XPC_SERVICE_NAME': '0', 08:23:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:23:21 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/AQmIoI9GT3GGWNJAfdvCbA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0077', '--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 08:23:22 INFO - mozversion application_buildid: 20161222064453 08:23:22 INFO - mozversion application_changeset: a368c15c83ace70e2e9a95a308a876e7689b4a8c 08:23:22 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:23:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:23:22 INFO - mozversion application_name: Firefox 08:23:22 INFO - mozversion application_remotingname: firefox-dev 08:23:22 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:23:22 INFO - mozversion application_vendor: Mozilla 08:23:22 INFO - mozversion application_version: 52.0a2 08:23:22 INFO - mozversion platform_buildid: 20161222064453 08:23:22 INFO - mozversion platform_changeset: a368c15c83ace70e2e9a95a308a876e7689b4a8c 08:23:22 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:23:22 INFO - mozversion platform_version: 52.0a2 08:23:22 INFO - using testdate: 1482423802 08:23:22 INFO - actual date: 1482423802 08:23:22 INFO - starting webserver on 'localhost:52771' 08:23:22 INFO - SUITE-START | Running 2 tests 08:23:22 INFO - TEST-START | dromaeo_css 08:23:22 INFO - operating with platform_type : mac_ 08:23:22 INFO - Initialising browser for dromaeo_css test... 08:23:22 INFO - TEST-INFO | started process 1915 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/tmpjR1RN_/profile http://localhost:52771/getInfo.html) 08:23:24 INFO - PROCESS | 1915 | __metrics Screen width/height:1600/1200 08:23:24 INFO - PROCESS | 1915 | colorDepth:24 08:23:24 INFO - PROCESS | 1915 | Browser inner width/height: 1024/689 08:23:24 INFO - PROCESS | 1915 | __metrics 08:23:24 INFO - PROCESS | 1915 | 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" 08:23:24 INFO - PROCESS | 1915 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:23:25 INFO - TEST-INFO | 1915: exit 0 08:23:25 INFO - Browser initialized. 08:23:25 INFO - Running cycle 1/1 for dromaeo_css test... 08:23:25 INFO - TEST-INFO | started process 1917 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/tmpjR1RN_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:23:37 INFO - PROCESS | 1917 | RSS: Main: 222326784 08:23:37 INFO - PROCESS | 1917 | 08:24:28 INFO - PROCESS | 1917 | 1482423868973 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] 08:26:42 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:52771/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52771/tests/dromaeo/cssquery-ext.html) 08:26:44 INFO - PROCESS | 1917 | RSS: Main: 376406016 08:26:44 INFO - PROCESS | 1917 | 08:29:47 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:52771/tests/dromaeo/cssquery-ext.html (next: http://localhost:52771/tests/dromaeo/cssquery-jquery.html) 08:29:47 INFO - PROCESS | 1917 | RSS: Main: 348180480 08:29:47 INFO - PROCESS | 1917 | 08:32:50 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:52771/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52771/tests/dromaeo/cssquery-mootools.html) 08:32:50 INFO - PROCESS | 1917 | RSS: Main: 329601024 08:32:50 INFO - PROCESS | 1917 | 08:35:53 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:52771/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52771/tests/dromaeo/cssquery-prototype.html) 08:35:53 INFO - PROCESS | 1917 | RSS: Main: 316899328 08:35:53 INFO - PROCESS | 1917 | 08:38:57 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:52771/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52771/tests/dromaeo/cssquery-yui.html) 08:38:57 INFO - PROCESS | 1917 | RSS: Main: 309252096 08:38:57 INFO - PROCESS | 1917 | 08:42:00 INFO - PROCESS | 1917 | Cycle 1(1): loaded http://localhost:52771/tests/dromaeo/cssquery-yui.html (next: http://localhost:52771/tests/dromaeo/cssquery-dojo.html) 08:42:01 INFO - PROCESS | 1917 | RSS: Main: 475582464 08:42:01 INFO - PROCESS | 1917 | 08:42:01 INFO - PROCESS | 1917 | __start_tp_report 08:42:01 INFO - PROCESS | 1917 | _x_x_mozilla_page_load 08:42:01 INFO - PROCESS | 1917 | _x_x_mozilla_page_load_details 08:42:01 INFO - PROCESS | 1917 | |i|pagename|runs| 08:42:01 INFO - PROCESS | 1917 | |0;dojo.html;3300.438925382685;3327.883524076657;3330.650281720419;3342.916427089324;3370.7471939604534;5630.831075067755;5648.604597678164;5680.943190568093;5697.572682048847;5739.971300143498;5592.049351610226;5667.914981275284;5678.687672178028;5682.971585142069;5701.857453563663;5157;5194.091034069042;5206.349206349207;5213.895722085556;5235.424103348629;5164.483551644843;5180.248863914749;5182.041322355358;5182.481751824825;5259.5529380002745;5212.817551385702;5281.128613778722;5289.86775330616;5290.68255904647;5292.047431462335;5264.894702105956;5268.8155914543195;5274;5274.736263186864;5344.064788662007;5027.321311622957;5161.66449180802;5174.560162386202;5186.988537235241;5215.269862219292;5594.860128496779;5632.3804381518;5670.3479099903625;5689.715514224314;5696.401877802812;6166.229221347332;6194.25668919728;6194.783182588588;6240.687965601747;6263.34234905333;6249.312575616678;6250.749970001195;6295.842603934892;6300.21247344082;6312.715927783215;5565.833025009256;5691.943080569225;5695.60130790849;5739.081746920473;5785.5660825437835;6165.951788196017;6255.030470277115;6273.529485288622;6283.183186185766;6290.308066112724;6143.232095988002;6152;6159.81520554384;6168.0439531872635;6169.598976066542;5737.8565535861935;6118;6153;6246.125542424064;6255.280642726054;6110.3765049759795;6120.933292792918;6126.050462178369;6145;6165.568410211332;5239.502247286502;6112.052631842072;6195.287541932646;6202.937970620236;6231.9688401557705;4912.1158191525155;4912.38595175603;4914.164592019365;4923.384576927884;4934.481879402683;3702.4816525686424;4377.321515165155;4397.582229688174;4408.977955110204;4432.49026361966;5724.885502290014;5757.740901659436;5764.337101233412;5787.39232380594;5797.710114494258;6260.686965651699;6281.8743625127245;6283.05754136874;6286.779962701284;6298.9291820390645;5554.277943867352;5668.348139963874;5739.770409183586;5758.596898217168;5760.481556659921;5359.678419294777;5374.596905232123;5394.3796463406425;5407.134858422633;5418.620696551204;6258.186435763321;6312.968435157794;6318.273398559225;6334.049892516159;6334.619922804646;4984.352034235526;6254.155688982111;6263.091851681572;6267.310595834363;6274.937250627435;6103.9694801527485;6204.534659900616;6208.255009398901;6239.939210334163;6246.906296405648;6187.938120618916;6210.906836397548;6215.409536094064;6236.594621349598;6242.250929888442;6258.405451482102;6267.435930766253;6273.466755325849;6291.402316779899;6291.779787707408;5940.495057920125;5977.790777322773;6050.1227322037175;6052.90920636182;6059.182010428536;6121.239585875057;6123.540734444845;6125.754381768121;6127.647695153045;6134.069235337404;5227.398849132398;5794.55136215946;5963.970180149245;5971.552133590085;6001.3998600141385;5928.525717942641;5964.821055368346;6068.817935461943;6103.42017508336;6115.205023346936;5800.564957628279;5843.065109582446;5853.414658534283;5856.12158176277;5857.062869940789 08:42:01 INFO - PROCESS | 1917 | |1;ext.html;467.3433825475207;507.2543361258949;512.6642049457604;514.4264145477794;543.6330476928073;24441;30357.544636830437;38605;39399.42402303904;39417;39982.00089995496;40323.798381008055;40443;40457;40496;9307.115823996726;9493.477858717768;9495.52522373882;9495.905040949605;9496.810063798719;8464.365172612048;8627.870581941244;11038.558457661724;11302.604408845693;11352.489137988769;9694.515274236295;11346.943265283662;11374.886251137465;11401.429928503583;11448.713782155472;8532.701355452591;8731.738047858575;9681.854772178458;11339.37633430161;11433.485493152744;11023;11279.436028198557;11349.716257093638;11363.772724545463;11507.424628768527;39069.82790516289;39216.01959950996;39436.802815985735;39513;39604.80197599022;3109.83381232038;3379.7803142795638;3449.7067749241346;3495.038864312309;3647.3252448296885;25016.124435644848;25102;29559;29577.55633665497;30245.941392051394;3091.9536206956914;3117.345357474933;3197.3605278944074;3215.2765627733806;3228.047725920859;450681;482291;482423;482562;482819;33072.66927330744;34146.80486182972;57629.71185144047;57840.553986150684;58028.12957805636;34464.310713785584;34855.651443485745;34876.9536913893;34948;35032.949011529694;14076.71846563063;14204.005719599529;14208.289585520683;14219.360128794022;14241.359138838778;10935.507902144423;11183.105351571854;11819.172657914036;11966.64100076999;11987.340696261615;7394.595026944863;7604.5437273763755;7706.344960678293;7866.449348545661;7882.448228623941;2688.5563881959256;3125.3749250150063;3227.0798874669536;3263.8204898730323;3300.5544251526217;945.6501094595044;959.9760005999906;989.342087511811;1025.6358992557684;1029.8249297619423;29346.853265733534;29546.896941167117;29584;29620;30047.549286760717;1017.3082304033328;1032.7934413117257;1045.29442626227;1073.4632683658172;1123.9269447485888;14479.131252124906;14968.326425310886;15087.924560377129;15256.618584535476;15316.923415382851;655941;664697;664752;664753;665792;55521.72239138859;55924.440755591924;55969.44030559642;56073.158902616495;56130;1055.3298655353751;1265.6709255593428;1344.368146970922;1359.8436179839446;1370.964921484293;28799.712002879704;29875.402491949604;29968.700312996592;29984.550231746976;30614.693853061184;47242.52757472381;47745.52254477411;47757.52242477531;47808.760956194994;47824;469068;546562;547907;550764;550798;26058.73941260563;26543;26789;26819.061332853256;26908.461830763667;37810.81094594509;45032.324515132954;45247.54752452565;45781.54218457906;46246.768766155954;37562.436563451025;37946.24107517889;38474.42288365621;39495;39931;35612.821935890155;37310.813445932596;37959.63464201652;38684.41973370458;39485.40771888367;36373.27253454863;37014.814925925195;37675.81162094172;37862.62137378701;38000.80999594984;32432.35135297328;33328.66671333255;33536.76808438431;33850.984470465926;33952.15119622029;25269;25329.74670253274;25426.74573254318;25620.61569076428;25830.483390331712 08:42:01 INFO - PROCESS | 1917 | |2;jquery.html;349.2874535946669;365.6599362592789;370.3740678253751;377.6695391532408;397.41976713997565;14573.489927852528;14798.92600536997;15784.210789460538;19826.306079287227;20268.493287667814;18351.816481835176;18879.339223127194;19561.70657440136;19603.019849007564;20403.693944590806;5462.5083742463075;5892.057270836667;5911.172435858984;5924.111383292519;5934.376890426508;4714.976425117869;5801.6519008859605;5833.125031245305;5844.503217226541;5848.824535263949;5512.972435137839;5696.515796157331;5714.457126572969;5715.428457154293;5787.363390027093;5697.373288938214;5726.141078838166;5758.3090029196555;5765.048766953448;5788.594798364116;4844.7819848106565;5058.873528161826;5070.594352451795;5072.264521644378;5080.74596270185;24241.151559695503;24424.389390265387;24961.875190624112;25124.24627261185;25393.746062539507;2838.5884046813303;2866.312085099561;3035.741961933238;3051.8474076296097;3114.7508199343997;14532.346044428026;15297.388104475918;15313.00465469752;15334.079955202724;15877.682446351124;1792.9013904235262;1846.5937493751353;1847.8244566766136;1860.4325680667391;1888;116164.83835161624;117390;120781.39609301985;122488;122672.77327226703;9109.453432793922;10058.74853128663;10494.475276236157;10537.736556586147;10948.671539853816;13481.393337299845;13529.120607160503;13535.39090740919;13537.45850166002;13553.864461355457;8137.471064380797;8366.874496882552;8444.61999210037;8566.571671416405;8574.828503429897;7143;8422.494650321001;8452.535110568855;8478.491290522588;8651.870221946676;6411.551191416649;6540.149780528596;6540.247871494839;6551.181102362205;6684.498662600227;1786.7141257398753;1822.9817701822813;1829.826166514179;1841.622467394181;1850.4818650777804;743.2715938380418;892.9821403571892;900.3922352412096;923.4920793563513;943.6414162618298;20237.886916219748;21196;21292.893535532225;21989.780102199093;22424.103035878383;878.2622597018535;902.5938327752538;920.2545937790411;952.9380590261611;1019.4647809899714;11089.83365249522;11372.14708896836;11458.58223815203;11539.4807233673;11715.4142292885;514318;611647;615156;622106;625964;85726;107529;110052.4497377524;114111;115231;951.9190868776232;966.4104896013426;990.9207263418765;996.1084829077935;998.1565577087349;18698.43904682862;18932.337368192046;19540.60918781588;19607.9215643141;19697.704534432283;50606.74696626493;50828.74585627196;51317;52710;52998.145064922544;63917.360826393;70499.64750176216;78828.21171788209;79932.60033699794;80620.387592249;5458.344998600193;7804.7122224832265;7872.881906771288;8035.959820200862;8141.8778718320455;10788.892111078789;12603.68490787701;12677.492900283885;13006;13175.14361566495;8250.711225107092;10561.947190264;10862.239643224899;10864.402457864577;11051.336919784839;10709.625163119254;10852;10948.616798412337;11243.943780281046;11302.643682757793;9017.639294428149;10237.692869213935;11168.944155279172;11356.26184298018;11526.827097593376;7901.130875603564;7985.600719964094;8207.876881846658;8212.603857344386;8273.875891861748;8688.609012594576;9430.41644175209;10536.262461627919;10744.140468762636;10929.960739653166 08:42:01 INFO - PROCESS | 1917 | |3;mootools.html;1303.804429335599;1446.9493567725128;1470.3603932289454;1617.3449752850092;1815.4372144635156;7462.850742985137;7851.685932562697;7988.36093112551;8291.336693064555;8324.209200125993;8184.672613095484;8271.875921861178;8274.95862520688;8283.5029898206;8285.958570207156;2282.417983414231;2286.302677683306;2299.839011269212;2333.1717240379676;2334.043042352636;1935.148534644759;2265.150568536799;2327.895244713992;2329.184785325131;2344.929652110431;2287.828412869033;2300.804431623306;2326.8138548916045;2332.4635333873134;2335.381124002141;1954.394137817274;2269.8297627677907;2276.3512398966213;2321.593721098801;2324.628059510487;2301.217586020761;2309.19178287598;2337.2988103569032;2357.0336162173426;2373.8100951923807;8271.17288271174;8373.204545568162;8378.874316885252;8384.455010424364;8386.496810191407;5010.649254552182;5216.478352164753;5225.581953443715;5239.5546378557865;5251.894962100737;3385.1875549867855;3492.633273506296;3566.732495062881;3569.214772750017;3586.4620306953907;1048.3657387280662;1062.2882668611971;1064.6965614799822;1079.5110974533839;1092.7541303206795;10723.624673136403;10795.08241799448;10859.13126949842;10873.184511161695;10873.836892446541;3751.793651349202;4267.103908179317;4480.014396832692;4487.259602165606;4501.144782491317;8504.31965442763;8629.741107766777;8631.870521942175;8668.089850565726;8679;5107.87230319245;5108.208227724708;5109;5114.820981265638;5122.897542049138;4061.2283666103936;4286.271333873249;4441.333799929972;4459.0858873930765;4709.811607535728;3270.689284517967;3448.7067349743847;3567.8037707926314;3576.803275819803;3639.1993761372455;968.8207681578931;975.9853602195973;980.3578655980344;981.1660088924357;987.3878195518681;692.3699433515577;729.6169511006764;861.5390765940317;866.8396346675759;880.5113162194915;5457.945420545823;5500.50495455411;5512.2558454608115;5539.446055394428;5721;839.6809212499334;865.372604861483;900.0999000999001;904.5025236120108;947.8056998315329;3187.9043628691174;3662.50556174913;3689.4281386385155;3714.0343510686876;3896.883093507199;9540.809183816285;10043.598256069821;10109.747256318504;10186.490675466197;10257.538410771533;10117.797644047077;10237.283390162765;10249.12882404989;10253.128484078938;10296.176305895511;7696.461247712607;7762.573058481725;7768.805779855549;7889;7953.76138715851;5366.168243922199;5423.484768946943;5497.016061998307;5679.148127780866;5734.283214598176;9347.859782103138;9406.059394060812;9435.858462123211;9467.905320946702;9555.61777528891;8025.719099831478;8722.651093956425;9550.188234000188;9569.856452153363;9586.568604412958;6675.933240667531;6875.5530890491955;6879.6560171992205;6890.896636550556;6910.896336554855;6725.630090345078;7635.5800430974605;7812;7905.723299684483;7918.802029949297;6525.727483140742;6942.652867356713;7122.60825654594;7124.073870396815;7257.201707812242;5933.732982015906;6444.77549265638;6983.266756990442;7112.0043193953925;7151;6433.420992110732;6647.60114393138;6722.596644201363;6724.966375168093;6727.192736871607;4576.336431217522;4781.378420805273;4802.423709154924;4823.517648235289;4947.480514546065;4508.256137737368;4677.672562920563;4688.437387513521;4741.336212930123;4839.443464001544 08:42:01 INFO - PROCESS | 1917 | |4;prototype.html;279.14998828567013;304.7333583114776;308.30784887926603;315.8768080448624;316.69280797626294;5191.558717509011;5197.714125723083;5212.244224587437;5251.501107394801;5269.920951185735;4552.726836389811;4641.234196357597;4863.708177509352;5021.2468129780555;5125.641205115643;3464.965350346502;3567.8751243706474;3837.982934522347;3977.6221258980495;4029.37544680575;3596.5504311961004;3993;3995.6204160604843;3995.781286707553;4022.296098182821;3298.950515742266;3669.9633003670024;3936.2127574485075;4002.7198096133284;4018.4173294872226;3339.3655205510995;3918.7648741075627;3931.2334094851517;3934.11482416454;3982.3230050891416;3318;3341.0979035660234;3396.4735466002808;3401.6598340166033;3425.2978139481347;4964.914735810932;4996.325496058022;5028.974855125701;5045.722485263309;5069.366329208849;3760.4547340635454;3767.3030489359558;4266.188662334771;4434.955650443487;4470.552944705503;4725.5510726480925;5139.948600514022;5365.973170134124;5372.865678358034;5380.381256155514;3270.411325961326;3643.7995910224927;3700.2414505026672;3716.7212459065677;3744.2885851688093;4755.714657120549;5385.5960802939935;5526.447355264522;5675.404082571353;5695.971520142414;2918.022462475052;3244.2213868671347;3468.283199816057;3528.700664878296;3806.1816709407613;3832.865849695247;3841.2509560635967;3841.2893614681375;3854.4989151410136;3885.3006458837117;4423.601875831127;4432.756198409118;4433;4488.755622188905;4504.346869703874;4632.814687412466;5097.8215762448135;5099.235114732745;5438.891222175614;5455.454454554513;1117.7205698575356;1224.915949384783;1232.7596118756755;1237.9195352302072;1248.6628610275175;793.9678418056503;810.793247721839;817.2930415190817;838.773531146587;856.1695155698962;4658.58072773452;4774;4825.396825396825;4836.951630483721;4882.926756098661;4630.930536041962;5144.228365745168;5259.973700131552;5278.340207474066;5314.441983591667;4443.511213766483;4508.549145085531;4676.929846052312;4730.432348118248;4734.692245004064;2378.8334816562674;2734.6581677290337;2750.91897075277;2755.917322480329;2768.958465623017;5339.332583427072;5378.902228131032;5387.461253874581;5391.973040134852;5402.972985135128;4936.384480510662;5137.893463679873;5204.635675502678;5290.941811637688;5322.973385133127;4617.746023968714;4770.902692380702;5201.1678131498775;5250.820909322589;5359.705216213146;4829.927551086666;5153.742312884416;5166.276721259102;5189.6107791915;5222.55608273301;4848.272759086024;5037.294778730908;5156.587473002076;5168.328117344721;5199.428062913001;4537.8184872604725;4668.7665616719705;4930.052629211513;5357.6158609418435;5360.705161216015;3557.5730912289655;3572.214112895159;3592.2995015971633;3595.6089200681254;3596.6942809861457;4812.868975790757;4913.459519452929;5021.422536408214;5122.718250496109;5210.827563798115;4923.556879880828;5041.470645582308;5067.77195026232;5090.770915308746;5138.895137545445;4593.908121837611;4783.986088764056;4796.400449943757;5013.373328333958;5038.974805125951;4098.364753463099;4496.0558282760985;4523.411956445641;4680.438347398198;4960.851174464772;5014.473480284518;5271.235670827785;5312.495312945264;5315.574754019592;5427.728613569227;4075.836966521306;4554.88612784683;5260.528051486001;5273.578113750814;5307.256984022163 08:42:01 INFO - PROCESS | 1917 | |5;yui.html;708.8334241453255;736.9447291453139;747.89155572442;768.8808234723616;787.0594639405911;14231.928840355797;15828.525144245677;16454.424095156643;16520.58698532537;16905.661886762256;15039.548813535612;15092.0190187638;15100.848991510082;15122.773158402606;15175.544733658007;644.5037321262625;790.8418316336741;814.702633538757;819.6598411659154;819.9303059239971;663.2505269045971;667.5393978155057;674.2482132422363;798.0582912163644;817.7587611654576;826.6528058215533;827.0571548434789;842.6250318608191;863.3999370437548;866.263675875504;672.5661948043485;860.956952152393;862.0000799073108;862.2584577263548;863.7797361672733;514.1824499046534;611.74918283504;623.8272048548699;632.8006677896494;633.3444884544521;14217.715645687029;14234.359453824603;14248.643783905381;14266.144031358152;14290.285485725672;12486.81269780954;12594.181378210387;12689.555865544755;12789.872101279054;13000.414981325863;11018.834717479243;11541.249818761838;12152;12281.754364912651;12314.152877068422;4225.598568136023;4275.679324050709;4357.629601483878;4385.232584297735;4519.163954668397;82188.65773724689;85295.14704852997;85986;85986.28027439416;86688;8562.571871406404;9720.416774993522;10104.444255566015;10140.44227567476;10370.896291037145;18848.15183316754;18859.52851178704;18992.43022709321;18993.525161871064;19026.238950442104;7658.2724641159;7674.270944260286;7674.347680447113;7853.8821917671285;7898.486598371088;4186.602272784081;4195.706300558993;4338.934915976263;4339.978300108479;4376.474823021194;3359.5632567766525;3413.6245013048547;3416.590009198912;3473.548438703003;3476.235228249781;561.4049107945591;637.0666972884746;675.4967549175896;717.8492516571579;718.4216705551976;584.739790793093;616.2727980982484;647.7700416352221;648.4779752299444;666.3103687683224;8588.441751286146;8713.433626814363;8782.825761357963;8811.559422029;9388.8591671125;588.2822955993681;623.6725718997471;645.854682696399;669.1234482827512;673.4545018535002;4435.090806384684;4439.1121775645;4448.822047118144;4471.552844715502;4473.776311184428;74501.25498744943;96870.54694179592;99748.5012574947;110948;119427;65262;91504.54247728852;93139.53430232806;103964.48017759863;104223.47888260511;85205.57397213098;88299;90193.09806901978;91420;92849.99999999865;8593.742187734379;8666.263367613823;8677.913220867711;8746.212840844355;8932.732018039469;47071;55604;56894.86210275689;57216.71391643015;57551.848963021344;51534;63507.6824615874;64010.7197856031;72030;72664.6366768184;11189.992900638981;11487.25332853362;11875.703107422383;12062.336571488653;12417.441215145522;13954.58136255914;13967.930160349133;14075.859241407456;14193.432262709788;14244.145351278956;10841.241113122005;12855.164414313414;13228.272445015617;13357.799633005317;13490.190588564717;12202.572909948109;13050.869491304966;13266.800997984845;13293.468261269441;13709.245991470565;12373.13388062827;12491.312977786309;13024.54414095502;13326.400311986177;13708.383122759698;10652.786944260917;11242;11508.027124432932;11651.88348116508;11903.40482975865;9807.803843922939;11468.19722619442;11471.740346950182;11531.827022594836;11578.478968446272 08:42:01 INFO - PROCESS | 1917 | __end_tp_report 08:42:01 INFO - PROCESS | 1917 | __start_cc_report 08:42:01 INFO - PROCESS | 1917 | _x_x_mozilla_cycle_collect,1856 08:42:01 INFO - PROCESS | 1917 | __end_cc_report 08:42:01 INFO - PROCESS | 1917 | __startTimestamp1482424921118__endTimestamp 08:42:01 INFO - PROCESS | 1917 | 08:42:01 INFO - PROCESS | 1917 | ------- Summary: start ------- 08:42:01 INFO - PROCESS | 1917 | Number of tests: 6 08:42:01 INFO - PROCESS | 1917 | 08:42:01 INFO - PROCESS | 1917 | [#0] dojo.html Cycles:165 Average:5709.80 Median:5853.41 stddev:648.97 (11.1%) stddev-sans-first:622.83 08:42:01 INFO - PROCESS | 1917 | Values: 3300.4 3327.9 3330.7 3342.9 3370.7 5630.8 5648.6 5680.9 5697.6 5740.0 5592.0 5667.9 5678.7 5683.0 5701.9 5157.0 5194.1 5206.3 5213.9 5235.4 5164.5 5180.2 5182.0 5182.5 5259.6 5212.8 5281.1 5289.9 5290.7 5292.0 5264.9 5268.8 5274.0 5274.7 5344.1 5027.3 5161.7 5174.6 5187.0 5215.3 5594.9 5632.4 5670.3 5689.7 5696.4 6166.2 6194.3 6194.8 6240.7 6263.3 6249.3 6250.7 6295.8 6300.2 6312.7 5565.8 5691.9 5695.6 5739.1 5785.6 6166.0 6255.0 6273.5 6283.2 6290.3 6143.2 6152.0 6159.8 6168.0 6169.6 5737.9 6118.0 6153.0 6246.1 6255.3 6110.4 6120.9 6126.1 6145.0 6165.6 5239.5 6112.1 6195.3 6202.9 6232.0 4912.1 4912.4 4914.2 4923.4 4934.5 3702.5 4377.3 4397.6 4409.0 4432.5 5724.9 5757.7 5764.3 5787.4 5797.7 6260.7 6281.9 6283.1 6286.8 6298.9 5554.3 5668.3 5739.8 5758.6 5760.5 5359.7 5374.6 5394.4 5407.1 5418.6 6258.2 6313.0 6318.3 6334.0 6334.6 4984.4 6254.2 6263.1 6267.3 6274.9 6104.0 6204.5 6208.3 6239.9 6246.9 6187.9 6210.9 6215.4 6236.6 6242.3 6258.4 6267.4 6273.5 6291.4 6291.8 5940.5 5977.8 6050.1 6052.9 6059.2 6121.2 6123.5 6125.8 6127.6 6134.1 5227.4 5794.6 5964.0 5971.6 6001.4 5928.5 5964.8 6068.8 6103.4 6115.2 5800.6 5843.1 5853.4 5856.1 5857.1 08:42:01 INFO - PROCESS | 1917 | 08:42:01 INFO - PROCESS | 1917 | [#1] ext.html Cycles:180 Average:67162.60 Median:34912.48 stddev:150922.71 (432.3%) stddev-sans-first:151263.02 08:42:01 INFO - PROCESS | 1917 | Values: 467.3 507.3 512.7 514.4 543.6 24441.0 30357.5 38605.0 39399.4 39417.0 39982.0 40323.8 40443.0 40457.0 40496.0 9307.1 9493.5 9495.5 9495.9 9496.8 8464.4 8627.9 11038.6 11302.6 11352.5 9694.5 11346.9 11374.9 11401.4 11448.7 8532.7 8731.7 9681.9 11339.4 11433.5 11023.0 11279.4 11349.7 11363.8 11507.4 39069.8 39216.0 39436.8 39513.0 39604.8 3109.8 3379.8 3449.7 3495.0 3647.3 25016.1 25102.0 29559.0 29577.6 30245.9 3092.0 3117.3 3197.4 3215.3 3228.0 450681.0 482291.0 482423.0 482562.0 482819.0 33072.7 34146.8 57629.7 57840.6 58028.1 34464.3 34855.7 34877.0 34948.0 35032.9 14076.7 14204.0 14208.3 14219.4 14241.4 10935.5 11183.1 11819.2 11966.6 11987.3 7394.6 7604.5 7706.3 7866.4 7882.4 2688.6 3125.4 3227.1 3263.8 3300.6 945.7 960.0 989.3 1025.6 1029.8 29346.9 29546.9 29584.0 29620.0 30047.5 1017.3 1032.8 1045.3 1073.5 1123.9 14479.1 14968.3 15087.9 15256.6 15316.9 655941.0 664697.0 664752.0 664753.0 665792.0 55521.7 55924.4 55969.4 56073.2 56130.0 1055.3 1265.7 1344.4 1359.8 1371.0 28799.7 29875.4 29968.7 29984.6 30614.7 47242.5 47745.5 47757.5 47808.8 47824.0 469068.0 546562.0 547907.0 550764.0 550798.0 26058.7 26543.0 26789.0 26819.1 26908.5 37810.8 45032.3 45247.5 45781.5 46246.8 37562.4 37946.2 38474.4 39495.0 39931.0 35612.8 37310.8 37959.6 38684.4 39485.4 36373.3 37014.8 37675.8 37862.6 38000.8 32432.4 33328.7 33536.8 33851.0 33952.2 25269.0 25329.7 25426.7 25620.6 25830.5 08:42:01 INFO - PROCESS | 1917 | 08:42:01 INFO - PROCESS | 1917 | [#2] jquery.html Cycles:180 Average:34281.64 Median:357.47 stddev:99562.89 (27851.8%) stddev-sans-first:99809.58 08:42:01 INFO - PROCESS | 1917 | Values: 349.3 365.7 370.4 377.7 397.4 14573.5 14798.9 15784.2 19826.3 20268.5 18351.8 18879.3 19561.7 19603.0 20403.7 5462.5 5892.1 5911.2 5924.1 5934.4 4715.0 5801.7 5833.1 5844.5 5848.8 5513.0 5696.5 5714.5 5715.4 5787.4 5697.4 5726.1 5758.3 5765.0 5788.6 4844.8 5058.9 5070.6 5072.3 5080.7 24241.2 24424.4 24961.9 25124.2 25393.7 2838.6 2866.3 3035.7 3051.8 3114.8 14532.3 15297.4 15313.0 15334.1 15877.7 1792.9 1846.6 1847.8 1860.4 1888.0 116164.8 117390.0 120781.4 122488.0 122672.8 9109.5 10058.7 10494.5 10537.7 10948.7 13481.4 13529.1 13535.4 13537.5 13553.9 8137.5 8366.9 8444.6 8566.6 8574.8 7143.0 8422.5 8452.5 8478.5 8651.9 6411.6 6540.1 6540.2 6551.2 6684.5 1786.7 1823.0 1829.8 1841.6 1850.5 743.3 893.0 900.4 923.5 943.6 20237.9 21196.0 21292.9 21989.8 22424.1 878.3 902.6 920.3 952.9 1019.5 11089.8 11372.1 11458.6 11539.5 11715.4 514318.0 611647.0 615156.0 622106.0 625964.0 85726.0 107529.0 110052.4 114111.0 115231.0 951.9 966.4 990.9 996.1 998.2 18698.4 18932.3 19540.6 19607.9 19697.7 50606.7 50828.7 51317.0 52710.0 52998.1 63917.4 70499.6 78828.2 79932.6 80620.4 5458.3 7804.7 7872.9 8036.0 8141.9 10788.9 12603.7 12677.5 13006.0 13175.1 8250.7 10561.9 10862.2 10864.4 11051.3 10709.6 10852.0 10948.6 11243.9 11302.6 9017.6 10237.7 11168.9 11356.3 11526.8 7901.1 7985.6 8207.9 8212.6 8273.9 8688.6 9430.4 10536.3 10744.1 10930.0 08:42:01 INFO - PROCESS | 1917 | 08:42:01 INFO - PROCESS | 1917 | [#3] mootools.html Cycles:180 Average:5344.00 Median:5221.03 stddev:2965.58 (56.8%) stddev-sans-first:2958.35 08:42:01 INFO - PROCESS | 1917 | Values: 1303.8 1446.9 1470.4 1617.3 1815.4 7462.9 7851.7 7988.4 8291.3 8324.2 8184.7 8271.9 8275.0 8283.5 8286.0 2282.4 2286.3 2299.8 2333.2 2334.0 1935.1 2265.2 2327.9 2329.2 2344.9 2287.8 2300.8 2326.8 2332.5 2335.4 1954.4 2269.8 2276.4 2321.6 2324.6 2301.2 2309.2 2337.3 2357.0 2373.8 8271.2 8373.2 8378.9 8384.5 8386.5 5010.6 5216.5 5225.6 5239.6 5251.9 3385.2 3492.6 3566.7 3569.2 3586.5 1048.4 1062.3 1064.7 1079.5 1092.8 10723.6 10795.1 10859.1 10873.2 10873.8 3751.8 4267.1 4480.0 4487.3 4501.1 8504.3 8629.7 8631.9 8668.1 8679.0 5107.9 5108.2 5109.0 5114.8 5122.9 4061.2 4286.3 4441.3 4459.1 4709.8 3270.7 3448.7 3567.8 3576.8 3639.2 968.8 976.0 980.4 981.2 987.4 692.4 729.6 861.5 866.8 880.5 5457.9 5500.5 5512.3 5539.4 5721.0 839.7 865.4 900.1 904.5 947.8 3187.9 3662.5 3689.4 3714.0 3896.9 9540.8 10043.6 10109.7 10186.5 10257.5 10117.8 10237.3 10249.1 10253.1 10296.2 7696.5 7762.6 7768.8 7889.0 7953.8 5366.2 5423.5 5497.0 5679.1 5734.3 9347.9 9406.1 9435.9 9467.9 9555.6 8025.7 8722.7 9550.2 9569.9 9586.6 6675.9 6875.6 6879.7 6890.9 6910.9 6725.6 7635.6 7812.0 7905.7 7918.8 6525.7 6942.7 7122.6 7124.1 7257.2 5933.7 6444.8 6983.3 7112.0 7151.0 6433.4 6647.6 6722.6 6725.0 6727.2 4576.3 4781.4 4802.4 4823.5 4947.5 4508.3 4677.7 4688.4 4741.3 4839.4 08:42:01 INFO - PROCESS | 1917 | 08:42:01 INFO - PROCESS | 1917 | [#4] prototype.html Cycles:180 Average:4236.25 Median:4745.20 stddev:1270.50 (26.8%) stddev-sans-first:1238.86 08:42:01 INFO - PROCESS | 1917 | Values: 279.1 304.7 308.3 315.9 316.7 5191.6 5197.7 5212.2 5251.5 5269.9 4552.7 4641.2 4863.7 5021.2 5125.6 3465.0 3567.9 3838.0 3977.6 4029.4 3596.6 3993.0 3995.6 3995.8 4022.3 3299.0 3670.0 3936.2 4002.7 4018.4 3339.4 3918.8 3931.2 3934.1 3982.3 3318.0 3341.1 3396.5 3401.7 3425.3 4964.9 4996.3 5029.0 5045.7 5069.4 3760.5 3767.3 4266.2 4435.0 4470.6 4725.6 5139.9 5366.0 5372.9 5380.4 3270.4 3643.8 3700.2 3716.7 3744.3 4755.7 5385.6 5526.4 5675.4 5696.0 2918.0 3244.2 3468.3 3528.7 3806.2 3832.9 3841.3 3841.3 3854.5 3885.3 4423.6 4432.8 4433.0 4488.8 4504.3 4632.8 5097.8 5099.2 5438.9 5455.5 1117.7 1224.9 1232.8 1237.9 1248.7 794.0 810.8 817.3 838.8 856.2 4658.6 4774.0 4825.4 4837.0 4882.9 4630.9 5144.2 5260.0 5278.3 5314.4 4443.5 4508.5 4676.9 4730.4 4734.7 2378.8 2734.7 2750.9 2755.9 2769.0 5339.3 5378.9 5387.5 5392.0 5403.0 4936.4 5137.9 5204.6 5290.9 5323.0 4617.7 4770.9 5201.2 5250.8 5359.7 4829.9 5153.7 5166.3 5189.6 5222.6 4848.3 5037.3 5156.6 5168.3 5199.4 4537.8 4668.8 4930.1 5357.6 5360.7 3557.6 3572.2 3592.3 3595.6 3596.7 4812.9 4913.5 5021.4 5122.7 5210.8 4923.6 5041.5 5067.8 5090.8 5138.9 4593.9 4784.0 4796.4 5013.4 5039.0 4098.4 4496.1 4523.4 4680.4 4960.9 5014.5 5271.2 5312.5 5315.6 5427.7 4075.8 4554.9 5260.5 5273.6 5307.3 08:42:01 INFO - PROCESS | 1917 | 08:42:01 INFO - PROCESS | 1917 | [#5] yui.html Cycles:180 Average:20017.08 Median:25795.08 stddev:28885.90 (112.0%) stddev-sans-first:28930.55 08:42:01 INFO - PROCESS | 1917 | Values: 708.8 736.9 747.9 768.9 787.1 14231.9 15828.5 16454.4 16520.6 16905.7 15039.5 15092.0 15100.8 15122.8 15175.5 644.5 790.8 814.7 819.7 819.9 663.3 667.5 674.2 798.1 817.8 826.7 827.1 842.6 863.4 866.3 672.6 861.0 862.0 862.3 863.8 514.2 611.7 623.8 632.8 633.3 14217.7 14234.4 14248.6 14266.1 14290.3 12486.8 12594.2 12689.6 12789.9 13000.4 11018.8 11541.2 12152.0 12281.8 12314.2 4225.6 4275.7 4357.6 4385.2 4519.2 82188.7 85295.1 85986.0 85986.3 86688.0 8562.6 9720.4 10104.4 10140.4 10370.9 18848.2 18859.5 18992.4 18993.5 19026.2 7658.3 7674.3 7674.3 7853.9 7898.5 4186.6 4195.7 4338.9 4340.0 4376.5 3359.6 3413.6 3416.6 3473.5 3476.2 561.4 637.1 675.5 717.8 718.4 584.7 616.3 647.8 648.5 666.3 8588.4 8713.4 8782.8 8811.6 9388.9 588.3 623.7 645.9 669.1 673.5 4435.1 4439.1 4448.8 4471.6 4473.8 74501.3 96870.5 99748.5 110948.0 119427.0 65262.0 91504.5 93139.5 103964.5 104223.5 85205.6 88299.0 90193.1 91420.0 92850.0 8593.7 8666.3 8677.9 8746.2 8932.7 47071.0 55604.0 56894.9 57216.7 57551.8 51534.0 63507.7 64010.7 72030.0 72664.6 11190.0 11487.3 11875.7 12062.3 12417.4 13954.6 13967.9 14075.9 14193.4 14244.1 10841.2 12855.2 13228.3 13357.8 13490.2 12202.6 13050.9 13266.8 13293.5 13709.2 12373.1 12491.3 13024.5 13326.4 13708.4 10652.8 11242.0 11508.0 11651.9 11903.4 9807.8 11468.2 11471.7 11531.8 11578.5 08:42:01 INFO - PROCESS | 1917 | -------- Summary: end -------- 08:42:01 INFO - PROCESS | 1917 | 08:42:01 INFO - TEST-INFO | 1917: exit 0 08:42:01 INFO - TEST-OK | dromaeo_css | took 1119693ms 08:42:01 INFO - TEST-START | kraken 08:42:01 INFO - operating with platform_type : mac_ 08:42:01 INFO - Initialising browser for kraken test... 08:42:01 INFO - TEST-INFO | started process 1922 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/tmpZ8hqbq/profile http://localhost:52771/getInfo.html) 08:42:04 INFO - PROCESS | 1922 | __metrics Screen width/height:1600/1200 08:42:04 INFO - PROCESS | 1922 | colorDepth:24 08:42:04 INFO - PROCESS | 1922 | Browser inner width/height: 1024/689 08:42:04 INFO - PROCESS | 1922 | __metrics 08:42:04 INFO - PROCESS | 1922 | 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" 08:42:04 INFO - PROCESS | 1922 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:42:05 INFO - TEST-INFO | 1922: exit 0 08:42:05 INFO - Browser initialized. 08:42:05 INFO - Running cycle 1/1 for kraken test... 08:42:05 INFO - TEST-INFO | started process 1923 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/tmpZ8hqbq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:42:17 INFO - PROCESS | 1923 | RSS: Main: 222801920 08:42:17 INFO - PROCESS | 1923 | 08:42:20 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:52771/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52771/tests/kraken/driver.html?testName=audio-beat-detection) 08:42:21 INFO - PROCESS | 1923 | RSS: Main: 270270464 08:42:21 INFO - PROCESS | 1923 | 08:42:24 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:52771/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52771/tests/kraken/driver.html?testName=audio-dft) 08:42:24 INFO - PROCESS | 1923 | RSS: Main: 283811840 08:42:24 INFO - PROCESS | 1923 | 08:42:27 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:52771/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52771/tests/kraken/driver.html?testName=audio-fft) 08:42:28 INFO - PROCESS | 1923 | RSS: Main: 382427136 08:42:28 INFO - PROCESS | 1923 | 08:42:30 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:52771/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52771/tests/kraken/driver.html?testName=audio-oscillator) 08:42:31 INFO - PROCESS | 1923 | RSS: Main: 285106176 08:42:31 INFO - PROCESS | 1923 | 08:42:33 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:52771/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52771/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:42:33 INFO - PROCESS | 1923 | RSS: Main: 286691328 08:42:33 INFO - PROCESS | 1923 | 08:42:36 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:52771/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52771/tests/kraken/driver.html?testName=imaging-darkroom) 08:42:37 INFO - PROCESS | 1923 | RSS: Main: 327184384 08:42:37 INFO - PROCESS | 1923 | 08:42:40 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:52771/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52771/tests/kraken/driver.html?testName=imaging-desaturate) 08:42:41 INFO - PROCESS | 1923 | RSS: Main: 327118848 08:42:41 INFO - PROCESS | 1923 | 08:42:43 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:52771/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52771/tests/kraken/driver.html?testName=json-parse-financial) 08:42:44 INFO - PROCESS | 1923 | RSS: Main: 324227072 08:42:44 INFO - PROCESS | 1923 | 08:42:46 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:52771/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52771/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:42:46 INFO - PROCESS | 1923 | RSS: Main: 298885120 08:42:46 INFO - PROCESS | 1923 | 08:42:48 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:52771/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52771/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:42:49 INFO - PROCESS | 1923 | RSS: Main: 298799104 08:42:49 INFO - PROCESS | 1923 | 08:42:51 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:52771/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52771/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:42:51 INFO - PROCESS | 1923 | RSS: Main: 302170112 08:42:51 INFO - PROCESS | 1923 | 08:42:54 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:52771/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52771/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:42:55 INFO - PROCESS | 1923 | RSS: Main: 313475072 08:42:55 INFO - PROCESS | 1923 | 08:42:58 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:52771/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52771/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:42:59 INFO - PROCESS | 1923 | RSS: Main: 313901056 08:42:59 INFO - PROCESS | 1923 | 08:43:01 INFO - PROCESS | 1923 | Cycle 1(1): loaded http://localhost:52771/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52771/tests/kraken/driver.html?testName=ai-astar) 08:43:01 INFO - PROCESS | 1923 | RSS: Main: 357425152 08:43:01 INFO - PROCESS | 1923 | 08:43:01 INFO - PROCESS | 1923 | __start_tp_report 08:43:01 INFO - PROCESS | 1923 | _x_x_mozilla_page_load 08:43:01 INFO - PROCESS | 1923 | _x_x_mozilla_page_load_details 08:43:01 INFO - PROCESS | 1923 | |i|pagename|runs| 08:43:01 INFO - PROCESS | 1923 | |0;ai-astar;104;112;115;113;113;113;113;115;114;113 08:43:01 INFO - PROCESS | 1923 | |1;audio-beat-detection;144;113;96;175;139;148;128;109;138;110 08:43:01 INFO - PROCESS | 1923 | |2;audio-dft;186;184;186;187;184;190;254;186;184;184 08:43:01 INFO - PROCESS | 1923 | |3;audio-fft;82;58;60;91;60;59;64;59;84;58 08:43:01 INFO - PROCESS | 1923 | |4;audio-oscillator;84;72;97;69;80;97;122;88;68;92 08:43:01 INFO - PROCESS | 1923 | |5;imaging-gaussian-blur;94;130;86;85;89;84;87;83;86;84 08:43:01 INFO - PROCESS | 1923 | |6;imaging-darkroom;92;92;93;93;92;93;117;149;108;92 08:43:01 INFO - PROCESS | 1923 | |7;imaging-desaturate;85;85;81;80;94;130;128;82;80;81 08:43:01 INFO - PROCESS | 1923 | |8;json-parse-financial;56;57;54;55;57;59;58;58;58;58 08:43:01 INFO - PROCESS | 1923 | |9;json-stringify-tinderbox;65;76;64;77;73;69;70;71;71;70 08:43:01 INFO - PROCESS | 1923 | |10;stanford-crypto-aes;84;83;84;84;85;83;86;88;84;84 08:43:01 INFO - PROCESS | 1923 | |11;stanford-crypto-ccm;144;138;140;142;143;143;140;156;223;156 08:43:01 INFO - PROCESS | 1923 | |12;stanford-crypto-pbkdf2;168;166;168;168;168;166;221;229;177;169 08:43:01 INFO - PROCESS | 1923 | |13;stanford-crypto-sha256-iterative;66;68;68;68;69;68;65;67;68;67 08:43:01 INFO - PROCESS | 1923 | __end_tp_report 08:43:01 INFO - PROCESS | 1923 | __start_cc_report 08:43:01 INFO - PROCESS | 1923 | _x_x_mozilla_cycle_collect,1961 08:43:01 INFO - PROCESS | 1923 | __end_cc_report 08:43:01 INFO - PROCESS | 1923 | __startTimestamp1482424981284__endTimestamp 08:43:01 INFO - PROCESS | 1923 | 08:43:01 INFO - PROCESS | 1923 | ------- Summary: start ------- 08:43:01 INFO - PROCESS | 1923 | Number of tests: 14 08:43:01 INFO - PROCESS | 1923 | 08:43:01 INFO - PROCESS | 1923 | [#0] ai-astar Cycles:10 Average:112.50 Median:113.00 stddev:3.14 (2.8%) stddev-sans-first:1.01 08:43:01 INFO - PROCESS | 1923 | Values: 104.0 112.0 115.0 113.0 113.0 113.0 113.0 115.0 114.0 113.0 08:43:01 INFO - PROCESS | 1923 | 08:43:01 INFO - PROCESS | 1923 | [#1] audio-beat-detection Cycles:10 Average:130.00 Median:141.50 stddev:23.52 (16.6%) stddev-sans-first:24.40 08:43:01 INFO - PROCESS | 1923 | Values: 144.0 113.0 96.0 175.0 139.0 148.0 128.0 109.0 138.0 110.0 08:43:01 INFO - PROCESS | 1923 | 08:43:01 INFO - PROCESS | 1923 | [#2] audio-dft Cycles:10 Average:192.50 Median:186.00 stddev:21.69 (11.7%) stddev-sans-first:22.88 08:43:01 INFO - PROCESS | 1923 | Values: 186.0 184.0 186.0 187.0 184.0 190.0 254.0 186.0 184.0 184.0 08:43:01 INFO - PROCESS | 1923 | 08:43:01 INFO - PROCESS | 1923 | [#3] audio-fft Cycles:10 Average:67.50 Median:62.00 stddev:12.84 (20.7%) stddev-sans-first:12.50 08:43:01 INFO - PROCESS | 1923 | Values: 82.0 58.0 60.0 91.0 60.0 59.0 64.0 59.0 84.0 58.0 08:43:01 INFO - PROCESS | 1923 | 08:43:01 INFO - PROCESS | 1923 | [#4] audio-oscillator Cycles:10 Average:86.90 Median:86.00 stddev:16.39 (19.1%) stddev-sans-first:17.35 08:43:01 INFO - PROCESS | 1923 | Values: 84.0 72.0 97.0 69.0 80.0 97.0 122.0 88.0 68.0 92.0 08:43:01 INFO - PROCESS | 1923 | 08:43:01 INFO - PROCESS | 1923 | [#5] imaging-gaussian-blur Cycles:10 Average:90.80 Median:86.00 stddev:14.13 (16.4%) stddev-sans-first:14.94 08:43:01 INFO - PROCESS | 1923 | Values: 94.0 130.0 86.0 85.0 89.0 84.0 87.0 83.0 86.0 84.0 08:43:01 INFO - PROCESS | 1923 | 08:43:01 INFO - PROCESS | 1923 | [#6] imaging-darkroom Cycles:10 Average:102.10 Median:92.00 stddev:18.60 (20.2%) stddev-sans-first:19.36 08:43:01 INFO - PROCESS | 1923 | Values: 92.0 92.0 93.0 93.0 92.0 93.0 117.0 149.0 108.0 92.0 08:43:01 INFO - PROCESS | 1923 | 08:43:01 INFO - PROCESS | 1923 | [#7] imaging-desaturate Cycles:10 Average:92.60 Median:81.50 stddev:19.63 (24.1%) stddev-sans-first:20.63 08:43:01 INFO - PROCESS | 1923 | Values: 85.0 85.0 81.0 80.0 94.0 130.0 128.0 82.0 80.0 81.0 08:43:01 INFO - PROCESS | 1923 | 08:43:01 INFO - PROCESS | 1923 | [#8] json-parse-financial Cycles:10 Average:57.00 Median:58.00 stddev:1.56 (2.7%) stddev-sans-first:1.62 08:43:01 INFO - PROCESS | 1923 | Values: 56.0 57.0 54.0 55.0 57.0 59.0 58.0 58.0 58.0 58.0 08:43:01 INFO - PROCESS | 1923 | 08:43:01 INFO - PROCESS | 1923 | [#9] json-stringify-tinderbox Cycles:10 Average:70.60 Median:71.00 stddev:4.14 (5.8%) stddev-sans-first:3.87 08:43:01 INFO - PROCESS | 1923 | Values: 65.0 76.0 64.0 77.0 73.0 69.0 70.0 71.0 71.0 70.0 08:43:01 INFO - PROCESS | 1923 | 08:43:01 INFO - PROCESS | 1923 | [#10] stanford-crypto-aes Cycles:10 Average:84.50 Median:84.00 stddev:1.51 (1.8%) stddev-sans-first:1.59 08:43:01 INFO - PROCESS | 1923 | Values: 84.0 83.0 84.0 84.0 85.0 83.0 86.0 88.0 84.0 84.0 08:43:01 INFO - PROCESS | 1923 | 08:43:01 INFO - PROCESS | 1923 | [#11] stanford-crypto-ccm Cycles:10 Average:152.50 Median:143.50 stddev:25.56 (17.8%) stddev-sans-first:26.93 08:43:01 INFO - PROCESS | 1923 | Values: 144.0 138.0 140.0 142.0 143.0 143.0 140.0 156.0 223.0 156.0 08:43:01 INFO - PROCESS | 1923 | 08:43:01 INFO - PROCESS | 1923 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.00 Median:168.50 stddev:23.99 (14.2%) stddev-sans-first:25.05 08:43:01 INFO - PROCESS | 1923 | Values: 168.0 166.0 168.0 168.0 168.0 166.0 221.0 229.0 177.0 169.0 08:43:01 INFO - PROCESS | 1923 | 08:43:01 INFO - PROCESS | 1923 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.40 Median:68.00 stddev:1.17 (1.7%) stddev-sans-first:1.13 08:43:01 INFO - PROCESS | 1923 | Values: 66.0 68.0 68.0 68.0 69.0 68.0 65.0 67.0 68.0 67.0 08:43:01 INFO - PROCESS | 1923 | -------- Summary: end -------- 08:43:01 INFO - PROCESS | 1923 | 08:43:02 INFO - TEST-INFO | 1923: exit 0 08:43:02 INFO - TEST-OK | kraken | took 60585ms 08:43:02 INFO - SUITE-END | took 1180s 08:43:02 INFO - Completed test suite (00:19:40) 08:43:02 INFO - javascript benchmark 08:43:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3300.438925382685, 3327.883524076657, 3330.650281720419, 3342.916427089324, 3370.7471939604534, 5630.831075067755, 5648.604597678164, 5680.943190568093, 5697.572682048847, 5739.971300143498, 5592.049351610226, 5667.914981275284, 5678.687672178028, 5682.971585142069, 5701.857453563663, 5157.0, 5194.091034069042, 5206.349206349207, 5213.895722085556, 5235.424103348629, 5164.483551644843, 5180.248863914749, 5182.041322355358, 5182.481751824825, 5259.5529380002745, 5212.817551385702, 5281.128613778722, 5289.86775330616, 5290.68255904647, 5292.047431462335, 5264.894702105956, 5268.8155914543195, 5274.0, 5274.736263186864, 5344.064788662007, 5027.321311622957, 5161.66449180802, 5174.560162386202, 5186.988537235241, 5215.269862219292, 5594.860128496779, 5632.3804381518, 5670.3479099903625, 5689.715514224314, 5696.401877802812, 6166.229221347332, 6194.25668919728, 6194.783182588588, 6240.687965601747, 6263.34234905333, 6249.312575616678, 6250.749970001195, 6295.842603934892, 6300.21247344082, 6312.715927783215, 5565.833025009256, 5691.943080569225, 5695.60130790849, 5739.081746920473, 5785.5660825437835, 6165.951788196017, 6255.030470277115, 6273.529485288622, 6283.183186185766, 6290.308066112724, 6143.232095988002, 6152.0, 6159.81520554384, 6168.0439531872635, 6169.598976066542, 5737.8565535861935, 6118.0, 6153.0, 6246.125542424064, 6255.280642726054, 6110.3765049759795, 6120.933292792918, 6126.050462178369, 6145.0, 6165.568410211332, 5239.502247286502, 6112.052631842072, 6195.287541932646, 6202.937970620236, 6231.9688401557705, 4912.1158191525155, 4912.38595175603, 4914.164592019365, 4923.384576927884, 4934.481879402683, 3702.4816525686424, 4377.321515165155, 4397.582229688174, 4408.977955110204, 4432.49026361966, 5724.885502290014, 5757.740901659436, 5764.337101233412, 5787.39232380594, 5797.710114494258, 6260.686965651699, 6281.8743625127245, 6283.05754136874, 6286.779962701284, 6298.9291820390645, 5554.277943867352, 5668.348139963874, 5739.770409183586, 5758.596898217168, 5760.481556659921, 5359.678419294777, 5374.596905232123, 5394.3796463406425, 5407.134858422633, 5418.620696551204, 6258.186435763321, 6312.968435157794, 6318.273398559225, 6334.049892516159, 6334.619922804646, 4984.352034235526, 6254.155688982111, 6263.091851681572, 6267.310595834363, 6274.937250627435, 6103.9694801527485, 6204.534659900616, 6208.255009398901, 6239.939210334163, 6246.906296405648, 6187.938120618916, 6210.906836397548, 6215.409536094064, 6236.594621349598, 6242.250929888442, 6258.405451482102, 6267.435930766253, 6273.466755325849, 6291.402316779899, 6291.779787707408, 5940.495057920125, 5977.790777322773, 6050.1227322037175, 6052.90920636182, 6059.182010428536, 6121.239585875057, 6123.540734444845, 6125.754381768121, 6127.647695153045, 6134.069235337404, 5227.398849132398, 5794.55136215946, 5963.970180149245, 5971.552133590085, 6001.3998600141385, 5928.525717942641, 5964.821055368346, 6068.817935461943, 6103.42017508336, 6115.205023346936, 5800.564957628279, 5843.065109582446, 5853.414658534283, 5856.12158176277, 5857.062869940789], "value": 5667.880838269046, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [467.3433825475207, 507.2543361258949, 512.6642049457604, 514.4264145477794, 543.6330476928073, 24441.0, 30357.544636830437, 38605.0, 39399.42402303904, 39417.0, 39982.00089995496, 40323.798381008055, 40443.0, 40457.0, 40496.0, 9307.115823996726, 9493.477858717768, 9495.52522373882, 9495.905040949605, 9496.810063798719, 8464.365172612048, 8627.870581941244, 11038.558457661724, 11302.604408845693, 11352.489137988769, 9694.515274236295, 11346.943265283662, 11374.886251137465, 11401.429928503583, 11448.713782155472, 8532.701355452591, 8731.738047858575, 9681.854772178458, 11339.37633430161, 11433.485493152744, 11023.0, 11279.436028198557, 11349.716257093638, 11363.772724545463, 11507.424628768527, 39069.82790516289, 39216.01959950996, 39436.802815985735, 39513.0, 39604.80197599022, 3109.83381232038, 3379.7803142795638, 3449.7067749241346, 3495.038864312309, 3647.3252448296885, 25016.124435644848, 25102.0, 29559.0, 29577.55633665497, 30245.941392051394, 3091.9536206956914, 3117.345357474933, 3197.3605278944074, 3215.2765627733806, 3228.047725920859, 450681.0, 482291.0, 482423.0, 482562.0, 482819.0, 33072.66927330744, 34146.80486182972, 57629.71185144047, 57840.553986150684, 58028.12957805636, 34464.310713785584, 34855.651443485745, 34876.9536913893, 34948.0, 35032.949011529694, 14076.71846563063, 14204.005719599529, 14208.289585520683, 14219.360128794022, 14241.359138838778, 10935.507902144423, 11183.105351571854, 11819.172657914036, 11966.64100076999, 11987.340696261615, 7394.595026944863, 7604.5437273763755, 7706.344960678293, 7866.449348545661, 7882.448228623941, 2688.5563881959256, 3125.3749250150063, 3227.0798874669536, 3263.8204898730323, 3300.5544251526217, 945.6501094595044, 959.9760005999906, 989.342087511811, 1025.6358992557684, 1029.8249297619423, 29346.853265733534, 29546.896941167117, 29584.0, 29620.0, 30047.549286760717, 1017.3082304033328, 1032.7934413117257, 1045.29442626227, 1073.4632683658172, 1123.9269447485888, 14479.131252124906, 14968.326425310886, 15087.924560377129, 15256.618584535476, 15316.923415382851, 655941.0, 664697.0, 664752.0, 664753.0, 665792.0, 55521.72239138859, 55924.440755591924, 55969.44030559642, 56073.158902616495, 56130.0, 1055.3298655353751, 1265.6709255593428, 1344.368146970922, 1359.8436179839446, 1370.964921484293, 28799.712002879704, 29875.402491949604, 29968.700312996592, 29984.550231746976, 30614.693853061184, 47242.52757472381, 47745.52254477411, 47757.52242477531, 47808.760956194994, 47824.0, 469068.0, 546562.0, 547907.0, 550764.0, 550798.0, 26058.73941260563, 26543.0, 26789.0, 26819.061332853256, 26908.461830763667, 37810.81094594509, 45032.324515132954, 45247.54752452565, 45781.54218457906, 46246.768766155954, 37562.436563451025, 37946.24107517889, 38474.42288365621, 39495.0, 39931.0, 35612.821935890155, 37310.813445932596, 37959.63464201652, 38684.41973370458, 39485.40771888367, 36373.27253454863, 37014.814925925195, 37675.81162094172, 37862.62137378701, 38000.80999594984, 32432.35135297328, 33328.66671333255, 33536.76808438431, 33850.984470465926, 33952.15119622029, 25269.0, 25329.74670253274, 25426.74573254318, 25620.61569076428, 25830.483390331712], "value": 18319.00095522197, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.2874535946669, 365.6599362592789, 370.3740678253751, 377.6695391532408, 397.41976713997565, 14573.489927852528, 14798.92600536997, 15784.210789460538, 19826.306079287227, 20268.493287667814, 18351.816481835176, 18879.339223127194, 19561.70657440136, 19603.019849007564, 20403.693944590806, 5462.5083742463075, 5892.057270836667, 5911.172435858984, 5924.111383292519, 5934.376890426508, 4714.976425117869, 5801.6519008859605, 5833.125031245305, 5844.503217226541, 5848.824535263949, 5512.972435137839, 5696.515796157331, 5714.457126572969, 5715.428457154293, 5787.363390027093, 5697.373288938214, 5726.141078838166, 5758.3090029196555, 5765.048766953448, 5788.594798364116, 4844.7819848106565, 5058.873528161826, 5070.594352451795, 5072.264521644378, 5080.74596270185, 24241.151559695503, 24424.389390265387, 24961.875190624112, 25124.24627261185, 25393.746062539507, 2838.5884046813303, 2866.312085099561, 3035.741961933238, 3051.8474076296097, 3114.7508199343997, 14532.346044428026, 15297.388104475918, 15313.00465469752, 15334.079955202724, 15877.682446351124, 1792.9013904235262, 1846.5937493751353, 1847.8244566766136, 1860.4325680667391, 1888.0, 116164.83835161624, 117390.0, 120781.39609301985, 122488.0, 122672.77327226703, 9109.453432793922, 10058.74853128663, 10494.475276236157, 10537.736556586147, 10948.671539853816, 13481.393337299845, 13529.120607160503, 13535.39090740919, 13537.45850166002, 13553.864461355457, 8137.471064380797, 8366.874496882552, 8444.61999210037, 8566.571671416405, 8574.828503429897, 7143.0, 8422.494650321001, 8452.535110568855, 8478.491290522588, 8651.870221946676, 6411.551191416649, 6540.149780528596, 6540.247871494839, 6551.181102362205, 6684.498662600227, 1786.7141257398753, 1822.9817701822813, 1829.826166514179, 1841.622467394181, 1850.4818650777804, 743.2715938380418, 892.9821403571892, 900.3922352412096, 923.4920793563513, 943.6414162618298, 20237.886916219748, 21196.0, 21292.893535532225, 21989.780102199093, 22424.103035878383, 878.2622597018535, 902.5938327752538, 920.2545937790411, 952.9380590261611, 1019.4647809899714, 11089.83365249522, 11372.14708896836, 11458.58223815203, 11539.4807233673, 11715.4142292885, 514318.0, 611647.0, 615156.0, 622106.0, 625964.0, 85726.0, 107529.0, 110052.4497377524, 114111.0, 115231.0, 951.9190868776232, 966.4104896013426, 990.9207263418765, 996.1084829077935, 998.1565577087349, 18698.43904682862, 18932.337368192046, 19540.60918781588, 19607.9215643141, 19697.704534432283, 50606.74696626493, 50828.74585627196, 51317.0, 52710.0, 52998.145064922544, 63917.360826393, 70499.64750176216, 78828.21171788209, 79932.60033699794, 80620.387592249, 5458.344998600193, 7804.7122224832265, 7872.881906771288, 8035.959820200862, 8141.8778718320455, 10788.892111078789, 12603.68490787701, 12677.492900283885, 13006.0, 13175.14361566495, 8250.711225107092, 10561.947190264, 10862.239643224899, 10864.402457864577, 11051.336919784839, 10709.625163119254, 10852.0, 10948.616798412337, 11243.943780281046, 11302.643682757793, 9017.639294428149, 10237.692869213935, 11168.944155279172, 11356.26184298018, 11526.827097593376, 7901.130875603564, 7985.600719964094, 8207.876881846658, 8212.603857344386, 8273.875891861748, 8688.609012594576, 9430.41644175209, 10536.262461627919, 10744.140468762636, 10929.960739653166], "value": 9510.722186311677, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1303.804429335599, 1446.9493567725128, 1470.3603932289454, 1617.3449752850092, 1815.4372144635156, 7462.850742985137, 7851.685932562697, 7988.36093112551, 8291.336693064555, 8324.209200125993, 8184.672613095484, 8271.875921861178, 8274.95862520688, 8283.5029898206, 8285.958570207156, 2282.417983414231, 2286.302677683306, 2299.839011269212, 2333.1717240379676, 2334.043042352636, 1935.148534644759, 2265.150568536799, 2327.895244713992, 2329.184785325131, 2344.929652110431, 2287.828412869033, 2300.804431623306, 2326.8138548916045, 2332.4635333873134, 2335.381124002141, 1954.394137817274, 2269.8297627677907, 2276.3512398966213, 2321.593721098801, 2324.628059510487, 2301.217586020761, 2309.19178287598, 2337.2988103569032, 2357.0336162173426, 2373.8100951923807, 8271.17288271174, 8373.204545568162, 8378.874316885252, 8384.455010424364, 8386.496810191407, 5010.649254552182, 5216.478352164753, 5225.581953443715, 5239.5546378557865, 5251.894962100737, 3385.1875549867855, 3492.633273506296, 3566.732495062881, 3569.214772750017, 3586.4620306953907, 1048.3657387280662, 1062.2882668611971, 1064.6965614799822, 1079.5110974533839, 1092.7541303206795, 10723.624673136403, 10795.08241799448, 10859.13126949842, 10873.184511161695, 10873.836892446541, 3751.793651349202, 4267.103908179317, 4480.014396832692, 4487.259602165606, 4501.144782491317, 8504.31965442763, 8629.741107766777, 8631.870521942175, 8668.089850565726, 8679.0, 5107.87230319245, 5108.208227724708, 5109.0, 5114.820981265638, 5122.897542049138, 4061.2283666103936, 4286.271333873249, 4441.333799929972, 4459.0858873930765, 4709.811607535728, 3270.689284517967, 3448.7067349743847, 3567.8037707926314, 3576.803275819803, 3639.1993761372455, 968.8207681578931, 975.9853602195973, 980.3578655980344, 981.1660088924357, 987.3878195518681, 692.3699433515577, 729.6169511006764, 861.5390765940317, 866.8396346675759, 880.5113162194915, 5457.945420545823, 5500.50495455411, 5512.2558454608115, 5539.446055394428, 5721.0, 839.6809212499334, 865.372604861483, 900.0999000999001, 904.5025236120108, 947.8056998315329, 3187.9043628691174, 3662.50556174913, 3689.4281386385155, 3714.0343510686876, 3896.883093507199, 9540.809183816285, 10043.598256069821, 10109.747256318504, 10186.490675466197, 10257.538410771533, 10117.797644047077, 10237.283390162765, 10249.12882404989, 10253.128484078938, 10296.176305895511, 7696.461247712607, 7762.573058481725, 7768.805779855549, 7889.0, 7953.76138715851, 5366.168243922199, 5423.484768946943, 5497.016061998307, 5679.148127780866, 5734.283214598176, 9347.859782103138, 9406.059394060812, 9435.858462123211, 9467.905320946702, 9555.61777528891, 8025.719099831478, 8722.651093956425, 9550.188234000188, 9569.856452153363, 9586.568604412958, 6675.933240667531, 6875.5530890491955, 6879.6560171992205, 6890.896636550556, 6910.896336554855, 6725.630090345078, 7635.5800430974605, 7812.0, 7905.723299684483, 7918.802029949297, 6525.727483140742, 6942.652867356713, 7122.60825654594, 7124.073870396815, 7257.201707812242, 5933.732982015906, 6444.77549265638, 6983.266756990442, 7112.0043193953925, 7151.0, 6433.420992110732, 6647.60114393138, 6722.596644201363, 6724.966375168093, 6727.192736871607, 4576.336431217522, 4781.378420805273, 4802.423709154924, 4823.517648235289, 4947.480514546065, 4508.256137737368, 4677.672562920563, 4688.437387513521, 4741.336212930123, 4839.443464001544], "value": 4300.633122605772, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [279.14998828567013, 304.7333583114776, 308.30784887926603, 315.8768080448624, 316.69280797626294, 5191.558717509011, 5197.714125723083, 5212.244224587437, 5251.501107394801, 5269.920951185735, 4552.726836389811, 4641.234196357597, 4863.708177509352, 5021.2468129780555, 5125.641205115643, 3464.965350346502, 3567.8751243706474, 3837.982934522347, 3977.6221258980495, 4029.37544680575, 3596.5504311961004, 3993.0, 3995.6204160604843, 3995.781286707553, 4022.296098182821, 3298.950515742266, 3669.9633003670024, 3936.2127574485075, 4002.7198096133284, 4018.4173294872226, 3339.3655205510995, 3918.7648741075627, 3931.2334094851517, 3934.11482416454, 3982.3230050891416, 3318.0, 3341.0979035660234, 3396.4735466002808, 3401.6598340166033, 3425.2978139481347, 4964.914735810932, 4996.325496058022, 5028.974855125701, 5045.722485263309, 5069.366329208849, 3760.4547340635454, 3767.3030489359558, 4266.188662334771, 4434.955650443487, 4470.552944705503, 4725.5510726480925, 5139.948600514022, 5365.973170134124, 5372.865678358034, 5380.381256155514, 3270.411325961326, 3643.7995910224927, 3700.2414505026672, 3716.7212459065677, 3744.2885851688093, 4755.714657120549, 5385.5960802939935, 5526.447355264522, 5675.404082571353, 5695.971520142414, 2918.022462475052, 3244.2213868671347, 3468.283199816057, 3528.700664878296, 3806.1816709407613, 3832.865849695247, 3841.2509560635967, 3841.2893614681375, 3854.4989151410136, 3885.3006458837117, 4423.601875831127, 4432.756198409118, 4433.0, 4488.755622188905, 4504.346869703874, 4632.814687412466, 5097.8215762448135, 5099.235114732745, 5438.891222175614, 5455.454454554513, 1117.7205698575356, 1224.915949384783, 1232.7596118756755, 1237.9195352302072, 1248.6628610275175, 793.9678418056503, 810.793247721839, 817.2930415190817, 838.773531146587, 856.1695155698962, 4658.58072773452, 4774.0, 4825.396825396825, 4836.951630483721, 4882.926756098661, 4630.930536041962, 5144.228365745168, 5259.973700131552, 5278.340207474066, 5314.441983591667, 4443.511213766483, 4508.549145085531, 4676.929846052312, 4730.432348118248, 4734.692245004064, 2378.8334816562674, 2734.6581677290337, 2750.91897075277, 2755.917322480329, 2768.958465623017, 5339.332583427072, 5378.902228131032, 5387.461253874581, 5391.973040134852, 5402.972985135128, 4936.384480510662, 5137.893463679873, 5204.635675502678, 5290.941811637688, 5322.973385133127, 4617.746023968714, 4770.902692380702, 5201.1678131498775, 5250.820909322589, 5359.705216213146, 4829.927551086666, 5153.742312884416, 5166.276721259102, 5189.6107791915, 5222.55608273301, 4848.272759086024, 5037.294778730908, 5156.587473002076, 5168.328117344721, 5199.428062913001, 4537.8184872604725, 4668.7665616719705, 4930.052629211513, 5357.6158609418435, 5360.705161216015, 3557.5730912289655, 3572.214112895159, 3592.2995015971633, 3595.6089200681254, 3596.6942809861457, 4812.868975790757, 4913.459519452929, 5021.422536408214, 5122.718250496109, 5210.827563798115, 4923.556879880828, 5041.470645582308, 5067.77195026232, 5090.770915308746, 5138.895137545445, 4593.908121837611, 4783.986088764056, 4796.400449943757, 5013.373328333958, 5038.974805125951, 4098.364753463099, 4496.0558282760985, 4523.411956445641, 4680.438347398198, 4960.851174464772, 5014.473480284518, 5271.235670827785, 5312.495312945264, 5315.574754019592, 5427.728613569227, 4075.836966521306, 4554.88612784683, 5260.528051486001, 5273.578113750814, 5307.256984022163], "value": 3831.8456804804096, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [708.8334241453255, 736.9447291453139, 747.89155572442, 768.8808234723616, 787.0594639405911, 14231.928840355797, 15828.525144245677, 16454.424095156643, 16520.58698532537, 16905.661886762256, 15039.548813535612, 15092.0190187638, 15100.848991510082, 15122.773158402606, 15175.544733658007, 644.5037321262625, 790.8418316336741, 814.702633538757, 819.6598411659154, 819.9303059239971, 663.2505269045971, 667.5393978155057, 674.2482132422363, 798.0582912163644, 817.7587611654576, 826.6528058215533, 827.0571548434789, 842.6250318608191, 863.3999370437548, 866.263675875504, 672.5661948043485, 860.956952152393, 862.0000799073108, 862.2584577263548, 863.7797361672733, 514.1824499046534, 611.74918283504, 623.8272048548699, 632.8006677896494, 633.3444884544521, 14217.715645687029, 14234.359453824603, 14248.643783905381, 14266.144031358152, 14290.285485725672, 12486.81269780954, 12594.181378210387, 12689.555865544755, 12789.872101279054, 13000.414981325863, 11018.834717479243, 11541.249818761838, 12152.0, 12281.754364912651, 12314.152877068422, 4225.598568136023, 4275.679324050709, 4357.629601483878, 4385.232584297735, 4519.163954668397, 82188.65773724689, 85295.14704852997, 85986.0, 85986.28027439416, 86688.0, 8562.571871406404, 9720.416774993522, 10104.444255566015, 10140.44227567476, 10370.896291037145, 18848.15183316754, 18859.52851178704, 18992.43022709321, 18993.525161871064, 19026.238950442104, 7658.2724641159, 7674.270944260286, 7674.347680447113, 7853.8821917671285, 7898.486598371088, 4186.602272784081, 4195.706300558993, 4338.934915976263, 4339.978300108479, 4376.474823021194, 3359.5632567766525, 3413.6245013048547, 3416.590009198912, 3473.548438703003, 3476.235228249781, 561.4049107945591, 637.0666972884746, 675.4967549175896, 717.8492516571579, 718.4216705551976, 584.739790793093, 616.2727980982484, 647.7700416352221, 648.4779752299444, 666.3103687683224, 8588.441751286146, 8713.433626814363, 8782.825761357963, 8811.559422029, 9388.8591671125, 588.2822955993681, 623.6725718997471, 645.854682696399, 669.1234482827512, 673.4545018535002, 4435.090806384684, 4439.1121775645, 4448.822047118144, 4471.552844715502, 4473.776311184428, 74501.25498744943, 96870.54694179592, 99748.5012574947, 110948.0, 119427.0, 65262.0, 91504.54247728852, 93139.53430232806, 103964.48017759863, 104223.47888260511, 85205.57397213098, 88299.0, 90193.09806901978, 91420.0, 92849.99999999865, 8593.742187734379, 8666.263367613823, 8677.913220867711, 8746.212840844355, 8932.732018039469, 47071.0, 55604.0, 56894.86210275689, 57216.71391643015, 57551.848963021344, 51534.0, 63507.6824615874, 64010.7197856031, 72030.0, 72664.6366768184, 11189.992900638981, 11487.25332853362, 11875.703107422383, 12062.336571488653, 12417.441215145522, 13954.58136255914, 13967.930160349133, 14075.859241407456, 14193.432262709788, 14244.145351278956, 10841.241113122005, 12855.164414313414, 13228.272445015617, 13357.799633005317, 13490.190588564717, 12202.572909948109, 13050.869491304966, 13266.800997984845, 13293.468261269441, 13709.245991470565, 12373.13388062827, 12491.312977786309, 13024.54414095502, 13326.400311986177, 13708.383122759698, 10652.786944260917, 11242.0, 11508.027124432932, 11651.88348116508, 11903.40482975865, 9807.803843922939, 11468.19722619442, 11471.740346950182, 11531.827022594836, 11578.478968446272], "value": 7242.503782449365, "unit": "score"}], "name": "dromaeo_css", "value": 7002.216647513274, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 112.0, 115.0, 113.0, 113.0, 113.0, 113.0, 115.0, 114.0, 113.0], "value": 112.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 113.0, 96.0, 175.0, 139.0, 148.0, 128.0, 109.0, 138.0, 110.0], "value": 130.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 184.0, 186.0, 187.0, 184.0, 190.0, 254.0, 186.0, 184.0, 184.0], "value": 192.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 58.0, 60.0, 91.0, 60.0, 59.0, 64.0, 59.0, 84.0, 58.0], "value": 67.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 72.0, 97.0, 69.0, 80.0, 97.0, 122.0, 88.0, 68.0, 92.0], "value": 86.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 130.0, 86.0, 85.0, 89.0, 84.0, 87.0, 83.0, 86.0, 84.0], "value": 90.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 93.0, 93.0, 92.0, 93.0, 117.0, 149.0, 108.0, 92.0], "value": 102.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 81.0, 80.0, 94.0, 130.0, 128.0, 82.0, 80.0, 81.0], "value": 92.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 54.0, 55.0, 57.0, 59.0, 58.0, 58.0, 58.0, 58.0], "value": 57.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 76.0, 64.0, 77.0, 73.0, 69.0, 70.0, 71.0, 71.0, 70.0], "value": 70.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 84.0, 84.0, 85.0, 83.0, 86.0, 88.0, 84.0, 84.0], "value": 84.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 138.0, 140.0, 142.0, 143.0, 143.0, 140.0, 156.0, 223.0, 156.0], "value": 152.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 166.0, 168.0, 168.0, 168.0, 166.0, 221.0, 229.0, 177.0, 169.0], "value": 180.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 68.0, 68.0, 68.0, 69.0, 68.0, 65.0, 67.0, 68.0, 67.0], "value": 67.4, "unit": "score"}], "name": "kraken", "value": 1486.9, "alertThreshold": 2.0}]} 08:43:02 INFO - Return code: 0 08:43:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:43:02 INFO - # TBPL SUCCESS # 08:43:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:43:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:43:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:43:02 INFO - 2016-12-22 08:43:02.626 screenresolution[1927:65773] starting screenresolution argv=screenresolution get 08:43:02 INFO - 2016-12-22 08:43:02.630 screenresolution[1927:65773] Display 0: 1600x1200x32@60 08:43:02 INFO - 2016-12-22 08:43:02.636 screenresolution[1928:65776] starting screenresolution argv=screenresolution list 08:43:02 INFO - Available Modes on Display 0 08:43:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:43:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:43:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:43:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:43:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:43:02 INFO - Intel Iris: 08:43:02 INFO - Chipset Model: Intel Iris 08:43:02 INFO - Type: GPU 08:43:02 INFO - Bus: Built-In 08:43:02 INFO - VRAM (Dynamic, Max): 1536 MB 08:43:02 INFO - Vendor: Intel (0x8086) 08:43:02 INFO - Device ID: 0x0a2e 08:43:02 INFO - Revision ID: 0x0009 08:43:02 INFO - Displays: 08:43:02 INFO - Display: 08:43:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:43:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:43:02 INFO - Display Serial Number: 1600x1200 60 08:43:02 INFO - Main Display: Yes 08:43:02 INFO - Mirror: Off 08:43:02 INFO - Online: Yes 08:43:02 INFO - Rotation: Supported 08:43:02 INFO - Return code: 0 08:43:02 INFO - Running post-action listener: _resource_record_post_action 08:43:02 INFO - [mozharness: 2016-12-22 16:43:02.883440Z] Finished run-tests step (success) 08:43:02 INFO - Running post-run listener: _resource_record_post_run 08:43:02 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 245760; Write bytes: 654331904; Read time: 6; Write time: 2012 08:43:02 INFO - TinderboxPrint: CPU usage
53.5% 08:43:02 INFO - TinderboxPrint: I/O read bytes / time
245,760 / 6 08:43:02 INFO - TinderboxPrint: I/O write bytes / time
654,331,904 / 2,012 08:43:02 INFO - TinderboxPrint: CPU idle
2,255.2 (46.5%) 08:43:02 INFO - TinderboxPrint: CPU system
969.6 (20.0%) 08:43:02 INFO - TinderboxPrint: CPU user
1,620.9 (33.5%) 08:43:02 INFO - TinderboxPrint: Swap in / out
348,397,568 / 0 08:43:02 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 349501952; Write bytes: 352663040; Read time: 26839; Write time: 724 08:43:02 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 118784; Write bytes: 300390912; Read time: 4; Write time: 1281 08:43:03 INFO - Running post-run listener: _upload_blobber_files 08:43:03 INFO - Blob upload gear active. 08:43:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:43:03 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 08:43:03 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'] 08:43:03 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 08:43:03 INFO - (blobuploader) - INFO - Open directory for files ... 08:43:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:43:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:43:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:43:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:43:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:43:04 INFO - (blobuploader) - INFO - Done attempting. 08:43:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:43:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:43:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:43:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:43:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:43:05 INFO - (blobuploader) - INFO - Done attempting. 08:43:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:43:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:43:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:43:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:43:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:43:07 INFO - (blobuploader) - INFO - Done attempting. 08:43:07 INFO - (blobuploader) - INFO - Iteration through files over. 08:43:07 INFO - Return code: 0 08:43:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:43:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:43:07 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0e541747e648ba91d918fb1e1fdcac733cad0234c6de1c1d2e4b3960055a14c95fa3229eac85819ffd42fefe74605f3fd96f4e3edbb8b13e6802514a4ac7e87a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d0fcd47de6a172aebd02f07994b018142b6356887caf9c2a8c2abd509f68d86a233e9bcf4c86cff8cc72ee4db5a43f0467a3086356c7541d831f0026365e8688", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aac13c23611bd83531b9f12e44d413579eb3d3cd4a4d44296c89209c7f7ccc13476154fc57c644a644988b22caea035f136bf8b299d6ef22d9f8d0a28e6dee5d"} 08:43:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:43:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:43:07 INFO - Contents: 08:43:07 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0e541747e648ba91d918fb1e1fdcac733cad0234c6de1c1d2e4b3960055a14c95fa3229eac85819ffd42fefe74605f3fd96f4e3edbb8b13e6802514a4ac7e87a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d0fcd47de6a172aebd02f07994b018142b6356887caf9c2a8c2abd509f68d86a233e9bcf4c86cff8cc72ee4db5a43f0467a3086356c7541d831f0026365e8688", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aac13c23611bd83531b9f12e44d413579eb3d3cd4a4d44296c89209c7f7ccc13476154fc57c644a644988b22caea035f136bf8b299d6ef22d9f8d0a28e6dee5d"} 08:43:07 INFO - Running post-run listener: copy_logs_to_upload_dir 08:43:07 INFO - Copying logs to upload dir... 08:43:07 INFO - mkdir: /builds/slave/test/build/upload/logs 08:43:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.823612 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-12-22 08:43:07.817946) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-22 08:43:07.821452) ========= 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.OnNeXKEvGn/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.i9FjvePrvC/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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/0e541747e648ba91d918fb1e1fdcac733cad0234c6de1c1d2e4b3960055a14c95fa3229eac85819ffd42fefe74605f3fd96f4e3edbb8b13e6802514a4ac7e87a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d0fcd47de6a172aebd02f07994b018142b6356887caf9c2a8c2abd509f68d86a233e9bcf4c86cff8cc72ee4db5a43f0467a3086356c7541d831f0026365e8688", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aac13c23611bd83531b9f12e44d413579eb3d3cd4a4d44296c89209c7f7ccc13476154fc57c644a644988b22caea035f136bf8b299d6ef22d9f8d0a28e6dee5d"} build_url:https://queue.taskcluster.net/v1/task/AQmIoI9GT3GGWNJAfdvCbA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011395 build_url: 'https://queue.taskcluster.net/v1/task/AQmIoI9GT3GGWNJAfdvCbA/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/0e541747e648ba91d918fb1e1fdcac733cad0234c6de1c1d2e4b3960055a14c95fa3229eac85819ffd42fefe74605f3fd96f4e3edbb8b13e6802514a4ac7e87a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d0fcd47de6a172aebd02f07994b018142b6356887caf9c2a8c2abd509f68d86a233e9bcf4c86cff8cc72ee4db5a43f0467a3086356c7541d831f0026365e8688", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/aac13c23611bd83531b9f12e44d413579eb3d3cd4a4d44296c89209c7f7ccc13476154fc57c644a644988b22caea035f136bf8b299d6ef22d9f8d0a28e6dee5d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-22 08:43:07.856228) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 08:43:07.856642) ========= 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.OnNeXKEvGn/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.i9FjvePrvC/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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.005263 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-22 08:43:07.906678) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-22 08:43:07.907224) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-22 08:43:07.907731) ========= ========= Total master_lag: 2.27 =========