builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0339 starttime: 1481904955.37 results: success (0) buildid: 20161216070649 builduid: 98dd92e848fe4847b9fbdaba583e6351 revision: 5a536a16e33798fe7b16de35c968d5bc0cbf8448 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-16 08:15:55.365312) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-16 08:15:55.365748) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-16 08:15:55.366023) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-16 08:15:55.392698) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 08:15:55.393086) ========= 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.oiUOqrTqZr/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.2qC71lUWCj/Listeners TMPDIR=/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-16 08:15:55-- 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% 18.8M=0.001s 2016-12-16 08:15:55 (18.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.125442 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 08:15:55.542905) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 08:15:55.543230) ========= 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.oiUOqrTqZr/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.2qC71lUWCj/Listeners TMPDIR=/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/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.025475 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 08:15:55.592469) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 08:15:55.592842) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5a536a16e33798fe7b16de35c968d5bc0cbf8448 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 5a536a16e33798fe7b16de35c968d5bc0cbf8448 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.oiUOqrTqZr/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.2qC71lUWCj/Listeners TMPDIR=/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-16 08:15:55,690 truncating revision to first 12 chars 2016-12-16 08:15:55,690 Setting DEBUG logging. 2016-12-16 08:15:55,691 attempt 1/10 2016-12-16 08:15:55,691 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5a536a16e337?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-16 08:15:56,372 unpacking tar archive at: mozilla-central-5a536a16e337/testing/mozharness/ program finished with exit code 0 elapsedTime=0.938407 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 08:15:56.558656) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-16 08:15:56.559039) ========= script_repo_revision: 5a536a16e33798fe7b16de35c968d5bc0cbf8448 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-16 08:15:56.559477) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-16 08:15:56.559781) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-16 08:15:56.580427) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-12-16 08:15:56.580876) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.oiUOqrTqZr/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.2qC71lUWCj/Listeners TMPDIR=/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/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:15:56 INFO - MultiFileLogger online at 20161216 08:15:56 in /builds/slave/test 08:15:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:15:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:15:56 INFO - {'append_to_log': False, 08:15:56 INFO - 'base_work_dir': '/builds/slave/test', 08:15:56 INFO - 'blob_upload_branch': 'Firefox', 08:15:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:15:56 INFO - 'branch': 'Firefox', 08:15:56 INFO - 'buildbot_json_path': 'buildprops.json', 08:15:56 INFO - 'config_files': ('talos/mac_config.py',), 08:15:56 INFO - 'default_actions': ('clobber', 08:15:56 INFO - 'read-buildbot-config', 08:15:56 INFO - 'download-and-extract', 08:15:56 INFO - 'populate-webroot', 08:15:56 INFO - 'create-virtualenv', 08:15:56 INFO - 'install', 08:15:56 INFO - 'run-tests'), 08:15:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:15:56 INFO - 'download_minidump_stackwalk': True, 08:15:56 INFO - 'download_symbols': 'ondemand', 08:15:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:15:56 INFO - 'tooltool.py': '/tools/tooltool.py', 08:15:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:15:56 INFO - '/tools/misc-python/virtualenv.py')}, 08:15:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:15:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:15:56 INFO - 'installer_path': 'installer.exe', 08:15:56 INFO - 'log_level': 'info', 08:15:56 INFO - 'log_name': 'talos', 08:15:56 INFO - 'log_to_console': True, 08:15:56 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:15:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:15:56 INFO - 'opt_config_files': (), 08:15:56 INFO - 'pip_index': False, 08:15:56 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:15:56 INFO - 'cmd': ('bash', 08:15:56 INFO - '-c', 08:15:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:15:56 INFO - 'enabled': True, 08:15:56 INFO - 'halt_on_failure': False, 08:15:56 INFO - 'name': 'check_screen_resolution'},), 08:15:56 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:15:56 INFO - 'cmd': ('bash', 08:15:56 INFO - '-c', 08:15:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:15:56 INFO - 'enabled': True, 08:15:56 INFO - 'halt_on_failure': False, 08:15:56 INFO - 'name': 'check_screen_resolution'},), 08:15:56 INFO - 'run_cmd_checks_enabled': True, 08:15:56 INFO - 'sps_profile': False, 08:15:56 INFO - 'sps_profile_interval': 0, 08:15:56 INFO - 'suite': 'dromaeojs-e10s', 08:15:56 INFO - 'system_bits': '32', 08:15:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:15:56 INFO - 'title': 't-yosemite-r7-0339', 08:15:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:15:56 INFO - 'use_talos_json': True, 08:15:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:15:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:15:56 INFO - 'work_dir': 'build'} 08:15:56 INFO - [mozharness: 2016-12-16 16:15:56.781860Z] Running clobber step. 08:15:56 INFO - Running pre-action listener: _resource_record_pre_action 08:15:56 INFO - Running main action method: clobber 08:15:56 INFO - rmtree: /builds/slave/test/build 08:15:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:15:59 INFO - Running post-action listener: _resource_record_post_action 08:15:59 INFO - [mozharness: 2016-12-16 16:15:59.072359Z] Finished clobber step (success) 08:15:59 INFO - [mozharness: 2016-12-16 16:15:59.072494Z] Running read-buildbot-config step. 08:15:59 INFO - Running pre-action listener: _resource_record_pre_action 08:15:59 INFO - Running main action method: read_buildbot_config 08:15:59 INFO - Using buildbot properties: 08:15:59 INFO - { 08:15:59 INFO - "project": "", 08:15:59 INFO - "product": "firefox", 08:15:59 INFO - "script_repo_revision": "production", 08:15:59 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 08:15:59 INFO - "repository": "", 08:15:59 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 08:15:59 INFO - "buildid": "20161216070649", 08:15:59 INFO - "pgo_build": "False", 08:15:59 INFO - "basedir": "/builds/slave/test", 08:15:59 INFO - "buildnumber": 107, 08:15:59 INFO - "slavename": "t-yosemite-r7-0339", 08:15:59 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 08:15:59 INFO - "platform": "yosemite_r7", 08:15:59 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:15:59 INFO - "branch": "mozilla-central", 08:15:59 INFO - "repo_path": "mozilla-central", 08:15:59 INFO - "revision": "5a536a16e33798fe7b16de35c968d5bc0cbf8448", 08:15:59 INFO - "stage_platform": "macosx64", 08:15:59 INFO - "builduid": "98dd92e848fe4847b9fbdaba583e6351", 08:15:59 INFO - "slavebuilddir": "test" 08:15:59 INFO - } 08:15:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/GYK5r_y8S1m7GQ6G0JPOxw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 08:15:59 INFO - Running post-action listener: _resource_record_post_action 08:15:59 INFO - [mozharness: 2016-12-16 16:15:59.076336Z] Finished read-buildbot-config step (success) 08:15:59 INFO - [mozharness: 2016-12-16 16:15:59.076466Z] Running download-and-extract step. 08:15:59 INFO - Running pre-action listener: _resource_record_pre_action 08:15:59 INFO - Running main action method: download_and_extract 08:15:59 INFO - mkdir: /builds/slave/test/build/tests 08:15:59 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:15:59 INFO - trying https://queue.taskcluster.net/v1/task/GYK5r_y8S1m7GQ6G0JPOxw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 08:15:59 INFO - Downloading https://queue.taskcluster.net/v1/task/GYK5r_y8S1m7GQ6G0JPOxw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 08:15:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GYK5r_y8S1m7GQ6G0JPOxw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 08:16:00 INFO - Downloaded 1199 bytes. 08:16:00 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 08:16:00 INFO - Using the following test package requirements: 08:16:00 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 08:16:00 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:16:00 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 08:16:00 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:16:00 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 08:16:00 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:16:00 INFO - u'jsshell-mac.zip'], 08:16:00 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:16:00 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 08:16:00 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 08:16:00 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:16:00 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 08:16:00 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:16:00 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 08:16:00 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:16:00 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 08:16:00 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 08:16:00 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 08:16:00 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:16:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GYK5r_y8S1m7GQ6G0JPOxw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 08:16:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GYK5r_y8S1m7GQ6G0JPOxw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:16:00 INFO - Fetch https://queue.taskcluster.net/v1/task/GYK5r_y8S1m7GQ6G0JPOxw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 08:16:01 INFO - Expected file size: 16277379 08:16:01 INFO - Obtained file size: 16277379 08:16:07 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:16:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GYK5r_y8S1m7GQ6G0JPOxw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 08:16:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GYK5r_y8S1m7GQ6G0JPOxw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:16:07 INFO - Fetch https://queue.taskcluster.net/v1/task/GYK5r_y8S1m7GQ6G0JPOxw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 08:16:08 INFO - Expected file size: 16277379 08:16:08 INFO - Obtained file size: 16277379 08:16:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GYK5r_y8S1m7GQ6G0JPOxw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 08:16:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GYK5r_y8S1m7GQ6G0JPOxw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:16:15 INFO - Fetch https://queue.taskcluster.net/v1/task/GYK5r_y8S1m7GQ6G0JPOxw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 08:16:16 INFO - Expected file size: 14409734 08:16:16 INFO - Obtained file size: 14409734 08:16:16 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:16:16 INFO - trying https://queue.taskcluster.net/v1/task/GYK5r_y8S1m7GQ6G0JPOxw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:16:16 INFO - Downloading https://queue.taskcluster.net/v1/task/GYK5r_y8S1m7GQ6G0JPOxw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 08:16:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GYK5r_y8S1m7GQ6G0JPOxw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 08:16:19 INFO - Downloaded 69608104 bytes. 08:16:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GYK5r_y8S1m7GQ6G0JPOxw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:16:19 INFO - mkdir: /builds/slave/test/properties 08:16:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:16:19 INFO - Writing to file /builds/slave/test/properties/build_url 08:16:19 INFO - Contents: 08:16:19 INFO - build_url:https://queue.taskcluster.net/v1/task/GYK5r_y8S1m7GQ6G0JPOxw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 08:16:20 INFO - Running post-action listener: _resource_record_post_action 08:16:20 INFO - Running post-action listener: set_extra_try_arguments 08:16:20 INFO - [mozharness: 2016-12-16 16:16:20.393182Z] Finished download-and-extract step (success) 08:16:20 INFO - [mozharness: 2016-12-16 16:16:20.393297Z] Running populate-webroot step. 08:16:20 INFO - Running pre-action listener: _resource_record_pre_action 08:16:20 INFO - Running main action method: populate_webroot 08:16:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:16:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:16:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:16:20 INFO - '--tppagecycles', 08:16:20 INFO - '7'], 08:16:20 INFO - 'tests': ['tsvgm']}}, 08:16:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:16:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:16:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:16:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:16:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:16:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:16:20 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:16:20 INFO - 'talos_options': ['--disable-e10s'], 08:16:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:16:20 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:16:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:16:20 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:16:20 INFO - 'talos_options': ['--disable-e10s'], 08:16:20 INFO - 'tests': ['damp', 'tps']}, 08:16:20 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:16:20 INFO - 'tests': ['damp', 'tps']}, 08:16:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:16:20 INFO - 'tests': ['dromaeo_dom']}, 08:16:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:16:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:16:20 INFO - 'tests': ['basic_compositor_video']}, 08:16:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:16:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:16:20 INFO - 'tests': ['a11yr', 08:16:20 INFO - 'ts_paint', 08:16:20 INFO - 'tpaint', 08:16:20 INFO - 'sessionrestore', 08:16:20 INFO - 'sessionrestore_no_auto_restore', 08:16:20 INFO - 'tabpaint']}, 08:16:20 INFO - 'other-e10s': {'tests': ['a11yr', 08:16:20 INFO - 'ts_paint', 08:16:20 INFO - 'tpaint', 08:16:20 INFO - 'sessionrestore', 08:16:20 INFO - 'sessionrestore_no_auto_restore', 08:16:20 INFO - 'tabpaint']}, 08:16:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:16:20 INFO - 'ts_paint', 08:16:20 INFO - 'tpaint', 08:16:20 INFO - 'sessionrestore', 08:16:20 INFO - 'sessionrestore_no_auto_restore', 08:16:20 INFO - 'tabpaint']}, 08:16:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:16:20 INFO - 'ts_paint', 08:16:20 INFO - 'tpaint', 08:16:20 INFO - 'sessionrestore', 08:16:20 INFO - 'sessionrestore_no_auto_restore', 08:16:20 INFO - 'tabpaint']}, 08:16:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:16:20 INFO - 'tests': ['a11yr', 08:16:20 INFO - 'ts_paint', 08:16:20 INFO - 'tpaint', 08:16:20 INFO - 'sessionrestore', 08:16:20 INFO - 'sessionrestore_no_auto_restore', 08:16:20 INFO - 'tabpaint']}, 08:16:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:16:20 INFO - 'tests': ['a11yr', 08:16:20 INFO - 'ts_paint', 08:16:20 INFO - 'tpaint', 08:16:20 INFO - 'sessionrestore', 08:16:20 INFO - 'sessionrestore_no_auto_restore', 08:16:20 INFO - 'tabpaint']}, 08:16:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:16:20 INFO - 'tests': ['tsvgx', 08:16:20 INFO - 'tsvgr_opacity', 08:16:20 INFO - 'tart', 08:16:20 INFO - 'tscrollx', 08:16:20 INFO - 'cart', 08:16:20 INFO - 'tsvg_static']}, 08:16:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:16:20 INFO - 'tsvgr_opacity', 08:16:20 INFO - 'tart', 08:16:20 INFO - 'tscrollx', 08:16:20 INFO - 'cart', 08:16:20 INFO - 'tsvg_static']}, 08:16:20 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:16:20 INFO - 'talos_options': ['--disable-e10s'], 08:16:20 INFO - 'tests': ['tp5o']}, 08:16:20 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:16:20 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:16:20 INFO - 'talos_options': ['--disable-e10s', 08:16:20 INFO - '--xperf_path', 08:16:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:16:20 INFO - 'tests': ['tp5n']}, 08:16:20 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:16:20 INFO - 'talos_options': ['--xperf_path', 08:16:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:16:20 INFO - 'tests': ['tp5n']}}, 08:16:20 INFO - 'talos.zip': {'path': '', 08:16:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:16:20 INFO - Running post-action listener: _resource_record_post_action 08:16:20 INFO - [mozharness: 2016-12-16 16:16:20.411464Z] Finished populate-webroot step (success) 08:16:20 INFO - [mozharness: 2016-12-16 16:16:20.411567Z] Running create-virtualenv step. 08:16:20 INFO - Running pre-action listener: _resource_record_pre_action 08:16:20 INFO - Running main action method: create_virtualenv 08:16:20 INFO - Creating virtualenv /builds/slave/test/build/venv 08:16:20 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:16:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:16:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:16:20 INFO - Using real prefix '/tools/python27' 08:16:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:16:21 INFO - Installing distribute.............................................................................................................................................................................................done. 08:16:24 INFO - Installing pip.................done. 08:16:24 INFO - Return code: 0 08:16:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:16:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:16:24 INFO - Reading from file tmpfile_stdout 08:16:24 INFO - Output received: 08:16:24 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:16:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:16:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:16:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:16:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cf983f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf0e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed52576520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdadd30>, '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 0x10cc14db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cda5b20>, '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.oiUOqrTqZr/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.2qC71lUWCj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/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:16:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:16:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:16:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oiUOqrTqZr/Render', 08:16:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:24 INFO - 'HOME': '/Users/cltbld', 08:16:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:24 INFO - 'LOGNAME': 'cltbld', 08:16:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:24 INFO - 'MOZ_NO_REMOTE': '1', 08:16:24 INFO - 'NO_EM_RESTART': '1', 08:16:24 INFO - 'PAGER': '/bin/cat', 08:16:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:24 INFO - 'PWD': '/builds/slave/test', 08:16:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:24 INFO - 'SHELL': '/bin/bash', 08:16:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2qC71lUWCj/Listeners', 08:16:24 INFO - 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', 08:16:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:24 INFO - 'USER': 'cltbld', 08:16:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:24 INFO - 'XPC_FLAGS': '0x0', 08:16:24 INFO - 'XPC_SERVICE_NAME': '0', 08:16:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:16:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:24 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:16:24 INFO - Cleaning up... 08:16:24 INFO - Return code: 0 08:16:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:16:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:16:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:16:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:24 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10cf983f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf0e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed52576520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdadd30>, '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 0x10cc14db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cda5b20>, '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.oiUOqrTqZr/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.2qC71lUWCj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/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:16:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:16:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:16:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oiUOqrTqZr/Render', 08:16:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:24 INFO - 'HOME': '/Users/cltbld', 08:16:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:24 INFO - 'LOGNAME': 'cltbld', 08:16:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:24 INFO - 'MOZ_NO_REMOTE': '1', 08:16:24 INFO - 'NO_EM_RESTART': '1', 08:16:24 INFO - 'PAGER': '/bin/cat', 08:16:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:24 INFO - 'PWD': '/builds/slave/test', 08:16:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:24 INFO - 'SHELL': '/bin/bash', 08:16:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2qC71lUWCj/Listeners', 08:16:24 INFO - 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', 08:16:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:24 INFO - 'USER': 'cltbld', 08:16:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:24 INFO - 'XPC_FLAGS': '0x0', 08:16:24 INFO - 'XPC_SERVICE_NAME': '0', 08:16:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:16:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:24 INFO - Downloading/unpacking psutil>=3.1.1 08:16:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:16:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:16:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:16:28 INFO - Installing collected packages: psutil 08:16:28 INFO - Running setup.py install for psutil 08:16:28 INFO - building 'psutil._psutil_osx' extension 08:16:28 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:16:28 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:16:29 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:16:29 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:16:29 INFO - building 'psutil._psutil_posix' extension 08:16:29 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:16:29 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:16:29 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:16:29 INFO - ^ 08:16:29 INFO - 1 warning generated. 08:16:29 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:16:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:16:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:16:29 INFO - Successfully installed psutil 08:16:29 INFO - Cleaning up... 08:16:29 INFO - Return code: 0 08:16:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:16:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:16:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:16:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cf983f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf0e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed52576520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdadd30>, '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 0x10cc14db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cda5b20>, '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.oiUOqrTqZr/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.2qC71lUWCj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/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:16:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:16:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:16:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oiUOqrTqZr/Render', 08:16:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:29 INFO - 'HOME': '/Users/cltbld', 08:16:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:29 INFO - 'LOGNAME': 'cltbld', 08:16:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:29 INFO - 'MOZ_NO_REMOTE': '1', 08:16:29 INFO - 'NO_EM_RESTART': '1', 08:16:29 INFO - 'PAGER': '/bin/cat', 08:16:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:29 INFO - 'PWD': '/builds/slave/test', 08:16:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:29 INFO - 'SHELL': '/bin/bash', 08:16:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2qC71lUWCj/Listeners', 08:16:29 INFO - 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', 08:16:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:29 INFO - 'USER': 'cltbld', 08:16:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:29 INFO - 'XPC_FLAGS': '0x0', 08:16:29 INFO - 'XPC_SERVICE_NAME': '0', 08:16:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:16:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:16:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:16:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:16:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:16:32 INFO - Installing collected packages: mozsystemmonitor 08:16:32 INFO - Running setup.py install for mozsystemmonitor 08:16:32 INFO - Successfully installed mozsystemmonitor 08:16:32 INFO - Cleaning up... 08:16:32 INFO - Return code: 0 08:16:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:16:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:16:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:16:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:32 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 0x10cf983f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf0e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed52576520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdadd30>, '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 0x10cc14db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cda5b20>, '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.oiUOqrTqZr/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.2qC71lUWCj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/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:16:32 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:16:32 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:16:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oiUOqrTqZr/Render', 08:16:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:32 INFO - 'HOME': '/Users/cltbld', 08:16:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:32 INFO - 'LOGNAME': 'cltbld', 08:16:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:32 INFO - 'MOZ_NO_REMOTE': '1', 08:16:32 INFO - 'NO_EM_RESTART': '1', 08:16:32 INFO - 'PAGER': '/bin/cat', 08:16:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:32 INFO - 'PWD': '/builds/slave/test', 08:16:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:32 INFO - 'SHELL': '/bin/bash', 08:16:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2qC71lUWCj/Listeners', 08:16:32 INFO - 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', 08:16:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:32 INFO - 'USER': 'cltbld', 08:16:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:32 INFO - 'XPC_FLAGS': '0x0', 08:16:32 INFO - 'XPC_SERVICE_NAME': '0', 08:16:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:16:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:32 INFO - Downloading/unpacking jsonschema==2.5.1 08:16:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:16:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:16:35 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:16:35 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:16:35 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:16:35 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:16:35 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:16:35 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:16:35 INFO - Downloading functools32-3.2.3-2.tar.gz 08:16:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:16:36 INFO - warning: no files found matching '*.txt' 08:16:36 INFO - no previously-included directories found matching 'build' 08:16:36 INFO - no previously-included directories found matching 'dist' 08:16:36 INFO - no previously-included directories found matching '.git*' 08:16:36 INFO - Installing collected packages: jsonschema, functools32 08:16:36 INFO - Running setup.py install for functools32 08:16:36 INFO - warning: no files found matching '*.txt' 08:16:36 INFO - no previously-included directories found matching 'build' 08:16:36 INFO - no previously-included directories found matching 'dist' 08:16:36 INFO - no previously-included directories found matching '.git*' 08:16:36 INFO - Successfully installed jsonschema functools32 08:16:36 INFO - Cleaning up... 08:16:36 INFO - Return code: 0 08:16:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:16:36 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:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:36 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:16:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:36 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 0x10cf983f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf0e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed52576520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdadd30>, '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 0x10cc14db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cda5b20>, '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.oiUOqrTqZr/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.2qC71lUWCj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/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:16:36 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:16:36 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:16:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oiUOqrTqZr/Render', 08:16:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:36 INFO - 'HOME': '/Users/cltbld', 08:16:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:36 INFO - 'LOGNAME': 'cltbld', 08:16:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:36 INFO - 'MOZ_NO_REMOTE': '1', 08:16:36 INFO - 'NO_EM_RESTART': '1', 08:16:36 INFO - 'PAGER': '/bin/cat', 08:16:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:36 INFO - 'PWD': '/builds/slave/test', 08:16:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:36 INFO - 'SHELL': '/bin/bash', 08:16:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2qC71lUWCj/Listeners', 08:16:36 INFO - 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', 08:16:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:36 INFO - 'USER': 'cltbld', 08:16:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:36 INFO - 'XPC_FLAGS': '0x0', 08:16:36 INFO - 'XPC_SERVICE_NAME': '0', 08:16:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:16:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:16:36 INFO - Cleaning up... 08:16:36 INFO - Return code: 0 08:16:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:16:36 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:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:36 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:16:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:36 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 0x10cf983f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf0e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed52576520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdadd30>, '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 0x10cc14db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cda5b20>, '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.oiUOqrTqZr/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.2qC71lUWCj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/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:16:36 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:16:36 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:16:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oiUOqrTqZr/Render', 08:16:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:36 INFO - 'HOME': '/Users/cltbld', 08:16:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:36 INFO - 'LOGNAME': 'cltbld', 08:16:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:36 INFO - 'MOZ_NO_REMOTE': '1', 08:16:36 INFO - 'NO_EM_RESTART': '1', 08:16:36 INFO - 'PAGER': '/bin/cat', 08:16:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:36 INFO - 'PWD': '/builds/slave/test', 08:16:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:36 INFO - 'SHELL': '/bin/bash', 08:16:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2qC71lUWCj/Listeners', 08:16:36 INFO - 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', 08:16:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:36 INFO - 'USER': 'cltbld', 08:16:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:36 INFO - 'XPC_FLAGS': '0x0', 08:16:36 INFO - 'XPC_SERVICE_NAME': '0', 08:16:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:16:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:36 INFO - Downloading/unpacking blobuploader==1.2.4 08:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:39 INFO - Downloading blobuploader-1.2.4.tar.gz 08:16:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:16:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:16:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:16:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:16:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:16:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:16:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:16:40 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:16:40 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:16:40 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:16:40 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:16:40 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:16:40 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:16:40 INFO - Downloading docopt-0.6.1.tar.gz 08:16:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:16:40 INFO - Installing collected packages: blobuploader, requests, docopt 08:16:40 INFO - Running setup.py install for blobuploader 08:16:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:16:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:16:40 INFO - Running setup.py install for requests 08:16:41 INFO - Running setup.py install for docopt 08:16:41 INFO - Successfully installed blobuploader requests docopt 08:16:41 INFO - Cleaning up... 08:16:41 INFO - Return code: 0 08:16:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:16:41 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:16:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16: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:16:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:42 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 0x10cf983f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf0e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed52576520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdadd30>, '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 0x10cc14db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cda5b20>, '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.oiUOqrTqZr/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.2qC71lUWCj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/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:16:42 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:16:42 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:16:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oiUOqrTqZr/Render', 08:16:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:42 INFO - 'HOME': '/Users/cltbld', 08:16:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:42 INFO - 'LOGNAME': 'cltbld', 08:16:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:42 INFO - 'MOZ_NO_REMOTE': '1', 08:16:42 INFO - 'NO_EM_RESTART': '1', 08:16:42 INFO - 'PAGER': '/bin/cat', 08:16:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:42 INFO - 'PWD': '/builds/slave/test', 08:16:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:42 INFO - 'SHELL': '/bin/bash', 08:16:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2qC71lUWCj/Listeners', 08:16:42 INFO - 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', 08:16:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:42 INFO - 'USER': 'cltbld', 08:16:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:42 INFO - 'XPC_FLAGS': '0x0', 08:16:42 INFO - 'XPC_SERVICE_NAME': '0', 08:16:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:16:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:16:42 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-bPqXAl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:16:42 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-E500z8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:16:42 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-IayU6m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:16:43 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-Cu9vti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:16:43 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-pMJGfg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:16:43 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-WzwOiC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:16:43 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-3qYh7X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:16:43 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-4jF8vR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:16:43 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-lkJuWh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:16:43 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-pGPnku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:16:43 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-k_67KR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:16:43 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-Bm2MCk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:16:43 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-AyQZ6D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:16:44 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-GiR3L2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:16:44 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-GJAG2P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:16:44 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-Cuehp5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:16:44 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-WQSYLr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:16:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:16:44 INFO - Running setup.py install for manifestparser 08:16:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:16:44 INFO - Running setup.py install for mozcrash 08:16:44 INFO - Running setup.py install for mozdebug 08:16:44 INFO - Running setup.py install for mozdevice 08:16:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:16:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:16:45 INFO - Running setup.py install for mozfile 08:16:45 INFO - Running setup.py install for mozhttpd 08:16:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:16:45 INFO - Running setup.py install for mozinfo 08:16:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:16:45 INFO - Running setup.py install for mozInstall 08:16:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:16:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:16:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:16:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:16:45 INFO - Running setup.py install for mozleak 08:16:45 INFO - Running setup.py install for mozlog 08:16:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:16:45 INFO - Running setup.py install for moznetwork 08:16:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:16:46 INFO - Running setup.py install for mozprocess 08:16:46 INFO - Running setup.py install for mozprofile 08:16:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:16:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:16:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:16:46 INFO - Running setup.py install for mozrunner 08:16:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:16:46 INFO - Running setup.py install for mozscreenshot 08:16:46 INFO - Running setup.py install for moztest 08:16:46 INFO - Running setup.py install for mozversion 08:16:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:16:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:16:47 INFO - Cleaning up... 08:16:47 INFO - Return code: 0 08:16:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:16:47 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:16:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:47 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:16:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:47 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 0x10cf983f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf0e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed52576520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdadd30>, '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 0x10cc14db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cda5b20>, '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.oiUOqrTqZr/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.2qC71lUWCj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/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:16:47 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:16:47 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:16:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oiUOqrTqZr/Render', 08:16:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:47 INFO - 'HOME': '/Users/cltbld', 08:16:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:47 INFO - 'LOGNAME': 'cltbld', 08:16:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:47 INFO - 'MOZ_NO_REMOTE': '1', 08:16:47 INFO - 'NO_EM_RESTART': '1', 08:16:47 INFO - 'PAGER': '/bin/cat', 08:16:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:47 INFO - 'PWD': '/builds/slave/test', 08:16:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:47 INFO - 'SHELL': '/bin/bash', 08:16:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2qC71lUWCj/Listeners', 08:16:47 INFO - 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', 08:16:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:47 INFO - 'USER': 'cltbld', 08:16:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:47 INFO - 'XPC_FLAGS': '0x0', 08:16:47 INFO - 'XPC_SERVICE_NAME': '0', 08:16:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:16:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:16:47 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-gKg89j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:16:47 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:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:16:47 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-hJCMYF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:16:47 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:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:16:47 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-SKFwwv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:16:48 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:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:16:48 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-bADraq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:16:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:16:48 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-2QxmeN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:16:48 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:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:16:48 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-jTvT8B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:16:48 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:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:16:48 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-jogXQb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:16:48 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:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:16:48 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-FOhqoj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:16:48 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:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:16:48 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-l0dKHd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:16:48 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:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:16:48 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-4Rx_Gs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:16:48 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:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:16:48 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-pCh1Cr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:16:48 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:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:16:48 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-oDjskG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:16:49 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:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:16:49 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-w8b7GD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:16:49 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:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:16:49 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-lczcNR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:16:49 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:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:16:49 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-BNuqpb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:16:49 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:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:16:49 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-68xlIM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:16:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:16:49 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-XnWSgI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:16:49 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:16:49 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:16:49 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:16:49 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:16:49 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:16:49 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:16:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:16:49 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:16:49 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:16:49 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:16:49 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:16:49 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:16:49 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:16:52 INFO - Downloading blessings-1.6.tar.gz 08:16:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:16:52 INFO - Installing collected packages: blessings 08:16:52 INFO - Running setup.py install for blessings 08:16:52 INFO - Successfully installed blessings 08:16:52 INFO - Cleaning up... 08:16:52 INFO - Return code: 0 08:16:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:16:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:16:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:16:52 INFO - Reading from file tmpfile_stdout 08:16:52 INFO - Current package versions: 08:16:52 INFO - blessings == 1.6 08:16:52 INFO - blobuploader == 1.2.4 08:16:52 INFO - docopt == 0.6.1 08:16:52 INFO - functools32 == 3.2.3-2 08:16:52 INFO - jsonschema == 2.5.1 08:16:52 INFO - manifestparser == 1.1 08:16:52 INFO - mozInstall == 1.12 08:16:52 INFO - mozcrash == 1.0 08:16:52 INFO - mozdebug == 0.1 08:16:52 INFO - mozdevice == 0.49 08:16:52 INFO - mozfile == 1.2 08:16:52 INFO - mozhttpd == 0.7 08:16:52 INFO - mozinfo == 0.9 08:16:52 INFO - mozleak == 0.1 08:16:52 INFO - mozlog == 3.4 08:16:52 INFO - moznetwork == 0.27 08:16:52 INFO - mozprocess == 0.23 08:16:52 INFO - mozprofile == 0.28 08:16:52 INFO - mozrunner == 6.13 08:16:52 INFO - mozscreenshot == 0.1 08:16:52 INFO - mozsystemmonitor == 0.3 08:16:52 INFO - moztest == 0.7 08:16:52 INFO - mozversion == 1.4 08:16:52 INFO - psutil == 3.1.1 08:16:52 INFO - requests == 1.2.3 08:16:52 INFO - wsgiref == 0.1.2 08:16:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:16: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:16:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16: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:16:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:52 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 0x10cf983f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf0e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed52576520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdadd30>, '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 0x10cc14db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cda5b20>, '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.oiUOqrTqZr/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.2qC71lUWCj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/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:16:52 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:16:52 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:16:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oiUOqrTqZr/Render', 08:16:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:52 INFO - 'HOME': '/Users/cltbld', 08:16:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:52 INFO - 'LOGNAME': 'cltbld', 08:16:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:52 INFO - 'MOZ_NO_REMOTE': '1', 08:16:52 INFO - 'NO_EM_RESTART': '1', 08:16:52 INFO - 'PAGER': '/bin/cat', 08:16:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:52 INFO - 'PWD': '/builds/slave/test', 08:16:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:52 INFO - 'SHELL': '/bin/bash', 08:16:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2qC71lUWCj/Listeners', 08:16:52 INFO - 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', 08:16:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:52 INFO - 'USER': 'cltbld', 08:16:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:52 INFO - 'XPC_FLAGS': '0x0', 08:16:52 INFO - 'XPC_SERVICE_NAME': '0', 08:16:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:16:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:53 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:16:53 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:16:53 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:16:53 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:16:53 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:16:53 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:16:53 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:16:53 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:16:53 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:16:53 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:16:53 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:16:53 INFO - Cleaning up... 08:16:53 INFO - Return code: 0 08:16:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:16:53 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:16:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:16:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:53 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:16:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:16:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:16:53 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 0x10cf983f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cf0e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fed52576520>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cdadd30>, '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 0x10cc14db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cda5b20>, '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.oiUOqrTqZr/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.2qC71lUWCj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/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:16:53 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:16:53 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:16:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oiUOqrTqZr/Render', 08:16:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:16:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:16:53 INFO - 'HOME': '/Users/cltbld', 08:16:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:16:53 INFO - 'LOGNAME': 'cltbld', 08:16:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:16:53 INFO - 'MOZ_NO_REMOTE': '1', 08:16:53 INFO - 'NO_EM_RESTART': '1', 08:16:53 INFO - 'PAGER': '/bin/cat', 08:16:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:16:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:16:53 INFO - 'PWD': '/builds/slave/test', 08:16:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:16:53 INFO - 'SHELL': '/bin/bash', 08:16:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2qC71lUWCj/Listeners', 08:16:53 INFO - 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', 08:16:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:16:53 INFO - 'USER': 'cltbld', 08:16:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:16:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:16:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:16:53 INFO - 'XPC_FLAGS': '0x0', 08:16:53 INFO - 'XPC_SERVICE_NAME': '0', 08:16:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:16:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:16:53 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:16:53 INFO - Cleaning up... 08:16:53 INFO - Return code: 0 08:16:53 INFO - Running post-action listener: _resource_record_post_action 08:16:53 INFO - Running post-action listener: _start_resource_monitoring 08:16:53 INFO - Starting resource monitoring. 08:16:53 INFO - [mozharness: 2016-12-16 16:16:53.416508Z] Finished create-virtualenv step (success) 08:16:53 INFO - [mozharness: 2016-12-16 16:16:53.417440Z] Running install step. 08:16:53 INFO - Running pre-action listener: _resource_record_pre_action 08:16:53 INFO - Running main action method: install 08:16:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:16:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:16:53 INFO - Reading from file tmpfile_stdout 08:16:53 INFO - Detecting whether we're running mozinstall >=1.0... 08:16:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:16:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:16:53 INFO - Reading from file tmpfile_stdout 08:16:53 INFO - Output received: 08:16:53 INFO - Usage: mozinstall [options] installer 08:16:53 INFO - Options: 08:16:53 INFO - -h, --help show this help message and exit 08:16:53 INFO - -d DEST, --destination=DEST 08:16:53 INFO - Directory to install application into. [default: 08:16:53 INFO - "/builds/slave/test"] 08:16:53 INFO - --app=APP Application being installed. [default: firefox] 08:16:53 INFO - mkdir: /builds/slave/test/build/application 08:16:53 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:16:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:17:15 INFO - Reading from file tmpfile_stdout 08:17:15 INFO - Output received: 08:17:15 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:17:15 INFO - Running post-action listener: _resource_record_post_action 08:17:15 INFO - [mozharness: 2016-12-16 16:17:15.476965Z] Finished install step (success) 08:17:15 INFO - [mozharness: 2016-12-16 16:17:15.477099Z] Running run-tests step. 08:17:15 INFO - Running pre-action listener: _resource_record_pre_action 08:17:15 INFO - Running main action method: run_tests 08:17:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:17:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:17:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:17:15 INFO - 2016-12-16 08:17:15.491 screenresolution[1638:7934] starting screenresolution argv=screenresolution get 08:17:15 INFO - 2016-12-16 08:17:15.494 screenresolution[1638:7934] Display 0: 1600x1200x32@60 08:17:15 INFO - 2016-12-16 08:17:15.500 screenresolution[1639:7936] starting screenresolution argv=screenresolution list 08:17:15 INFO - Available Modes on Display 0 08:17:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:17:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:17:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:17:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:17:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:17:15 INFO - Intel Iris: 08:17:15 INFO - Chipset Model: Intel Iris 08:17:15 INFO - Type: GPU 08:17:15 INFO - Bus: Built-In 08:17:15 INFO - VRAM (Dynamic, Max): 1536 MB 08:17:15 INFO - Vendor: Intel (0x8086) 08:17:15 INFO - Device ID: 0x0a2e 08:17:15 INFO - Revision ID: 0x0009 08:17:15 INFO - Displays: 08:17:15 INFO - Display: 08:17:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:17:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:17:15 INFO - Display Serial Number: 1600x1200 60 08:17:15 INFO - Main Display: Yes 08:17:15 INFO - Mirror: Off 08:17:15 INFO - Online: Yes 08:17:15 INFO - Rotation: Supported 08:17:15 INFO - Return code: 0 08:17:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:17:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:17:15 INFO - Python 2.7.3 08:17:15 INFO - Return code: 0 08:17:15 INFO - grabbing minidump binary from tooltool 08:17:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:17:15 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 0x10cdadd30>, '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 0x10cc14db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cda5b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:17:15 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:17:15 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:17:15 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:17:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:17:15 INFO - Return code: 0 08:17:15 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:17:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:17:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:17:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:17:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:17:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GYK5r_y8S1m7GQ6G0JPOxw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0339', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 08:17:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GYK5r_y8S1m7GQ6G0JPOxw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0339 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 08:17:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.oiUOqrTqZr/Render', 08:17:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:17:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:17:15 INFO - 'HOME': '/Users/cltbld', 08:17:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:17:15 INFO - 'LOGNAME': 'cltbld', 08:17:15 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:17:15 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:17:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:17:15 INFO - 'MOZ_NO_REMOTE': '1', 08:17:15 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:17:15 INFO - 'NO_EM_RESTART': '1', 08:17:15 INFO - 'PAGER': '/bin/cat', 08:17:15 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:17:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:17:15 INFO - 'PWD': '/builds/slave/test', 08:17:15 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:17:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:17:15 INFO - 'SHELL': '/bin/bash', 08:17:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2qC71lUWCj/Listeners', 08:17:15 INFO - 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', 08:17:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:17:15 INFO - 'USER': 'cltbld', 08:17:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:17:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:17:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:17:15 INFO - 'XPC_FLAGS': '0x0', 08:17:15 INFO - 'XPC_SERVICE_NAME': '0', 08:17:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:17:15 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GYK5r_y8S1m7GQ6G0JPOxw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0339', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 08:17:16 INFO - mozversion application_buildid: 20161216070649 08:17:16 INFO - mozversion application_changeset: 5a536a16e33798fe7b16de35c968d5bc0cbf8448 08:17:16 INFO - mozversion application_display_name: Nightly 08:17:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:17:16 INFO - mozversion application_name: Firefox 08:17:16 INFO - mozversion application_remotingname: firefox 08:17:16 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:17:16 INFO - mozversion application_vendor: Mozilla 08:17:16 INFO - mozversion application_version: 53.0a1 08:17:16 INFO - mozversion platform_buildid: 20161216070649 08:17:16 INFO - mozversion platform_changeset: 5a536a16e33798fe7b16de35c968d5bc0cbf8448 08:17:16 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:17:16 INFO - mozversion platform_version: 53.0a1 08:17:16 INFO - using testdate: 1481905036 08:17:16 INFO - actual date: 1481905036 08:17:16 INFO - starting webserver on 'localhost:49236' 08:17:16 INFO - SUITE-START | Running 2 tests 08:17:16 INFO - TEST-START | dromaeo_css 08:17:16 INFO - operating with platform_type : mac_ 08:17:16 INFO - Initialising browser for dromaeo_css test... 08:17:16 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/tmp0GZ1OJ/profile http://localhost:49236/getInfo.html) 08:17:19 INFO - PROCESS | 1649 | 2016-12-16 08:17:19.091 plugin-container[1651:8106] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x494f, name = 'com.apple.tsm.portname' 08:17:19 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:17:19 INFO - PROCESS | 1649 | 2016-12-16 08:17:19.094 plugin-container[1651:8106] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 08:17:19 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:17:19 INFO - PROCESS | 1649 | 2016-12-16 08:17:19.094 plugin-container[1651:8106] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:17:19 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 08:17:19 INFO - PROCESS | 1649 | colorDepth:24 08:17:19 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 08:17:19 INFO - PROCESS | 1649 | __metrics 08:17:19 ERROR - PROCESS | 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3232: TypeError: this.tabs is undefined 08:17:19 INFO - TEST-INFO | 1649: exit 0 08:17:19 INFO - Browser initialized. 08:17:19 INFO - Running cycle 1/1 for dromaeo_css test... 08:17:19 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/tmp0GZ1OJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:17:22 INFO - PROCESS | 1652 | 2016-12-16 08:17:22.351 plugin-container[1653:8228] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b4f, name = 'com.apple.tsm.portname' 08:17:22 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:17:22 INFO - PROCESS | 1652 | 2016-12-16 08:17:22.351 plugin-container[1653:8228] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 08:17:22 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:17:22 INFO - PROCESS | 1652 | 2016-12-16 08:17:22.351 plugin-container[1653:8228] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:17:32 INFO - PROCESS | 1652 | RSS: Main: 236908544 08:17:32 INFO - PROCESS | 1652 | 08:18:21 INFO - PROCESS | 1652 | 1481905101626 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 08:20:35 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49236/tests/dromaeo/cssquery-ext.html) 08:20:37 INFO - PROCESS | 1652 | RSS: Main: 257912832 08:20:37 INFO - PROCESS | 1652 | 08:23:39 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-ext.html (next: http://localhost:49236/tests/dromaeo/cssquery-jquery.html) 08:23:40 INFO - PROCESS | 1652 | RSS: Main: 266735616 08:23:40 INFO - PROCESS | 1652 | 08:26:42 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49236/tests/dromaeo/cssquery-mootools.html) 08:26:43 INFO - PROCESS | 1652 | RSS: Main: 271872000 08:26:43 INFO - PROCESS | 1652 | 08:29:45 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49236/tests/dromaeo/cssquery-prototype.html) 08:29:45 INFO - PROCESS | 1652 | RSS: Main: 270610432 08:29:45 INFO - PROCESS | 1652 | 08:32:48 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49236/tests/dromaeo/cssquery-yui.html) 08:32:49 INFO - PROCESS | 1652 | RSS: Main: 277319680 08:32:49 INFO - PROCESS | 1652 | 08:35:52 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49236/tests/dromaeo/cssquery-yui.html (next: http://localhost:49236/tests/dromaeo/cssquery-dojo.html) 08:35:52 INFO - PROCESS | 1652 | RSS: Main: 280072192 08:35:52 INFO - PROCESS | 1652 | 08:35:52 INFO - PROCESS | 1652 | __start_tp_report 08:35:52 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 08:35:52 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 08:35:52 INFO - PROCESS | 1652 | |i|pagename|runs| 08:35:52 INFO - PROCESS | 1652 | |0;dojo.html;3384.2385463270766;3388.0852169914124;3391.1013581400935;3398.8640454381825;3428.6057103433113;5759.712014399279;5767.33675627303;5774.769009239631;5810.099434587635;5864.970675146618;5629.887402251952;5748.798792042279;5766.567507436938;5788.5369170466365;5799.449052340032;5153.613478989081;5183.637145399824;5229.398619158807;5258.710770907618;5302.072137375963;5303.204519322094;5315.096433606296;5333.679979201241;5336.226247194155;5361.303030606015;4336.84821031264;5306.7611957462;5319.574434045287;5324.547413469841;5330.866728331804;5306.257124002643;5318.494742999409;5322.494363035506;5346.438623944468;5358.383785864637;5198.532132108128;5272.420033796279;5274.97362513185;5276.604254680876;5285.497877701612;5040.36995375578;5819.156222347779;5823.4467725566;5869.677167755772;5899.233099697055;6039.275286965549;6215.844603884894;6245.219347581552;6270.090836828635;6314.336994615591;4489.955100448987;5877.294724633029;5931.932252194603;6220.284667263278;6242.344553821828;5959.463648271676;5970.940290597126;5974.3726908674835;5981.611195272293;6032.849178770522;5793.188953546507;6273.560850740496;6313.55805093639;6335.778247761306;6345.858799894082;6119.4416233545735;6124.448799608057;6124.9281375759065;6216.44052035319;6223.439890409885;6054.243219597551;6075.635461872302;6117.990531562243;6128.19758601264;6144.77104579086;6202.689865506707;6231.065340199007;6253.96809526423;6256.5307601930035;6268.9969604863;5442.938626967703;5684.8010319638615;6188.938110618927;6220.657863817533;6313.021481670348;4905.386826646669;4915.48387419323;4949.282354058713;4979.128652485801;5015.573676237488;4413.955860441419;4458.197524445631;4470.709403888737;4487.955120448819;4503.954960450419;5972.343042265347;6057.242844644419;6064.636121832704;6096.146539484475;6127.6782420562195;6170.7223174957235;6203.472704820141;6231.9688401557705;6269.404406581368;6283.874322513524;5820.35976042635;5874.559408044413;5877;5955.672438015864;6089.543284253698;5458.781648734006;5459.5359394451125;5493.2034854945805;5520.392756796749;5554.305711786027;6269.467095296951;6298.559100862895;6314.400131987546;6317.9052314214405;6334.429901308904;6193;6208.762566712176;6237.844053898689;6240.7503699851495;6260.092286618324;5426.7829286829665;6075;6213.565050446426;6222.09779581949;6288.371162883675;6246.9062964054665;6255.843603909939;6256.812295631138;6267.965785645318;6279.623222606659;6192.225971753531;6216.160818289473;6271.122042913905;6271.96864015677;6288.874222515616;4638.652101092364;6132.233470816148;6139.2325959255095;6141.877162456815;6142.815715528542;5011.899762004667;5964.463198312173;6082.330943596289;6126.792505495216;6141.731496609813;5955.136505206634;6066.059760737093;6113.415495409546;6113.962834745988;6122.550631082853;6165.815025549241;6197.907031394623;6203.16257305276;6216;6236.03341482071;5895.056790913433;5939.792107276225;6080.756769729338;6110.022396416552;6111.694415279308 08:35:52 INFO - PROCESS | 1652 | |1;ext.html;450.0324302749087;463.54340974140484;475.86913598760344;504.8763053052003;508.9794961102989;34587;39287.017824554365;39677.40483892744;39695.40456893149;41529;29855.10434686963;40277.19445611086;40814.979625509375;40886;40947.92890533207;8224.917750822475;8444.831103377928;9093.454392736425;9337.253019758404;9354.345195836295;11049.226554141213;11287.040601548879;11419.942900285487;11467.541298348056;11492.712682182928;9529.142377186052;11283.66149015531;11298.774024519545;11300.378479183642;11328;10245.48772561369;11102.500387482542;11132.944335278353;11145.498452569655;11183.273087249385;9446.244300455946;10039.845417776976;10192.643257485952;10368.533415996299;11049.502772375183;32805.343893122;37759;38668.80665596682;38868.61131388678;38869.41695874564;3316.286998295354;3748.662620364153;4016.8995775105564;4056.1887622475624;4127.199924581096;25493.745062549322;28069.315841049603;29926.55110173349;30366.69633303661;30431;3181.602299712536;3185.490321548541;3207.684849211812;3250.8374581271078;3292.835358232103;322749.7725022743;326824;328180;328972;330550;56585.77556468785;57321.85356292934;57750;57791.42208577944;57985.13022304669;49784.751076244385;54444.63888402742;58681.239562813185;59777.505562361286;60151.69924150351;15380.461683841011;15594.532164035098;15610.21948902573;15644.530664080095;15659.982101163389;10909.072728818139;11179.161562882817;11902.88097119035;12131;12221.388930553438;15543.45597904068;15636.92181539085;15648.765268520981;15659.43405659425;15680;5951.4048595141;6919.238883722786;7419.108127427471;7442.464829206084;7527.548347099191;865.8051938313955;953.2897990996752;1016.8423894296396;1093.305750848217;1120.1430905357408;29121.708782912323;29181.56227656587;29467.263318417212;30139.698603014127;30401.087967361014;1048.8479170520231;1063.5267306048734;1068.3216157739892;1143.2454579977175;1147.437755499799;14973.176475293963;15170.469033583771;15271.465498707714;15491.767623485657;15612.4535641252;479891;581257;583145;584224;588772;56313.71843140758;56598.71700641471;56667;56698;56882;1366.7334869700312;1375.7592421326228;1384.4254634326642;1394.7350003499303;1404.9789253161211;28374.85812570924;29054.564181536873;29064.982725604503;29706.702932971257;29758.553621696126;47720.522794771605;47739.522604774895;48126.759366204344;48163.27755083747;48208;430220;489022;493193;496011;496043;38182.42726359162;38435.11736619709;38517.80741096276;39079.41380879346;40175.79912100421;34705.65294347024;42847.57152428436;43557.12885742331;44456.55543444524;44597;26437.471250575265;28292.85853570719;33734.83132584322;36696.08259793527;37876.05309867275;29354.266143346587;31567.526487102252;33785.3242935135;35292.23538822997;37646.494140234085;31349;37105.44341834821;37116.81441592775;37573.4363984535;37623.623763762014;26321.736782631928;32708.509372358956;33366.666333336354;33497.665023350426;33572.496412553344;20805.271815486387;22299.10803567839;24533.16001299874;24681.753182467946;25003.87498062498 08:35:52 INFO - PROCESS | 1652 | |2;jquery.html;370.172664095746;376.7231085152412;379.80819686058544;387.93599056155733;402.9435878976944;14755.92622036891;15027.624309392244;18229.08854557272;20295.898520507413;20574.279900203495;16220.188990550485;16588.004719716824;20299.18803247872;20488.897555512238;20502.589948201065;5675.20547123401;5688.203651488795;5936.673482958436;5950;5950.5834591578605;5674.3190817102;5840.591158618899;5851.385604511529;5853.063509838426;5861.384554621767;4088.550259471456;4283.993261583525;6186;6469;6484.286728459866;6256.343083976162;6397.96801015992;6520.510961677869;6545.738170473175;6561.967190164042;5585.8603534911545;5613.129964855454;5625.324961004665;5633.943660563383;5635.520980716644;20995;23852.688102154374;24183.758162418326;24712.876435617887;25138.24585262445;2180.3349978256624;2219.622664147099;2225.18780645065;2278.3962250003597;2314.328844635054;14895.627609309746;15157.393704251817;15345.46290879814;15411.998220115656;15826.525204243891;1785.2055835153362;1806.1781889240365;1857.3406440713488;1881.7177423386465;1883.839873610731;102814;108392;108613.45693271483;108864.91135088707;109004;10248.180145588334;10307.093187760289;10467.633632822966;10901.618443354444;11170.609028683957;13426.731465370638;13440.731185376433;13442.529511467248;13450.932745336211;13454.260015699036;7670.923290767132;8391.706290279812;8430.747077587683;8538.70114545988;8568.785780355416;8483.363747718946;8508.319334453228;8513.957430212933;8649.481031138152;8673.869891951626;6378.52161087911;6583.736650533925;6592.30780768022;6592.868142637216;6595.637239951753;1540.691861627679;1795.9820401795814;1823.6443893440917;1873.878197917131;1912.7322174895526;861.793169639282;903.9276857851356;924.5793164110314;932.9253659707207;970.1559643110398;20227.797722022886;21560.460988475414;21715.674264886038;21734.673979890315;21751.347459576238;959.5825815770162;965.2085290062082;983.9655612053543;989.4077516905753;1030.469308306223;11164.776704465865;11583.6524904253;11637.359945203096;11765.176437649283;12404.131710780339;234927.82536087552;457167;464422;542899;554784;84343.57828210942;97428.02571974331;102080.48959755302;106719;110438;976.9218462522981;1011.1203253169789;1025.5333823110616;1034.3483605328595;1035.0270745499206;18678.34625788091;18741.71887421717;18868.036908171493;18986.52533686669;19019;49579.50420495749;49999;50351.74824125856;50575;51394;67448.66275668786;68790.65604672144;69760.95358569524;70608.64695676694;70732.64633676798;6903.619276144892;7666.578338191453;7732;7845.372370210255;7920.405969552422;7314.487985841153;9369.437833729999;10758.46207689628;12068.879311206776;12310.446029928853;8621.267192288726;10476.16190704757;10682.83975740349;10941.726456838644;11149.163812713916;10260.897391025994;10519.26365154432;10712.517936693024;11102.056325212447;11163.944180279046;8978.595963181542;11035.172362072715;11456.541738330374;11576.24754390962;11629.883701163219;7680.731174408869;7740.612969351622;7809;7906.446548741533;8019.438639295194;8605.311575073854;9724.75688107803;11174.720631984266;11201.66395008151;11290.7741845161 08:35:52 INFO - PROCESS | 1652 | |3;mootools.html;1306.967495678414;1493.7460631692609;1675.8407951244635;1804.2151664026142;1855.3228071753806;7554.811129721746;7818.609069546515;8041.839163216732;8087;8492.447990880588;6877.31226877312;7935.127136015034;8300.41897067204;8307;8313.875291870612;2300.2869110575734;2352.9058837646476;2357.917472888449;2359.988200059006;2362.9054837806466;2109.9766613192587;2345.5074434368717;2371.8932648030795;2382.118616112041;2388.0567175965484;1934.6130773845216;2227.7772222777753;2280.1905323610044;2301.9079236830507;2371.087131454394;2310.5032418030037;2310.6765052892524;2324.4421338878715;2329.743728189898;2336.941576460585;1891.5933074388936;2363.6927199463958;2363.869987150706;2365.455945132629;2379.559781440439;8246;8265.752027439186;8316;8352.415330926837;8375.162483751576;5091.847244582668;5221.791128354861;5247.86880327991;5251.842444726664;5269.973650131763;3289.19414743387;3382.8646854125805;3475.548178736748;3536.1866770642637;3553.2538166984705;977.4721650308825;1021.6577446555339;1051.232600201857;1070;1073.958260487326;9541.2367010639;10618.150547956146;10688.412137332443;10826.566937322497;10847.240693151483;3857.614238576176;4467.262901621196;4672.649551283667;4711.575958163782;4720.315554244616;9435.292353073546;9454.716358509255;9528.237740980705;9540.809183816285;9554.235661147091;5156.458571849977;5168.844934651891;5204.635675502755;5229.424763276036;5235.842924712265;4276.337167739005;4327.511680783562;4675.438947326343;4762.547557981986;4780.0917825613715;3814.5422549294262;3873.869371845474;3930.4890364252465;3950.229705207457;3996.1408297215658;1260.615512268767;1297.772889744291;1298.2084723082087;1305.2168698780656;1328.4221363706818;754.2231501553409;834.7954751086023;836.5817091454273;847.9703210387606;858.9012263589767;4995.825146119869;5250.973745131327;5529.336479622471;5876;5999.130126131774;879.9252063574539;926.0693003531408;927.5269612497675;942.5202432181546;985.3792110970186;3065.7700672449655;3355.681210285019;3475.826208689555;3841.7118716096406;3852.9422058669143;9715.368501047551;10058.698239052841;10059.597616095421;10076.546555404877;10078.546465409074;9929.106380425652;9983.550740216708;10023.198144148451;10026.799464010825;10035.09684128432;6376.756532476123;7409.592472414069;7787.143414224431;7793.883091753628;7901.091374491892;4873.731944743073;5563.970665426909;5603.719814009365;5815.273090863642;5919.585629005928;9112.589933452879;9137.360384773268;9212.032736562564;9311.394759340621;9352.158305752242;7732.845343092994;9374.437533747998;9415.435073895589;9506.524673766145;9542.475163866055;6575.342465753386;6751.156105486814;6893.862122757617;7002.159740831133;7154.105736782903;7722.150563437905;7847.372210223054;7872.212778722082;7878.56667883249;7879.566623835515;6965.268646792217;7102.14774227096;7177.712891484491;7185.389241914496;7221.277872212737;6604.933950660432;6809.182898052265;6936.941888994793;6965.495321904562;7083.964580177066;5221;6679.833004174934;6726.966365168339;6749.628770417674;6767.560108592926;4826.300186472871;4868.48880867518;4883.316335712932;4884.438289596742;4959.454460009469;4809.5430934061205;4880.975595122001;4882.023595280887;4966.503349665005;4968.652194346361 08:35:52 INFO - PROCESS | 1652 | |4;prototype.html;350.8842082112903;355.7918617608699;360.96570825771573;367.6250224770734;373.80748914309135;4328.545502722216;5320.0581787647425;5390.811321603745;5524.392316845144;5525.25954324388;5226;5387.488188622075;5411.080116380215;5421.457854214576;5436.972815135929;4230.4500414946015;4243.353527886959;4263.275243208662;4275.764832934187;4280.4221430106845;3393.2863903728585;3581.2300355423577;4244.745315281078;4252.319628859384;4273.358996150587;3378.3918894598964;3678.5217921670146;3945.619033338323;4031.717779755418;4068.4304197412384;3200.775945683803;3890.396988466792;4010.9197816043807;4076.388541718736;4111.239420707178;2860.427914417125;3262.396456655503;3456.084137703511;3531.1525233943926;3537.3455910656353;5141.331626888518;5249.343832020997;5272.971770504753;5329.733513324319;5335.492318610247;4322.5735507282525;4663.600919724103;4673.6027437667835;4701.294805779126;4712.222483290253;4646.933024204043;5404.675719456806;5671.8582035449035;5762.386531771111;5793.565482588822;3385.4752513360468;3452.5166476693284;3723.640871082063;3860.9227815443533;3888.6111388860886;5321.365923867672;5932.258467691496;5995.6702381369005;6031.4270144336215;6161.2298462691715;3896.7661940283674;3908.257431088084;3917.0990672145567;3934.429507721364;3940.7832569208967;3637.5998640149564;3643.2148247358605;3924.5682974872743;3951.545572259227;3969.900752481211;3879.3405121129476;4223.809928553161;4566.634669226454;4586.243269860503;4616.746078965622;4704;4831.164157620082;4891.902161956741;5788.3343415506915;5853.326867410217;1105.618561596248;1162.2910024884814;1212.563477148226;1237.9566715164926;1253.2856271925098;752.4732687119039;759.0132827324456;793.9245771651683;861.814709837388;865.9610317535726;4160.126373461547;4344.348347747863;4956.587372598792;5118.667286626357;5141.665791723526;5183.740812959412;5550.361708403585;5573.554115670737;5646.237757902631;5649.711866693996;4291.999820209421;4701.506341834058;5028.245763135559;5029.5355275914535;5036.471170527115;2483.552960467097;2674;2771.9307017324727;2920.0217926994696;2925.829668132764;5032.597392208614;5216.921746173734;5682.261306030189;5697.971510142423;5747.626404283792;4543.220072361198;5066;5427.864303392446;5554.250411120484;5593;5016.749162541858;5112.769925353368;5134.073578060545;5743.195952566644;5816.883662326645;4596;4630.087575998276;4712.3873896394625;4782.378290822171;4862;4343.978280108579;4680.555347242007;5425.348958125051;5510.972445137909;5908.155312643763;5806.477417032318;5992.221011268682;6041.2750469943885;6049.546284028627;6050.608360077119;3537.7346698998194;3849;3857.498525191707;3930.4497370368745;3940.4089386591095;4796.640251981045;5470.972645136749;5573.913086948169;5630.774769009194;5998.610090344114;4532.478764941942;4668.416447944007;4971.577415919687;5060.0132974071175;5626.330145978746;4294.634956028773;4670;5149.510796474329;5527.419620939904;5774.181132943109;5090.643654944267;5244.89510209786;5445.891082178414;5608.251956541184;5693.857653558694;5688.146777983336;5718.456746609066;5770.740316685843;5778.219940308005;5854.795082172103;4575.83316254353;5179.611529135254;5315.893682126259;5352.143657014859;5588.972055139699 08:35:52 INFO - PROCESS | 1652 | |5;yui.html;634.1968499233388;727.3562896836298;744.3226663735998;782.9843403131941;785.7132146766428;14319.355628996695;15494.767578486304;15516.767248491253;15527.844721552781;15577.688446231083;13960.905864120386;14103.435862565486;14120.940929430308;14133.293335333243;14217.644558886033;683.4566519616892;701.7052837808108;834.7203686764941;849.6856163219618;851.5401683091123;837.2924878477677;850.200811237438;852.5225873510853;852.8166444214493;855.5080828523593;916.8028873792133;918.5912269040247;919.3197034194715;921.5991043895926;921.6958403726787;907.4736652741394;912.9908700913039;915.3821170709778;917.3670167584345;917.5595714057257;668.749219042859;681.8397676546008;684.165318311659;685.4961603221628;685.8251145957798;12951.963842892546;13581.117227380288;14126;14478.638034049127;14675.63310917225;13644.454221831114;13833.723325533534;13959.371828267651;13996.370163342572;13998.790018149735;11586.768264634744;11626.360550169737;11766.176367654181;11768.470418831173;11955;3678.1172518595613;3685.355062864015;3727.5713292971113;3729.216864458467;3815.1797363566966;95724.56413153808;98621.50689246508;99010.50494747481;100934.99065009403;101784.99999999926;8919.866202006975;10494.055535001733;10526.736831579272;10758.300710453796;10915.290506117077;21319.040643171094;21655.242066527597;21707.131714731233;21790.782092179194;21829.45426364322;7540.283673051052;7590.620468976529;7616.466847320745;7670.539767613961;7792.727254546064;3692.48305237267;4202.6847986400535;4220.008298049956;4227.154569086134;4243.533211346749;2954.5863579098946;3005.6092707948264;3431.3137372525093;3561.127523756696;3597.712183025352;536.8201652446396;651.2868409092036;651.6513665189195;664.3954001858285;668.3683918696883;568.496880260972;622.8131560531822;643.6299128001418;648.4293821437105;660.8050625065521;8738.91261087381;8890.985376995533;8995.775105622412;9060.81878362429;9140.817183656289;564.4073722591263;649.3538928765909;671.9630420326831;676.9695363708645;690.1063123255396;4280.764557949343;4489.910201795946;4556.3393307970155;4558.49856515783;4698.9530104698515;75660.24339756642;80902.59548702219;90249;93357;103674.48162759289;64584;74622;75773;78363.21636783674;86166;58969.705151473965;74518.6274068637;84046;85247;86380.70428943571;9950.10449059588;9964.448102659111;10159.949200253952;10210.285280030326;10218.335808172445;52049.739751301;52391.21413178729;53298.73350633222;53663.19505207347;54084;58660;64395;73468;74710.91402660789;74794.39042071758;12624.810627840774;12743.553975610808;12770.38207167547;13307.201567905957;13483.797743033667;14202.360893759598;14427.49503767363;14715.411383544966;14785.260736962893;14860;13503.59489215325;13650.522231721841;13761.724765504834;13836.723265534432;14590.85409145895;13117.672058198239;13656.65858353549;13920.373583188983;13938.860611394162;14657.413703451743;13032.609021729364;13570.660733481742;13674.179549226681;13737.969652275919;13923.791143133063;10952.726181845199;11025.228234023542;11496.712582185512;11527.135464840338;12018.939905300418;9930.782446348936;10462.63380781669;10935;11510;11645.126615503701 08:35:52 INFO - PROCESS | 1652 | __end_tp_report 08:35:52 INFO - PROCESS | 1652 | __start_cc_report 08:35:52 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,369 08:35:52 INFO - PROCESS | 1652 | __end_cc_report 08:35:52 INFO - PROCESS | 1652 | __startTimestamp1481906152696__endTimestamp 08:35:52 INFO - PROCESS | 1652 | 08:35:52 INFO - PROCESS | 1652 | ------- Summary: start ------- 08:35:52 INFO - PROCESS | 1652 | Number of tests: 6 08:35:52 INFO - PROCESS | 1652 | 08:35:52 INFO - PROCESS | 1652 | [#0] dojo.html Cycles:165 Average:5750.96 Median:6032.85 stddev:645.45 (10.7%) stddev-sans-first:620.15 08:35:52 INFO - PROCESS | 1652 | Values: 3384.2 3388.1 3391.1 3398.9 3428.6 5759.7 5767.3 5774.8 5810.1 5865.0 5629.9 5748.8 5766.6 5788.5 5799.4 5153.6 5183.6 5229.4 5258.7 5302.1 5303.2 5315.1 5333.7 5336.2 5361.3 4336.8 5306.8 5319.6 5324.5 5330.9 5306.3 5318.5 5322.5 5346.4 5358.4 5198.5 5272.4 5275.0 5276.6 5285.5 5040.4 5819.2 5823.4 5869.7 5899.2 6039.3 6215.8 6245.2 6270.1 6314.3 4490.0 5877.3 5931.9 6220.3 6242.3 5959.5 5970.9 5974.4 5981.6 6032.8 5793.2 6273.6 6313.6 6335.8 6345.9 6119.4 6124.4 6124.9 6216.4 6223.4 6054.2 6075.6 6118.0 6128.2 6144.8 6202.7 6231.1 6254.0 6256.5 6269.0 5442.9 5684.8 6188.9 6220.7 6313.0 4905.4 4915.5 4949.3 4979.1 5015.6 4414.0 4458.2 4470.7 4488.0 4504.0 5972.3 6057.2 6064.6 6096.1 6127.7 6170.7 6203.5 6232.0 6269.4 6283.9 5820.4 5874.6 5877.0 5955.7 6089.5 5458.8 5459.5 5493.2 5520.4 5554.3 6269.5 6298.6 6314.4 6317.9 6334.4 6193.0 6208.8 6237.8 6240.8 6260.1 5426.8 6075.0 6213.6 6222.1 6288.4 6246.9 6255.8 6256.8 6268.0 6279.6 6192.2 6216.2 6271.1 6272.0 6288.9 4638.7 6132.2 6139.2 6141.9 6142.8 5011.9 5964.5 6082.3 6126.8 6141.7 5955.1 6066.1 6113.4 6114.0 6122.6 6165.8 6197.9 6203.2 6216.0 6236.0 5895.1 5939.8 6080.8 6110.0 6111.7 08:35:52 INFO - PROCESS | 1652 | 08:35:52 INFO - PROCESS | 1652 | [#1] ext.html Cycles:180 Average:60008.67 Median:18341.48 stddev:124809.06 (680.5%) stddev-sans-first:125079.08 08:35:52 INFO - PROCESS | 1652 | Values: 450.0 463.5 475.9 504.9 509.0 34587.0 39287.0 39677.4 39695.4 41529.0 29855.1 40277.2 40815.0 40886.0 40947.9 8224.9 8444.8 9093.5 9337.3 9354.3 11049.2 11287.0 11419.9 11467.5 11492.7 9529.1 11283.7 11298.8 11300.4 11328.0 10245.5 11102.5 11132.9 11145.5 11183.3 9446.2 10039.8 10192.6 10368.5 11049.5 32805.3 37759.0 38668.8 38868.6 38869.4 3316.3 3748.7 4016.9 4056.2 4127.2 25493.7 28069.3 29926.6 30366.7 30431.0 3181.6 3185.5 3207.7 3250.8 3292.8 322749.8 326824.0 328180.0 328972.0 330550.0 56585.8 57321.9 57750.0 57791.4 57985.1 49784.8 54444.6 58681.2 59777.5 60151.7 15380.5 15594.5 15610.2 15644.5 15660.0 10909.1 11179.2 11902.9 12131.0 12221.4 15543.5 15636.9 15648.8 15659.4 15680.0 5951.4 6919.2 7419.1 7442.5 7527.5 865.8 953.3 1016.8 1093.3 1120.1 29121.7 29181.6 29467.3 30139.7 30401.1 1048.8 1063.5 1068.3 1143.2 1147.4 14973.2 15170.5 15271.5 15491.8 15612.5 479891.0 581257.0 583145.0 584224.0 588772.0 56313.7 56598.7 56667.0 56698.0 56882.0 1366.7 1375.8 1384.4 1394.7 1405.0 28374.9 29054.6 29065.0 29706.7 29758.6 47720.5 47739.5 48126.8 48163.3 48208.0 430220.0 489022.0 493193.0 496011.0 496043.0 38182.4 38435.1 38517.8 39079.4 40175.8 34705.7 42847.6 43557.1 44456.6 44597.0 26437.5 28292.9 33734.8 36696.1 37876.1 29354.3 31567.5 33785.3 35292.2 37646.5 31349.0 37105.4 37116.8 37573.4 37623.6 26321.7 32708.5 33366.7 33497.7 33572.5 20805.3 22299.1 24533.2 24681.8 25003.9 08:35:52 INFO - PROCESS | 1652 | 08:35:52 INFO - PROCESS | 1652 | [#2] jquery.html Cycles:180 Average:29452.12 Median:24448.32 stddev:78051.82 (319.3%) stddev-sans-first:78240.23 08:35:52 INFO - PROCESS | 1652 | Values: 370.2 376.7 379.8 387.9 402.9 14755.9 15027.6 18229.1 20295.9 20574.3 16220.2 16588.0 20299.2 20488.9 20502.6 5675.2 5688.2 5936.7 5950.0 5950.6 5674.3 5840.6 5851.4 5853.1 5861.4 4088.6 4284.0 6186.0 6469.0 6484.3 6256.3 6398.0 6520.5 6545.7 6562.0 5585.9 5613.1 5625.3 5633.9 5635.5 20995.0 23852.7 24183.8 24712.9 25138.2 2180.3 2219.6 2225.2 2278.4 2314.3 14895.6 15157.4 15345.5 15412.0 15826.5 1785.2 1806.2 1857.3 1881.7 1883.8 102814.0 108392.0 108613.5 108864.9 109004.0 10248.2 10307.1 10467.6 10901.6 11170.6 13426.7 13440.7 13442.5 13450.9 13454.3 7670.9 8391.7 8430.7 8538.7 8568.8 8483.4 8508.3 8514.0 8649.5 8673.9 6378.5 6583.7 6592.3 6592.9 6595.6 1540.7 1796.0 1823.6 1873.9 1912.7 861.8 903.9 924.6 932.9 970.2 20227.8 21560.5 21715.7 21734.7 21751.3 959.6 965.2 984.0 989.4 1030.5 11164.8 11583.7 11637.4 11765.2 12404.1 234927.8 457167.0 464422.0 542899.0 554784.0 84343.6 97428.0 102080.5 106719.0 110438.0 976.9 1011.1 1025.5 1034.3 1035.0 18678.3 18741.7 18868.0 18986.5 19019.0 49579.5 49999.0 50351.7 50575.0 51394.0 67448.7 68790.7 69761.0 70608.6 70732.6 6903.6 7666.6 7732.0 7845.4 7920.4 7314.5 9369.4 10758.5 12068.9 12310.4 8621.3 10476.2 10682.8 10941.7 11149.2 10260.9 10519.3 10712.5 11102.1 11163.9 8978.6 11035.2 11456.5 11576.2 11629.9 7680.7 7740.6 7809.0 7906.4 8019.4 8605.3 9724.8 11174.7 11201.7 11290.8 08:35:52 INFO - PROCESS | 1652 | 08:35:52 INFO - PROCESS | 1652 | [#3] mootools.html Cycles:180 Average:5378.69 Median:5212.82 stddev:2929.89 (56.2%) stddev-sans-first:2922.13 08:35:52 INFO - PROCESS | 1652 | Values: 1307.0 1493.7 1675.8 1804.2 1855.3 7554.8 7818.6 8041.8 8087.0 8492.4 6877.3 7935.1 8300.4 8307.0 8313.9 2300.3 2352.9 2357.9 2360.0 2362.9 2110.0 2345.5 2371.9 2382.1 2388.1 1934.6 2227.8 2280.2 2301.9 2371.1 2310.5 2310.7 2324.4 2329.7 2336.9 1891.6 2363.7 2363.9 2365.5 2379.6 8246.0 8265.8 8316.0 8352.4 8375.2 5091.8 5221.8 5247.9 5251.8 5270.0 3289.2 3382.9 3475.5 3536.2 3553.3 977.5 1021.7 1051.2 1070.0 1074.0 9541.2 10618.2 10688.4 10826.6 10847.2 3857.6 4467.3 4672.6 4711.6 4720.3 9435.3 9454.7 9528.2 9540.8 9554.2 5156.5 5168.8 5204.6 5229.4 5235.8 4276.3 4327.5 4675.4 4762.5 4780.1 3814.5 3873.9 3930.5 3950.2 3996.1 1260.6 1297.8 1298.2 1305.2 1328.4 754.2 834.8 836.6 848.0 858.9 4995.8 5251.0 5529.3 5876.0 5999.1 879.9 926.1 927.5 942.5 985.4 3065.8 3355.7 3475.8 3841.7 3852.9 9715.4 10058.7 10059.6 10076.5 10078.5 9929.1 9983.6 10023.2 10026.8 10035.1 6376.8 7409.6 7787.1 7793.9 7901.1 4873.7 5564.0 5603.7 5815.3 5919.6 9112.6 9137.4 9212.0 9311.4 9352.2 7732.8 9374.4 9415.4 9506.5 9542.5 6575.3 6751.2 6893.9 7002.2 7154.1 7722.2 7847.4 7872.2 7878.6 7879.6 6965.3 7102.1 7177.7 7185.4 7221.3 6604.9 6809.2 6936.9 6965.5 7084.0 5221.0 6679.8 6727.0 6749.6 6767.6 4826.3 4868.5 4883.3 4884.4 4959.5 4809.5 4881.0 4882.0 4966.5 4968.7 08:35:52 INFO - PROCESS | 1652 | 08:35:52 INFO - PROCESS | 1652 | [#4] prototype.html Cycles:180 Average:4442.76 Median:4846.58 stddev:1379.87 (28.5%) stddev-sans-first:1349.13 08:35:52 INFO - PROCESS | 1652 | Values: 350.9 355.8 361.0 367.6 373.8 4328.5 5320.1 5390.8 5524.4 5525.3 5226.0 5387.5 5411.1 5421.5 5437.0 4230.5 4243.4 4263.3 4275.8 4280.4 3393.3 3581.2 4244.7 4252.3 4273.4 3378.4 3678.5 3945.6 4031.7 4068.4 3200.8 3890.4 4010.9 4076.4 4111.2 2860.4 3262.4 3456.1 3531.2 3537.3 5141.3 5249.3 5273.0 5329.7 5335.5 4322.6 4663.6 4673.6 4701.3 4712.2 4646.9 5404.7 5671.9 5762.4 5793.6 3385.5 3452.5 3723.6 3860.9 3888.6 5321.4 5932.3 5995.7 6031.4 6161.2 3896.8 3908.3 3917.1 3934.4 3940.8 3637.6 3643.2 3924.6 3951.5 3969.9 3879.3 4223.8 4566.6 4586.2 4616.7 4704.0 4831.2 4891.9 5788.3 5853.3 1105.6 1162.3 1212.6 1238.0 1253.3 752.5 759.0 793.9 861.8 866.0 4160.1 4344.3 4956.6 5118.7 5141.7 5183.7 5550.4 5573.6 5646.2 5649.7 4292.0 4701.5 5028.2 5029.5 5036.5 2483.6 2674.0 2771.9 2920.0 2925.8 5032.6 5216.9 5682.3 5698.0 5747.6 4543.2 5066.0 5427.9 5554.3 5593.0 5016.7 5112.8 5134.1 5743.2 5816.9 4596.0 4630.1 4712.4 4782.4 4862.0 4344.0 4680.6 5425.3 5511.0 5908.2 5806.5 5992.2 6041.3 6049.5 6050.6 3537.7 3849.0 3857.5 3930.4 3940.4 4796.6 5471.0 5573.9 5630.8 5998.6 4532.5 4668.4 4971.6 5060.0 5626.3 4294.6 4670.0 5149.5 5527.4 5774.2 5090.6 5244.9 5445.9 5608.3 5693.9 5688.1 5718.5 5770.7 5778.2 5854.8 4575.8 5179.6 5315.9 5352.1 5589.0 08:35:52 INFO - PROCESS | 1652 | 08:35:52 INFO - PROCESS | 1652 | [#5] yui.html Cycles:180 Average:19593.48 Median:4523.12 stddev:27296.11 (603.5%) stddev-sans-first:27335.56 08:35:52 INFO - PROCESS | 1652 | Values: 634.2 727.4 744.3 783.0 785.7 14319.4 15494.8 15516.8 15527.8 15577.7 13960.9 14103.4 14120.9 14133.3 14217.6 683.5 701.7 834.7 849.7 851.5 837.3 850.2 852.5 852.8 855.5 916.8 918.6 919.3 921.6 921.7 907.5 913.0 915.4 917.4 917.6 668.7 681.8 684.2 685.5 685.8 12952.0 13581.1 14126.0 14478.6 14675.6 13644.5 13833.7 13959.4 13996.4 13998.8 11586.8 11626.4 11766.2 11768.5 11955.0 3678.1 3685.4 3727.6 3729.2 3815.2 95724.6 98621.5 99010.5 100935.0 101785.0 8919.9 10494.1 10526.7 10758.3 10915.3 21319.0 21655.2 21707.1 21790.8 21829.5 7540.3 7590.6 7616.5 7670.5 7792.7 3692.5 4202.7 4220.0 4227.2 4243.5 2954.6 3005.6 3431.3 3561.1 3597.7 536.8 651.3 651.7 664.4 668.4 568.5 622.8 643.6 648.4 660.8 8738.9 8891.0 8995.8 9060.8 9140.8 564.4 649.4 672.0 677.0 690.1 4280.8 4489.9 4556.3 4558.5 4699.0 75660.2 80902.6 90249.0 93357.0 103674.5 64584.0 74622.0 75773.0 78363.2 86166.0 58969.7 74518.6 84046.0 85247.0 86380.7 9950.1 9964.4 10159.9 10210.3 10218.3 52049.7 52391.2 53298.7 53663.2 54084.0 58660.0 64395.0 73468.0 74710.9 74794.4 12624.8 12743.6 12770.4 13307.2 13483.8 14202.4 14427.5 14715.4 14785.3 14860.0 13503.6 13650.5 13761.7 13836.7 14590.9 13117.7 13656.7 13920.4 13938.9 14657.4 13032.6 13570.7 13674.2 13738.0 13923.8 10952.7 11025.2 11496.7 11527.1 12018.9 9930.8 10462.6 10935.0 11510.0 11645.1 08:35:52 INFO - PROCESS | 1652 | -------- Summary: end -------- 08:35:52 INFO - PROCESS | 1652 | 08:35:53 INFO - TEST-INFO | 1652: exit 0 08:35:53 INFO - TEST-OK | dromaeo_css | took 1117237ms 08:35:53 INFO - TEST-START | kraken 08:35:53 INFO - operating with platform_type : mac_ 08:35:53 INFO - Initialising browser for kraken test... 08:35:53 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/tmp9mXh_s/profile http://localhost:49236/getInfo.html) 08:35:56 INFO - PROCESS | 1663 | 2016-12-16 08:35:56.300 plugin-container[1664:10519] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x872b, name = 'com.apple.tsm.portname' 08:35:56 INFO - PROCESS | 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:35:56 INFO - PROCESS | 1663 | 2016-12-16 08:35:56.302 plugin-container[1664:10519] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x900b, name = 'com.apple.CFPasteboardClient' 08:35:56 INFO - PROCESS | 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:35:56 INFO - PROCESS | 1663 | 2016-12-16 08:35:56.303 plugin-container[1664:10519] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:35:56 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 08:35:56 INFO - PROCESS | 1663 | colorDepth:24 08:35:56 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 08:35:56 INFO - PROCESS | 1663 | __metrics 08:35:56 ERROR - PROCESS | 1663 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3232: TypeError: this.tabs is undefined 08:35:57 INFO - TEST-INFO | 1663: exit 0 08:35:57 INFO - Browser initialized. 08:35:57 INFO - Running cycle 1/1 for kraken test... 08:35:57 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/tmp9mXh_s/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:35:59 INFO - PROCESS | 1665 | 2016-12-16 08:35:59.713 plugin-container[1666:10634] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 08:35:59 INFO - PROCESS | 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:35:59 INFO - PROCESS | 1665 | 2016-12-16 08:35:59.714 plugin-container[1666:10634] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4323, name = 'com.apple.CFPasteboardClient' 08:35:59 INFO - PROCESS | 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:35:59 INFO - PROCESS | 1665 | 2016-12-16 08:35:59.714 plugin-container[1666:10634] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:36:09 INFO - PROCESS | 1665 | RSS: Main: 237056000 08:36:09 INFO - PROCESS | 1665 | 08:36:12 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection) 08:36:13 INFO - PROCESS | 1665 | RSS: Main: 249294848 08:36:13 INFO - PROCESS | 1665 | 08:36:15 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-dft) 08:36:16 INFO - PROCESS | 1665 | RSS: Main: 256872448 08:36:16 INFO - PROCESS | 1665 | 08:36:19 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-fft) 08:36:20 INFO - PROCESS | 1665 | RSS: Main: 259514368 08:36:20 INFO - PROCESS | 1665 | 08:36:22 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator) 08:36:23 INFO - PROCESS | 1665 | RSS: Main: 261816320 08:36:23 INFO - PROCESS | 1665 | 08:36:25 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:36:25 INFO - PROCESS | 1665 | RSS: Main: 262123520 08:36:25 INFO - PROCESS | 1665 | 08:36:29 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom) 08:36:29 INFO - PROCESS | 1665 | RSS: Main: 268361728 08:36:29 INFO - PROCESS | 1665 | 08:36:32 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate) 08:36:33 INFO - PROCESS | 1665 | RSS: Main: 267370496 08:36:33 INFO - PROCESS | 1665 | 08:36:36 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial) 08:36:37 INFO - PROCESS | 1665 | RSS: Main: 267358208 08:36:37 INFO - PROCESS | 1665 | 08:36:38 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:36:39 INFO - PROCESS | 1665 | RSS: Main: 266592256 08:36:39 INFO - PROCESS | 1665 | 08:36:41 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:36:42 INFO - PROCESS | 1665 | RSS: Main: 266641408 08:36:42 INFO - PROCESS | 1665 | 08:36:44 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:36:45 INFO - PROCESS | 1665 | RSS: Main: 266645504 08:36:45 INFO - PROCESS | 1665 | 08:36:47 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:36:48 INFO - PROCESS | 1665 | RSS: Main: 266977280 08:36:48 INFO - PROCESS | 1665 | 08:36:51 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:36:52 INFO - PROCESS | 1665 | RSS: Main: 266731520 08:36:52 INFO - PROCESS | 1665 | 08:36:54 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49236/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49236/tests/kraken/driver.html?testName=ai-astar) 08:36:54 INFO - PROCESS | 1665 | RSS: Main: 268058624 08:36:54 INFO - PROCESS | 1665 | 08:36:54 INFO - PROCESS | 1665 | __start_tp_report 08:36:54 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 08:36:54 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 08:36:54 INFO - PROCESS | 1665 | |i|pagename|runs| 08:36:54 INFO - PROCESS | 1665 | |0;ai-astar;115;119;119;141;170;155;119;117;118;117 08:36:54 INFO - PROCESS | 1665 | |1;audio-beat-detection;174;148;101;181;146;147;97;137;160;146 08:36:54 INFO - PROCESS | 1665 | |2;audio-dft;201;211;209;202;197;196;196;197;198;193 08:36:54 INFO - PROCESS | 1665 | |3;audio-fft;80;59;61;83;59;62;60;76;98;73 08:36:54 INFO - PROCESS | 1665 | |4;audio-oscillator;91;71;84;81;97;104;117;71;71;84 08:36:54 INFO - PROCESS | 1665 | |5;imaging-gaussian-blur;134;117;167;156;107;137;144;171;136;111 08:36:54 INFO - PROCESS | 1665 | |6;imaging-darkroom;152;114;97;98;97;105;100;96;97;119 08:36:54 INFO - PROCESS | 1665 | |7;imaging-desaturate;89;105;127;102;76;132;88;101;103;135 08:36:54 INFO - PROCESS | 1665 | |8;json-parse-financial;58;60;78;87;57;58;58;58;58;59 08:36:54 INFO - PROCESS | 1665 | |9;json-stringify-tinderbox;70;76;78;83;79;76;70;71;72;74 08:36:54 INFO - PROCESS | 1665 | |10;stanford-crypto-aes;78;78;87;113;112;114;82;106;108;109 08:36:54 INFO - PROCESS | 1665 | |11;stanford-crypto-ccm;140;146;157;160;157;128;150;152;194;150 08:36:54 INFO - PROCESS | 1665 | |12;stanford-crypto-pbkdf2;176;176;174;179;178;175;174;174;175;175 08:36:54 INFO - PROCESS | 1665 | |13;stanford-crypto-sha256-iterative;68;68;70;84;87;74;70;69;66;66 08:36:54 INFO - PROCESS | 1665 | __end_tp_report 08:36:54 INFO - PROCESS | 1665 | __start_cc_report 08:36:54 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,965 08:36:54 INFO - PROCESS | 1665 | __end_cc_report 08:36:54 INFO - PROCESS | 1665 | __startTimestamp1481906214339__endTimestamp 08:36:54 INFO - PROCESS | 1665 | 08:36:54 INFO - PROCESS | 1665 | ------- Summary: start ------- 08:36:54 INFO - PROCESS | 1665 | Number of tests: 14 08:36:54 INFO - PROCESS | 1665 | 08:36:54 INFO - PROCESS | 1665 | [#0] ai-astar Cycles:10 Average:129.00 Median:119.00 stddev:19.45 (16.3%) stddev-sans-first:19.96 08:36:54 INFO - PROCESS | 1665 | Values: 115.0 119.0 119.0 141.0 170.0 155.0 119.0 117.0 118.0 117.0 08:36:54 INFO - PROCESS | 1665 | 08:36:54 INFO - PROCESS | 1665 | [#1] audio-beat-detection Cycles:10 Average:143.70 Median:154.00 stddev:27.21 (17.7%) stddev-sans-first:26.56 08:36:54 INFO - PROCESS | 1665 | Values: 174.0 148.0 101.0 181.0 146.0 147.0 97.0 137.0 160.0 146.0 08:36:54 INFO - PROCESS | 1665 | 08:36:54 INFO - PROCESS | 1665 | [#2] audio-dft Cycles:10 Average:200.00 Median:199.50 stddev:5.87 (2.9%) stddev-sans-first:6.21 08:36:54 INFO - PROCESS | 1665 | Values: 201.0 211.0 209.0 202.0 197.0 196.0 196.0 197.0 198.0 193.0 08:36:54 INFO - PROCESS | 1665 | 08:36:54 INFO - PROCESS | 1665 | [#3] audio-fft Cycles:10 Average:71.10 Median:74.50 stddev:13.22 (17.7%) stddev-sans-first:13.62 08:36:54 INFO - PROCESS | 1665 | Values: 80.0 59.0 61.0 83.0 59.0 62.0 60.0 76.0 98.0 73.0 08:36:54 INFO - PROCESS | 1665 | 08:36:54 INFO - PROCESS | 1665 | [#4] audio-oscillator Cycles:10 Average:87.10 Median:82.50 stddev:15.37 (18.6%) stddev-sans-first:16.24 08:36:54 INFO - PROCESS | 1665 | Values: 91.0 71.0 84.0 81.0 97.0 104.0 117.0 71.0 71.0 84.0 08:36:54 INFO - PROCESS | 1665 | 08:36:54 INFO - PROCESS | 1665 | [#5] imaging-gaussian-blur Cycles:10 Average:138.00 Median:140.50 stddev:22.22 (15.8%) stddev-sans-first:23.52 08:36:54 INFO - PROCESS | 1665 | Values: 134.0 117.0 167.0 156.0 107.0 137.0 144.0 171.0 136.0 111.0 08:36:54 INFO - PROCESS | 1665 | 08:36:54 INFO - PROCESS | 1665 | [#6] imaging-darkroom Cycles:10 Average:107.50 Median:96.50 stddev:17.55 (18.2%) stddev-sans-first:8.44 08:36:54 INFO - PROCESS | 1665 | Values: 152.0 114.0 97.0 98.0 97.0 105.0 100.0 96.0 97.0 119.0 08:36:54 INFO - PROCESS | 1665 | 08:36:54 INFO - PROCESS | 1665 | [#7] imaging-desaturate Cycles:10 Average:105.80 Median:133.50 stddev:19.78 (14.8%) stddev-sans-first:20.02 08:36:54 INFO - PROCESS | 1665 | Values: 89.0 105.0 127.0 102.0 76.0 132.0 88.0 101.0 103.0 135.0 08:36:54 INFO - PROCESS | 1665 | 08:36:54 INFO - PROCESS | 1665 | [#8] json-parse-financial Cycles:10 Average:63.10 Median:58.50 stddev:10.47 (17.9%) stddev-sans-first:10.94 08:36:54 INFO - PROCESS | 1665 | Values: 58.0 60.0 78.0 87.0 57.0 58.0 58.0 58.0 58.0 59.0 08:36:54 INFO - PROCESS | 1665 | 08:36:54 INFO - PROCESS | 1665 | [#9] json-stringify-tinderbox Cycles:10 Average:74.90 Median:76.00 stddev:4.31 (5.7%) stddev-sans-first:4.19 08:36:54 INFO - PROCESS | 1665 | Values: 70.0 76.0 78.0 83.0 79.0 76.0 70.0 71.0 72.0 74.0 08:36:54 INFO - PROCESS | 1665 | 08:36:54 INFO - PROCESS | 1665 | [#10] stanford-crypto-aes Cycles:10 Average:98.70 Median:96.00 stddev:15.40 (16.0%) stddev-sans-first:14.40 08:36:54 INFO - PROCESS | 1665 | Values: 78.0 78.0 87.0 113.0 112.0 114.0 82.0 106.0 108.0 109.0 08:36:54 INFO - PROCESS | 1665 | 08:36:54 INFO - PROCESS | 1665 | [#11] stanford-crypto-ccm Cycles:10 Average:153.40 Median:154.50 stddev:17.07 (11.0%) stddev-sans-first:17.40 08:36:54 INFO - PROCESS | 1665 | Values: 140.0 146.0 157.0 160.0 157.0 128.0 150.0 152.0 194.0 150.0 08:36:54 INFO - PROCESS | 1665 | 08:36:54 INFO - PROCESS | 1665 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.60 Median:175.50 stddev:1.71 (1.0%) stddev-sans-first:1.81 08:36:54 INFO - PROCESS | 1665 | Values: 176.0 176.0 174.0 179.0 178.0 175.0 174.0 174.0 175.0 175.0 08:36:54 INFO - PROCESS | 1665 | 08:36:54 INFO - PROCESS | 1665 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.20 Median:70.00 stddev:7.41 (10.6%) stddev-sans-first:7.70 08:36:54 INFO - PROCESS | 1665 | Values: 68.0 68.0 70.0 84.0 87.0 74.0 70.0 69.0 66.0 66.0 08:36:54 INFO - PROCESS | 1665 | -------- Summary: end -------- 08:36:54 INFO - PROCESS | 1665 | 08:36:55 INFO - TEST-INFO | 1665: exit 0 08:36:55 INFO - TEST-OK | kraken | took 61878ms 08:36:55 INFO - SUITE-END | took 1179s 08:36:55 INFO - Completed test suite (00:19:39) 08:36:55 INFO - javascript benchmark 08:36:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7144.038426602974, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3384.2385463270766, 3388.0852169914124, 3391.1013581400935, 3398.8640454381825, 3428.6057103433113, 5759.712014399279, 5767.33675627303, 5774.769009239631, 5810.099434587635, 5864.970675146618, 5629.887402251952, 5748.798792042279, 5766.567507436938, 5788.5369170466365, 5799.449052340032, 5153.613478989081, 5183.637145399824, 5229.398619158807, 5258.710770907618, 5302.072137375963, 5303.204519322094, 5315.096433606296, 5333.679979201241, 5336.226247194155, 5361.303030606015, 4336.84821031264, 5306.7611957462, 5319.574434045287, 5324.547413469841, 5330.866728331804, 5306.257124002643, 5318.494742999409, 5322.494363035506, 5346.438623944468, 5358.383785864637, 5198.532132108128, 5272.420033796279, 5274.97362513185, 5276.604254680876, 5285.497877701612, 5040.36995375578, 5819.156222347779, 5823.4467725566, 5869.677167755772, 5899.233099697055, 6039.275286965549, 6215.844603884894, 6245.219347581552, 6270.090836828635, 6314.336994615591, 4489.955100448987, 5877.294724633029, 5931.932252194603, 6220.284667263278, 6242.344553821828, 5959.463648271676, 5970.940290597126, 5974.3726908674835, 5981.611195272293, 6032.849178770522, 5793.188953546507, 6273.560850740496, 6313.55805093639, 6335.778247761306, 6345.858799894082, 6119.4416233545735, 6124.448799608057, 6124.9281375759065, 6216.44052035319, 6223.439890409885, 6054.243219597551, 6075.635461872302, 6117.990531562243, 6128.19758601264, 6144.77104579086, 6202.689865506707, 6231.065340199007, 6253.96809526423, 6256.5307601930035, 6268.9969604863, 5442.938626967703, 5684.8010319638615, 6188.938110618927, 6220.657863817533, 6313.021481670348, 4905.386826646669, 4915.48387419323, 4949.282354058713, 4979.128652485801, 5015.573676237488, 4413.955860441419, 4458.197524445631, 4470.709403888737, 4487.955120448819, 4503.954960450419, 5972.343042265347, 6057.242844644419, 6064.636121832704, 6096.146539484475, 6127.6782420562195, 6170.7223174957235, 6203.472704820141, 6231.9688401557705, 6269.404406581368, 6283.874322513524, 5820.35976042635, 5874.559408044413, 5877.0, 5955.672438015864, 6089.543284253698, 5458.781648734006, 5459.5359394451125, 5493.2034854945805, 5520.392756796749, 5554.305711786027, 6269.467095296951, 6298.559100862895, 6314.400131987546, 6317.9052314214405, 6334.429901308904, 6193.0, 6208.762566712176, 6237.844053898689, 6240.7503699851495, 6260.092286618324, 5426.7829286829665, 6075.0, 6213.565050446426, 6222.09779581949, 6288.371162883675, 6246.9062964054665, 6255.843603909939, 6256.812295631138, 6267.965785645318, 6279.623222606659, 6192.225971753531, 6216.160818289473, 6271.122042913905, 6271.96864015677, 6288.874222515616, 4638.652101092364, 6132.233470816148, 6139.2325959255095, 6141.877162456815, 6142.815715528542, 5011.899762004667, 5964.463198312173, 6082.330943596289, 6126.792505495216, 6141.731496609813, 5955.136505206634, 6066.059760737093, 6113.415495409546, 6113.962834745988, 6122.550631082853, 6165.815025549241, 6197.907031394623, 6203.16257305276, 6216.0, 6236.03341482071, 5895.056790913433, 5939.792107276225, 6080.756769729338, 6110.022396416552, 6111.694415279308], "value": 5712.563005900321, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [450.0324302749087, 463.54340974140484, 475.86913598760344, 504.8763053052003, 508.9794961102989, 34587.0, 39287.017824554365, 39677.40483892744, 39695.40456893149, 41529.0, 29855.10434686963, 40277.19445611086, 40814.979625509375, 40886.0, 40947.92890533207, 8224.917750822475, 8444.831103377928, 9093.454392736425, 9337.253019758404, 9354.345195836295, 11049.226554141213, 11287.040601548879, 11419.942900285487, 11467.541298348056, 11492.712682182928, 9529.142377186052, 11283.66149015531, 11298.774024519545, 11300.378479183642, 11328.0, 10245.48772561369, 11102.500387482542, 11132.944335278353, 11145.498452569655, 11183.273087249385, 9446.244300455946, 10039.845417776976, 10192.643257485952, 10368.533415996299, 11049.502772375183, 32805.343893122, 37759.0, 38668.80665596682, 38868.61131388678, 38869.41695874564, 3316.286998295354, 3748.662620364153, 4016.8995775105564, 4056.1887622475624, 4127.199924581096, 25493.745062549322, 28069.315841049603, 29926.55110173349, 30366.69633303661, 30431.0, 3181.602299712536, 3185.490321548541, 3207.684849211812, 3250.8374581271078, 3292.835358232103, 322749.7725022743, 326824.0, 328180.0, 328972.0, 330550.0, 56585.77556468785, 57321.85356292934, 57750.0, 57791.42208577944, 57985.13022304669, 49784.751076244385, 54444.63888402742, 58681.239562813185, 59777.505562361286, 60151.69924150351, 15380.461683841011, 15594.532164035098, 15610.21948902573, 15644.530664080095, 15659.982101163389, 10909.072728818139, 11179.161562882817, 11902.88097119035, 12131.0, 12221.388930553438, 15543.45597904068, 15636.92181539085, 15648.765268520981, 15659.43405659425, 15680.0, 5951.4048595141, 6919.238883722786, 7419.108127427471, 7442.464829206084, 7527.548347099191, 865.8051938313955, 953.2897990996752, 1016.8423894296396, 1093.305750848217, 1120.1430905357408, 29121.708782912323, 29181.56227656587, 29467.263318417212, 30139.698603014127, 30401.087967361014, 1048.8479170520231, 1063.5267306048734, 1068.3216157739892, 1143.2454579977175, 1147.437755499799, 14973.176475293963, 15170.469033583771, 15271.465498707714, 15491.767623485657, 15612.4535641252, 479891.0, 581257.0, 583145.0, 584224.0, 588772.0, 56313.71843140758, 56598.71700641471, 56667.0, 56698.0, 56882.0, 1366.7334869700312, 1375.7592421326228, 1384.4254634326642, 1394.7350003499303, 1404.9789253161211, 28374.85812570924, 29054.564181536873, 29064.982725604503, 29706.702932971257, 29758.553621696126, 47720.522794771605, 47739.522604774895, 48126.759366204344, 48163.27755083747, 48208.0, 430220.0, 489022.0, 493193.0, 496011.0, 496043.0, 38182.42726359162, 38435.11736619709, 38517.80741096276, 39079.41380879346, 40175.79912100421, 34705.65294347024, 42847.57152428436, 43557.12885742331, 44456.55543444524, 44597.0, 26437.471250575265, 28292.85853570719, 33734.83132584322, 36696.08259793527, 37876.05309867275, 29354.266143346587, 31567.526487102252, 33785.3242935135, 35292.23538822997, 37646.494140234085, 31349.0, 37105.44341834821, 37116.81441592775, 37573.4363984535, 37623.623763762014, 26321.736782631928, 32708.509372358956, 33366.666333336354, 33497.665023350426, 33572.496412553344, 20805.271815486387, 22299.10803567839, 24533.16001299874, 24681.753182467946, 25003.87498062498], "value": 19285.089345826615, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [370.172664095746, 376.7231085152412, 379.80819686058544, 387.93599056155733, 402.9435878976944, 14755.92622036891, 15027.624309392244, 18229.08854557272, 20295.898520507413, 20574.279900203495, 16220.188990550485, 16588.004719716824, 20299.18803247872, 20488.897555512238, 20502.589948201065, 5675.20547123401, 5688.203651488795, 5936.673482958436, 5950.0, 5950.5834591578605, 5674.3190817102, 5840.591158618899, 5851.385604511529, 5853.063509838426, 5861.384554621767, 4088.550259471456, 4283.993261583525, 6186.0, 6469.0, 6484.286728459866, 6256.343083976162, 6397.96801015992, 6520.510961677869, 6545.738170473175, 6561.967190164042, 5585.8603534911545, 5613.129964855454, 5625.324961004665, 5633.943660563383, 5635.520980716644, 20995.0, 23852.688102154374, 24183.758162418326, 24712.876435617887, 25138.24585262445, 2180.3349978256624, 2219.622664147099, 2225.18780645065, 2278.3962250003597, 2314.328844635054, 14895.627609309746, 15157.393704251817, 15345.46290879814, 15411.998220115656, 15826.525204243891, 1785.2055835153362, 1806.1781889240365, 1857.3406440713488, 1881.7177423386465, 1883.839873610731, 102814.0, 108392.0, 108613.45693271483, 108864.91135088707, 109004.0, 10248.180145588334, 10307.093187760289, 10467.633632822966, 10901.618443354444, 11170.609028683957, 13426.731465370638, 13440.731185376433, 13442.529511467248, 13450.932745336211, 13454.260015699036, 7670.923290767132, 8391.706290279812, 8430.747077587683, 8538.70114545988, 8568.785780355416, 8483.363747718946, 8508.319334453228, 8513.957430212933, 8649.481031138152, 8673.869891951626, 6378.52161087911, 6583.736650533925, 6592.30780768022, 6592.868142637216, 6595.637239951753, 1540.691861627679, 1795.9820401795814, 1823.6443893440917, 1873.878197917131, 1912.7322174895526, 861.793169639282, 903.9276857851356, 924.5793164110314, 932.9253659707207, 970.1559643110398, 20227.797722022886, 21560.460988475414, 21715.674264886038, 21734.673979890315, 21751.347459576238, 959.5825815770162, 965.2085290062082, 983.9655612053543, 989.4077516905753, 1030.469308306223, 11164.776704465865, 11583.6524904253, 11637.359945203096, 11765.176437649283, 12404.131710780339, 234927.82536087552, 457167.0, 464422.0, 542899.0, 554784.0, 84343.57828210942, 97428.02571974331, 102080.48959755302, 106719.0, 110438.0, 976.9218462522981, 1011.1203253169789, 1025.5333823110616, 1034.3483605328595, 1035.0270745499206, 18678.34625788091, 18741.71887421717, 18868.036908171493, 18986.52533686669, 19019.0, 49579.50420495749, 49999.0, 50351.74824125856, 50575.0, 51394.0, 67448.66275668786, 68790.65604672144, 69760.95358569524, 70608.64695676694, 70732.64633676798, 6903.619276144892, 7666.578338191453, 7732.0, 7845.372370210255, 7920.405969552422, 7314.487985841153, 9369.437833729999, 10758.46207689628, 12068.879311206776, 12310.446029928853, 8621.267192288726, 10476.16190704757, 10682.83975740349, 10941.726456838644, 11149.163812713916, 10260.897391025994, 10519.26365154432, 10712.517936693024, 11102.056325212447, 11163.944180279046, 8978.595963181542, 11035.172362072715, 11456.541738330374, 11576.24754390962, 11629.883701163219, 7680.731174408869, 7740.612969351622, 7809.0, 7906.446548741533, 8019.438639295194, 8605.311575073854, 9724.75688107803, 11174.720631984266, 11201.66395008151, 11290.7741845161], "value": 9356.309115357666, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1306.967495678414, 1493.7460631692609, 1675.8407951244635, 1804.2151664026142, 1855.3228071753806, 7554.811129721746, 7818.609069546515, 8041.839163216732, 8087.0, 8492.447990880588, 6877.31226877312, 7935.127136015034, 8300.41897067204, 8307.0, 8313.875291870612, 2300.2869110575734, 2352.9058837646476, 2357.917472888449, 2359.988200059006, 2362.9054837806466, 2109.9766613192587, 2345.5074434368717, 2371.8932648030795, 2382.118616112041, 2388.0567175965484, 1934.6130773845216, 2227.7772222777753, 2280.1905323610044, 2301.9079236830507, 2371.087131454394, 2310.5032418030037, 2310.6765052892524, 2324.4421338878715, 2329.743728189898, 2336.941576460585, 1891.5933074388936, 2363.6927199463958, 2363.869987150706, 2365.455945132629, 2379.559781440439, 8246.0, 8265.752027439186, 8316.0, 8352.415330926837, 8375.162483751576, 5091.847244582668, 5221.791128354861, 5247.86880327991, 5251.842444726664, 5269.973650131763, 3289.19414743387, 3382.8646854125805, 3475.548178736748, 3536.1866770642637, 3553.2538166984705, 977.4721650308825, 1021.6577446555339, 1051.232600201857, 1070.0, 1073.958260487326, 9541.2367010639, 10618.150547956146, 10688.412137332443, 10826.566937322497, 10847.240693151483, 3857.614238576176, 4467.262901621196, 4672.649551283667, 4711.575958163782, 4720.315554244616, 9435.292353073546, 9454.716358509255, 9528.237740980705, 9540.809183816285, 9554.235661147091, 5156.458571849977, 5168.844934651891, 5204.635675502755, 5229.424763276036, 5235.842924712265, 4276.337167739005, 4327.511680783562, 4675.438947326343, 4762.547557981986, 4780.0917825613715, 3814.5422549294262, 3873.869371845474, 3930.4890364252465, 3950.229705207457, 3996.1408297215658, 1260.615512268767, 1297.772889744291, 1298.2084723082087, 1305.2168698780656, 1328.4221363706818, 754.2231501553409, 834.7954751086023, 836.5817091454273, 847.9703210387606, 858.9012263589767, 4995.825146119869, 5250.973745131327, 5529.336479622471, 5876.0, 5999.130126131774, 879.9252063574539, 926.0693003531408, 927.5269612497675, 942.5202432181546, 985.3792110970186, 3065.7700672449655, 3355.681210285019, 3475.826208689555, 3841.7118716096406, 3852.9422058669143, 9715.368501047551, 10058.698239052841, 10059.597616095421, 10076.546555404877, 10078.546465409074, 9929.106380425652, 9983.550740216708, 10023.198144148451, 10026.799464010825, 10035.09684128432, 6376.756532476123, 7409.592472414069, 7787.143414224431, 7793.883091753628, 7901.091374491892, 4873.731944743073, 5563.970665426909, 5603.719814009365, 5815.273090863642, 5919.585629005928, 9112.589933452879, 9137.360384773268, 9212.032736562564, 9311.394759340621, 9352.158305752242, 7732.845343092994, 9374.437533747998, 9415.435073895589, 9506.524673766145, 9542.475163866055, 6575.342465753386, 6751.156105486814, 6893.862122757617, 7002.159740831133, 7154.105736782903, 7722.150563437905, 7847.372210223054, 7872.212778722082, 7878.56667883249, 7879.566623835515, 6965.268646792217, 7102.14774227096, 7177.712891484491, 7185.389241914496, 7221.277872212737, 6604.933950660432, 6809.182898052265, 6936.941888994793, 6965.495321904562, 7083.964580177066, 5221.0, 6679.833004174934, 6726.966365168339, 6749.628770417674, 6767.560108592926, 4826.300186472871, 4868.48880867518, 4883.316335712932, 4884.438289596742, 4959.454460009469, 4809.5430934061205, 4880.975595122001, 4882.023595280887, 4966.503349665005, 4968.652194346361], "value": 4374.006405707608, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.8842082112903, 355.7918617608699, 360.96570825771573, 367.6250224770734, 373.80748914309135, 4328.545502722216, 5320.0581787647425, 5390.811321603745, 5524.392316845144, 5525.25954324388, 5226.0, 5387.488188622075, 5411.080116380215, 5421.457854214576, 5436.972815135929, 4230.4500414946015, 4243.353527886959, 4263.275243208662, 4275.764832934187, 4280.4221430106845, 3393.2863903728585, 3581.2300355423577, 4244.745315281078, 4252.319628859384, 4273.358996150587, 3378.3918894598964, 3678.5217921670146, 3945.619033338323, 4031.717779755418, 4068.4304197412384, 3200.775945683803, 3890.396988466792, 4010.9197816043807, 4076.388541718736, 4111.239420707178, 2860.427914417125, 3262.396456655503, 3456.084137703511, 3531.1525233943926, 3537.3455910656353, 5141.331626888518, 5249.343832020997, 5272.971770504753, 5329.733513324319, 5335.492318610247, 4322.5735507282525, 4663.600919724103, 4673.6027437667835, 4701.294805779126, 4712.222483290253, 4646.933024204043, 5404.675719456806, 5671.8582035449035, 5762.386531771111, 5793.565482588822, 3385.4752513360468, 3452.5166476693284, 3723.640871082063, 3860.9227815443533, 3888.6111388860886, 5321.365923867672, 5932.258467691496, 5995.6702381369005, 6031.4270144336215, 6161.2298462691715, 3896.7661940283674, 3908.257431088084, 3917.0990672145567, 3934.429507721364, 3940.7832569208967, 3637.5998640149564, 3643.2148247358605, 3924.5682974872743, 3951.545572259227, 3969.900752481211, 3879.3405121129476, 4223.809928553161, 4566.634669226454, 4586.243269860503, 4616.746078965622, 4704.0, 4831.164157620082, 4891.902161956741, 5788.3343415506915, 5853.326867410217, 1105.618561596248, 1162.2910024884814, 1212.563477148226, 1237.9566715164926, 1253.2856271925098, 752.4732687119039, 759.0132827324456, 793.9245771651683, 861.814709837388, 865.9610317535726, 4160.126373461547, 4344.348347747863, 4956.587372598792, 5118.667286626357, 5141.665791723526, 5183.740812959412, 5550.361708403585, 5573.554115670737, 5646.237757902631, 5649.711866693996, 4291.999820209421, 4701.506341834058, 5028.245763135559, 5029.5355275914535, 5036.471170527115, 2483.552960467097, 2674.0, 2771.9307017324727, 2920.0217926994696, 2925.829668132764, 5032.597392208614, 5216.921746173734, 5682.261306030189, 5697.971510142423, 5747.626404283792, 4543.220072361198, 5066.0, 5427.864303392446, 5554.250411120484, 5593.0, 5016.749162541858, 5112.769925353368, 5134.073578060545, 5743.195952566644, 5816.883662326645, 4596.0, 4630.087575998276, 4712.3873896394625, 4782.378290822171, 4862.0, 4343.978280108579, 4680.555347242007, 5425.348958125051, 5510.972445137909, 5908.155312643763, 5806.477417032318, 5992.221011268682, 6041.2750469943885, 6049.546284028627, 6050.608360077119, 3537.7346698998194, 3849.0, 3857.498525191707, 3930.4497370368745, 3940.4089386591095, 4796.640251981045, 5470.972645136749, 5573.913086948169, 5630.774769009194, 5998.610090344114, 4532.478764941942, 4668.416447944007, 4971.577415919687, 5060.0132974071175, 5626.330145978746, 4294.634956028773, 4670.0, 5149.510796474329, 5527.419620939904, 5774.181132943109, 5090.643654944267, 5244.89510209786, 5445.891082178414, 5608.251956541184, 5693.857653558694, 5688.146777983336, 5718.456746609066, 5770.740316685843, 5778.219940308005, 5854.795082172103, 4575.83316254353, 5179.611529135254, 5315.893682126259, 5352.143657014859, 5588.972055139699], "value": 4013.4825323210835, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [634.1968499233388, 727.3562896836298, 744.3226663735998, 782.9843403131941, 785.7132146766428, 14319.355628996695, 15494.767578486304, 15516.767248491253, 15527.844721552781, 15577.688446231083, 13960.905864120386, 14103.435862565486, 14120.940929430308, 14133.293335333243, 14217.644558886033, 683.4566519616892, 701.7052837808108, 834.7203686764941, 849.6856163219618, 851.5401683091123, 837.2924878477677, 850.200811237438, 852.5225873510853, 852.8166444214493, 855.5080828523593, 916.8028873792133, 918.5912269040247, 919.3197034194715, 921.5991043895926, 921.6958403726787, 907.4736652741394, 912.9908700913039, 915.3821170709778, 917.3670167584345, 917.5595714057257, 668.749219042859, 681.8397676546008, 684.165318311659, 685.4961603221628, 685.8251145957798, 12951.963842892546, 13581.117227380288, 14126.0, 14478.638034049127, 14675.63310917225, 13644.454221831114, 13833.723325533534, 13959.371828267651, 13996.370163342572, 13998.790018149735, 11586.768264634744, 11626.360550169737, 11766.176367654181, 11768.470418831173, 11955.0, 3678.1172518595613, 3685.355062864015, 3727.5713292971113, 3729.216864458467, 3815.1797363566966, 95724.56413153808, 98621.50689246508, 99010.50494747481, 100934.99065009403, 101784.99999999926, 8919.866202006975, 10494.055535001733, 10526.736831579272, 10758.300710453796, 10915.290506117077, 21319.040643171094, 21655.242066527597, 21707.131714731233, 21790.782092179194, 21829.45426364322, 7540.283673051052, 7590.620468976529, 7616.466847320745, 7670.539767613961, 7792.727254546064, 3692.48305237267, 4202.6847986400535, 4220.008298049956, 4227.154569086134, 4243.533211346749, 2954.5863579098946, 3005.6092707948264, 3431.3137372525093, 3561.127523756696, 3597.712183025352, 536.8201652446396, 651.2868409092036, 651.6513665189195, 664.3954001858285, 668.3683918696883, 568.496880260972, 622.8131560531822, 643.6299128001418, 648.4293821437105, 660.8050625065521, 8738.91261087381, 8890.985376995533, 8995.775105622412, 9060.81878362429, 9140.817183656289, 564.4073722591263, 649.3538928765909, 671.9630420326831, 676.9695363708645, 690.1063123255396, 4280.764557949343, 4489.910201795946, 4556.3393307970155, 4558.49856515783, 4698.9530104698515, 75660.24339756642, 80902.59548702219, 90249.0, 93357.0, 103674.48162759289, 64584.0, 74622.0, 75773.0, 78363.21636783674, 86166.0, 58969.705151473965, 74518.6274068637, 84046.0, 85247.0, 86380.70428943571, 9950.10449059588, 9964.448102659111, 10159.949200253952, 10210.285280030326, 10218.335808172445, 52049.739751301, 52391.21413178729, 53298.73350633222, 53663.19505207347, 54084.0, 58660.0, 64395.0, 73468.0, 74710.91402660789, 74794.39042071758, 12624.810627840774, 12743.553975610808, 12770.38207167547, 13307.201567905957, 13483.797743033667, 14202.360893759598, 14427.49503767363, 14715.411383544966, 14785.260736962893, 14860.0, 13503.59489215325, 13650.522231721841, 13761.724765504834, 13836.723265534432, 14590.85409145895, 13117.672058198239, 13656.65858353549, 13920.373583188983, 13938.860611394162, 14657.413703451743, 13032.609021729364, 13570.660733481742, 13674.179549226681, 13737.969652275919, 13923.791143133063, 10952.726181845199, 11025.228234023542, 11496.712582185512, 11527.135464840338, 12018.939905300418, 9930.782446348936, 10462.63380781669, 10935.0, 11510.0, 11645.126615503701], "value": 7346.117328455222, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1620.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 119.0, 119.0, 141.0, 170.0, 155.0, 119.0, 117.0, 118.0, 117.0], "value": 129.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 148.0, 101.0, 181.0, 146.0, 147.0, 97.0, 137.0, 160.0, 146.0], "value": 143.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 211.0, 209.0, 202.0, 197.0, 196.0, 196.0, 197.0, 198.0, 193.0], "value": 200.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 59.0, 61.0, 83.0, 59.0, 62.0, 60.0, 76.0, 98.0, 73.0], "value": 71.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 71.0, 84.0, 81.0, 97.0, 104.0, 117.0, 71.0, 71.0, 84.0], "value": 87.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 117.0, 167.0, 156.0, 107.0, 137.0, 144.0, 171.0, 136.0, 111.0], "value": 138.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 114.0, 97.0, 98.0, 97.0, 105.0, 100.0, 96.0, 97.0, 119.0], "value": 107.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 105.0, 127.0, 102.0, 76.0, 132.0, 88.0, 101.0, 103.0, 135.0], "value": 105.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 60.0, 78.0, 87.0, 57.0, 58.0, 58.0, 58.0, 58.0, 59.0], "value": 63.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 76.0, 78.0, 83.0, 79.0, 76.0, 70.0, 71.0, 72.0, 74.0], "value": 74.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 87.0, 113.0, 112.0, 114.0, 82.0, 106.0, 108.0, 109.0], "value": 98.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 146.0, 157.0, 160.0, 157.0, 128.0, 150.0, 152.0, 194.0, 150.0], "value": 153.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 176.0, 174.0, 179.0, 178.0, 175.0, 174.0, 174.0, 175.0, 175.0], "value": 175.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 70.0, 84.0, 87.0, 74.0, 70.0, 69.0, 66.0, 66.0], "value": 72.2, "unit": "score"}]}]} 08:36:55 INFO - Return code: 0 08:36:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 08:36:55 ERROR - # TBPL SUCCESS # 08:36:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:36:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:36:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:36:55 INFO - 2016-12-16 08:36:55.682 screenresolution[1670:11019] starting screenresolution argv=screenresolution get 08:36:55 INFO - 2016-12-16 08:36:55.685 screenresolution[1670:11019] Display 0: 1600x1200x32@60 08:36:55 INFO - 2016-12-16 08:36:55.691 screenresolution[1671:11023] starting screenresolution argv=screenresolution list 08:36:55 INFO - Available Modes on Display 0 08:36:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:36:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:36:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:36:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:36:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:36:55 INFO - Intel Iris: 08:36:55 INFO - Chipset Model: Intel Iris 08:36:55 INFO - Type: GPU 08:36:55 INFO - Bus: Built-In 08:36:55 INFO - VRAM (Dynamic, Max): 1536 MB 08:36:55 INFO - Vendor: Intel (0x8086) 08:36:55 INFO - Device ID: 0x0a2e 08:36:55 INFO - Revision ID: 0x0009 08:36:55 INFO - Displays: 08:36:55 INFO - Display: 08:36:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:36:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:36:55 INFO - Display Serial Number: 1600x1200 60 08:36:55 INFO - Main Display: Yes 08:36:55 INFO - Mirror: Off 08:36:55 INFO - Online: Yes 08:36:55 INFO - Rotation: Supported 08:36:55 INFO - Return code: 0 08:36:55 INFO - Running post-action listener: _resource_record_post_action 08:36:55 INFO - [mozharness: 2016-12-16 16:36:55.926978Z] Finished run-tests step (success) 08:36:55 INFO - Running post-run listener: _resource_record_post_run 08:36:55 INFO - Total resource usage - Wall time: 1202s; CPU: 55.0%; Read bytes: 44712448; Write bytes: 562685952; Read time: 359; Write time: 1774 08:36:55 INFO - TinderboxPrint: CPU usage
54.5% 08:36:55 INFO - TinderboxPrint: I/O read bytes / time
44,712,448 / 359 08:36:55 INFO - TinderboxPrint: I/O write bytes / time
562,685,952 / 1,774 08:36:55 INFO - TinderboxPrint: CPU idle
2,186.2 (45.5%) 08:36:55 INFO - TinderboxPrint: CPU system
969.2 (20.2%) 08:36:55 INFO - TinderboxPrint: CPU user
1,654.2 (34.4%) 08:36:55 INFO - TinderboxPrint: Swap in / out
283,213,824 / 0 08:36:55 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 250432000; Write bytes: 250224640; Read time: 18109; Write time: 536 08:36:56 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 41542144; Write bytes: 310884352; Read time: 336; Write time: 1230 08:36:56 INFO - Running post-run listener: _upload_blobber_files 08:36:56 INFO - Blob upload gear active. 08:36:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:36:56 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:36:56 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 08:36:56 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 08:36:56 INFO - (blobuploader) - INFO - Open directory for files ... 08:36:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:36:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:36:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:36:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:36:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:36:57 INFO - (blobuploader) - INFO - Done attempting. 08:36:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:36:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:36:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:36:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:36:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:36:59 INFO - (blobuploader) - INFO - Done attempting. 08:36:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 08:36:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:36:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:37:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 08:37:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:37:01 INFO - (blobuploader) - INFO - Done attempting. 08:37:01 INFO - (blobuploader) - INFO - Iteration through files over. 08:37:01 INFO - Return code: 0 08:37:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:37:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:37:01 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/274e2878d04363511fffc4e5b0472c468b3335651515628ddfab09015e47ba92f88b9e30e585f5dd47d5eb84099e61d77ed5210244bde202d3460747ecd02cae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c2a7ad95880100fb46e46b38c5d8bcc3b848805d4f12ac757696117d0951af65ac460900a0e2f988a98b1f2ee787e8417ec8c103b53e0ef059a2695c306a936f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ace7cc6ad505d607487f13a0062879214f68d6cf61b53b9d70362c1ebae328f1f3f7bcb956509080ef1364381be14cd906f8c776b8ff0d738a0a4d02b3829df"} 08:37:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:37:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:37:01 INFO - Contents: 08:37:01 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/274e2878d04363511fffc4e5b0472c468b3335651515628ddfab09015e47ba92f88b9e30e585f5dd47d5eb84099e61d77ed5210244bde202d3460747ecd02cae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c2a7ad95880100fb46e46b38c5d8bcc3b848805d4f12ac757696117d0951af65ac460900a0e2f988a98b1f2ee787e8417ec8c103b53e0ef059a2695c306a936f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ace7cc6ad505d607487f13a0062879214f68d6cf61b53b9d70362c1ebae328f1f3f7bcb956509080ef1364381be14cd906f8c776b8ff0d738a0a4d02b3829df"} 08:37:01 INFO - Running post-run listener: copy_logs_to_upload_dir 08:37:01 INFO - Copying logs to upload dir... 08:37:01 INFO - mkdir: /builds/slave/test/build/upload/logs 08:37:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.622532 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-12-16 08:37:01.270242) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-16 08:37:01.274179) ========= 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.oiUOqrTqZr/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.2qC71lUWCj/Listeners TMPDIR=/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/274e2878d04363511fffc4e5b0472c468b3335651515628ddfab09015e47ba92f88b9e30e585f5dd47d5eb84099e61d77ed5210244bde202d3460747ecd02cae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c2a7ad95880100fb46e46b38c5d8bcc3b848805d4f12ac757696117d0951af65ac460900a0e2f988a98b1f2ee787e8417ec8c103b53e0ef059a2695c306a936f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ace7cc6ad505d607487f13a0062879214f68d6cf61b53b9d70362c1ebae328f1f3f7bcb956509080ef1364381be14cd906f8c776b8ff0d738a0a4d02b3829df"} build_url:https://queue.taskcluster.net/v1/task/GYK5r_y8S1m7GQ6G0JPOxw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010937 build_url: 'https://queue.taskcluster.net/v1/task/GYK5r_y8S1m7GQ6G0JPOxw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/274e2878d04363511fffc4e5b0472c468b3335651515628ddfab09015e47ba92f88b9e30e585f5dd47d5eb84099e61d77ed5210244bde202d3460747ecd02cae", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c2a7ad95880100fb46e46b38c5d8bcc3b848805d4f12ac757696117d0951af65ac460900a0e2f988a98b1f2ee787e8417ec8c103b53e0ef059a2695c306a936f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/7ace7cc6ad505d607487f13a0062879214f68d6cf61b53b9d70362c1ebae328f1f3f7bcb956509080ef1364381be14cd906f8c776b8ff0d738a0a4d02b3829df"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-16 08:37:01.313200) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 08:37:01.313589) ========= 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.oiUOqrTqZr/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.2qC71lUWCj/Listeners TMPDIR=/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/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.005172 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-16 08:37:01.362889) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-16 08:37:01.363224) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-16 08:37:01.363601) ========= ========= Total master_lag: 0.21 =========