builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0131 starttime: 1485014660.82 results: success (0) buildid: 20170121070324 builduid: b6ed1739d3b74464a0cfe2ff37864145 revision: b58eb6e9e6c683cd32855ad927990fa6d585a1a8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-21 08:04:20.820547) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-21 08:04:20.821444) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-21 08:04:20.822163) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-21 08:04:20.857009) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-21 08:04:20.857690) ========= 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.oxi2ZbfgYp/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.gEaIiVyC8Y/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-21 08:04:20-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 69.5M=0s 2017-01-21 08:04:21 (69.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.145289 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-21 08:04:21.031205) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-21 08:04:21.031961) ========= 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.oxi2ZbfgYp/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.gEaIiVyC8Y/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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.019634 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-21 08:04:21.082331) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-21 08:04:21.082724) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev b58eb6e9e6c683cd32855ad927990fa6d585a1a8 --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 b58eb6e9e6c683cd32855ad927990fa6d585a1a8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.oxi2ZbfgYp/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.gEaIiVyC8Y/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-21 08:04:21,158 truncating revision to first 12 chars 2017-01-21 08:04:21,158 Setting DEBUG logging. 2017-01-21 08:04:21,158 attempt 1/10 2017-01-21 08:04:21,158 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/b58eb6e9e6c6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-21 08:04:22,996 unpacking tar archive at: mozilla-aurora-b58eb6e9e6c6/testing/mozharness/ program finished with exit code 0 elapsedTime=2.088471 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-01-21 08:04:23.195392) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-21 08:04:23.195835) ========= script_repo_revision: b58eb6e9e6c683cd32855ad927990fa6d585a1a8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-21 08:04:23.196279) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-21 08:04:23.196613) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-21 08:04:23.217029) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2017-01-21 08:04:23.217489) ========= /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.oxi2ZbfgYp/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.gEaIiVyC8Y/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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:04:23 INFO - MultiFileLogger online at 20170121 08:04:23 in /builds/slave/test 08:04:23 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:04:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:04:23 INFO - {'append_to_log': False, 08:04:23 INFO - 'base_work_dir': '/builds/slave/test', 08:04:23 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:04:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:04:23 INFO - 'branch': 'Mozilla-Aurora', 08:04:23 INFO - 'buildbot_json_path': 'buildprops.json', 08:04:23 INFO - 'config_files': ('talos/mac_config.py',), 08:04:23 INFO - 'default_actions': ('clobber', 08:04:23 INFO - 'read-buildbot-config', 08:04:23 INFO - 'download-and-extract', 08:04:23 INFO - 'populate-webroot', 08:04:23 INFO - 'create-virtualenv', 08:04:23 INFO - 'install', 08:04:23 INFO - 'run-tests'), 08:04:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:04:23 INFO - 'download_minidump_stackwalk': True, 08:04:23 INFO - 'download_symbols': 'ondemand', 08:04:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:04:23 INFO - 'tooltool.py': '/tools/tooltool.py', 08:04:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:04:23 INFO - '/tools/misc-python/virtualenv.py')}, 08:04:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:04:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:04:23 INFO - 'installer_path': 'installer.exe', 08:04:23 INFO - 'log_level': 'info', 08:04:23 INFO - 'log_name': 'talos', 08:04:23 INFO - 'log_to_console': True, 08:04:23 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:04:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:04:23 INFO - 'opt_config_files': (), 08:04:23 INFO - 'pip_index': False, 08:04:23 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:04:23 INFO - 'cmd': ('bash', 08:04:23 INFO - '-c', 08:04:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:04:23 INFO - 'enabled': True, 08:04:23 INFO - 'halt_on_failure': False, 08:04:23 INFO - 'name': 'check_screen_resolution'},), 08:04:23 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:04:23 INFO - 'cmd': ('bash', 08:04:23 INFO - '-c', 08:04:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:04:23 INFO - 'enabled': True, 08:04:23 INFO - 'halt_on_failure': False, 08:04:23 INFO - 'name': 'check_screen_resolution'},), 08:04:23 INFO - 'run_cmd_checks_enabled': True, 08:04:23 INFO - 'sps_profile': False, 08:04:23 INFO - 'sps_profile_interval': 0, 08:04:23 INFO - 'suite': 'dromaeojs', 08:04:23 INFO - 'system_bits': '32', 08:04:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:04:23 INFO - 'title': 't-yosemite-r7-0131', 08:04:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:04:23 INFO - 'use_talos_json': True, 08:04:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:04:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:04:23 INFO - 'work_dir': 'build'} 08:04:23 INFO - [mozharness: 2017-01-21 16:04:23.419738Z] Running clobber step. 08:04:23 INFO - Running pre-action listener: _resource_record_pre_action 08:04:23 INFO - Running main action method: clobber 08:04:23 INFO - rmtree: /builds/slave/test/build 08:04:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:04:25 INFO - Running post-action listener: _resource_record_post_action 08:04:25 INFO - [mozharness: 2017-01-21 16:04:25.271713Z] Finished clobber step (success) 08:04:25 INFO - [mozharness: 2017-01-21 16:04:25.271881Z] Running read-buildbot-config step. 08:04:25 INFO - Running pre-action listener: _resource_record_pre_action 08:04:25 INFO - Running main action method: read_buildbot_config 08:04:25 INFO - Using buildbot properties: 08:04:25 INFO - { 08:04:25 INFO - "project": "", 08:04:25 INFO - "product": "firefox", 08:04:25 INFO - "script_repo_revision": "production", 08:04:25 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 08:04:25 INFO - "repository": "", 08:04:25 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 08:04:25 INFO - "buildid": "20170121070324", 08:04:25 INFO - "pgo_build": "False", 08:04:25 INFO - "basedir": "/builds/slave/test", 08:04:25 INFO - "buildnumber": 28, 08:04:25 INFO - "slavename": "t-yosemite-r7-0131", 08:04:25 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 08:04:25 INFO - "platform": "yosemite_r7", 08:04:25 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:04:25 INFO - "branch": "mozilla-aurora", 08:04:25 INFO - "repo_path": "releases/mozilla-aurora", 08:04:25 INFO - "revision": "b58eb6e9e6c683cd32855ad927990fa6d585a1a8", 08:04:25 INFO - "stage_platform": "macosx64", 08:04:25 INFO - "builduid": "b6ed1739d3b74464a0cfe2ff37864145", 08:04:25 INFO - "slavebuilddir": "test" 08:04:25 INFO - } 08:04:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/b8QGS0x6SleI5IAcI54Oqg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 08:04:25 INFO - Running post-action listener: _resource_record_post_action 08:04:25 INFO - [mozharness: 2017-01-21 16:04:25.275137Z] Finished read-buildbot-config step (success) 08:04:25 INFO - [mozharness: 2017-01-21 16:04:25.275235Z] Running download-and-extract step. 08:04:25 INFO - Running pre-action listener: _resource_record_pre_action 08:04:25 INFO - Running main action method: download_and_extract 08:04:25 INFO - mkdir: /builds/slave/test/build/tests 08:04:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:04:25 INFO - trying https://queue.taskcluster.net/v1/task/b8QGS0x6SleI5IAcI54Oqg/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 08:04:25 INFO - Downloading https://queue.taskcluster.net/v1/task/b8QGS0x6SleI5IAcI54Oqg/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:04:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b8QGS0x6SleI5IAcI54Oqg/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:04:26 INFO - Downloaded 1199 bytes. 08:04:26 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 08:04:26 INFO - Using the following test package requirements: 08:04:26 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:04:26 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:04:26 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 08:04:26 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:04:26 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 08:04:26 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:04:26 INFO - u'jsshell-mac.zip'], 08:04:26 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:04:26 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 08:04:26 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 08:04:26 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:04:26 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 08:04:26 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:04:26 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 08:04:26 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:04:26 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 08:04:26 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 08:04:26 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 08:04:26 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:04:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b8QGS0x6SleI5IAcI54Oqg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:04:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b8QGS0x6SleI5IAcI54Oqg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:04:26 INFO - Fetch https://queue.taskcluster.net/v1/task/b8QGS0x6SleI5IAcI54Oqg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:04:27 INFO - Http code: 200 08:04:27 INFO - via: 1.1 dc7c4fb5024ff022cad1642ec506a6e8.cloudfront.net (CloudFront) 08:04:27 INFO - x-amz-cf-id: VpBfo1xpicoE9k93gkYddsx69gqvQd2U0v8PRwY4nB23pu2IT3GZYQ== 08:04:27 INFO - x-amz-version-id: lrzOdeAGfFkFKDiJ3DQO9r.rLkvTbpzh 08:04:27 INFO - Expected file size: 16277533 08:04:27 INFO - Obtained file size: 16277533 08:04:34 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:04:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b8QGS0x6SleI5IAcI54Oqg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 08:04:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b8QGS0x6SleI5IAcI54Oqg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:04:34 INFO - Fetch https://queue.taskcluster.net/v1/task/b8QGS0x6SleI5IAcI54Oqg/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 08:04:34 INFO - Http code: 200 08:04:34 INFO - via: 1.1 55676da1e5c0a9c4e60a94a95b01dc04.cloudfront.net (CloudFront) 08:04:34 INFO - x-amz-cf-id: hjg7aAy_E_gmleCOVnfQ2geyUgi7hur3VtuD_ek1HZaa2rVZ8GMbtw== 08:04:34 INFO - x-amz-version-id: lrzOdeAGfFkFKDiJ3DQO9r.rLkvTbpzh 08:04:34 INFO - Expected file size: 16277533 08:04:34 INFO - Obtained file size: 16277533 08:04:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/b8QGS0x6SleI5IAcI54Oqg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 08:04:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/b8QGS0x6SleI5IAcI54Oqg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:04:41 INFO - Fetch https://queue.taskcluster.net/v1/task/b8QGS0x6SleI5IAcI54Oqg/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 08:04:41 INFO - Http code: 200 08:04:41 INFO - via: 1.1 55676da1e5c0a9c4e60a94a95b01dc04.cloudfront.net (CloudFront) 08:04:41 INFO - x-amz-cf-id: QchdUU6dd200VDWfidlM21C1Gs4GzurBH9izHwsOxR2KmJukPhmupA== 08:04:41 INFO - x-amz-version-id: IZKJRgkDaO.6Cw37smZeEfOGM2_ys8cG 08:04:42 INFO - Expected file size: 14402992 08:04:42 INFO - Obtained file size: 14402992 08:04:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:04:42 INFO - trying https://queue.taskcluster.net/v1/task/b8QGS0x6SleI5IAcI54Oqg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:04:42 INFO - Downloading https://queue.taskcluster.net/v1/task/b8QGS0x6SleI5IAcI54Oqg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 08:04:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/b8QGS0x6SleI5IAcI54Oqg/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:04:45 INFO - Downloaded 110607605 bytes. 08:04:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/b8QGS0x6SleI5IAcI54Oqg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:04:45 INFO - mkdir: /builds/slave/test/properties 08:04:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:04:45 INFO - Writing to file /builds/slave/test/properties/build_url 08:04:45 INFO - Contents: 08:04:45 INFO - build_url:https://queue.taskcluster.net/v1/task/b8QGS0x6SleI5IAcI54Oqg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 08:04:45 INFO - Running post-action listener: _resource_record_post_action 08:04:45 INFO - Running post-action listener: set_extra_try_arguments 08:04:45 INFO - [mozharness: 2017-01-21 16:04:45.943116Z] Finished download-and-extract step (success) 08:04:45 INFO - [mozharness: 2017-01-21 16:04:45.943247Z] Running populate-webroot step. 08:04:45 INFO - Running pre-action listener: _resource_record_pre_action 08:04:45 INFO - Running main action method: populate_webroot 08:04:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:04:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:04:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:04:45 INFO - '--tppagecycles', 08:04:45 INFO - '7'], 08:04:45 INFO - 'tests': ['tsvgm']}}, 08:04:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:04:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:04:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:04:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:04:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:04:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:04:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:04:45 INFO - 'talos_options': ['--disable-e10s'], 08:04:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:04:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:04:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:04:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:04:45 INFO - 'talos_options': ['--disable-e10s'], 08:04:45 INFO - 'tests': ['damp', 'tps']}, 08:04:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:04:45 INFO - 'tests': ['damp', 'tps']}, 08:04:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:04:45 INFO - 'tests': ['dromaeo_dom']}, 08:04:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:04:45 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:04:45 INFO - 'tests': ['basic_compositor_video']}, 08:04:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:04:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:04:45 INFO - 'tests': ['a11yr', 08:04:45 INFO - 'ts_paint', 08:04:45 INFO - 'tpaint', 08:04:45 INFO - 'sessionrestore', 08:04:45 INFO - 'sessionrestore_no_auto_restore', 08:04:45 INFO - 'tabpaint']}, 08:04:45 INFO - 'other-e10s': {'tests': ['a11yr', 08:04:45 INFO - 'ts_paint', 08:04:45 INFO - 'tpaint', 08:04:45 INFO - 'sessionrestore', 08:04:45 INFO - 'sessionrestore_no_auto_restore', 08:04:45 INFO - 'tabpaint']}, 08:04:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:04:45 INFO - 'ts_paint', 08:04:45 INFO - 'tpaint', 08:04:45 INFO - 'sessionrestore', 08:04:45 INFO - 'sessionrestore_no_auto_restore', 08:04:45 INFO - 'tabpaint']}, 08:04:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:04:45 INFO - 'ts_paint', 08:04:45 INFO - 'tpaint', 08:04:45 INFO - 'sessionrestore', 08:04:45 INFO - 'sessionrestore_no_auto_restore', 08:04:45 INFO - 'tabpaint']}, 08:04:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:04:45 INFO - 'tests': ['a11yr', 08:04:45 INFO - 'ts_paint', 08:04:45 INFO - 'tpaint', 08:04:45 INFO - 'sessionrestore', 08:04:45 INFO - 'sessionrestore_no_auto_restore', 08:04:45 INFO - 'tabpaint']}, 08:04:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:04:45 INFO - 'tests': ['a11yr', 08:04:45 INFO - 'ts_paint', 08:04:45 INFO - 'tpaint', 08:04:45 INFO - 'sessionrestore', 08:04:45 INFO - 'sessionrestore_no_auto_restore', 08:04:45 INFO - 'tabpaint']}, 08:04:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:04:45 INFO - 'tests': ['tsvgx', 08:04:45 INFO - 'tsvgr_opacity', 08:04:45 INFO - 'tart', 08:04:45 INFO - 'tscrollx', 08:04:45 INFO - 'cart']}, 08:04:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:04:45 INFO - 'tsvgr_opacity', 08:04:45 INFO - 'tart', 08:04:45 INFO - 'tscrollx', 08:04:45 INFO - 'cart']}, 08:04:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:04:45 INFO - 'talos_options': ['--disable-e10s'], 08:04:45 INFO - 'tests': ['tp5o']}, 08:04:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:04:45 INFO - 'tests': ['tp5o']}, 08:04:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:04:45 INFO - 'talos_options': ['--disable-e10s', 08:04:45 INFO - '--xperf_path', 08:04:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:04:45 INFO - 'tests': ['tp5n']}, 08:04:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 08:04:45 INFO - 'talos_options': ['--xperf_path', 08:04:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:04:45 INFO - 'tests': ['tp5n']}}, 08:04:45 INFO - 'talos.zip': {'path': '', 08:04:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:04:45 INFO - Running post-action listener: _resource_record_post_action 08:04:45 INFO - [mozharness: 2017-01-21 16:04:45.961899Z] Finished populate-webroot step (success) 08:04:45 INFO - [mozharness: 2017-01-21 16:04:45.962013Z] Running create-virtualenv step. 08:04:45 INFO - Running pre-action listener: _resource_record_pre_action 08:04:45 INFO - Running main action method: create_virtualenv 08:04:45 INFO - Creating virtualenv /builds/slave/test/build/venv 08:04:45 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:04:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:04:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:04:46 INFO - Using real prefix '/tools/python27' 08:04:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:04:46 INFO - Installing distribute.............................................................................................................................................................................................done. 08:04:49 INFO - Installing pip.................done. 08:04:49 INFO - Return code: 0 08:04:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:04:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:04:50 INFO - Reading from file tmpfile_stdout 08:04:50 INFO - Output received: 08:04:50 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:04:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:04:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:04:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:04:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:04:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:04:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10b842490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7b8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe96955a9d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b656d30>, '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 0x10b4bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b64eb20>, '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.oxi2ZbfgYp/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.gEaIiVyC8Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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:04: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', 'pip>=1.5'] in /builds/slave/test/build 08:04: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 pip>=1.5 08:04:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oxi2ZbfgYp/Render', 08:04:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:04:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:04:50 INFO - 'HOME': '/Users/cltbld', 08:04:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:04:50 INFO - 'LOGNAME': 'cltbld', 08:04:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:04:50 INFO - 'MOZ_NO_REMOTE': '1', 08:04:50 INFO - 'NO_EM_RESTART': '1', 08:04:50 INFO - 'PAGER': '/bin/cat', 08:04:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:04:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:04:50 INFO - 'PWD': '/builds/slave/test', 08:04:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:04:50 INFO - 'SHELL': '/bin/bash', 08:04:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gEaIiVyC8Y/Listeners', 08:04:50 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 08:04:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:04:50 INFO - 'USER': 'cltbld', 08:04:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:04:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:04:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:04:50 INFO - 'XPC_FLAGS': '0x0', 08:04:50 INFO - 'XPC_SERVICE_NAME': '0', 08:04:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:04:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:04:50 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:04:50 INFO - Cleaning up... 08:04:50 INFO - Return code: 0 08:04:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:04:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:04:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:04:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:04:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:04:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04: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', '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 0x10b842490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7b8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe96955a9d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b656d30>, '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 0x10b4bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b64eb20>, '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.oxi2ZbfgYp/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.gEaIiVyC8Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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:04: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', 'psutil>=3.1.1'] in /builds/slave/test/build 08:04: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 psutil>=3.1.1 08:04:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oxi2ZbfgYp/Render', 08:04:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:04:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:04:50 INFO - 'HOME': '/Users/cltbld', 08:04:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:04:50 INFO - 'LOGNAME': 'cltbld', 08:04:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:04:50 INFO - 'MOZ_NO_REMOTE': '1', 08:04:50 INFO - 'NO_EM_RESTART': '1', 08:04:50 INFO - 'PAGER': '/bin/cat', 08:04:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:04:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:04:50 INFO - 'PWD': '/builds/slave/test', 08:04:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:04:50 INFO - 'SHELL': '/bin/bash', 08:04:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gEaIiVyC8Y/Listeners', 08:04:50 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 08:04:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:04:50 INFO - 'USER': 'cltbld', 08:04:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:04:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:04:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:04:50 INFO - 'XPC_FLAGS': '0x0', 08:04:50 INFO - 'XPC_SERVICE_NAME': '0', 08:04:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:04:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:04:50 INFO - Downloading/unpacking psutil>=3.1.1 08:04:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:04:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:04:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:04:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:04:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:04:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:04:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:04:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:04:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:04:54 INFO - Installing collected packages: psutil 08:04:54 INFO - Running setup.py install for psutil 08:04:54 INFO - building 'psutil._psutil_osx' extension 08:04:54 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 08:04:54 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 08:04:54 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 08:04:54 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 08:04:54 INFO - building 'psutil._psutil_posix' extension 08:04:54 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 08:04:54 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:04:54 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:04:54 INFO - ^ 08:04:54 INFO - 1 warning generated. 08:04:54 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 08:04:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:04:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:04:54 INFO - Successfully installed psutil 08:04:54 INFO - Cleaning up... 08:04:55 INFO - Return code: 0 08:04:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:04:55 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:04:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:04:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:55 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:04:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:04:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:55 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 0x10b842490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7b8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe96955a9d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b656d30>, '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 0x10b4bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b64eb20>, '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.oxi2ZbfgYp/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.gEaIiVyC8Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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:04:55 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:04:55 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:04:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oxi2ZbfgYp/Render', 08:04:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:04:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:04:55 INFO - 'HOME': '/Users/cltbld', 08:04:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:04:55 INFO - 'LOGNAME': 'cltbld', 08:04:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:04:55 INFO - 'MOZ_NO_REMOTE': '1', 08:04:55 INFO - 'NO_EM_RESTART': '1', 08:04:55 INFO - 'PAGER': '/bin/cat', 08:04:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:04:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:04:55 INFO - 'PWD': '/builds/slave/test', 08:04:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:04:55 INFO - 'SHELL': '/bin/bash', 08:04:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gEaIiVyC8Y/Listeners', 08:04:55 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 08:04:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:04:55 INFO - 'USER': 'cltbld', 08:04:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:04:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:04:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:04:55 INFO - 'XPC_FLAGS': '0x0', 08:04:55 INFO - 'XPC_SERVICE_NAME': '0', 08:04:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:04:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:04:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:04:55 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:04:55 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:04:55 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:04:55 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:04:55 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:04:55 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:04:57 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:04:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:04:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:04:58 INFO - Installing collected packages: mozsystemmonitor 08:04:58 INFO - Running setup.py install for mozsystemmonitor 08:04:58 INFO - Successfully installed mozsystemmonitor 08:04:58 INFO - Cleaning up... 08:04:58 INFO - Return code: 0 08:04:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:04:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:04:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:04:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:04:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:04:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:04:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b842490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7b8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe96955a9d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b656d30>, '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 0x10b4bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b64eb20>, '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.oxi2ZbfgYp/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.gEaIiVyC8Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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:04:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:04:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:04:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oxi2ZbfgYp/Render', 08:04:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:04:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:04:58 INFO - 'HOME': '/Users/cltbld', 08:04:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:04:58 INFO - 'LOGNAME': 'cltbld', 08:04:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:04:58 INFO - 'MOZ_NO_REMOTE': '1', 08:04:58 INFO - 'NO_EM_RESTART': '1', 08:04:58 INFO - 'PAGER': '/bin/cat', 08:04:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:04:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:04:58 INFO - 'PWD': '/builds/slave/test', 08:04:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:04:58 INFO - 'SHELL': '/bin/bash', 08:04:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gEaIiVyC8Y/Listeners', 08:04:58 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 08:04:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:04:58 INFO - 'USER': 'cltbld', 08:04:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:04:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:04:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:04:58 INFO - 'XPC_FLAGS': '0x0', 08:04:58 INFO - 'XPC_SERVICE_NAME': '0', 08:04:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:04:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:04:58 INFO - Downloading/unpacking jsonschema==2.5.1 08:04:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:04:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:04:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:04:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:04:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:04:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:05:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:05:02 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:05:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:05:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:05:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:05:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:05:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:05:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:05:02 INFO - Downloading functools32-3.2.3-2.tar.gz 08:05:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:05:02 INFO - warning: no files found matching '*.txt' 08:05:02 INFO - no previously-included directories found matching 'build' 08:05:02 INFO - no previously-included directories found matching 'dist' 08:05:02 INFO - no previously-included directories found matching '.git*' 08:05:02 INFO - Installing collected packages: jsonschema, functools32 08:05:02 INFO - Running setup.py install for functools32 08:05:02 INFO - warning: no files found matching '*.txt' 08:05:02 INFO - no previously-included directories found matching 'build' 08:05:02 INFO - no previously-included directories found matching 'dist' 08:05:02 INFO - no previously-included directories found matching '.git*' 08:05:02 INFO - Successfully installed jsonschema functools32 08:05:02 INFO - Cleaning up... 08:05:02 INFO - Return code: 0 08:05:02 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:05:02 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:05:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:02 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:05:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:02 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 0x10b842490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7b8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe96955a9d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b656d30>, '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 0x10b4bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b64eb20>, '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.oxi2ZbfgYp/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.gEaIiVyC8Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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:05:02 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:05:02 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:05:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oxi2ZbfgYp/Render', 08:05:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:05:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:05:02 INFO - 'HOME': '/Users/cltbld', 08:05:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:05:02 INFO - 'LOGNAME': 'cltbld', 08:05:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:02 INFO - 'MOZ_NO_REMOTE': '1', 08:05:02 INFO - 'NO_EM_RESTART': '1', 08:05:02 INFO - 'PAGER': '/bin/cat', 08:05:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:05:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:02 INFO - 'PWD': '/builds/slave/test', 08:05:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:05:02 INFO - 'SHELL': '/bin/bash', 08:05:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gEaIiVyC8Y/Listeners', 08:05:02 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 08:05:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:05:02 INFO - 'USER': 'cltbld', 08:05:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:05:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:05:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:02 INFO - 'XPC_FLAGS': '0x0', 08:05:02 INFO - 'XPC_SERVICE_NAME': '0', 08:05:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:05:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:03 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:05:03 INFO - Cleaning up... 08:05:03 INFO - Return code: 0 08:05:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:05:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:05:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:05:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b842490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7b8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe96955a9d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b656d30>, '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 0x10b4bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b64eb20>, '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.oxi2ZbfgYp/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.gEaIiVyC8Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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:05:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:05:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:05:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oxi2ZbfgYp/Render', 08:05:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:05:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:05:03 INFO - 'HOME': '/Users/cltbld', 08:05:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:05:03 INFO - 'LOGNAME': 'cltbld', 08:05:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:03 INFO - 'MOZ_NO_REMOTE': '1', 08:05:03 INFO - 'NO_EM_RESTART': '1', 08:05:03 INFO - 'PAGER': '/bin/cat', 08:05:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:05:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:03 INFO - 'PWD': '/builds/slave/test', 08:05:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:05:03 INFO - 'SHELL': '/bin/bash', 08:05:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gEaIiVyC8Y/Listeners', 08:05:03 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 08:05:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:05:03 INFO - 'USER': 'cltbld', 08:05:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:05:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:05:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:03 INFO - 'XPC_FLAGS': '0x0', 08:05:03 INFO - 'XPC_SERVICE_NAME': '0', 08:05:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:05:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:03 INFO - Downloading/unpacking blobuploader==1.2.4 08:05:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:05:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:05:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:05:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:05:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:05:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:05:05 INFO - Downloading blobuploader-1.2.4.tar.gz 08:05:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:05:06 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:05:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:05:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:05:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:05:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:05:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:05:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:05:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:05:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:05:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:05:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:05:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:05:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:05:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:05:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:05:07 INFO - Downloading docopt-0.6.1.tar.gz 08:05:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:05:07 INFO - Installing collected packages: blobuploader, requests, docopt 08:05:07 INFO - Running setup.py install for blobuploader 08:05:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:05:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:05:07 INFO - Running setup.py install for requests 08:05:07 INFO - Running setup.py install for docopt 08:05:08 INFO - Successfully installed blobuploader requests docopt 08:05:08 INFO - Cleaning up... 08:05:08 INFO - Return code: 0 08:05:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:05:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:05:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:05:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:08 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 0x10b842490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7b8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe96955a9d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b656d30>, '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 0x10b4bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b64eb20>, '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.oxi2ZbfgYp/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.gEaIiVyC8Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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:05:08 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:05:08 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:05:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oxi2ZbfgYp/Render', 08:05:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:05:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:05:08 INFO - 'HOME': '/Users/cltbld', 08:05:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:05:08 INFO - 'LOGNAME': 'cltbld', 08:05:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:08 INFO - 'MOZ_NO_REMOTE': '1', 08:05:08 INFO - 'NO_EM_RESTART': '1', 08:05:08 INFO - 'PAGER': '/bin/cat', 08:05:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:05:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:08 INFO - 'PWD': '/builds/slave/test', 08:05:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:05:08 INFO - 'SHELL': '/bin/bash', 08:05:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gEaIiVyC8Y/Listeners', 08:05:08 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 08:05:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:05:08 INFO - 'USER': 'cltbld', 08:05:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:05:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:05:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:08 INFO - 'XPC_FLAGS': '0x0', 08:05:08 INFO - 'XPC_SERVICE_NAME': '0', 08:05:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:05:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:05:08 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-ka0IAb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:05:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:05:08 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-aYMjLu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:05:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:05:08 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-_IUb_2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:05:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:05:08 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-AGjbxz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:05:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:05:08 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-uFcLMp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:05:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:05:08 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-bMwHp4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:05:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:05:09 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-ddF8vQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:05:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:05:09 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-_Dd6kJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:05:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:05:09 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-mSUqiq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:05:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:05:09 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-aPjl3N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:05:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:05:09 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-Jq6EUw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:05:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:05:09 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-E9ExNz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:05:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:05:09 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-ZV7ueu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:05:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:05:09 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-4EZ_Wp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:05:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:05:09 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-U2xnub-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:05:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:05:09 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-MO5rmQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:05:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:05:10 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-tpWraK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:05:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:05:10 INFO - Running setup.py install for manifestparser 08:05:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:05:10 INFO - Running setup.py install for mozcrash 08:05:10 INFO - Running setup.py install for mozdebug 08:05:10 INFO - Running setup.py install for mozdevice 08:05:10 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:05:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:05:10 INFO - Running setup.py install for mozfile 08:05:10 INFO - Running setup.py install for mozhttpd 08:05:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:05:11 INFO - Running setup.py install for mozinfo 08:05:11 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:05:11 INFO - Running setup.py install for mozInstall 08:05:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:05:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:05:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:05:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:05:11 INFO - Running setup.py install for mozleak 08:05:11 INFO - Running setup.py install for mozlog 08:05:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:05:11 INFO - Running setup.py install for moznetwork 08:05:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:05:11 INFO - Running setup.py install for mozprocess 08:05:11 INFO - Running setup.py install for mozprofile 08:05:12 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:05:12 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:05:12 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:05:12 INFO - Running setup.py install for mozrunner 08:05:12 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:05:12 INFO - Running setup.py install for mozscreenshot 08:05:12 INFO - Running setup.py install for moztest 08:05:12 INFO - Running setup.py install for mozversion 08:05:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:05:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:05:12 INFO - Cleaning up... 08:05:12 INFO - Return code: 0 08:05:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:05:12 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:05:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:12 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:05:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:12 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 0x10b842490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7b8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe96955a9d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b656d30>, '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 0x10b4bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b64eb20>, '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.oxi2ZbfgYp/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.gEaIiVyC8Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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:05:12 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:05:12 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:05:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oxi2ZbfgYp/Render', 08:05:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:05:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:05:12 INFO - 'HOME': '/Users/cltbld', 08:05:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:05:12 INFO - 'LOGNAME': 'cltbld', 08:05:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:12 INFO - 'MOZ_NO_REMOTE': '1', 08:05:12 INFO - 'NO_EM_RESTART': '1', 08:05:12 INFO - 'PAGER': '/bin/cat', 08:05:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:05:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:12 INFO - 'PWD': '/builds/slave/test', 08:05:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:05:12 INFO - 'SHELL': '/bin/bash', 08:05:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gEaIiVyC8Y/Listeners', 08:05:12 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 08:05:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:05:12 INFO - 'USER': 'cltbld', 08:05:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:05:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:05:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:12 INFO - 'XPC_FLAGS': '0x0', 08:05:12 INFO - 'XPC_SERVICE_NAME': '0', 08:05:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:05:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:05:13 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-l1lxI_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:05:13 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:05:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:05:13 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-q3ceC1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:05:13 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:05:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:05:13 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-xsqdM2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:05:13 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:05:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:05:13 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-vsyK_t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:05:13 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:05:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:05:13 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-PnWwGg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:05:13 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:05:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:05:13 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-Haxcp1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:05:13 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:05:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:05:13 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-TUv9Io-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:05:13 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:05:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:05:13 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-OWOR4b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:05:13 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:05:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:05:13 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-mj3Lz4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:05:13 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:05:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:05:13 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-i40_eH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:05:14 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:05:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:05:14 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-E9UyPS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:05:14 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:05:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:05:14 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-As2hY4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:05:14 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:05:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:05:14 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-O95hjQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:05:14 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:05:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:05:14 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-uaKFXP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:05:14 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:05:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:05:14 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-NoHx9a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:05:14 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:05:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:05:14 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-3zlYQc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:05:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:05:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:05:14 INFO - Running setup.py (path:/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/pip-kxMZHT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:05:14 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:05:14 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:05:14 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:05:14 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:05:14 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:05:14 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:05:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:05:14 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:05:14 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:05:14 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:05:14 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:05:14 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:05:14 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:05:17 INFO - Downloading blessings-1.6.tar.gz 08:05:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:05:17 INFO - Installing collected packages: blessings 08:05:17 INFO - Running setup.py install for blessings 08:05:17 INFO - Successfully installed blessings 08:05:17 INFO - Cleaning up... 08:05:17 INFO - Return code: 0 08:05:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:05:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:05:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:05:18 INFO - Reading from file tmpfile_stdout 08:05:18 INFO - Current package versions: 08:05:18 INFO - blessings == 1.6 08:05:18 INFO - blobuploader == 1.2.4 08:05:18 INFO - docopt == 0.6.1 08:05:18 INFO - functools32 == 3.2.3-2 08:05:18 INFO - jsonschema == 2.5.1 08:05:18 INFO - manifestparser == 1.1 08:05:18 INFO - mozInstall == 1.12 08:05:18 INFO - mozcrash == 1.0 08:05:18 INFO - mozdebug == 0.1 08:05:18 INFO - mozdevice == 0.48 08:05:18 INFO - mozfile == 1.2 08:05:18 INFO - mozhttpd == 0.7 08:05:18 INFO - mozinfo == 0.9 08:05:18 INFO - mozleak == 0.1 08:05:18 INFO - mozlog == 3.4 08:05:18 INFO - moznetwork == 0.27 08:05:18 INFO - mozprocess == 0.23 08:05:18 INFO - mozprofile == 0.28 08:05:18 INFO - mozrunner == 6.13 08:05:18 INFO - mozscreenshot == 0.1 08:05:18 INFO - mozsystemmonitor == 0.3 08:05:18 INFO - moztest == 0.8 08:05:18 INFO - mozversion == 1.4 08:05:18 INFO - psutil == 3.1.1 08:05:18 INFO - requests == 1.2.3 08:05:18 INFO - wsgiref == 0.1.2 08:05:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:05:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:05:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:05:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:19 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 0x10b842490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7b8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe96955a9d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b656d30>, '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 0x10b4bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b64eb20>, '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.oxi2ZbfgYp/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.gEaIiVyC8Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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:05:19 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:05:19 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:05:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oxi2ZbfgYp/Render', 08:05:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:05:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:05:19 INFO - 'HOME': '/Users/cltbld', 08:05:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:05:19 INFO - 'LOGNAME': 'cltbld', 08:05:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:19 INFO - 'MOZ_NO_REMOTE': '1', 08:05:19 INFO - 'NO_EM_RESTART': '1', 08:05:19 INFO - 'PAGER': '/bin/cat', 08:05:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:05:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:19 INFO - 'PWD': '/builds/slave/test', 08:05:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:05:19 INFO - 'SHELL': '/bin/bash', 08:05:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gEaIiVyC8Y/Listeners', 08:05:19 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 08:05:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:05:19 INFO - 'USER': 'cltbld', 08:05:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:05:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:05:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:19 INFO - 'XPC_FLAGS': '0x0', 08:05:19 INFO - 'XPC_SERVICE_NAME': '0', 08:05:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:05:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:19 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:05:19 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:05:19 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:05:19 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:05:19 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:05:19 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:05:19 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:05:19 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:05:19 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:05:19 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:05:19 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:05:19 INFO - Cleaning up... 08:05:19 INFO - Return code: 0 08:05:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:05:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:05:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:05:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:05:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:05:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:05:19 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 0x10b842490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b7b8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe96955a9d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b656d30>, '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 0x10b4bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b64eb20>, '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.oxi2ZbfgYp/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.gEaIiVyC8Y/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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:05:19 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:05:19 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:05:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oxi2ZbfgYp/Render', 08:05:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:05:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:05:19 INFO - 'HOME': '/Users/cltbld', 08:05:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:05:19 INFO - 'LOGNAME': 'cltbld', 08:05:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:19 INFO - 'MOZ_NO_REMOTE': '1', 08:05:19 INFO - 'NO_EM_RESTART': '1', 08:05:19 INFO - 'PAGER': '/bin/cat', 08:05:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:05:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:19 INFO - 'PWD': '/builds/slave/test', 08:05:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:05:19 INFO - 'SHELL': '/bin/bash', 08:05:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gEaIiVyC8Y/Listeners', 08:05:19 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 08:05:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:05:19 INFO - 'USER': 'cltbld', 08:05:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:05:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:05:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:19 INFO - 'XPC_FLAGS': '0x0', 08:05:19 INFO - 'XPC_SERVICE_NAME': '0', 08:05:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:05:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:05:19 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:05:19 INFO - Cleaning up... 08:05:19 INFO - Return code: 0 08:05:19 INFO - Running post-action listener: _resource_record_post_action 08:05:19 INFO - Running post-action listener: _start_resource_monitoring 08:05:19 INFO - Starting resource monitoring. 08:05:19 INFO - [mozharness: 2017-01-21 16:05:19.960225Z] Finished create-virtualenv step (success) 08:05:19 INFO - [mozharness: 2017-01-21 16:05:19.961136Z] Running install step. 08:05:19 INFO - Running pre-action listener: _resource_record_pre_action 08:05:19 INFO - Running main action method: install 08:05:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:05:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:05:20 INFO - Reading from file tmpfile_stdout 08:05:20 INFO - Detecting whether we're running mozinstall >=1.0... 08:05:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:05:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:05:20 INFO - Reading from file tmpfile_stdout 08:05:20 INFO - Output received: 08:05:20 INFO - Usage: mozinstall [options] installer 08:05:20 INFO - Options: 08:05:20 INFO - -h, --help show this help message and exit 08:05:20 INFO - -d DEST, --destination=DEST 08:05:20 INFO - Directory to install application into. [default: 08:05:20 INFO - "/builds/slave/test"] 08:05:20 INFO - --app=APP Application being installed. [default: firefox] 08:05:20 INFO - mkdir: /builds/slave/test/build/application 08:05:20 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:05:20 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:05:52 INFO - Reading from file tmpfile_stdout 08:05:52 INFO - Output received: 08:05:52 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:05:52 INFO - Running post-action listener: _resource_record_post_action 08:05:52 INFO - [mozharness: 2017-01-21 16:05:52.254156Z] Finished install step (success) 08:05:52 INFO - [mozharness: 2017-01-21 16:05:52.254297Z] Running run-tests step. 08:05:52 INFO - Running pre-action listener: _resource_record_pre_action 08:05:52 INFO - Running main action method: run_tests 08:05:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:05:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:05:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:05:52 INFO - 2017-01-21 08:05:52.268 screenresolution[1908:16745] starting screenresolution argv=screenresolution get 08:05:52 INFO - 2017-01-21 08:05:52.271 screenresolution[1908:16745] Display 0: 1600x1200x32@60 08:05:52 INFO - 2017-01-21 08:05:52.277 screenresolution[1909:16748] starting screenresolution argv=screenresolution list 08:05:52 INFO - Available Modes on Display 0 08:05:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:05:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:05:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:05:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:05:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:05:52 INFO - Intel Iris: 08:05:52 INFO - Chipset Model: Intel Iris 08:05:52 INFO - Type: GPU 08:05:52 INFO - Bus: Built-In 08:05:52 INFO - VRAM (Dynamic, Max): 1536 MB 08:05:52 INFO - Vendor: Intel (0x8086) 08:05:52 INFO - Device ID: 0x0a2e 08:05:52 INFO - Revision ID: 0x0009 08:05:52 INFO - Displays: 08:05:52 INFO - Display: 08:05:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:05:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:05:52 INFO - Display Serial Number: 1600x1200 60 08:05:52 INFO - Main Display: Yes 08:05:52 INFO - Mirror: Off 08:05:52 INFO - Online: Yes 08:05:52 INFO - Rotation: Supported 08:05:52 INFO - Return code: 0 08:05:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:05:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:05:52 INFO - Python 2.7.3 08:05:52 INFO - Return code: 0 08:05:52 INFO - grabbing minidump binary from tooltool 08:05:52 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:05:52 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 0x10b656d30>, '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 0x10b4bcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b64eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:05:52 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:05:52 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:05:52 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:05:52 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:05:52 INFO - Return code: 0 08:05:52 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:05:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:05:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:05:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:05:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:05:52 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/b8QGS0x6SleI5IAcI54Oqg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0131', '--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:05:52 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/b8QGS0x6SleI5IAcI54Oqg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0131 --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:05:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oxi2ZbfgYp/Render', 08:05:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:05:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:05:52 INFO - 'HOME': '/Users/cltbld', 08:05:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:05:52 INFO - 'LOGNAME': 'cltbld', 08:05:52 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:05:52 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:05:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:05:52 INFO - 'MOZ_NO_REMOTE': '1', 08:05:52 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:05:52 INFO - 'NO_EM_RESTART': '1', 08:05:52 INFO - 'PAGER': '/bin/cat', 08:05:52 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:05:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:05:52 INFO - 'PWD': '/builds/slave/test', 08:05:52 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:05:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:05:52 INFO - 'SHELL': '/bin/bash', 08:05:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gEaIiVyC8Y/Listeners', 08:05:52 INFO - 'TMPDIR': '/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/', 08:05:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:05:52 INFO - 'USER': 'cltbld', 08:05:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:05:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:05:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:05:52 INFO - 'XPC_FLAGS': '0x0', 08:05:52 INFO - 'XPC_SERVICE_NAME': '0', 08:05:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:05:52 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/b8QGS0x6SleI5IAcI54Oqg/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0131', '--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:05:52 INFO - mozversion application_buildid: 20170121070324 08:05:52 INFO - mozversion application_changeset: b58eb6e9e6c683cd32855ad927990fa6d585a1a8 08:05:52 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:05:52 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:05:52 INFO - mozversion application_name: Firefox 08:05:52 INFO - mozversion application_remotingname: firefox-dev 08:05:52 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:05:52 INFO - mozversion application_vendor: Mozilla 08:05:52 INFO - mozversion application_version: 52.0a2 08:05:52 INFO - mozversion platform_buildid: 20170121070324 08:05:52 INFO - mozversion platform_changeset: b58eb6e9e6c683cd32855ad927990fa6d585a1a8 08:05:52 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:05:52 INFO - mozversion platform_version: 52.0a2 08:05:52 INFO - using testdate: 1485014752 08:05:52 INFO - actual date: 1485014752 08:05:52 INFO - starting webserver on 'localhost:51963' 08:05:52 INFO - SUITE-START | Running 2 tests 08:05:52 INFO - TEST-START | dromaeo_css 08:05:52 INFO - operating with platform_type : mac_ 08:05:52 INFO - Initialising browser for dromaeo_css test... 08:05:52 INFO - TEST-INFO | started process 1919 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/tmp5VBWgQ/profile http://localhost:51963/getInfo.html) 08:05:55 INFO - PROCESS | 1919 | __metrics Screen width/height:1600/1200 08:05:55 INFO - PROCESS | 1919 | colorDepth:24 08:05:55 INFO - PROCESS | 1919 | Browser inner width/height: 1024/689 08:05:55 INFO - PROCESS | 1919 | __metrics 08:05:55 INFO - PROCESS | 1919 | 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:05:55 INFO - PROCESS | 1919 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:05:56 INFO - TEST-INFO | 1919: exit 0 08:05:56 INFO - Browser initialized. 08:05:56 INFO - Running cycle 1/1 for dromaeo_css test... 08:05:56 INFO - TEST-INFO | started process 1921 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/tmp5VBWgQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:06:09 INFO - PROCESS | 1921 | RSS: Main: 222040064 08:06:09 INFO - PROCESS | 1921 | 08:09:13 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:51963/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51963/tests/dromaeo/cssquery-ext.html) 08:09:14 INFO - PROCESS | 1921 | RSS: Main: 349802496 08:09:14 INFO - PROCESS | 1921 | 08:12:17 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:51963/tests/dromaeo/cssquery-ext.html (next: http://localhost:51963/tests/dromaeo/cssquery-jquery.html) 08:12:18 INFO - PROCESS | 1921 | RSS: Main: 349667328 08:12:18 INFO - PROCESS | 1921 | 08:15:20 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:51963/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51963/tests/dromaeo/cssquery-mootools.html) 08:15:21 INFO - PROCESS | 1921 | RSS: Main: 322957312 08:15:21 INFO - PROCESS | 1921 | 08:18:23 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:51963/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51963/tests/dromaeo/cssquery-prototype.html) 08:18:24 INFO - PROCESS | 1921 | RSS: Main: 320073728 08:18:24 INFO - PROCESS | 1921 | 08:21:27 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:51963/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51963/tests/dromaeo/cssquery-yui.html) 08:21:28 INFO - PROCESS | 1921 | RSS: Main: 307171328 08:21:28 INFO - PROCESS | 1921 | 08:24:31 INFO - PROCESS | 1921 | Cycle 1(1): loaded http://localhost:51963/tests/dromaeo/cssquery-yui.html (next: http://localhost:51963/tests/dromaeo/cssquery-dojo.html) 08:24:31 INFO - PROCESS | 1921 | RSS: Main: 610648064 08:24:31 INFO - PROCESS | 1921 | 08:24:31 INFO - PROCESS | 1921 | __start_tp_report 08:24:31 INFO - PROCESS | 1921 | _x_x_mozilla_page_load 08:24:31 INFO - PROCESS | 1921 | _x_x_mozilla_page_load_details 08:24:31 INFO - PROCESS | 1921 | |i|pagename|runs| 08:24:31 INFO - PROCESS | 1921 | |0;dojo.html;3325.6508066653;3326.833658317085;3330.900072997808;3335.966640333597;3395.8132302723357;4739.644526660506;5695.060315048017;5723.942760572393;5760.510356619682;5764.827055188341;5662;5753.884922301562;5766.423357664232;5775.884482310351;5777.393373695764;5221.190715439094;5228.686278823264;5247.658902171346;5266.447023062581;5301;5155.974220128895;5195.688258704471;5209.479052094798;5225.738713064351;5343;5266.183741520071;5334.013207556595;5334.67991920486;5358.758855851476;5390.0836857734275;5309.787608495675;5311.601629877775;5335.253064570924;5344.652597581184;5395.163749618776;5058.772355244023;5177.870553236162;5242.292290540804;5264.973675131638;5265.052290587692;5573.359063707644;5629.943700563023;5648.322201335866;5660.122680984413;5699.914501282484;6187.041008643668;6205.503559715212;6232.657203853786;6237.625742455467;6266.279377871558;6261.46777523911;6291.433770960635;6298.3701629837105;6324.430801227865;6324.9051264231075;5544.889102217973;5685.40303268155;5698.772049118072;5774.451427114502;5778.566607504454;6209.842635469627;6232.906506402408;6234.9688251559355;6236.656983865841;6246.718897649616;6134.447899689049;6139.631622102688;6144.907826382608;6152.600080994721;6158.938410615837;6088.360722124202;6139.351796347371;6140.780336713758;6176.629402235881;6246.562740608205;6058.666773327421;6105.4294214941465;6105.436769732072;6105.671256026659;6107.355864811133;6197.349278325801;6200.131981522591;6258.15514905491;6272.372762723691;6285.151504546919;4933.87665308363;4936.456989731127;4941.308216849644;4945.134601444733;4963.305137280783;4440.644748420119;4452.243118669834;4454.37638730578;4474.686771925999;4485.59629633328;5634.464725851038;5649.576281778883;5686.402927692616;5687.118496633028;5745.3105627324985;6229.53278504114;6278.089676996809;6291.245050593958;6298.7480500780375;6298.779542715983;5766.855828604234;5786.334571524244;5825.359210486928;5829.825105246765;5843.357230704619;5307.310049693592;5347.25138480613;5348.91976620351;5415.83752487426;5422.322209723785;6167.784127555514;6208.565400422108;6223.533235007302;6262.874742505215;6273.52948528853;6069.544784141295;6260.40526150015;6273.843153921189;6275.96862015687;6278.4035516625845;6198.938010619836;6211.844703882439;6274.560780745304;6276.6547839869245;6346.492280617632;6030.4572588467245;6084.148219249397;6094.969525152346;6110.6195130195665;6131.570790044654;5532.529734972572;5944.5838791284195;6238;6263.78076767311;6282.497400207881;6076.149339092619;6092.055731361646;6101.206843110367;6118.816435506942;6128.509719222362;5979.730912108878;6027.487663548647;6073.878522429438;6118.785842495491;6142.293636231891;5952.672603006876;6009.909851352146;6030.939690603038;6052.243469566304;6070.271567411763;6112.8777424452155;6132.08018797184;6135.202423684892;6149.046897730859;6150.815475535741;5813.15709222152;5854.765809367577;5893.2044174035955;5919.171316015847;5948.940510595012 08:24:31 INFO - PROCESS | 1921 | |1;ext.html;434.5155152005511;499.8325560937084;504.99747501262493;505.55006044620274;510.9718965456901;28717.425651486985;37800.621993780056;39103.60896391035;39150;39156.99999999996;36177.0955726107;38871.80564097183;39901.60098399015;40345.59654403455;40591.39112913309;8240.670373185065;9118.680846170386;9217.769555761093;9315.860262096074;9324.720258392224;8317.126701696325;8523.829523409528;8525.872111918326;8618.224359807617;8627.741167764978;10882.61910833121;11102.833457498144;11117.499712512956;11335.546578136906;11345.546178152865;11033.503492342823;11109.666709998632;11114.888851111466;11116.888831111666;11127;8279.172082791732;11023.83464248037;11088.667339979813;11094.778104437946;11123.165762567758;29785.10644680663;31062.844685776654;31087.22281942947;37324.81337593322;37918;3215.1640573451073;3280.5899262592175;3313.5254811608843;3438.0373495421322;3808.90477738056;27089.1873243803;27285;30159;30491;31524.842375787975;3152.2592190835367;3218.7746857719967;3233.175540237248;3252.4308246056844;3300.3069355435387;429888;439903;441081;442033;442777;53309;55047;62695.6865215671;64060;64089;66533.667331664;67306;68173;72014.9197762034;72057;13681.863181368059;16032.118233497036;16055.43805966809;16063.437779677888;16068.75896861548;12162.878371216351;12238.082143839247;12243.449044793006;12254.877451225551;12257.632271031884;17164.227609757592;17496.650066998587;19605.607887842165;19881.701774473106;19973.600527989358;7968.362530997506;8199.467034642728;8224.753257402288;8235.21765432283;8284.047334556602;846.7713717296295;921.7695576105973;946.7017889364828;977.9022097790306;1042.3016578892161;27178.32054198661;27731.86134069317;28678.426431471253;28732.96405515236;28820.711792882223;945.2438049560461;1001.6544292219173;1038.3769738157046;1038.9896101039044;1088.1077516436603;14701.411943522353;14945.804335653122;15110.548582078041;15136.091834489966;15270.31283592241;642523;650112;650268;650854;651395;46970.29544556834;47825.76087119542;53766.92466150655;54116.188257176174;54730;1317.736452709462;1323.966900827487;1363.8295213098363;1369.4590636698647;1369.8904087672763;28905.855470722512;29612.407751845272;29739.851300743358;30325.545116823705;30511.237219069702;47107.76446117885;52205.95588088141;52750.20874686806;52832.471675284294;56379.436205637416;631288;667246;696865;698515;866507;39771.60228397679;40075.398869016404;40232.79301620956;40547.39178912378;41105.79447102745;36229.275414491036;43561.34657980196;43801.56198438102;44042.779786100866;44197.337039943784;35625.00000000062;35762.284754304244;36162;36278.8186059068;37255.06862328355;36097.45853812143;36305.63694363023;36980.81509592435;37438.8128059358;37548.812255939636;35584.288314233054;35786;36058.09854753652;36087.819560902026;36584.634153658124;31190.376192476477;33013.844515441844;33106.66893331036;33161;33198.5020224692;21330;24098.759012409653;24862.87568562218;25015.749212538936;25079.87460062688 08:24:31 INFO - PROCESS | 1921 | |2;jquery.html;348.6687646735602;369.6599128801502;371.76950290819696;382.77990155660495;399.49862922032844;12385.504579816808;14552.708945821078;15708.764368534481;17049.48851534453;19982.100805463753;16620.916895415536;17584.120793960283;17913.373031943884;18934.905325473388;19569.412917612495;4779.64152688548;5814.360420353758;5822.155787410823;5824.068149096145;5824.912626310609;5508.889822203553;5685.573581981347;5697.487226149646;5783.855403614923;5792.623479473821;5705.6006079574445;5723.942760572403;5732.455416735404;5734.225879506255;5735.111057786029;5465.59008074394;5706.914396284059;5712.800051998181;5778.537716982673;5796.565257605655;5057.115004874169;5069.340985671839;5069.746512674352;5070;5084.898302033976;24065.87967060171;24438.877805611035;24446.755532444804;24537.50924981508;24761.133360332482;2102.4848912016655;2230.9330720078424;2272.250157448038;2273.079402841852;2299.2642354446584;14141.929290353586;15014.174220417872;15184.696306073927;15722.921385393;15752.921235393864;1661.1528120658552;1785.526835388623;1788.0880750817048;1789.9463016109537;1816.8455681267108;112043.31935020981;118037.40981295124;118505.8149418512;120172.39913800375;123224.9999999991;9844.655437059668;11386.94306528462;11452.770944581229;11471.598494052669;11650.30098194111;14617.707645847024;14634.634134146518;14652;14671.486497972734;14673.486427975184;8187.754367368989;8259.793505162299;8300.916990830014;8433.156684331518;8453.915460845436;8093.635786389626;8133;8167.14245004266;8277.379196560281;8283.461574997606;4357.694961352675;4506.571875671872;4507.797149128297;4524.819007239739;4585.495595484494;1696.0925904641203;1856.2296646891389;1881.2192939930048;1907.980920190808;1912.913918873654;809.0897740042452;812.8293058457672;853.2534032721369;914.593006112287;934.8270569944582;19422;19879;20096.69854952177;20165.596688066158;20178.495537611678;911.7128104647015;920.2683866326287;933.659214386744;961.3799099580731;997.5959736306808;8579.613917373734;9827.262955278382;10526.368417894952;10627.043566078935;10703.678889633324;580135;586295;587258;602770;609841;79595;108676;117539.6492070171;118519.81480185088;126956;906.3791302957495;967.7532229281488;982.4547376206132;1021.5352014833232;1021.7292417509366;14194.574162775134;17444;17458.47624571265;17989.100544972436;18011.549711257325;40809;51326.74336628293;52287.69280768011;53664.463355365944;53711.73144134254;65886.67056664686;76336.23663763292;78061;80757.8123830574;82038.76941845997;7131.144262688511;7959.840803183788;7985.560794156378;8119.5897722955315;8249.010118785784;12377.195482293622;12646.810297845725;12761.680957976125;13036.739265214832;13133.802992955305;10902;11187.32876027441;11456.312621242752;11531.94234028858;11640.708982275512;9510.619575216913;11134.498947547541;11137.94431027872;11423.371714555778;11490.885091149315;11220.719482013015;11342.489587968726;11413.750946608816;11566.710832229262;11773.646790596296;7408.925910740823;7533.7363192288;7567.583782891774;7580.279873412017;7763.844723105619;9522.619095236112;11151.940565646251;11350.148738844455;11539;11683.357415342238 08:24:31 INFO - PROCESS | 1921 | |3;mootools.html;1153.7750138722952;1443.4154167562135;1473.2118316700567;1609.44474156416;1804.0799192411873;7053.89419158713;7417.443691723122;7808.492447990884;8101.675932962682;8325.500469971807;8226.01287845458;8314.875276870851;8330.750077497669;8331.750047498585;8350.91649083509;2277.5900337939165;2313.791758741713;2314.652802079693;2326.499802542453;2331.8484298520625;2315;2348.8825558722083;2350.2361732437025;2366.7396586375485;2367.621180611103;2288.9198878039274;2292.6790249365104;2319.9072037118494;2321.408040949564;2321.605327094398;2284.5773531896652;2301.2866011536394;2320.234322673514;2321.6633588129625;2326.1974618756503;2019.5254115282896;2306.527161931817;2308.5036717105904;2324.697789287382;2337.3338598499518;8043.11525732169;8224.62989165483;8423.410361274713;8430.241278284984;8447.155284471504;4999.875003124951;5189.948100518985;5190.143626301656;5193.143131383317;5196.896062078775;3328;3441.913952151216;3443.345764304774;3511.122219445139;3527.4003419418505;1051.2956319266034;1072.2976450424985;1732.0993083596586;1735.6788994036144;1829.4785985993929;10546.05085542305;10776.245662803607;10852.945735271272;10865.619703310425;10866.891331086666;4540.750258735735;4672.322513235562;4687.507811679793;4701.294805779161;4711.575958163782;9484.14642682148;9500.667476638284;9504.287178461644;9515.048495150568;9526.809463810685;5188.558972487305;5223.7910483580235;5227.059129356676;5228.163493840967;5233.633645644768;4627.981843994316;4776.355192049098;4788.904221915543;4812.807487700453;4828.251620998751;3632.182758879231;3655.1227705350525;3739.3269211542183;3773.132179598653;3779.4519794629623;1206.8008778551616;1233.5744168261936;1235.1539195651008;1247.650657815813;1248.3259040118235;877.6401675313218;878.9648414063494;883.9469631822111;891.4740303221131;900.1088921967205;5190.273361729361;5311.707856067954;5487.807926722546;5692.857678558069;5705.2012718219485;426.4818245831268;436.53727049327813;462.4335189392967;465.0350522665483;465.4902881344922;3547.5033495310677;3580.03339098442;3599.6760291573883;3602.8018458985007;3758;9778.41329520231;10144.847827282596;10155.035271649182;10157.187425005981;10169.898301016896;10066.949665251626;10112;10113.393196408239;10113.949430252802;10123.746906327253;7326.487145899736;7670;7730.458867879194;7836.647350869224;7855.842883142305;5338.626296159232;5480.588955828249;5522.144067669517;5630.60585758993;5671.0662022990045;9169.587368568564;9223.631054757734;9276.489793061177;9305.395149315273;9406.24750019983;7527.73652922167;7682.9757560706375;9320.300977426856;9378.812423751624;9434.811303774024;7059.258777828254;7153.284671532805;7155.3202445767565;7160.355567998905;7163.212046674757;6807.5575087619145;7648.273414025659;7702.961485192538;7810.258025487569;7817.296443320129;7034.929650703428;7060.435165186876;7108.289171082851;7212.531185472927;7224.927750722636;6228.314885362697;6908.967594578046;6931.930680693128;7102.715891364288;7110.43116550685;5675.091985282335;6458.095866578588;6618.7683431081605;6622.668866556556;6638.402543771083;4632.675712700078;4786.976065119652;4798.008826337171;4843.612510999182;5010.624203184703;4781.617470602413;4796.35249241348;4796.904061918812;4857.805687772449;4875.0493653737185 08:24:31 INFO - PROCESS | 1921 | |4;prototype.html;290.3756922616375;292.7087547889851;299.668865903177;304.2894840547322;307.80762023735167;4555.476120246172;4601.79291931863;5051.045138593922;5052.747362631872;5120.15517439622;4197.181549597822;4248.723832950856;4921.261810728392;5091.821786237482;5125.256837758523;3513.8243087845635;3800.5439347278225;4019.9799001004953;4020.2361551305303;4035.2131334389805;3411.641777613352;3687.9120659405407;4017.4978127734034;4034.0519977805193;4058.939115913264;3916.647501724844;3944.901377465572;3954.528419737664;3989.4441167944524;3993.0833200063985;3315.3203593263324;3816.8740221634685;3941.2214388855787;3946.625070618287;4030.1939612077554;3427.8114703691285;3438.6561343865665;3441.294534620397;3446.5347178130883;3452.223249768807;4424.756638384888;4760.214564596838;5003.574696150795;5057.69653820772;5090.389153301628;3811.8856434307013;4410.272305069691;4475.530069342704;4490.932636010462;4491.595756381941;5365.409804921455;5385.811496597639;5396.973015134939;5427.3215848019;5436.565074794007;3578.427451607756;3754.3054534910857;3759.342115129841;3764.962350376516;3770.925286293393;5090.149516270863;5345.33183352081;5638.351589567212;5654.547636189095;5670.979223739725;3390.5083762854247;3460.7058400035776;3702.2965636528975;3753.887383378503;3771.152135453608;3354.3626710924846;3415.0289248354093;3700.4634328022826;3847.922581677135;3854.325493038707;4262.488501379792;4365.781710914442;4432.468103827497;4439.800208990603;4440.955590444119;4673.462551806586;5139.406783897004;5342.786288548492;5392.595555333366;5410;1161.7211869151342;1176.505867535637;1196.5333519927235;1216.355331674214;1223.5289413575838;754.0913199594476;808.7533302342842;834.2283387866199;844.3835999720238;861.5649097205996;4129.112240868228;4198.85304014358;4376.912461750747;4549.977250113794;4875.244911831738;4854.80580776765;5169.061601899288;5225.059489291964;5267.525922666979;5310.283111779938;4197.25813787281;4435.977820110943;4490.7754612269255;4641.744704041243;4766.308885211625;2551.7830984366537;2695.2588038289236;2715.5383584790634;2734.480448714738;2743.8490883001627;5010.824621138243;5335.733213339395;5362.839114826562;5377.274068000848;5443.700596467233;4693.55411235932;5485.6434331768305;5814.970925145347;5830.271216097988;5961.612495187799;5332.626716129834;5532.336119665666;5575.553955683536;5845.3277873045145;5901.675407852609;4991.625628077982;5014.598832093351;5633.042382795016;5642.210090587239;5673.574481913955;5397.514223719884;5528.419515950928;5592.133219351007;5604.122618922706;5605.831825045255;5610.298712660918;5825.970870145622;5839.0949402842625;5878.500327472213;5887.293524777054;3384.918275260746;3718.2377612589894;3828.764693833706;3923.686105111527;3963.8810835674976;5339.652922560022;5516.586256030733;5585.273914391103;5599.552035837041;5623.550115990629;4914.926276105933;5269.631125821309;5465.699386533779;5499.312585926759;5547.502174412966;4542.863714088583;5342.919856202082;5363.919541206807;5405.594580406564;5470.781168753205;4685.6657591726835;5127.923136141452;5291.656042357235;5319.46805319465;5358;4662.344867571963;5498.697571633598;5505.559555235652;5524.502794748492;5565.912782706485;4301.161273551627;5193.935578008842;5319.143620874989;5453.545904342982;5503.284573005483 08:24:31 INFO - PROCESS | 1921 | |5;yui.html;690.3131384272648;704.499805138352;741.7144399406227;742.3646056499425;803.7709252862934;13766.17402955822;13856.237906915128;14237.288135593204;14362.066465679723;14456.204908730017;12955.157914735535;13058.608241752763;13082.215067095982;13089;13141.211527308367;632.3486808587163;656.7734131724548;794.5828440068952;810.6271115286945;823.567626995829;815.1440986963664;818.5661599352353;818.8730746734266;819.1767273889733;821.7493664432345;869.1265278395203;870.9912900871005;871.7776966873438;872.162723785167;874.0691163910446;862.1981557151815;863.3309185381316;864.8054187807755;864.9783755406165;868.0148031983681;636.6498425865756;637.1207733327977;637.4677144584277;637.6811594202899;637.735339833973;13092.214467132002;13410.93294533531;13459;13472.797908031387;13793.172409655454;12019.699507512294;12567.93716031414;13696.65758356049;14076.507322243775;14128.576142715736;11751;12005.459754311078;12005.968664511316;12234.143609947307;12451.439685214187;4302.591253830881;4355.607995280408;4388.429504164438;4539.750313732743;4548.272276435788;91667;92180.53909730476;92308;92554.07445925522;93174.06825931722;9756.512174391251;9848.753781155385;10312.639057633098;10326.948365258126;10466.947665261625;18652.067396630384;18725.157367918477;18728.531786705167;18768.436946891616;18837.152328145265;7259.491835571459;7335.48651594394;7358.632068396559;7595;7597.696092156362;4123.463949686581;4135.834566617362;4213.705040647125;4247.193033323659;4337.175936572041;2941.5587661850464;2982.2693440107314;3012.5330573761103;3045.25391279138;3049.161480592855;629.3203340392365;634.6445990245477;636.6466611030922;636.9426751592287;645.812714312844;585.871108356161;602.5270162922172;604.8578584032749;608.856918624123;610.9602875813058;8176.959115204505;8554.786130346667;8584;8628.835107260566;8660;538.9434109418534;589.964602123874;593.2880543348;615.0743131586933;625.08425157145;3849.364854798983;4043.73715708478;4053.6351728344002;4125.0825066129555;4199.9370009449885;115036.42481787538;116163.41918290354;118086;118921;121534;55167.72416137894;59544.70227648921;91667;99293;109137;83385.58307208425;84451.99999999878;84677;85477.29045419057;89230.55384723035;8494.830103397773;9280.257579393767;9364.110409511086;9677.643118329606;9729.416235025921;47747.52252477431;51294.74352628213;53462;54669.1799622998;56293.71853140708;77508;77549;79157.60421197857;80935.59532202301;81325.18674813176;11516.30902145874;11925.284482931052;12086.818697719365;12182.939085304872;12486.750264994831;13435.798463023259;13496.662583435493;13876.514321998682;14053.859461405254;14774.335154917839;12131.393430328271;12266.141370104007;12453.06602004835;12637.494500220257;13324.267165306;12173.087018473472;13167.802482962572;13183.736325273248;13339.06626536133;13766.793498097737;11252.26860254081;11662;12987.935060325015;13232.206067635972;13402.329883505981;10088;10775.137988961022;11057.778844423228;11343.716407089889;11748.501619398756;9098.681546145854;9388.295877809329;9758.560864761244;9944.403335799876;11328.490217940047 08:24:31 INFO - PROCESS | 1921 | __end_tp_report 08:24:31 INFO - PROCESS | 1921 | __start_cc_report 08:24:31 INFO - PROCESS | 1921 | _x_x_mozilla_cycle_collect,1463 08:24:31 INFO - PROCESS | 1921 | __end_cc_report 08:24:31 INFO - PROCESS | 1921 | __startTimestamp1485015871493__endTimestamp 08:24:31 INFO - PROCESS | 1921 | 08:24:31 INFO - PROCESS | 1921 | ------- Summary: start ------- 08:24:31 INFO - PROCESS | 1921 | Number of tests: 6 08:24:31 INFO - PROCESS | 1921 | 08:24:31 INFO - PROCESS | 1921 | [#0] dojo.html Cycles:165 Average:5737.83 Median:5952.67 stddev:627.08 (10.5%) stddev-sans-first:599.77 08:24:31 INFO - PROCESS | 1921 | Values: 3325.7 3326.8 3330.9 3336.0 3395.8 4739.6 5695.1 5723.9 5760.5 5764.8 5662.0 5753.9 5766.4 5775.9 5777.4 5221.2 5228.7 5247.7 5266.4 5301.0 5156.0 5195.7 5209.5 5225.7 5343.0 5266.2 5334.0 5334.7 5358.8 5390.1 5309.8 5311.6 5335.3 5344.7 5395.2 5058.8 5177.9 5242.3 5265.0 5265.1 5573.4 5629.9 5648.3 5660.1 5699.9 6187.0 6205.5 6232.7 6237.6 6266.3 6261.5 6291.4 6298.4 6324.4 6324.9 5544.9 5685.4 5698.8 5774.5 5778.6 6209.8 6232.9 6235.0 6236.7 6246.7 6134.4 6139.6 6144.9 6152.6 6158.9 6088.4 6139.4 6140.8 6176.6 6246.6 6058.7 6105.4 6105.4 6105.7 6107.4 6197.3 6200.1 6258.2 6272.4 6285.2 4933.9 4936.5 4941.3 4945.1 4963.3 4440.6 4452.2 4454.4 4474.7 4485.6 5634.5 5649.6 5686.4 5687.1 5745.3 6229.5 6278.1 6291.2 6298.7 6298.8 5766.9 5786.3 5825.4 5829.8 5843.4 5307.3 5347.3 5348.9 5415.8 5422.3 6167.8 6208.6 6223.5 6262.9 6273.5 6069.5 6260.4 6273.8 6276.0 6278.4 6198.9 6211.8 6274.6 6276.7 6346.5 6030.5 6084.1 6095.0 6110.6 6131.6 5532.5 5944.6 6238.0 6263.8 6282.5 6076.1 6092.1 6101.2 6118.8 6128.5 5979.7 6027.5 6073.9 6118.8 6142.3 5952.7 6009.9 6030.9 6052.2 6070.3 6112.9 6132.1 6135.2 6149.0 6150.8 5813.2 5854.8 5893.2 5919.2 5948.9 08:24:31 INFO - PROCESS | 1921 | 08:24:31 INFO - PROCESS | 1921 | [#1] ext.html Cycles:180 Average:72474.60 Median:36203.19 stddev:164577.58 (454.6%) stddev-sans-first:164950.38 08:24:31 INFO - PROCESS | 1921 | Values: 434.5 499.8 505.0 505.6 511.0 28717.4 37800.6 39103.6 39150.0 39157.0 36177.1 38871.8 39901.6 40345.6 40591.4 8240.7 9118.7 9217.8 9315.9 9324.7 8317.1 8523.8 8525.9 8618.2 8627.7 10882.6 11102.8 11117.5 11335.5 11345.5 11033.5 11109.7 11114.9 11116.9 11127.0 8279.2 11023.8 11088.7 11094.8 11123.2 29785.1 31062.8 31087.2 37324.8 37918.0 3215.2 3280.6 3313.5 3438.0 3808.9 27089.2 27285.0 30159.0 30491.0 31524.8 3152.3 3218.8 3233.2 3252.4 3300.3 429888.0 439903.0 441081.0 442033.0 442777.0 53309.0 55047.0 62695.7 64060.0 64089.0 66533.7 67306.0 68173.0 72014.9 72057.0 13681.9 16032.1 16055.4 16063.4 16068.8 12162.9 12238.1 12243.4 12254.9 12257.6 17164.2 17496.7 19605.6 19881.7 19973.6 7968.4 8199.5 8224.8 8235.2 8284.0 846.8 921.8 946.7 977.9 1042.3 27178.3 27731.9 28678.4 28733.0 28820.7 945.2 1001.7 1038.4 1039.0 1088.1 14701.4 14945.8 15110.5 15136.1 15270.3 642523.0 650112.0 650268.0 650854.0 651395.0 46970.3 47825.8 53766.9 54116.2 54730.0 1317.7 1324.0 1363.8 1369.5 1369.9 28905.9 29612.4 29739.9 30325.5 30511.2 47107.8 52206.0 52750.2 52832.5 56379.4 631288.0 667246.0 696865.0 698515.0 866507.0 39771.6 40075.4 40232.8 40547.4 41105.8 36229.3 43561.3 43801.6 44042.8 44197.3 35625.0 35762.3 36162.0 36278.8 37255.1 36097.5 36305.6 36980.8 37438.8 37548.8 35584.3 35786.0 36058.1 36087.8 36584.6 31190.4 33013.8 33106.7 33161.0 33198.5 21330.0 24098.8 24862.9 25015.7 25079.9 08:24:31 INFO - PROCESS | 1921 | 08:24:31 INFO - PROCESS | 1921 | [#2] jquery.html Cycles:180 Average:34063.09 Median:12554.90 stddev:98719.69 (786.3%) stddev-sans-first:98964.16 08:24:31 INFO - PROCESS | 1921 | Values: 348.7 369.7 371.8 382.8 399.5 12385.5 14552.7 15708.8 17049.5 19982.1 16620.9 17584.1 17913.4 18934.9 19569.4 4779.6 5814.4 5822.2 5824.1 5824.9 5508.9 5685.6 5697.5 5783.9 5792.6 5705.6 5723.9 5732.5 5734.2 5735.1 5465.6 5706.9 5712.8 5778.5 5796.6 5057.1 5069.3 5069.7 5070.0 5084.9 24065.9 24438.9 24446.8 24537.5 24761.1 2102.5 2230.9 2272.3 2273.1 2299.3 14141.9 15014.2 15184.7 15722.9 15752.9 1661.2 1785.5 1788.1 1789.9 1816.8 112043.3 118037.4 118505.8 120172.4 123225.0 9844.7 11386.9 11452.8 11471.6 11650.3 14617.7 14634.6 14652.0 14671.5 14673.5 8187.8 8259.8 8300.9 8433.2 8453.9 8093.6 8133.0 8167.1 8277.4 8283.5 4357.7 4506.6 4507.8 4524.8 4585.5 1696.1 1856.2 1881.2 1908.0 1912.9 809.1 812.8 853.3 914.6 934.8 19422.0 19879.0 20096.7 20165.6 20178.5 911.7 920.3 933.7 961.4 997.6 8579.6 9827.3 10526.4 10627.0 10703.7 580135.0 586295.0 587258.0 602770.0 609841.0 79595.0 108676.0 117539.6 118519.8 126956.0 906.4 967.8 982.5 1021.5 1021.7 14194.6 17444.0 17458.5 17989.1 18011.5 40809.0 51326.7 52287.7 53664.5 53711.7 65886.7 76336.2 78061.0 80757.8 82038.8 7131.1 7959.8 7985.6 8119.6 8249.0 12377.2 12646.8 12761.7 13036.7 13133.8 10902.0 11187.3 11456.3 11531.9 11640.7 9510.6 11134.5 11137.9 11423.4 11490.9 11220.7 11342.5 11413.8 11566.7 11773.6 7408.9 7533.7 7567.6 7580.3 7763.8 9522.6 11151.9 11350.1 11539.0 11683.4 08:24:31 INFO - PROCESS | 1921 | 08:24:31 INFO - PROCESS | 1921 | [#3] mootools.html Cycles:180 Average:5385.47 Median:4850.71 stddev:2947.03 (60.8%) stddev-sans-first:2938.13 08:24:31 INFO - PROCESS | 1921 | Values: 1153.8 1443.4 1473.2 1609.4 1804.1 7053.9 7417.4 7808.5 8101.7 8325.5 8226.0 8314.9 8330.8 8331.8 8350.9 2277.6 2313.8 2314.7 2326.5 2331.8 2315.0 2348.9 2350.2 2366.7 2367.6 2288.9 2292.7 2319.9 2321.4 2321.6 2284.6 2301.3 2320.2 2321.7 2326.2 2019.5 2306.5 2308.5 2324.7 2337.3 8043.1 8224.6 8423.4 8430.2 8447.2 4999.9 5189.9 5190.1 5193.1 5196.9 3328.0 3441.9 3443.3 3511.1 3527.4 1051.3 1072.3 1732.1 1735.7 1829.5 10546.1 10776.2 10852.9 10865.6 10866.9 4540.8 4672.3 4687.5 4701.3 4711.6 9484.1 9500.7 9504.3 9515.0 9526.8 5188.6 5223.8 5227.1 5228.2 5233.6 4628.0 4776.4 4788.9 4812.8 4828.3 3632.2 3655.1 3739.3 3773.1 3779.5 1206.8 1233.6 1235.2 1247.7 1248.3 877.6 879.0 883.9 891.5 900.1 5190.3 5311.7 5487.8 5692.9 5705.2 426.5 436.5 462.4 465.0 465.5 3547.5 3580.0 3599.7 3602.8 3758.0 9778.4 10144.8 10155.0 10157.2 10169.9 10066.9 10112.0 10113.4 10113.9 10123.7 7326.5 7670.0 7730.5 7836.6 7855.8 5338.6 5480.6 5522.1 5630.6 5671.1 9169.6 9223.6 9276.5 9305.4 9406.2 7527.7 7683.0 9320.3 9378.8 9434.8 7059.3 7153.3 7155.3 7160.4 7163.2 6807.6 7648.3 7703.0 7810.3 7817.3 7034.9 7060.4 7108.3 7212.5 7224.9 6228.3 6909.0 6931.9 7102.7 7110.4 5675.1 6458.1 6618.8 6622.7 6638.4 4632.7 4787.0 4798.0 4843.6 5010.6 4781.6 4796.4 4796.9 4857.8 4875.0 08:24:31 INFO - PROCESS | 1921 | 08:24:31 INFO - PROCESS | 1921 | [#4] prototype.html Cycles:180 Average:4362.09 Median:4810.56 stddev:1353.40 (28.1%) stddev-sans-first:1322.24 08:24:31 INFO - PROCESS | 1921 | Values: 290.4 292.7 299.7 304.3 307.8 4555.5 4601.8 5051.0 5052.7 5120.2 4197.2 4248.7 4921.3 5091.8 5125.3 3513.8 3800.5 4020.0 4020.2 4035.2 3411.6 3687.9 4017.5 4034.1 4058.9 3916.6 3944.9 3954.5 3989.4 3993.1 3315.3 3816.9 3941.2 3946.6 4030.2 3427.8 3438.7 3441.3 3446.5 3452.2 4424.8 4760.2 5003.6 5057.7 5090.4 3811.9 4410.3 4475.5 4490.9 4491.6 5365.4 5385.8 5397.0 5427.3 5436.6 3578.4 3754.3 3759.3 3765.0 3770.9 5090.1 5345.3 5638.4 5654.5 5671.0 3390.5 3460.7 3702.3 3753.9 3771.2 3354.4 3415.0 3700.5 3847.9 3854.3 4262.5 4365.8 4432.5 4439.8 4441.0 4673.5 5139.4 5342.8 5392.6 5410.0 1161.7 1176.5 1196.5 1216.4 1223.5 754.1 808.8 834.2 844.4 861.6 4129.1 4198.9 4376.9 4550.0 4875.2 4854.8 5169.1 5225.1 5267.5 5310.3 4197.3 4436.0 4490.8 4641.7 4766.3 2551.8 2695.3 2715.5 2734.5 2743.8 5010.8 5335.7 5362.8 5377.3 5443.7 4693.6 5485.6 5815.0 5830.3 5961.6 5332.6 5532.3 5575.6 5845.3 5901.7 4991.6 5014.6 5633.0 5642.2 5673.6 5397.5 5528.4 5592.1 5604.1 5605.8 5610.3 5826.0 5839.1 5878.5 5887.3 3384.9 3718.2 3828.8 3923.7 3963.9 5339.7 5516.6 5585.3 5599.6 5623.6 4914.9 5269.6 5465.7 5499.3 5547.5 4542.9 5342.9 5363.9 5405.6 5470.8 4685.7 5127.9 5291.7 5319.5 5358.0 4662.3 5498.7 5505.6 5524.5 5565.9 4301.2 5193.9 5319.1 5453.5 5503.3 08:24:31 INFO - PROCESS | 1921 | 08:24:31 INFO - PROCESS | 1921 | [#5] yui.html Cycles:180 Average:20504.73 Median:49521.13 stddev:30684.15 (62.0%) stddev-sans-first:30734.16 08:24:31 INFO - PROCESS | 1921 | Values: 690.3 704.5 741.7 742.4 803.8 13766.2 13856.2 14237.3 14362.1 14456.2 12955.2 13058.6 13082.2 13089.0 13141.2 632.3 656.8 794.6 810.6 823.6 815.1 818.6 818.9 819.2 821.7 869.1 871.0 871.8 872.2 874.1 862.2 863.3 864.8 865.0 868.0 636.6 637.1 637.5 637.7 637.7 13092.2 13410.9 13459.0 13472.8 13793.2 12019.7 12567.9 13696.7 14076.5 14128.6 11751.0 12005.5 12006.0 12234.1 12451.4 4302.6 4355.6 4388.4 4539.8 4548.3 91667.0 92180.5 92308.0 92554.1 93174.1 9756.5 9848.8 10312.6 10326.9 10466.9 18652.1 18725.2 18728.5 18768.4 18837.2 7259.5 7335.5 7358.6 7595.0 7597.7 4123.5 4135.8 4213.7 4247.2 4337.2 2941.6 2982.3 3012.5 3045.3 3049.2 629.3 634.6 636.6 636.9 645.8 585.9 602.5 604.9 608.9 611.0 8177.0 8554.8 8584.0 8628.8 8660.0 538.9 590.0 593.3 615.1 625.1 3849.4 4043.7 4053.6 4125.1 4199.9 115036.4 116163.4 118086.0 118921.0 121534.0 55167.7 59544.7 91667.0 99293.0 109137.0 83385.6 84452.0 84677.0 85477.3 89230.6 8494.8 9280.3 9364.1 9677.6 9729.4 47747.5 51294.7 53462.0 54669.2 56293.7 77508.0 77549.0 79157.6 80935.6 81325.2 11516.3 11925.3 12086.8 12182.9 12486.8 13435.8 13496.7 13876.5 14053.9 14774.3 12131.4 12266.1 12453.1 12637.5 13324.3 12173.1 13167.8 13183.7 13339.1 13766.8 11252.3 11662.0 12987.9 13232.2 13402.3 10088.0 10775.1 11057.8 11343.7 11748.5 9098.7 9388.3 9758.6 9944.4 11328.5 08:24:31 INFO - PROCESS | 1921 | -------- Summary: end -------- 08:24:31 INFO - PROCESS | 1921 | 08:24:32 INFO - TEST-INFO | 1921: exit 0 08:24:32 INFO - TEST-OK | dromaeo_css | took 1119303ms 08:24:32 INFO - TEST-START | kraken 08:24:32 INFO - operating with platform_type : mac_ 08:24:32 INFO - Initialising browser for kraken test... 08:24:32 INFO - TEST-INFO | started process 1923 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/tmpk_uwMp/profile http://localhost:51963/getInfo.html) 08:24:34 INFO - PROCESS | 1923 | __metrics Screen width/height:1600/1200 08:24:34 INFO - PROCESS | 1923 | colorDepth:24 08:24:34 INFO - PROCESS | 1923 | Browser inner width/height: 1024/689 08:24:34 INFO - PROCESS | 1923 | __metrics 08:24:34 INFO - PROCESS | 1923 | 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:24:34 INFO - PROCESS | 1923 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:24:35 INFO - TEST-INFO | 1923: exit 0 08:24:35 INFO - Browser initialized. 08:24:35 INFO - Running cycle 1/1 for kraken test... 08:24:35 INFO - TEST-INFO | started process 1924 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/T/tmpk_uwMp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:24:47 INFO - PROCESS | 1924 | RSS: Main: 221990912 08:24:47 INFO - PROCESS | 1924 | 08:24:50 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:51963/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51963/tests/kraken/driver.html?testName=audio-beat-detection) 08:24:51 INFO - PROCESS | 1924 | RSS: Main: 271785984 08:24:51 INFO - PROCESS | 1924 | 08:24:54 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:51963/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51963/tests/kraken/driver.html?testName=audio-dft) 08:24:54 INFO - PROCESS | 1924 | RSS: Main: 283176960 08:24:54 INFO - PROCESS | 1924 | 08:24:58 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:51963/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51963/tests/kraken/driver.html?testName=audio-fft) 08:24:58 INFO - PROCESS | 1924 | RSS: Main: 379404288 08:24:58 INFO - PROCESS | 1924 | 08:25:00 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:51963/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51963/tests/kraken/driver.html?testName=audio-oscillator) 08:25:01 INFO - PROCESS | 1924 | RSS: Main: 283058176 08:25:01 INFO - PROCESS | 1924 | 08:25:03 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:51963/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51963/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:25:04 INFO - PROCESS | 1924 | RSS: Main: 284712960 08:25:04 INFO - PROCESS | 1924 | 08:25:06 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:51963/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51963/tests/kraken/driver.html?testName=imaging-darkroom) 08:25:07 INFO - PROCESS | 1924 | RSS: Main: 325103616 08:25:07 INFO - PROCESS | 1924 | 08:25:10 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:51963/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51963/tests/kraken/driver.html?testName=imaging-desaturate) 08:25:11 INFO - PROCESS | 1924 | RSS: Main: 326311936 08:25:11 INFO - PROCESS | 1924 | 08:25:14 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:51963/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51963/tests/kraken/driver.html?testName=json-parse-financial) 08:25:14 INFO - PROCESS | 1924 | RSS: Main: 324554752 08:25:14 INFO - PROCESS | 1924 | 08:25:16 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:51963/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51963/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:25:16 INFO - PROCESS | 1924 | RSS: Main: 298627072 08:25:16 INFO - PROCESS | 1924 | 08:25:18 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:51963/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51963/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:25:19 INFO - PROCESS | 1924 | RSS: Main: 299261952 08:25:19 INFO - PROCESS | 1924 | 08:25:21 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:51963/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51963/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:25:22 INFO - PROCESS | 1924 | RSS: Main: 301875200 08:25:22 INFO - PROCESS | 1924 | 08:25:25 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:51963/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51963/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:25:26 INFO - PROCESS | 1924 | RSS: Main: 313888768 08:25:26 INFO - PROCESS | 1924 | 08:25:29 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:51963/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51963/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:25:29 INFO - PROCESS | 1924 | RSS: Main: 313520128 08:25:29 INFO - PROCESS | 1924 | 08:25:31 INFO - PROCESS | 1924 | Cycle 1(1): loaded http://localhost:51963/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51963/tests/kraken/driver.html?testName=ai-astar) 08:25:31 INFO - PROCESS | 1924 | RSS: Main: 357007360 08:25:31 INFO - PROCESS | 1924 | 08:25:31 INFO - PROCESS | 1924 | __start_tp_report 08:25:31 INFO - PROCESS | 1924 | _x_x_mozilla_page_load 08:25:31 INFO - PROCESS | 1924 | _x_x_mozilla_page_load_details 08:25:31 INFO - PROCESS | 1924 | |i|pagename|runs| 08:25:31 INFO - PROCESS | 1924 | |0;ai-astar;117;113;113;114;115;113;114;114;116;114 08:25:31 INFO - PROCESS | 1924 | |1;audio-beat-detection;144;109;112;143;124;110;110;109;138;109 08:25:31 INFO - PROCESS | 1924 | |2;audio-dft;185;184;184;185;185;186;192;237;182;183 08:25:31 INFO - PROCESS | 1924 | |3;audio-fft;81;58;59;90;60;59;59;58;84;61 08:25:31 INFO - PROCESS | 1924 | |4;audio-oscillator;88;69;93;69;69;69;89;67;94;119 08:25:31 INFO - PROCESS | 1924 | |5;imaging-gaussian-blur;89;86;89;86;86;120;82;117;126;86 08:25:31 INFO - PROCESS | 1924 | |6;imaging-darkroom;96;104;133;140;95;115;135;95;95;95 08:25:31 INFO - PROCESS | 1924 | |7;imaging-desaturate;80;83;80;80;81;85;86;86;81;81 08:25:31 INFO - PROCESS | 1924 | |8;json-parse-financial;59;54;53;54;53;58;59;57;56;58 08:25:31 INFO - PROCESS | 1924 | |9;json-stringify-tinderbox;64;73;71;72;73;71;70;70;69;71 08:25:31 INFO - PROCESS | 1924 | |10;stanford-crypto-aes;85;82;85;83;84;86;83;82;85;84 08:25:31 INFO - PROCESS | 1924 | |11;stanford-crypto-ccm;139;139;141;148;153;154;143;144;180;171 08:25:31 INFO - PROCESS | 1924 | |12;stanford-crypto-pbkdf2;167;160;202;210;209;174;169;210;205;165 08:25:31 INFO - PROCESS | 1924 | |13;stanford-crypto-sha256-iterative;67;68;67;67;69;66;65;71;67;68 08:25:31 INFO - PROCESS | 1924 | __end_tp_report 08:25:31 INFO - PROCESS | 1924 | __start_cc_report 08:25:31 INFO - PROCESS | 1924 | _x_x_mozilla_cycle_collect,2051 08:25:31 INFO - PROCESS | 1924 | __end_cc_report 08:25:31 INFO - PROCESS | 1924 | __startTimestamp1485015931714__endTimestamp 08:25:31 INFO - PROCESS | 1924 | 08:25:31 INFO - PROCESS | 1924 | ------- Summary: start ------- 08:25:31 INFO - PROCESS | 1924 | Number of tests: 14 08:25:31 INFO - PROCESS | 1924 | 08:25:31 INFO - PROCESS | 1924 | [#0] ai-astar Cycles:10 Average:114.30 Median:114.00 stddev:1.34 (1.2%) stddev-sans-first:1.00 08:25:31 INFO - PROCESS | 1924 | Values: 117.0 113.0 113.0 114.0 115.0 113.0 114.0 114.0 116.0 114.0 08:25:31 INFO - PROCESS | 1924 | 08:25:31 INFO - PROCESS | 1924 | [#1] audio-beat-detection Cycles:10 Average:120.80 Median:118.00 stddev:15.15 (12.8%) stddev-sans-first:13.54 08:25:31 INFO - PROCESS | 1924 | Values: 144.0 109.0 112.0 143.0 124.0 110.0 110.0 109.0 138.0 109.0 08:25:31 INFO - PROCESS | 1924 | 08:25:31 INFO - PROCESS | 1924 | [#2] audio-dft Cycles:10 Average:190.30 Median:185.00 stddev:16.63 (9.0%) stddev-sans-first:17.52 08:25:31 INFO - PROCESS | 1924 | Values: 185.0 184.0 184.0 185.0 185.0 186.0 192.0 237.0 182.0 183.0 08:25:31 INFO - PROCESS | 1924 | 08:25:31 INFO - PROCESS | 1924 | [#3] audio-fft Cycles:10 Average:66.90 Median:60.50 stddev:12.71 (21.0%) stddev-sans-first:12.41 08:25:31 INFO - PROCESS | 1924 | Values: 81.0 58.0 59.0 90.0 60.0 59.0 59.0 58.0 84.0 61.0 08:25:31 INFO - PROCESS | 1924 | 08:25:31 INFO - PROCESS | 1924 | [#4] audio-oscillator Cycles:10 Average:82.60 Median:78.50 stddev:17.05 (21.7%) stddev-sans-first:17.97 08:25:31 INFO - PROCESS | 1924 | Values: 88.0 69.0 93.0 69.0 69.0 69.0 89.0 67.0 94.0 119.0 08:25:31 INFO - PROCESS | 1924 | 08:25:31 INFO - PROCESS | 1924 | [#5] imaging-gaussian-blur Cycles:10 Average:96.70 Median:86.00 stddev:17.02 (19.8%) stddev-sans-first:17.82 08:25:31 INFO - PROCESS | 1924 | Values: 89.0 86.0 89.0 86.0 86.0 120.0 82.0 117.0 126.0 86.0 08:25:31 INFO - PROCESS | 1924 | 08:25:31 INFO - PROCESS | 1924 | [#6] imaging-darkroom Cycles:10 Average:110.30 Median:95.00 stddev:18.89 (19.9%) stddev-sans-first:19.31 08:25:31 INFO - PROCESS | 1924 | Values: 96.0 104.0 133.0 140.0 95.0 115.0 135.0 95.0 95.0 95.0 08:25:31 INFO - PROCESS | 1924 | 08:25:31 INFO - PROCESS | 1924 | [#7] imaging-desaturate Cycles:10 Average:82.30 Median:82.00 stddev:2.50 (3.0%) stddev-sans-first:2.51 08:25:31 INFO - PROCESS | 1924 | Values: 80.0 83.0 80.0 80.0 81.0 85.0 86.0 86.0 81.0 81.0 08:25:31 INFO - PROCESS | 1924 | 08:25:31 INFO - PROCESS | 1924 | [#8] json-parse-financial Cycles:10 Average:56.10 Median:57.50 stddev:2.42 (4.2%) stddev-sans-first:2.33 08:25:31 INFO - PROCESS | 1924 | Values: 59.0 54.0 53.0 54.0 53.0 58.0 59.0 57.0 56.0 58.0 08:25:31 INFO - PROCESS | 1924 | 08:25:31 INFO - PROCESS | 1924 | [#9] json-stringify-tinderbox Cycles:10 Average:70.40 Median:71.00 stddev:2.59 (3.6%) stddev-sans-first:1.36 08:25:31 INFO - PROCESS | 1924 | Values: 64.0 73.0 71.0 72.0 73.0 71.0 70.0 70.0 69.0 71.0 08:25:31 INFO - PROCESS | 1924 | 08:25:31 INFO - PROCESS | 1924 | [#10] stanford-crypto-aes Cycles:10 Average:83.90 Median:84.50 stddev:1.37 (1.6%) stddev-sans-first:1.39 08:25:31 INFO - PROCESS | 1924 | Values: 85.0 82.0 85.0 83.0 84.0 86.0 83.0 82.0 85.0 84.0 08:25:31 INFO - PROCESS | 1924 | 08:25:31 INFO - PROCESS | 1924 | [#11] stanford-crypto-ccm Cycles:10 Average:151.20 Median:150.50 stddev:14.00 (9.3%) stddev-sans-first:14.13 08:25:31 INFO - PROCESS | 1924 | Values: 139.0 139.0 141.0 148.0 153.0 154.0 143.0 144.0 180.0 171.0 08:25:31 INFO - PROCESS | 1924 | 08:25:31 INFO - PROCESS | 1924 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.10 Median:203.50 stddev:21.59 (10.6%) stddev-sans-first:21.64 08:25:31 INFO - PROCESS | 1924 | Values: 167.0 160.0 202.0 210.0 209.0 174.0 169.0 210.0 205.0 165.0 08:25:31 INFO - PROCESS | 1924 | 08:25:31 INFO - PROCESS | 1924 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:67.50 stddev:1.65 (2.4%) stddev-sans-first:1.74 08:25:31 INFO - PROCESS | 1924 | Values: 67.0 68.0 67.0 67.0 69.0 66.0 65.0 71.0 67.0 68.0 08:25:31 INFO - PROCESS | 1924 | -------- Summary: end -------- 08:25:31 INFO - PROCESS | 1924 | 08:25:32 INFO - TEST-INFO | 1924: exit 0 08:25:32 INFO - TEST-OK | kraken | took 60631ms 08:25:32 INFO - SUITE-END | took 1179s 08:25:32 INFO - Completed test suite (00:19:40) 08:25:32 INFO - javascript benchmark 08:25:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3325.6508066653, 3326.833658317085, 3330.900072997808, 3335.966640333597, 3395.8132302723357, 4739.644526660506, 5695.060315048017, 5723.942760572393, 5760.510356619682, 5764.827055188341, 5662.0, 5753.884922301562, 5766.423357664232, 5775.884482310351, 5777.393373695764, 5221.190715439094, 5228.686278823264, 5247.658902171346, 5266.447023062581, 5301.0, 5155.974220128895, 5195.688258704471, 5209.479052094798, 5225.738713064351, 5343.0, 5266.183741520071, 5334.013207556595, 5334.67991920486, 5358.758855851476, 5390.0836857734275, 5309.787608495675, 5311.601629877775, 5335.253064570924, 5344.652597581184, 5395.163749618776, 5058.772355244023, 5177.870553236162, 5242.292290540804, 5264.973675131638, 5265.052290587692, 5573.359063707644, 5629.943700563023, 5648.322201335866, 5660.122680984413, 5699.914501282484, 6187.041008643668, 6205.503559715212, 6232.657203853786, 6237.625742455467, 6266.279377871558, 6261.46777523911, 6291.433770960635, 6298.3701629837105, 6324.430801227865, 6324.9051264231075, 5544.889102217973, 5685.40303268155, 5698.772049118072, 5774.451427114502, 5778.566607504454, 6209.842635469627, 6232.906506402408, 6234.9688251559355, 6236.656983865841, 6246.718897649616, 6134.447899689049, 6139.631622102688, 6144.907826382608, 6152.600080994721, 6158.938410615837, 6088.360722124202, 6139.351796347371, 6140.780336713758, 6176.629402235881, 6246.562740608205, 6058.666773327421, 6105.4294214941465, 6105.436769732072, 6105.671256026659, 6107.355864811133, 6197.349278325801, 6200.131981522591, 6258.15514905491, 6272.372762723691, 6285.151504546919, 4933.87665308363, 4936.456989731127, 4941.308216849644, 4945.134601444733, 4963.305137280783, 4440.644748420119, 4452.243118669834, 4454.37638730578, 4474.686771925999, 4485.59629633328, 5634.464725851038, 5649.576281778883, 5686.402927692616, 5687.118496633028, 5745.3105627324985, 6229.53278504114, 6278.089676996809, 6291.245050593958, 6298.7480500780375, 6298.779542715983, 5766.855828604234, 5786.334571524244, 5825.359210486928, 5829.825105246765, 5843.357230704619, 5307.310049693592, 5347.25138480613, 5348.91976620351, 5415.83752487426, 5422.322209723785, 6167.784127555514, 6208.565400422108, 6223.533235007302, 6262.874742505215, 6273.52948528853, 6069.544784141295, 6260.40526150015, 6273.843153921189, 6275.96862015687, 6278.4035516625845, 6198.938010619836, 6211.844703882439, 6274.560780745304, 6276.6547839869245, 6346.492280617632, 6030.4572588467245, 6084.148219249397, 6094.969525152346, 6110.6195130195665, 6131.570790044654, 5532.529734972572, 5944.5838791284195, 6238.0, 6263.78076767311, 6282.497400207881, 6076.149339092619, 6092.055731361646, 6101.206843110367, 6118.816435506942, 6128.509719222362, 5979.730912108878, 6027.487663548647, 6073.878522429438, 6118.785842495491, 6142.293636231891, 5952.672603006876, 6009.909851352146, 6030.939690603038, 6052.243469566304, 6070.271567411763, 6112.8777424452155, 6132.08018797184, 6135.202423684892, 6149.046897730859, 6150.815475535741, 5813.15709222152, 5854.765809367577, 5893.2044174035955, 5919.171316015847, 5948.940510595012], "value": 5697.802682995984, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [434.5155152005511, 499.8325560937084, 504.99747501262493, 505.55006044620274, 510.9718965456901, 28717.425651486985, 37800.621993780056, 39103.60896391035, 39150.0, 39156.99999999996, 36177.0955726107, 38871.80564097183, 39901.60098399015, 40345.59654403455, 40591.39112913309, 8240.670373185065, 9118.680846170386, 9217.769555761093, 9315.860262096074, 9324.720258392224, 8317.126701696325, 8523.829523409528, 8525.872111918326, 8618.224359807617, 8627.741167764978, 10882.61910833121, 11102.833457498144, 11117.499712512956, 11335.546578136906, 11345.546178152865, 11033.503492342823, 11109.666709998632, 11114.888851111466, 11116.888831111666, 11127.0, 8279.172082791732, 11023.83464248037, 11088.667339979813, 11094.778104437946, 11123.165762567758, 29785.10644680663, 31062.844685776654, 31087.22281942947, 37324.81337593322, 37918.0, 3215.1640573451073, 3280.5899262592175, 3313.5254811608843, 3438.0373495421322, 3808.90477738056, 27089.1873243803, 27285.0, 30159.0, 30491.0, 31524.842375787975, 3152.2592190835367, 3218.7746857719967, 3233.175540237248, 3252.4308246056844, 3300.3069355435387, 429888.0, 439903.0, 441081.0, 442033.0, 442777.0, 53309.0, 55047.0, 62695.6865215671, 64060.0, 64089.0, 66533.667331664, 67306.0, 68173.0, 72014.9197762034, 72057.0, 13681.863181368059, 16032.118233497036, 16055.43805966809, 16063.437779677888, 16068.75896861548, 12162.878371216351, 12238.082143839247, 12243.449044793006, 12254.877451225551, 12257.632271031884, 17164.227609757592, 17496.650066998587, 19605.607887842165, 19881.701774473106, 19973.600527989358, 7968.362530997506, 8199.467034642728, 8224.753257402288, 8235.21765432283, 8284.047334556602, 846.7713717296295, 921.7695576105973, 946.7017889364828, 977.9022097790306, 1042.3016578892161, 27178.32054198661, 27731.86134069317, 28678.426431471253, 28732.96405515236, 28820.711792882223, 945.2438049560461, 1001.6544292219173, 1038.3769738157046, 1038.9896101039044, 1088.1077516436603, 14701.411943522353, 14945.804335653122, 15110.548582078041, 15136.091834489966, 15270.31283592241, 642523.0, 650112.0, 650268.0, 650854.0, 651395.0, 46970.29544556834, 47825.76087119542, 53766.92466150655, 54116.188257176174, 54730.0, 1317.736452709462, 1323.966900827487, 1363.8295213098363, 1369.4590636698647, 1369.8904087672763, 28905.855470722512, 29612.407751845272, 29739.851300743358, 30325.545116823705, 30511.237219069702, 47107.76446117885, 52205.95588088141, 52750.20874686806, 52832.471675284294, 56379.436205637416, 631288.0, 667246.0, 696865.0, 698515.0, 866507.0, 39771.60228397679, 40075.398869016404, 40232.79301620956, 40547.39178912378, 41105.79447102745, 36229.275414491036, 43561.34657980196, 43801.56198438102, 44042.779786100866, 44197.337039943784, 35625.00000000062, 35762.284754304244, 36162.0, 36278.8186059068, 37255.06862328355, 36097.45853812143, 36305.63694363023, 36980.81509592435, 37438.8128059358, 37548.812255939636, 35584.288314233054, 35786.0, 36058.09854753652, 36087.819560902026, 36584.634153658124, 31190.376192476477, 33013.844515441844, 33106.66893331036, 33161.0, 33198.5020224692, 21330.0, 24098.759012409653, 24862.87568562218, 25015.749212538936, 25079.87460062688], "value": 19875.473378694365, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.6687646735602, 369.6599128801502, 371.76950290819696, 382.77990155660495, 399.49862922032844, 12385.504579816808, 14552.708945821078, 15708.764368534481, 17049.48851534453, 19982.100805463753, 16620.916895415536, 17584.120793960283, 17913.373031943884, 18934.905325473388, 19569.412917612495, 4779.64152688548, 5814.360420353758, 5822.155787410823, 5824.068149096145, 5824.912626310609, 5508.889822203553, 5685.573581981347, 5697.487226149646, 5783.855403614923, 5792.623479473821, 5705.6006079574445, 5723.942760572403, 5732.455416735404, 5734.225879506255, 5735.111057786029, 5465.59008074394, 5706.914396284059, 5712.800051998181, 5778.537716982673, 5796.565257605655, 5057.115004874169, 5069.340985671839, 5069.746512674352, 5070.0, 5084.898302033976, 24065.87967060171, 24438.877805611035, 24446.755532444804, 24537.50924981508, 24761.133360332482, 2102.4848912016655, 2230.9330720078424, 2272.250157448038, 2273.079402841852, 2299.2642354446584, 14141.929290353586, 15014.174220417872, 15184.696306073927, 15722.921385393, 15752.921235393864, 1661.1528120658552, 1785.526835388623, 1788.0880750817048, 1789.9463016109537, 1816.8455681267108, 112043.31935020981, 118037.40981295124, 118505.8149418512, 120172.39913800375, 123224.9999999991, 9844.655437059668, 11386.94306528462, 11452.770944581229, 11471.598494052669, 11650.30098194111, 14617.707645847024, 14634.634134146518, 14652.0, 14671.486497972734, 14673.486427975184, 8187.754367368989, 8259.793505162299, 8300.916990830014, 8433.156684331518, 8453.915460845436, 8093.635786389626, 8133.0, 8167.14245004266, 8277.379196560281, 8283.461574997606, 4357.694961352675, 4506.571875671872, 4507.797149128297, 4524.819007239739, 4585.495595484494, 1696.0925904641203, 1856.2296646891389, 1881.2192939930048, 1907.980920190808, 1912.913918873654, 809.0897740042452, 812.8293058457672, 853.2534032721369, 914.593006112287, 934.8270569944582, 19422.0, 19879.0, 20096.69854952177, 20165.596688066158, 20178.495537611678, 911.7128104647015, 920.2683866326287, 933.659214386744, 961.3799099580731, 997.5959736306808, 8579.613917373734, 9827.262955278382, 10526.368417894952, 10627.043566078935, 10703.678889633324, 580135.0, 586295.0, 587258.0, 602770.0, 609841.0, 79595.0, 108676.0, 117539.6492070171, 118519.81480185088, 126956.0, 906.3791302957495, 967.7532229281488, 982.4547376206132, 1021.5352014833232, 1021.7292417509366, 14194.574162775134, 17444.0, 17458.47624571265, 17989.100544972436, 18011.549711257325, 40809.0, 51326.74336628293, 52287.69280768011, 53664.463355365944, 53711.73144134254, 65886.67056664686, 76336.23663763292, 78061.0, 80757.8123830574, 82038.76941845997, 7131.144262688511, 7959.840803183788, 7985.560794156378, 8119.5897722955315, 8249.010118785784, 12377.195482293622, 12646.810297845725, 12761.680957976125, 13036.739265214832, 13133.802992955305, 10902.0, 11187.32876027441, 11456.312621242752, 11531.94234028858, 11640.708982275512, 9510.619575216913, 11134.498947547541, 11137.94431027872, 11423.371714555778, 11490.885091149315, 11220.719482013015, 11342.489587968726, 11413.750946608816, 11566.710832229262, 11773.646790596296, 7408.925910740823, 7533.7363192288, 7567.583782891774, 7580.279873412017, 7763.844723105619, 9522.619095236112, 11151.940565646251, 11350.148738844455, 11539.0, 11683.357415342238], "value": 9311.565521126762, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1153.7750138722952, 1443.4154167562135, 1473.2118316700567, 1609.44474156416, 1804.0799192411873, 7053.89419158713, 7417.443691723122, 7808.492447990884, 8101.675932962682, 8325.500469971807, 8226.01287845458, 8314.875276870851, 8330.750077497669, 8331.750047498585, 8350.91649083509, 2277.5900337939165, 2313.791758741713, 2314.652802079693, 2326.499802542453, 2331.8484298520625, 2315.0, 2348.8825558722083, 2350.2361732437025, 2366.7396586375485, 2367.621180611103, 2288.9198878039274, 2292.6790249365104, 2319.9072037118494, 2321.408040949564, 2321.605327094398, 2284.5773531896652, 2301.2866011536394, 2320.234322673514, 2321.6633588129625, 2326.1974618756503, 2019.5254115282896, 2306.527161931817, 2308.5036717105904, 2324.697789287382, 2337.3338598499518, 8043.11525732169, 8224.62989165483, 8423.410361274713, 8430.241278284984, 8447.155284471504, 4999.875003124951, 5189.948100518985, 5190.143626301656, 5193.143131383317, 5196.896062078775, 3328.0, 3441.913952151216, 3443.345764304774, 3511.122219445139, 3527.4003419418505, 1051.2956319266034, 1072.2976450424985, 1732.0993083596586, 1735.6788994036144, 1829.4785985993929, 10546.05085542305, 10776.245662803607, 10852.945735271272, 10865.619703310425, 10866.891331086666, 4540.750258735735, 4672.322513235562, 4687.507811679793, 4701.294805779161, 4711.575958163782, 9484.14642682148, 9500.667476638284, 9504.287178461644, 9515.048495150568, 9526.809463810685, 5188.558972487305, 5223.7910483580235, 5227.059129356676, 5228.163493840967, 5233.633645644768, 4627.981843994316, 4776.355192049098, 4788.904221915543, 4812.807487700453, 4828.251620998751, 3632.182758879231, 3655.1227705350525, 3739.3269211542183, 3773.132179598653, 3779.4519794629623, 1206.8008778551616, 1233.5744168261936, 1235.1539195651008, 1247.650657815813, 1248.3259040118235, 877.6401675313218, 878.9648414063494, 883.9469631822111, 891.4740303221131, 900.1088921967205, 5190.273361729361, 5311.707856067954, 5487.807926722546, 5692.857678558069, 5705.2012718219485, 426.4818245831268, 436.53727049327813, 462.4335189392967, 465.0350522665483, 465.4902881344922, 3547.5033495310677, 3580.03339098442, 3599.6760291573883, 3602.8018458985007, 3758.0, 9778.41329520231, 10144.847827282596, 10155.035271649182, 10157.187425005981, 10169.898301016896, 10066.949665251626, 10112.0, 10113.393196408239, 10113.949430252802, 10123.746906327253, 7326.487145899736, 7670.0, 7730.458867879194, 7836.647350869224, 7855.842883142305, 5338.626296159232, 5480.588955828249, 5522.144067669517, 5630.60585758993, 5671.0662022990045, 9169.587368568564, 9223.631054757734, 9276.489793061177, 9305.395149315273, 9406.24750019983, 7527.73652922167, 7682.9757560706375, 9320.300977426856, 9378.812423751624, 9434.811303774024, 7059.258777828254, 7153.284671532805, 7155.3202445767565, 7160.355567998905, 7163.212046674757, 6807.5575087619145, 7648.273414025659, 7702.961485192538, 7810.258025487569, 7817.296443320129, 7034.929650703428, 7060.435165186876, 7108.289171082851, 7212.531185472927, 7224.927750722636, 6228.314885362697, 6908.967594578046, 6931.930680693128, 7102.715891364288, 7110.43116550685, 5675.091985282335, 6458.095866578588, 6618.7683431081605, 6622.668866556556, 6638.402543771083, 4632.675712700078, 4786.976065119652, 4798.008826337171, 4843.612510999182, 5010.624203184703, 4781.617470602413, 4796.35249241348, 4796.904061918812, 4857.805687772449, 4875.0493653737185], "value": 4329.919454244663, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [290.3756922616375, 292.7087547889851, 299.668865903177, 304.2894840547322, 307.80762023735167, 4555.476120246172, 4601.79291931863, 5051.045138593922, 5052.747362631872, 5120.15517439622, 4197.181549597822, 4248.723832950856, 4921.261810728392, 5091.821786237482, 5125.256837758523, 3513.8243087845635, 3800.5439347278225, 4019.9799001004953, 4020.2361551305303, 4035.2131334389805, 3411.641777613352, 3687.9120659405407, 4017.4978127734034, 4034.0519977805193, 4058.939115913264, 3916.647501724844, 3944.901377465572, 3954.528419737664, 3989.4441167944524, 3993.0833200063985, 3315.3203593263324, 3816.8740221634685, 3941.2214388855787, 3946.625070618287, 4030.1939612077554, 3427.8114703691285, 3438.6561343865665, 3441.294534620397, 3446.5347178130883, 3452.223249768807, 4424.756638384888, 4760.214564596838, 5003.574696150795, 5057.69653820772, 5090.389153301628, 3811.8856434307013, 4410.272305069691, 4475.530069342704, 4490.932636010462, 4491.595756381941, 5365.409804921455, 5385.811496597639, 5396.973015134939, 5427.3215848019, 5436.565074794007, 3578.427451607756, 3754.3054534910857, 3759.342115129841, 3764.962350376516, 3770.925286293393, 5090.149516270863, 5345.33183352081, 5638.351589567212, 5654.547636189095, 5670.979223739725, 3390.5083762854247, 3460.7058400035776, 3702.2965636528975, 3753.887383378503, 3771.152135453608, 3354.3626710924846, 3415.0289248354093, 3700.4634328022826, 3847.922581677135, 3854.325493038707, 4262.488501379792, 4365.781710914442, 4432.468103827497, 4439.800208990603, 4440.955590444119, 4673.462551806586, 5139.406783897004, 5342.786288548492, 5392.595555333366, 5410.0, 1161.7211869151342, 1176.505867535637, 1196.5333519927235, 1216.355331674214, 1223.5289413575838, 754.0913199594476, 808.7533302342842, 834.2283387866199, 844.3835999720238, 861.5649097205996, 4129.112240868228, 4198.85304014358, 4376.912461750747, 4549.977250113794, 4875.244911831738, 4854.80580776765, 5169.061601899288, 5225.059489291964, 5267.525922666979, 5310.283111779938, 4197.25813787281, 4435.977820110943, 4490.7754612269255, 4641.744704041243, 4766.308885211625, 2551.7830984366537, 2695.2588038289236, 2715.5383584790634, 2734.480448714738, 2743.8490883001627, 5010.824621138243, 5335.733213339395, 5362.839114826562, 5377.274068000848, 5443.700596467233, 4693.55411235932, 5485.6434331768305, 5814.970925145347, 5830.271216097988, 5961.612495187799, 5332.626716129834, 5532.336119665666, 5575.553955683536, 5845.3277873045145, 5901.675407852609, 4991.625628077982, 5014.598832093351, 5633.042382795016, 5642.210090587239, 5673.574481913955, 5397.514223719884, 5528.419515950928, 5592.133219351007, 5604.122618922706, 5605.831825045255, 5610.298712660918, 5825.970870145622, 5839.0949402842625, 5878.500327472213, 5887.293524777054, 3384.918275260746, 3718.2377612589894, 3828.764693833706, 3923.686105111527, 3963.8810835674976, 5339.652922560022, 5516.586256030733, 5585.273914391103, 5599.552035837041, 5623.550115990629, 4914.926276105933, 5269.631125821309, 5465.699386533779, 5499.312585926759, 5547.502174412966, 4542.863714088583, 5342.919856202082, 5363.919541206807, 5405.594580406564, 5470.781168753205, 4685.6657591726835, 5127.923136141452, 5291.656042357235, 5319.46805319465, 5358.0, 4662.344867571963, 5498.697571633598, 5505.559555235652, 5524.502794748492, 5565.912782706485, 4301.161273551627, 5193.935578008842, 5319.143620874989, 5453.545904342982, 5503.284573005483], "value": 3925.9403783092685, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [690.3131384272648, 704.499805138352, 741.7144399406227, 742.3646056499425, 803.7709252862934, 13766.17402955822, 13856.237906915128, 14237.288135593204, 14362.066465679723, 14456.204908730017, 12955.157914735535, 13058.608241752763, 13082.215067095982, 13089.0, 13141.211527308367, 632.3486808587163, 656.7734131724548, 794.5828440068952, 810.6271115286945, 823.567626995829, 815.1440986963664, 818.5661599352353, 818.8730746734266, 819.1767273889733, 821.7493664432345, 869.1265278395203, 870.9912900871005, 871.7776966873438, 872.162723785167, 874.0691163910446, 862.1981557151815, 863.3309185381316, 864.8054187807755, 864.9783755406165, 868.0148031983681, 636.6498425865756, 637.1207733327977, 637.4677144584277, 637.6811594202899, 637.735339833973, 13092.214467132002, 13410.93294533531, 13459.0, 13472.797908031387, 13793.172409655454, 12019.699507512294, 12567.93716031414, 13696.65758356049, 14076.507322243775, 14128.576142715736, 11751.0, 12005.459754311078, 12005.968664511316, 12234.143609947307, 12451.439685214187, 4302.591253830881, 4355.607995280408, 4388.429504164438, 4539.750313732743, 4548.272276435788, 91667.0, 92180.53909730476, 92308.0, 92554.07445925522, 93174.06825931722, 9756.512174391251, 9848.753781155385, 10312.639057633098, 10326.948365258126, 10466.947665261625, 18652.067396630384, 18725.157367918477, 18728.531786705167, 18768.436946891616, 18837.152328145265, 7259.491835571459, 7335.48651594394, 7358.632068396559, 7595.0, 7597.696092156362, 4123.463949686581, 4135.834566617362, 4213.705040647125, 4247.193033323659, 4337.175936572041, 2941.5587661850464, 2982.2693440107314, 3012.5330573761103, 3045.25391279138, 3049.161480592855, 629.3203340392365, 634.6445990245477, 636.6466611030922, 636.9426751592287, 645.812714312844, 585.871108356161, 602.5270162922172, 604.8578584032749, 608.856918624123, 610.9602875813058, 8176.959115204505, 8554.786130346667, 8584.0, 8628.835107260566, 8660.0, 538.9434109418534, 589.964602123874, 593.2880543348, 615.0743131586933, 625.08425157145, 3849.364854798983, 4043.73715708478, 4053.6351728344002, 4125.0825066129555, 4199.9370009449885, 115036.42481787538, 116163.41918290354, 118086.0, 118921.0, 121534.0, 55167.72416137894, 59544.70227648921, 91667.0, 99293.0, 109137.0, 83385.58307208425, 84451.99999999878, 84677.0, 85477.29045419057, 89230.55384723035, 8494.830103397773, 9280.257579393767, 9364.110409511086, 9677.643118329606, 9729.416235025921, 47747.52252477431, 51294.74352628213, 53462.0, 54669.1799622998, 56293.71853140708, 77508.0, 77549.0, 79157.60421197857, 80935.59532202301, 81325.18674813176, 11516.30902145874, 11925.284482931052, 12086.818697719365, 12182.939085304872, 12486.750264994831, 13435.798463023259, 13496.662583435493, 13876.514321998682, 14053.859461405254, 14774.335154917839, 12131.393430328271, 12266.141370104007, 12453.06602004835, 12637.494500220257, 13324.267165306, 12173.087018473472, 13167.802482962572, 13183.736325273248, 13339.06626536133, 13766.793498097737, 11252.26860254081, 11662.0, 12987.935060325015, 13232.206067635972, 13402.329883505981, 10088.0, 10775.137988961022, 11057.778844423228, 11343.716407089889, 11748.501619398756, 9098.681546145854, 9388.295877809329, 9758.560864761244, 9944.403335799876, 11328.490217940047], "value": 7164.287996861575, "unit": "score"}], "name": "dromaeo_css", "value": 7103.116252644589, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 113.0, 113.0, 114.0, 115.0, 113.0, 114.0, 114.0, 116.0, 114.0], "value": 114.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 109.0, 112.0, 143.0, 124.0, 110.0, 110.0, 109.0, 138.0, 109.0], "value": 120.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 184.0, 185.0, 185.0, 186.0, 192.0, 237.0, 182.0, 183.0], "value": 190.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 58.0, 59.0, 90.0, 60.0, 59.0, 59.0, 58.0, 84.0, 61.0], "value": 66.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 69.0, 93.0, 69.0, 69.0, 69.0, 89.0, 67.0, 94.0, 119.0], "value": 82.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 89.0, 86.0, 86.0, 120.0, 82.0, 117.0, 126.0, 86.0], "value": 96.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 104.0, 133.0, 140.0, 95.0, 115.0, 135.0, 95.0, 95.0, 95.0], "value": 110.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 83.0, 80.0, 80.0, 81.0, 85.0, 86.0, 86.0, 81.0, 81.0], "value": 82.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 54.0, 53.0, 54.0, 53.0, 58.0, 59.0, 57.0, 56.0, 58.0], "value": 56.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 73.0, 71.0, 72.0, 73.0, 71.0, 70.0, 70.0, 69.0, 71.0], "value": 70.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 82.0, 85.0, 83.0, 84.0, 86.0, 83.0, 82.0, 85.0, 84.0], "value": 83.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 139.0, 141.0, 148.0, 153.0, 154.0, 143.0, 144.0, 180.0, 171.0], "value": 151.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 160.0, 202.0, 210.0, 209.0, 174.0, 169.0, 210.0, 205.0, 165.0], "value": 187.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 67.0, 67.0, 69.0, 66.0, 65.0, 71.0, 67.0, 68.0], "value": 67.5, "unit": "score"}], "name": "kraken", "value": 1480.3999999999999, "alertThreshold": 2.0}]} 08:25:32 INFO - Return code: 0 08:25:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:25:32 INFO - # TBPL SUCCESS # 08:25:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:25:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:25:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:25:33 INFO - 2017-01-21 08:25:33.015 screenresolution[1928:18814] starting screenresolution argv=screenresolution get 08:25:33 INFO - 2017-01-21 08:25:33.020 screenresolution[1928:18814] Display 0: 1600x1200x32@60 08:25:33 INFO - 2017-01-21 08:25:33.026 screenresolution[1929:18817] starting screenresolution argv=screenresolution list 08:25:33 INFO - Available Modes on Display 0 08:25:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:25:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:25:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:25:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:25:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:25:33 INFO - Intel Iris: 08:25:33 INFO - Chipset Model: Intel Iris 08:25:33 INFO - Type: GPU 08:25:33 INFO - Bus: Built-In 08:25:33 INFO - VRAM (Dynamic, Max): 1536 MB 08:25:33 INFO - Vendor: Intel (0x8086) 08:25:33 INFO - Device ID: 0x0a2e 08:25:33 INFO - Revision ID: 0x0009 08:25:33 INFO - Displays: 08:25:33 INFO - Display: 08:25:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:25:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:25:33 INFO - Display Serial Number: 1600x1200 60 08:25:33 INFO - Main Display: Yes 08:25:33 INFO - Mirror: Off 08:25:33 INFO - Online: Yes 08:25:33 INFO - Rotation: Supported 08:25:33 INFO - Return code: 0 08:25:33 INFO - Running post-action listener: _resource_record_post_action 08:25:33 INFO - [mozharness: 2017-01-21 16:25:33.267131Z] Finished run-tests step (success) 08:25:33 INFO - Running post-run listener: _resource_record_post_run 08:25:33 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 507904; Write bytes: 657204224; Read time: 8; Write time: 1877 08:25:33 INFO - TinderboxPrint: CPU usage
53.7% 08:25:33 INFO - TinderboxPrint: I/O read bytes / time
507,904 / 8 08:25:33 INFO - TinderboxPrint: I/O write bytes / time
657,204,224 / 1,877 08:25:33 INFO - TinderboxPrint: CPU idle
2,246.3 (46.3%) 08:25:33 INFO - TinderboxPrint: CPU system
973.0 (20.1%) 08:25:33 INFO - TinderboxPrint: CPU user
1,630.4 (33.6%) 08:25:33 INFO - TinderboxPrint: Swap in / out
351,973,376 / 0 08:25:33 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 348760576; Write bytes: 349038080; Read time: 28078; Write time: 691 08:25:33 INFO - run-tests - Wall time: 1181s; CPU: 54.0%; Read bytes: 430080; Write bytes: 300924416; Read time: 7; Write time: 1155 08:25:33 INFO - Running post-run listener: _upload_blobber_files 08:25:33 INFO - Blob upload gear active. 08:25:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:25:33 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:25:33 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:25:33 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:25:34 INFO - (blobuploader) - INFO - Open directory for files ... 08:25:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 08:25:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:25:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:25:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 08:25:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:25:35 INFO - (blobuploader) - INFO - Done attempting. 08:25:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 08:25:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:25:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:25:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 08:25:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:25:35 INFO - (blobuploader) - INFO - Done attempting. 08:25:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:25:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:25:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:25:38 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:25:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:25:38 INFO - (blobuploader) - INFO - Done attempting. 08:25:38 INFO - (blobuploader) - INFO - Iteration through files over. 08:25:38 INFO - Return code: 0 08:25:38 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:25:38 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:25:38 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/83aa2d2c00f2cfd16583410735c054968534fbf82896e4879bb2f0882617ff3567bba0fdee02885d825e0e5595e5142ef10e5e7ce87a0d60fa1f12a52c26ae02", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/173cbdfa8831718c173ab02d9d08b496dd2c79504ed57491620c5fdc533b7bbb7aee452fb7e9d0610b3cefb9daaaff7bfd20b8d31ae6da37c084ac70a178a130", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c4a5c1c7428f242757bf6af57ffa3d6e71ceb365a850ff80b5ebab07ef3ab029a8b08116d93b972c226973185db61dee96a962d9cc908b585c2f9125498fea10"} 08:25:38 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:25:38 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:25:38 INFO - Contents: 08:25:38 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/83aa2d2c00f2cfd16583410735c054968534fbf82896e4879bb2f0882617ff3567bba0fdee02885d825e0e5595e5142ef10e5e7ce87a0d60fa1f12a52c26ae02", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/173cbdfa8831718c173ab02d9d08b496dd2c79504ed57491620c5fdc533b7bbb7aee452fb7e9d0610b3cefb9daaaff7bfd20b8d31ae6da37c084ac70a178a130", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c4a5c1c7428f242757bf6af57ffa3d6e71ceb365a850ff80b5ebab07ef3ab029a8b08116d93b972c226973185db61dee96a962d9cc908b585c2f9125498fea10"} 08:25:38 INFO - Running post-run listener: copy_logs_to_upload_dir 08:25:38 INFO - Copying logs to upload dir... 08:25:38 INFO - mkdir: /builds/slave/test/build/upload/logs 08:25:38 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.046849 ========= master_lag: 3.64 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2017-01-21 08:25:41.903846) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-21 08:25:41.906990) ========= 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.oxi2ZbfgYp/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.gEaIiVyC8Y/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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/83aa2d2c00f2cfd16583410735c054968534fbf82896e4879bb2f0882617ff3567bba0fdee02885d825e0e5595e5142ef10e5e7ce87a0d60fa1f12a52c26ae02", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/173cbdfa8831718c173ab02d9d08b496dd2c79504ed57491620c5fdc533b7bbb7aee452fb7e9d0610b3cefb9daaaff7bfd20b8d31ae6da37c084ac70a178a130", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c4a5c1c7428f242757bf6af57ffa3d6e71ceb365a850ff80b5ebab07ef3ab029a8b08116d93b972c226973185db61dee96a962d9cc908b585c2f9125498fea10"} build_url:https://queue.taskcluster.net/v1/task/b8QGS0x6SleI5IAcI54Oqg/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011195 build_url: 'https://queue.taskcluster.net/v1/task/b8QGS0x6SleI5IAcI54Oqg/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/83aa2d2c00f2cfd16583410735c054968534fbf82896e4879bb2f0882617ff3567bba0fdee02885d825e0e5595e5142ef10e5e7ce87a0d60fa1f12a52c26ae02", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/173cbdfa8831718c173ab02d9d08b496dd2c79504ed57491620c5fdc533b7bbb7aee452fb7e9d0610b3cefb9daaaff7bfd20b8d31ae6da37c084ac70a178a130", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c4a5c1c7428f242757bf6af57ffa3d6e71ceb365a850ff80b5ebab07ef3ab029a8b08116d93b972c226973185db61dee96a962d9cc908b585c2f9125498fea10"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-21 08:25:41.942556) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-21 08:25:41.942914) ========= 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.oxi2ZbfgYp/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.gEaIiVyC8Y/Listeners TMPDIR=/var/folders/9j/4fh549jj3szc5hrvft_030_w00000w/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.005361 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-21 08:25:41.991357) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-21 08:25:41.991683) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-21 08:25:41.991997) ========= ========= Total master_lag: 3.79 =========