builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0090 starttime: 1462963566.2 results: success (0) buildid: 20160510142413 builduid: 93e058acf8334e7aae447a8479c457fb revision: 1dd42a351054c5b697873d647169019d4d77d762 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 03:46:06.202942) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 03:46:06.203325) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 03:46:06.203598) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 03:46:06.222626) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 03:46:06.222932) ========= 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.CoGeYFnCDh/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.tStY6duBFS/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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-05-11 03:46:06-- 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: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 8.43M=0.001s 2016-05-11 03:46:06 (8.43 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.088134 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 03:46:06.327093) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 03:46:06.327398) ========= 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.CoGeYFnCDh/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.tStY6duBFS/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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.020309 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 03:46:06.375071) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-11 03:46:06.375376) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 1dd42a351054c5b697873d647169019d4d77d762 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 1dd42a351054c5b697873d647169019d4d77d762 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CoGeYFnCDh/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.tStY6duBFS/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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-05-11 03:46:06,441 truncating revision to first 12 chars 2016-05-11 03:46:06,441 Setting DEBUG logging. 2016-05-11 03:46:06,441 attempt 1/10 2016-05-11 03:46:06,441 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/1dd42a351054?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-11 03:46:07,692 unpacking tar archive at: mozilla-aurora-1dd42a351054/testing/mozharness/ program finished with exit code 0 elapsedTime=1.491959 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-11 03:46:07.885572) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 03:46:07.888908) ========= script_repo_revision: 1dd42a351054c5b697873d647169019d4d77d762 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 03:46:07.889287) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 03:46:07.889548) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 03:46:07.908383) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-05-11 03:46:07.908652) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CoGeYFnCDh/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.tStY6duBFS/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 03:46:08 INFO - MultiFileLogger online at 20160511 03:46:08 in /builds/slave/test 03:46:08 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:46:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:46:08 INFO - {'append_to_log': False, 03:46:08 INFO - 'base_work_dir': '/builds/slave/test', 03:46:08 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:46:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:46:08 INFO - 'branch': 'Mozilla-Aurora', 03:46:08 INFO - 'buildbot_json_path': 'buildprops.json', 03:46:08 INFO - 'config_files': ('talos/mac_config.py',), 03:46:08 INFO - 'default_actions': ('clobber', 03:46:08 INFO - 'read-buildbot-config', 03:46:08 INFO - 'download-and-extract', 03:46:08 INFO - 'populate-webroot', 03:46:08 INFO - 'create-virtualenv', 03:46:08 INFO - 'install', 03:46:08 INFO - 'run-tests'), 03:46:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:46:08 INFO - 'download_minidump_stackwalk': True, 03:46:08 INFO - 'download_symbols': 'ondemand', 03:46:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:46:08 INFO - 'tooltool.py': '/tools/tooltool.py', 03:46:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:46:08 INFO - '/tools/misc-python/virtualenv.py')}, 03:46:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:46:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:46:08 INFO - 'installer_path': 'installer.exe', 03:46:08 INFO - 'log_level': 'info', 03:46:08 INFO - 'log_name': 'talos', 03:46:08 INFO - 'log_to_console': True, 03:46:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:46:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:46:08 INFO - 'opt_config_files': (), 03:46:08 INFO - 'pip_index': False, 03:46:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:46:08 INFO - 'cmd': ('bash', 03:46:08 INFO - '-c', 03:46:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:46:08 INFO - 'enabled': True, 03:46:08 INFO - 'halt_on_failure': False, 03:46:08 INFO - 'name': 'check_screen_resolution'},), 03:46:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:46:08 INFO - 'cmd': ('bash', 03:46:08 INFO - '-c', 03:46:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:46:08 INFO - 'enabled': True, 03:46:08 INFO - 'halt_on_failure': False, 03:46:08 INFO - 'name': 'check_screen_resolution'},), 03:46:08 INFO - 'run_cmd_checks_enabled': True, 03:46:08 INFO - 'sps_profile': False, 03:46:08 INFO - 'sps_profile_interval': 0, 03:46:08 INFO - 'suite': 'dromaeojs-e10s', 03:46:08 INFO - 'system_bits': '32', 03:46:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:46:08 INFO - 'title': 't-yosemite-r7-0090', 03:46:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:46:08 INFO - 'use_talos_json': True, 03:46:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:46:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:46:08 INFO - 'work_dir': 'build'} 03:46:08 INFO - ##### 03:46:08 INFO - ##### Running clobber step. 03:46:08 INFO - ##### 03:46:08 INFO - Running pre-action listener: _resource_record_pre_action 03:46:08 INFO - Running main action method: clobber 03:46:08 INFO - rmtree: /builds/slave/test/build 03:46:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:46:09 INFO - Running post-action listener: _resource_record_post_action 03:46:09 INFO - ##### 03:46:09 INFO - ##### Running read-buildbot-config step. 03:46:09 INFO - ##### 03:46:09 INFO - Running pre-action listener: _resource_record_pre_action 03:46:09 INFO - Running main action method: read_buildbot_config 03:46:09 INFO - Using buildbot properties: 03:46:09 INFO - { 03:46:09 INFO - "project": "", 03:46:09 INFO - "product": "firefox", 03:46:09 INFO - "script_repo_revision": "production", 03:46:09 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 03:46:09 INFO - "repository": "", 03:46:09 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 03:46:09 INFO - "buildid": "20160510142413", 03:46:09 INFO - "pgo_build": "False", 03:46:09 INFO - "basedir": "/builds/slave/test", 03:46:09 INFO - "buildnumber": 290, 03:46:09 INFO - "slavename": "t-yosemite-r7-0090", 03:46:09 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 03:46:09 INFO - "platform": "yosemite_r7", 03:46:09 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:46:09 INFO - "branch": "mozilla-aurora", 03:46:09 INFO - "repo_path": "releases/mozilla-aurora", 03:46:09 INFO - "revision": "1dd42a351054c5b697873d647169019d4d77d762", 03:46:09 INFO - "stage_platform": "macosx64", 03:46:09 INFO - "builduid": "93e058acf8334e7aae447a8479c457fb", 03:46:09 INFO - "slavebuilddir": "test" 03:46:09 INFO - } 03:46:09 INFO - Found installer url https://queue.taskcluster.net/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 03:46:09 INFO - Running post-action listener: _resource_record_post_action 03:46:09 INFO - ##### 03:46:09 INFO - ##### Running download-and-extract step. 03:46:09 INFO - ##### 03:46:09 INFO - Running pre-action listener: _resource_record_pre_action 03:46:09 INFO - Running main action method: download_and_extract 03:46:09 INFO - mkdir: /builds/slave/test/build/tests 03:46:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:09 INFO - https://queue.taskcluster.net/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 03:46:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 03:46:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 03:46:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 03:46:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 03:46:11 INFO - Downloaded 1199 bytes. 03:46:11 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 03:46:11 INFO - Using the following test package requirements: 03:46:11 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 03:46:11 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:46:11 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 03:46:11 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:46:11 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 03:46:11 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:46:11 INFO - u'jsshell-mac.zip'], 03:46:11 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:46:11 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 03:46:11 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 03:46:11 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:46:11 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 03:46:11 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:46:11 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 03:46:11 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:46:11 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 03:46:11 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:46:11 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 03:46:11 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:46:11 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:11 INFO - https://queue.taskcluster.net/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:46:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:46:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:46:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:46:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:46:12 INFO - Downloaded 18089246 bytes. 03:46:12 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:46:12 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:46:15 INFO - Return code: 0 03:46:15 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 03:46: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:15 INFO - https://queue.taskcluster.net/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:46:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:46:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:46:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:46:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:46:16 INFO - Downloaded 18089246 bytes. 03:46:16 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:46:16 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:46:20 INFO - Return code: 0 03:46:20 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:20 INFO - https://queue.taskcluster.net/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 03:46:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 03:46:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 03:46:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 03:46:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 03:46:21 INFO - Downloaded 10840145 bytes. 03:46:21 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:46:21 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 03:46:22 INFO - Return code: 0 03:46:22 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:22 INFO - https://queue.taskcluster.net/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 03:46:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:46:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:46:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 03:46:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 03:46:25 INFO - Downloaded 114088564 bytes. 03:46:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:46:25 INFO - mkdir: /builds/slave/test/properties 03:46:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:46:25 INFO - Writing to file /builds/slave/test/properties/build_url 03:46:25 INFO - Contents: 03:46:25 INFO - build_url:https://queue.taskcluster.net/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:46:26 INFO - Running post-action listener: _resource_record_post_action 03:46:26 INFO - Running post-action listener: set_extra_try_arguments 03:46:26 INFO - ##### 03:46:26 INFO - ##### Running populate-webroot step. 03:46:26 INFO - ##### 03:46:26 INFO - Running pre-action listener: _resource_record_pre_action 03:46:26 INFO - Running main action method: populate_webroot 03:46:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:46:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:46:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:46:26 INFO - '--tppagecycles', 03:46:26 INFO - '7'], 03:46:26 INFO - 'tests': ['tsvgm']}}, 03:46:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:46:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:46:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:46:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:46:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:46:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:46:26 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:46:26 INFO - 'talos_options': ['--disable-e10s'], 03:46:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:46:26 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:46:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:46:26 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:46:26 INFO - 'talos_options': ['--disable-e10s'], 03:46:26 INFO - 'tests': ['damp', 'tps']}, 03:46:26 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:46:26 INFO - 'tests': ['damp', 'tps']}, 03:46:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:46:26 INFO - 'tests': ['dromaeo_dom']}, 03:46:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:46:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:46:26 INFO - 'tests': ['a11yr', 03:46:26 INFO - 'ts_paint', 03:46:26 INFO - 'tpaint', 03:46:26 INFO - 'sessionrestore', 03:46:26 INFO - 'sessionrestore_no_auto_restore', 03:46:26 INFO - 'tabpaint']}, 03:46:26 INFO - 'other-e10s': {'tests': ['a11yr', 03:46:26 INFO - 'ts_paint', 03:46:26 INFO - 'tpaint', 03:46:26 INFO - 'sessionrestore', 03:46:26 INFO - 'sessionrestore_no_auto_restore', 03:46:26 INFO - 'tabpaint']}, 03:46:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:46:26 INFO - 'ts_paint', 03:46:26 INFO - 'tpaint', 03:46:26 INFO - 'sessionrestore', 03:46:26 INFO - 'sessionrestore_no_auto_restore', 03:46:26 INFO - 'tabpaint']}, 03:46:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:46:26 INFO - 'ts_paint', 03:46:26 INFO - 'tpaint', 03:46:26 INFO - 'sessionrestore', 03:46:26 INFO - 'sessionrestore_no_auto_restore', 03:46:26 INFO - 'tabpaint']}, 03:46:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:46:26 INFO - 'tests': ['a11yr', 03:46:26 INFO - 'ts_paint', 03:46:26 INFO - 'tpaint', 03:46:26 INFO - 'sessionrestore', 03:46:26 INFO - 'sessionrestore_no_auto_restore', 03:46:26 INFO - 'tabpaint']}, 03:46:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:46:26 INFO - 'tests': ['a11yr', 03:46:26 INFO - 'ts_paint', 03:46:26 INFO - 'tpaint', 03:46:26 INFO - 'sessionrestore', 03:46:26 INFO - 'sessionrestore_no_auto_restore', 03:46:26 INFO - 'tabpaint']}, 03:46:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:46:26 INFO - 'tests': ['tsvgx', 03:46:26 INFO - 'tsvgr_opacity', 03:46:26 INFO - 'tart', 03:46:26 INFO - 'tscrollx', 03:46:26 INFO - 'cart']}, 03:46:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:46:26 INFO - 'tsvgr_opacity', 03:46:26 INFO - 'tart', 03:46:26 INFO - 'tscrollx', 03:46:26 INFO - 'cart']}, 03:46:26 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:46:26 INFO - 'talos_options': ['--disable-e10s'], 03:46:26 INFO - 'tests': ['tp5o']}, 03:46:26 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:46:26 INFO - 'tests': ['tp5o']}, 03:46:26 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:46:26 INFO - 'talos_options': ['--disable-e10s', 03:46:26 INFO - '--xperf_path', 03:46:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:46:26 INFO - 'tests': ['tp5n']}, 03:46:26 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:46:26 INFO - 'talos_options': ['--xperf_path', 03:46:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:46:26 INFO - 'tests': ['tp5n']}}, 03:46:26 INFO - 'talos.zip': {'path': '', 03:46:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:46:26 INFO - Running post-action listener: _resource_record_post_action 03:46:26 INFO - ##### 03:46:26 INFO - ##### Running create-virtualenv step. 03:46:26 INFO - ##### 03:46:26 INFO - Running pre-action listener: _resource_record_pre_action 03:46:26 INFO - Running main action method: create_virtualenv 03:46:26 INFO - Creating virtualenv /builds/slave/test/build/venv 03:46:26 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 03:46:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:46:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:46:26 INFO - Using real prefix '/tools/python27' 03:46:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:46:27 INFO - Installing distribute.............................................................................................................................................................................................done. 03:46:30 INFO - Installing pip.................done. 03:46:30 INFO - Return code: 0 03:46:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:46:30 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46:30 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46:30 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 0x1006f1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100701420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb94b4ec550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100590e00>, '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 0x1003f9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10058bb20>, '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.CoGeYFnCDh/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.tStY6duBFS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 03:46:30 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 03:46:30 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 03:46:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CoGeYFnCDh/Render', 03:46:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:46:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:46:30 INFO - 'HOME': '/Users/cltbld', 03:46:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:46:30 INFO - 'LOGNAME': 'cltbld', 03:46:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:30 INFO - 'MOZ_NO_REMOTE': '1', 03:46:30 INFO - 'NO_EM_RESTART': '1', 03:46:30 INFO - 'PAGER': '/bin/cat', 03:46:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:46:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:30 INFO - 'PWD': '/builds/slave/test', 03:46:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:46:30 INFO - 'SHELL': '/bin/bash', 03:46:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tStY6duBFS/Listeners', 03:46:30 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 03:46:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:46:30 INFO - 'USER': 'cltbld', 03:46:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:46:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:46:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:30 INFO - 'XPC_FLAGS': '0x0', 03:46:30 INFO - 'XPC_SERVICE_NAME': '0', 03:46:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:46:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:30 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 03:46:30 INFO - Cleaning up... 03:46:30 INFO - Return code: 0 03:46:30 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 03:46:30 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46:30 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46:30 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>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1006f1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100701420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb94b4ec550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100590e00>, '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 0x1003f9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10058bb20>, '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.CoGeYFnCDh/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.tStY6duBFS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 03:46:30 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>=0.7.1'] in /builds/slave/test/build 03:46:30 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>=0.7.1 03:46:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CoGeYFnCDh/Render', 03:46:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:46:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:46:30 INFO - 'HOME': '/Users/cltbld', 03:46:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:46:30 INFO - 'LOGNAME': 'cltbld', 03:46:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:30 INFO - 'MOZ_NO_REMOTE': '1', 03:46:30 INFO - 'NO_EM_RESTART': '1', 03:46:30 INFO - 'PAGER': '/bin/cat', 03:46:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:46:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:30 INFO - 'PWD': '/builds/slave/test', 03:46:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:46:30 INFO - 'SHELL': '/bin/bash', 03:46:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tStY6duBFS/Listeners', 03:46:30 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 03:46:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:46:30 INFO - 'USER': 'cltbld', 03:46:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:46:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:46:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:30 INFO - 'XPC_FLAGS': '0x0', 03:46:30 INFO - 'XPC_SERVICE_NAME': '0', 03:46:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:46:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:30 INFO - Downloading/unpacking psutil>=0.7.1 03:46:30 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 03:46:30 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 03:46:30 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 03:46:30 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 03:46:30 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 03:46:30 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 03:46:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:46:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:46:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:46:33 INFO - Installing collected packages: psutil 03:46:33 INFO - Running setup.py install for psutil 03:46:33 INFO - building 'psutil._psutil_osx' extension 03:46:33 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 03:46:33 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 03:46:33 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 03:46:34 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 03:46:34 INFO - building 'psutil._psutil_posix' extension 03:46:34 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 03:46:34 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:46:34 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:46:34 INFO - ^ 03:46:34 INFO - 1 warning generated. 03:46:34 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 03:46:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:46:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:46:34 INFO - Successfully installed psutil 03:46:34 INFO - Cleaning up... 03:46:34 INFO - Return code: 0 03:46:34 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 03:46:34 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46:34 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46:34 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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1006f1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100701420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb94b4ec550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100590e00>, '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 0x1003f9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10058bb20>, '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.CoGeYFnCDh/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.tStY6duBFS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 03:46:34 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.0.0'] in /builds/slave/test/build 03:46:34 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.0.0 03:46:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CoGeYFnCDh/Render', 03:46:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:46:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:46:34 INFO - 'HOME': '/Users/cltbld', 03:46:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:46:34 INFO - 'LOGNAME': 'cltbld', 03:46:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:34 INFO - 'MOZ_NO_REMOTE': '1', 03:46:34 INFO - 'NO_EM_RESTART': '1', 03:46:34 INFO - 'PAGER': '/bin/cat', 03:46:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:46:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:34 INFO - 'PWD': '/builds/slave/test', 03:46:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:46:34 INFO - 'SHELL': '/bin/bash', 03:46:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tStY6duBFS/Listeners', 03:46:34 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 03:46:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:46:34 INFO - 'USER': 'cltbld', 03:46:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:46:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:46:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:34 INFO - 'XPC_FLAGS': '0x0', 03:46:34 INFO - 'XPC_SERVICE_NAME': '0', 03:46:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:46:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:34 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 03:46:34 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 03:46:34 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 03:46:34 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 03:46:34 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 03:46:34 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 03:46:34 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 03:46:36 INFO - Downloading mozsystemmonitor-0.0.tar.gz 03:46:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:46:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 03:46:37 INFO - Installing collected packages: mozsystemmonitor 03:46:37 INFO - Running setup.py install for mozsystemmonitor 03:46:37 INFO - Successfully installed mozsystemmonitor 03:46:37 INFO - Cleaning up... 03:46:37 INFO - Return code: 0 03:46:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:46:37 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46:37 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46:37 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 0x1006f1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100701420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb94b4ec550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100590e00>, '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 0x1003f9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10058bb20>, '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.CoGeYFnCDh/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.tStY6duBFS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 03:46:37 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 03:46:37 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 03:46:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CoGeYFnCDh/Render', 03:46:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:46:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:46:37 INFO - 'HOME': '/Users/cltbld', 03:46:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:46:37 INFO - 'LOGNAME': 'cltbld', 03:46:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:37 INFO - 'MOZ_NO_REMOTE': '1', 03:46:37 INFO - 'NO_EM_RESTART': '1', 03:46:37 INFO - 'PAGER': '/bin/cat', 03:46:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:46:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:37 INFO - 'PWD': '/builds/slave/test', 03:46:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:46:37 INFO - 'SHELL': '/bin/bash', 03:46:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tStY6duBFS/Listeners', 03:46:37 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 03:46:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:46:37 INFO - 'USER': 'cltbld', 03:46:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:46:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:46:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:37 INFO - 'XPC_FLAGS': '0x0', 03:46:37 INFO - 'XPC_SERVICE_NAME': '0', 03:46:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:46:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:37 INFO - Downloading/unpacking blobuploader==1.2.4 03:46:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:46:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:46:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:39 INFO - Downloading blobuploader-1.2.4.tar.gz 03:46:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:46:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:46: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 03:46: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 03:46: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 03:46: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 03:46: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 03:46: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 03:46:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:46:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:46: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 03:46: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 03:46: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 03:46: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 03:46: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 03:46: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 03:46:41 INFO - Downloading docopt-0.6.1.tar.gz 03:46:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:46:41 INFO - Installing collected packages: blobuploader, requests, docopt 03:46:41 INFO - Running setup.py install for blobuploader 03:46:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:46:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:46:41 INFO - Running setup.py install for requests 03:46:41 INFO - Running setup.py install for docopt 03:46:42 INFO - Successfully installed blobuploader requests docopt 03:46:42 INFO - Cleaning up... 03:46:42 INFO - Return code: 0 03:46:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:46: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46: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 0x1006f1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100701420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb94b4ec550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100590e00>, '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 0x1003f9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10058bb20>, '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.CoGeYFnCDh/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.tStY6duBFS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 03:46: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 03:46: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 03:46:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CoGeYFnCDh/Render', 03:46:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:46:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:46:42 INFO - 'HOME': '/Users/cltbld', 03:46:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:46:42 INFO - 'LOGNAME': 'cltbld', 03:46:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:42 INFO - 'MOZ_NO_REMOTE': '1', 03:46:42 INFO - 'NO_EM_RESTART': '1', 03:46:42 INFO - 'PAGER': '/bin/cat', 03:46:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:46:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:42 INFO - 'PWD': '/builds/slave/test', 03:46:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:46:42 INFO - 'SHELL': '/bin/bash', 03:46:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tStY6duBFS/Listeners', 03:46:42 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 03:46:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:46:42 INFO - 'USER': 'cltbld', 03:46:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:46:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:46:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:42 INFO - 'XPC_FLAGS': '0x0', 03:46:42 INFO - 'XPC_SERVICE_NAME': '0', 03:46:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:46:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:46:42 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-Kh7TGw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:46:42 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-1l4TsR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:46:42 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-MkweUF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:46:42 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-c696CB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:46:42 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-QQ0llm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:46:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:46:42 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-rKIHGc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:46:43 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-xpKDpF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:46:43 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-iwn5qu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:46:43 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-xlV4AN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:46:43 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-y9_JPP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:46:43 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-jSVDwM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:46:43 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-59bvwS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:46:43 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-D1Rxdo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:46:43 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-lKHFdq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:46:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:46:43 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-fPpUAG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:46:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:46:44 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-OOyB15-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:46:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:46:44 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-DnIJBp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:46:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:46:44 INFO - Running setup.py install for manifestparser 03:46:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:46:44 INFO - Running setup.py install for mozcrash 03:46:44 INFO - Running setup.py install for mozdebug 03:46:44 INFO - Running setup.py install for mozdevice 03:46:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:46:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:46:44 INFO - Running setup.py install for mozfile 03:46:44 INFO - Running setup.py install for mozhttpd 03:46:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:46:45 INFO - Running setup.py install for mozinfo 03:46:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:46:45 INFO - Running setup.py install for mozInstall 03:46:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:46:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:46:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:46:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:46:45 INFO - Running setup.py install for mozleak 03:46:45 INFO - Running setup.py install for mozlog 03:46:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:46:45 INFO - Running setup.py install for moznetwork 03:46:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:46:45 INFO - Running setup.py install for mozprocess 03:46:46 INFO - Running setup.py install for mozprofile 03:46:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:46:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:46:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:46:46 INFO - Running setup.py install for mozrunner 03:46:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:46:46 INFO - Running setup.py install for mozscreenshot 03:46:46 INFO - Running setup.py install for moztest 03:46:46 INFO - Running setup.py install for mozversion 03:46:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:46:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:46:46 INFO - Cleaning up... 03:46:46 INFO - Return code: 0 03:46:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:46:46 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46:46 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46:46 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 0x1006f1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100701420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb94b4ec550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100590e00>, '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 0x1003f9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10058bb20>, '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.CoGeYFnCDh/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.tStY6duBFS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 03:46:46 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 03:46:46 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 03:46:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CoGeYFnCDh/Render', 03:46:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:46:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:46:46 INFO - 'HOME': '/Users/cltbld', 03:46:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:46:46 INFO - 'LOGNAME': 'cltbld', 03:46:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:46 INFO - 'MOZ_NO_REMOTE': '1', 03:46:46 INFO - 'NO_EM_RESTART': '1', 03:46:46 INFO - 'PAGER': '/bin/cat', 03:46:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:46:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:46 INFO - 'PWD': '/builds/slave/test', 03:46:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:46:46 INFO - 'SHELL': '/bin/bash', 03:46:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tStY6duBFS/Listeners', 03:46:46 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 03:46:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:46:46 INFO - 'USER': 'cltbld', 03:46:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:46:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:46:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:46 INFO - 'XPC_FLAGS': '0x0', 03:46:46 INFO - 'XPC_SERVICE_NAME': '0', 03:46:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:46:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:46:47 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-AT7dXY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:46: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)) 03:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:46:47 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-Zpi4bn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:46:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 03:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:46:47 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-afo5rm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:46:47 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)) 03:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:46:47 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-t4eV_y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:46:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:46:47 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-Ugqz4C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:46:47 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)) 03:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:46:47 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-E_YxgK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:46:47 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)) 03:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:46:47 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-vKMrbO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:46:47 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)) 03:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:46:47 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-atJ3nk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:46:47 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)) 03:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:46:47 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-ReOISY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:46: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)) 03:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:46:48 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-w6uosJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:46:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 03:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:46:48 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-ELydtc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:46: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)) 03:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:46:48 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-ttf_La-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:46:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 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)) 03:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:46:48 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-qSm3DM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:46:48 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)) 03:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:46:48 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-85BYeh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:46:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 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)) 03:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:46:48 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-C5afEI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:46:48 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)) 03:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:46:48 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-WqPchK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:46:48 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)) 03:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:46:48 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-ANJ_P6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:46:48 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)) 03:46:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:46:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:46:48 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)) 03:46:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:46:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:46:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:46:48 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 03:46:48 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 03:46:48 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 03:46:48 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 03:46:48 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 03:46:48 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 03:46:51 INFO - Downloading blessings-1.6.tar.gz 03:46:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:46:51 INFO - Installing collected packages: blessings 03:46:51 INFO - Running setup.py install for blessings 03:46:51 INFO - Successfully installed blessings 03:46:51 INFO - Cleaning up... 03:46:51 INFO - Return code: 0 03:46:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:46:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:46:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:46:51 INFO - Reading from file tmpfile_stdout 03:46:51 INFO - Current package versions: 03:46:51 INFO - blessings == 1.6 03:46:51 INFO - blobuploader == 1.2.4 03:46:51 INFO - docopt == 0.6.1 03:46:51 INFO - manifestparser == 1.1 03:46:51 INFO - mozInstall == 1.12 03:46:51 INFO - mozcrash == 0.17 03:46:51 INFO - mozdebug == 0.1 03:46:51 INFO - mozdevice == 0.48 03:46:51 INFO - mozfile == 1.2 03:46:51 INFO - mozhttpd == 0.7 03:46:51 INFO - mozinfo == 0.9 03:46:51 INFO - mozleak == 0.1 03:46:51 INFO - mozlog == 3.1 03:46:51 INFO - moznetwork == 0.27 03:46:51 INFO - mozprocess == 0.22 03:46:51 INFO - mozprofile == 0.28 03:46:51 INFO - mozrunner == 6.11 03:46:51 INFO - mozscreenshot == 0.1 03:46:51 INFO - mozsystemmonitor == 0.0 03:46:51 INFO - moztest == 0.7 03:46:51 INFO - mozversion == 1.4 03:46:51 INFO - psutil == 3.1.1 03:46:51 INFO - requests == 1.2.3 03:46:51 INFO - wsgiref == 0.1.2 03:46:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:46:51 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46:51 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46:51 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 0x1006f1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100701420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb94b4ec550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100590e00>, '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 0x1003f9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10058bb20>, '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.CoGeYFnCDh/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.tStY6duBFS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 03:46:51 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 03:46:51 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 03:46:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CoGeYFnCDh/Render', 03:46:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:46:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:46:51 INFO - 'HOME': '/Users/cltbld', 03:46:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:46:51 INFO - 'LOGNAME': 'cltbld', 03:46:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:51 INFO - 'MOZ_NO_REMOTE': '1', 03:46:51 INFO - 'NO_EM_RESTART': '1', 03:46:51 INFO - 'PAGER': '/bin/cat', 03:46:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:46:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:51 INFO - 'PWD': '/builds/slave/test', 03:46:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:46:51 INFO - 'SHELL': '/bin/bash', 03:46:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tStY6duBFS/Listeners', 03:46:51 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 03:46:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:46:51 INFO - 'USER': 'cltbld', 03:46:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:46:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:46:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:51 INFO - 'XPC_FLAGS': '0x0', 03:46:51 INFO - 'XPC_SERVICE_NAME': '0', 03:46:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:46:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:52 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)) 03:46:52 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)) 03:46:52 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)) 03:46:52 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)) 03:46:52 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)) 03:46:52 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)) 03:46:52 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)) 03:46:52 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)) 03:46:52 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)) 03:46:52 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)) 03:46:52 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)) 03:46:52 INFO - Cleaning up... 03:46:52 INFO - Return code: 0 03:46:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:46: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46:52 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 0x1006f1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100701420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb94b4ec550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100590e00>, '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 0x1003f9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10058bb20>, '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.CoGeYFnCDh/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.tStY6duBFS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 03:46:52 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 03:46:52 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 03:46:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CoGeYFnCDh/Render', 03:46:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:46:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:46:52 INFO - 'HOME': '/Users/cltbld', 03:46:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:46:52 INFO - 'LOGNAME': 'cltbld', 03:46:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:52 INFO - 'MOZ_NO_REMOTE': '1', 03:46:52 INFO - 'NO_EM_RESTART': '1', 03:46:52 INFO - 'PAGER': '/bin/cat', 03:46:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:46:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:52 INFO - 'PWD': '/builds/slave/test', 03:46:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:46:52 INFO - 'SHELL': '/bin/bash', 03:46:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tStY6duBFS/Listeners', 03:46:52 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 03:46:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:46:52 INFO - 'USER': 'cltbld', 03:46:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:46:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:46:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:52 INFO - 'XPC_FLAGS': '0x0', 03:46:52 INFO - 'XPC_SERVICE_NAME': '0', 03:46:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:46:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:52 INFO - Downloading/unpacking jsonschema 03:46:52 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 03:46:52 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 03:46:52 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 03:46:52 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 03:46:52 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 03:46:52 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 03:46:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:46:54 INFO - Downloading/unpacking functools32 (from jsonschema) 03:46:54 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 03:46:54 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 03:46:54 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 03:46:54 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 03:46:54 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 03:46:54 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 03:46:55 INFO - Downloading functools32-3.2.3-2.tar.gz 03:46:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:46:55 INFO - warning: no files found matching '*.txt' 03:46:55 INFO - no previously-included directories found matching 'build' 03:46:55 INFO - no previously-included directories found matching 'dist' 03:46:55 INFO - no previously-included directories found matching '.git*' 03:46:55 INFO - Installing collected packages: jsonschema, functools32 03:46:55 INFO - Running setup.py install for functools32 03:46:55 INFO - warning: no files found matching '*.txt' 03:46:55 INFO - no previously-included directories found matching 'build' 03:46:55 INFO - no previously-included directories found matching 'dist' 03:46:55 INFO - no previously-included directories found matching '.git*' 03:46:55 INFO - Successfully installed jsonschema functools32 03:46:55 INFO - Cleaning up... 03:46:55 INFO - Return code: 0 03:46:55 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:46:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1006f1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100701420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb94b4ec550>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100590e00>, '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 0x1003f9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10058bb20>, '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.CoGeYFnCDh/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.tStY6duBFS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 03:46:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 03:46:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 03:46:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CoGeYFnCDh/Render', 03:46:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:46:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:46:55 INFO - 'HOME': '/Users/cltbld', 03:46:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:46:55 INFO - 'LOGNAME': 'cltbld', 03:46:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:55 INFO - 'MOZ_NO_REMOTE': '1', 03:46:55 INFO - 'NO_EM_RESTART': '1', 03:46:55 INFO - 'PAGER': '/bin/cat', 03:46:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:46:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:55 INFO - 'PWD': '/builds/slave/test', 03:46:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:46:55 INFO - 'SHELL': '/bin/bash', 03:46:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tStY6duBFS/Listeners', 03:46:55 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 03:46:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:46:55 INFO - 'USER': 'cltbld', 03:46:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:46:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:46:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:55 INFO - 'XPC_FLAGS': '0x0', 03:46:55 INFO - 'XPC_SERVICE_NAME': '0', 03:46:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:46:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:55 INFO - Downloading/unpacking flake8 03:46:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:46:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:46:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:58 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:46:58 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:46:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:46:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:46:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:58 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:46:58 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:46:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:46:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:46:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:59 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:46:59 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 03:46:59 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 03:46:59 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 03:46:59 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 03:46:59 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 03:46:59 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 03:46:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:46:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:46:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:46:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:46:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:46:59 INFO - no previously-included directories found matching 'docs/_build' 03:46:59 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:46:59 INFO - Running setup.py install for pep8 03:46:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:46:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:46:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:46:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:46:59 INFO - no previously-included directories found matching 'docs/_build' 03:46:59 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:46:59 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:46:59 INFO - Cleaning up... 03:46:59 INFO - Return code: 0 03:46:59 INFO - Running post-action listener: _resource_record_post_action 03:46:59 INFO - Running post-action listener: _start_resource_monitoring 03:46:59 INFO - Starting resource monitoring. 03:46:59 INFO - ##### 03:46:59 INFO - ##### Running install step. 03:46:59 INFO - ##### 03:46:59 INFO - Running pre-action listener: _resource_record_pre_action 03:46:59 INFO - Running main action method: install 03:46:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:46:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:47:00 INFO - Reading from file tmpfile_stdout 03:47:00 INFO - Detecting whether we're running mozinstall >=1.0... 03:47:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:47:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:47:00 INFO - Reading from file tmpfile_stdout 03:47:00 INFO - Output received: 03:47:00 INFO - Usage: mozinstall [options] installer 03:47:00 INFO - Options: 03:47:00 INFO - -h, --help show this help message and exit 03:47:00 INFO - -d DEST, --destination=DEST 03:47:00 INFO - Directory to install application into. [default: 03:47:00 INFO - "/builds/slave/test"] 03:47:00 INFO - --app=APP Application being installed. [default: firefox] 03:47:00 INFO - mkdir: /builds/slave/test/build/application 03:47:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:47:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 03:47:32 INFO - Reading from file tmpfile_stdout 03:47:32 INFO - Output received: 03:47:32 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:47:32 INFO - Running post-action listener: _resource_record_post_action 03:47:32 INFO - ##### 03:47:32 INFO - ##### Running run-tests step. 03:47:32 INFO - ##### 03:47:32 INFO - Running pre-action listener: _resource_record_pre_action 03:47:32 INFO - Running main action method: run_tests 03:47:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:47:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:47:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:47:32 INFO - 2016-05-11 03:47:32.058 screenresolution[1940:13519] starting screenresolution argv=screenresolution get 03:47:32 INFO - 2016-05-11 03:47:32.061 screenresolution[1940:13519] Display 0: 1600x1200x32@60 03:47:32 INFO - 2016-05-11 03:47:32.067 screenresolution[1941:13522] starting screenresolution argv=screenresolution list 03:47:32 INFO - Available Modes on Display 0 03:47:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:47:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:47:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:47:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:47:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:47:32 INFO - Intel Iris: 03:47:32 INFO - Chipset Model: Intel Iris 03:47:32 INFO - Type: GPU 03:47:32 INFO - Bus: Built-In 03:47:32 INFO - VRAM (Dynamic, Max): 1536 MB 03:47:32 INFO - Vendor: Intel (0x8086) 03:47:32 INFO - Device ID: 0x0a2e 03:47:32 INFO - Revision ID: 0x0009 03:47:32 INFO - Displays: 03:47:32 INFO - Display: 03:47:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:47:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:47:32 INFO - Display Serial Number: 1600x1200 60 03:47:32 INFO - Main Display: Yes 03:47:32 INFO - Mirror: Off 03:47:32 INFO - Online: Yes 03:47:32 INFO - Rotation: Supported 03:47:32 INFO - Return code: 0 03:47:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:47:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:47:32 INFO - Python 2.7.3 03:47:32 INFO - Return code: 0 03:47:32 INFO - grabbing minidump binary from tooltool 03:47: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:47:32 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: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100590e00>, '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 0x1003f9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10058bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:47:32 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 03:47:32 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 03:47:32 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:47:32 INFO - Return code: 0 03:47:32 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:47:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:47:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:47:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:47:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:47:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:47:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:47:33 INFO - Return code: 0 03:47:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0090', '--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 03:47:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0090 --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 03:47:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CoGeYFnCDh/Render', 03:47:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:47:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:47:33 INFO - 'HOME': '/Users/cltbld', 03:47:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:47:33 INFO - 'LOGNAME': 'cltbld', 03:47:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:47:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:47:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:33 INFO - 'MOZ_NO_REMOTE': '1', 03:47:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:47:33 INFO - 'NO_EM_RESTART': '1', 03:47:33 INFO - 'PAGER': '/bin/cat', 03:47:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:47:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:47:33 INFO - 'PWD': '/builds/slave/test', 03:47:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:47:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:47:33 INFO - 'SHELL': '/bin/bash', 03:47:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tStY6duBFS/Listeners', 03:47:33 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 03:47:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:47:33 INFO - 'USER': 'cltbld', 03:47:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:47:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:47:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:33 INFO - 'XPC_FLAGS': '0x0', 03:47:33 INFO - 'XPC_SERVICE_NAME': '0', 03:47:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:47:33 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0090', '--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 03:47:33 INFO - mozversion application_buildid: 20160510142413 03:47:33 INFO - mozversion application_changeset: 1dd42a351054c5b697873d647169019d4d77d762 03:47:33 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:47:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:47:33 INFO - mozversion application_name: Firefox 03:47:33 INFO - mozversion application_remotingname: firefox-dev 03:47:33 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:47:33 INFO - mozversion application_vendor: Mozilla 03:47:33 INFO - mozversion application_version: 48.0a2 03:47:33 INFO - mozversion platform_buildid: 20160510142413 03:47:33 INFO - mozversion platform_changeset: 1dd42a351054c5b697873d647169019d4d77d762 03:47:33 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:47:33 INFO - mozversion platform_version: 48.0a2 03:47:33 INFO - using testdate: 1462963653 03:47:33 INFO - actual date: 1462963653 03:47:33 INFO - starting webserver on 'localhost:51167' 03:47:33 INFO - SUITE-START | Running 2 tests 03:47:33 INFO - TEST-START | dromaeo_css 03:47:33 INFO - operating with platform_type : mac_ 03:47:33 INFO - Initialising browser for dromaeo_css test... 03:47:33 INFO - TEST-INFO | started process 1959 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/tmp282aHX/profile http://localhost:51167/getInfo.html) 03:47:36 INFO - PROCESS | 1959 | __metrics Screen width/height:1600/1200 03:47:36 INFO - PROCESS | 1959 | colorDepth:24 03:47:36 INFO - PROCESS | 1959 | Browser inner width/height: 1024/689 03:47:36 INFO - PROCESS | 1959 | __metrics 03:47:36 ERROR - PROCESS | 1959 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 03:47:37 INFO - TEST-INFO | 1959: exit 0 03:47:37 INFO - Browser initialized. 03:47:37 INFO - Running cycle 1/1 for dromaeo_css test... 03:47:37 INFO - TEST-INFO | started process 1962 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/tmp282aHX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:47:49 INFO - PROCESS | 1962 | RSS: Main: 220979200 03:47:49 INFO - PROCESS | 1962 | 03:48:38 INFO - PROCESS | 1962 | 1462963718771 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] 03:50:50 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:51167/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51167/tests/dromaeo/cssquery-ext.html) 03:50:51 INFO - PROCESS | 1962 | RSS: Main: 231305216 03:50:51 INFO - PROCESS | 1962 | 03:53:54 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:51167/tests/dromaeo/cssquery-ext.html (next: http://localhost:51167/tests/dromaeo/cssquery-jquery.html) 03:53:54 INFO - PROCESS | 1962 | RSS: Main: 243142656 03:53:54 INFO - PROCESS | 1962 | 03:56:56 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:51167/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51167/tests/dromaeo/cssquery-mootools.html) 03:56:57 INFO - PROCESS | 1962 | RSS: Main: 245121024 03:56:57 INFO - PROCESS | 1962 | 03:59:58 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:51167/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51167/tests/dromaeo/cssquery-prototype.html) 03:59:59 INFO - PROCESS | 1962 | RSS: Main: 247554048 03:59:59 INFO - PROCESS | 1962 | 04:03:02 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:51167/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51167/tests/dromaeo/cssquery-yui.html) 04:03:02 INFO - PROCESS | 1962 | RSS: Main: 249602048 04:03:02 INFO - PROCESS | 1962 | 04:06:05 INFO - PROCESS | 1962 | Cycle 1(1): loaded http://localhost:51167/tests/dromaeo/cssquery-yui.html (next: http://localhost:51167/tests/dromaeo/cssquery-dojo.html) 04:06:05 INFO - PROCESS | 1962 | RSS: Main: 250687488 04:06:05 INFO - PROCESS | 1962 | 04:06:05 INFO - PROCESS | 1962 | __start_tp_report 04:06:05 INFO - PROCESS | 1962 | _x_x_mozilla_page_load 04:06:05 INFO - PROCESS | 1962 | _x_x_mozilla_page_load_details 04:06:05 INFO - PROCESS | 1962 | |i|pagename|runs| 04:06:05 INFO - PROCESS | 1962 | |0;dojo.html;3455.6026057003446;3472.2534655049167;3515.7187425006;3517.4196257617496;3550.6981906537944;5813.040848260042;5836.9124463132985;5854.063349864023;5855.32663743669;5862.530997520199;5831.567849292307;5838.970805145979;5859.648421094737;5879.441453061952;5927.88144237115;5252.921206181911;5253.763580638871;5307.230451584518;5332.973335133319;5346.144616861303;5216.139337009407;5302.072137375943;5317.8936421271555;5318;5371.946280537203;5315;5326.573874090082;5330.573554115662;5357;5371.946280537184;5284.630075894689;5322.76047577858;5331.253624492574;5363.115086010804;5365.919511207335;5138.922916156261;5151.330327057459;5231.398389185254;5253.474652534755;5257.447967963347;5589.413111623302;5710.7430165642645;5731;5765.740541675635;5813.360530341659;5286.784039670859;6201.5348848836065;6241.313455519889;6271.968640156816;6282.089097080943;6275.937240627627;6301.117843501914;6321.241451025906;6322.462590679797;6350.920343541609;4499.662525310615;4984.053029924303;5985.40145985398;6019.518438524908;6044.939550604482;4810.326554282403;6305.873882522324;6410.326915673787;6444.2589102252905;6453.354664533509;6216.160818289563;6244.219472565929;6251.343608921089;6254.093156492283;6263.655498947511;6282.842928926722;6313.96843015782;6320.936790632127;6337.33457986914;6341.968290158519;5505.284313039279;6014.827108713759;6129.267100814801;6176.3514830943;6224.253089629183;5550.333959924835;5892.356345844061;6176.845578860474;6250.749970001149;6254.2807577129215;3762.6049264826797;4985.102681517289;5047.848564543069;5049.798008079636;5073.619478539124;4395.1868904252815;4427.092446048552;4510.864674059783;4516.412866327357;4546.65900057497;5861.267341582302;5901.321348044944;5917.763289468459;5944.881102377928;6024.57827952048;5604;6310.305866354697;6339.4611458025665;6353.205849268841;6376.521760867953;5893.882122357529;5931;5935.495482883917;5983.19226904371;6012.39876012404;5462;5484.890302193934;5521.00621888064;5532.2531458253425;5537.224788529609;5383.842473868215;5501.762103526675;6043.456088952015;6414.711337989802;6483.578567393104;5717.113847353667;6233.719482623203;6289.308176100717;6314.179156709783;6323.747050117944;6470.094186813756;6474.449671777952;6486.675666216576;6501;6502.219733631994;5673.801416950387;6185.917464443614;6267.749290028348;6275.121482992476;6281.497480201481;6204.472619827366;6279.591826531261;6294.6852657366035;6295.276043255084;6314.968425157845;6155.1382806406245;6203.8138855834395;6213.285472170758;6232.501399887908;6237.039479930368;5148.253503241934;5988.940110598838;6045.909311360245;6081.209442772411;6119.715820550657;4824.614030877591;6113.113598528277;6129.544705212454;6156.938430615636;6177.81466556004;6224.346443623535;6227.501799856092;6230.283517395557;6232.220972378453;6240;5025.84922452327;5967.791127310524;6098.115773212948;6141.723622437091;6159.476444502267 04:06:05 INFO - PROCESS | 1962 | |1;ext.html;489.5447234072311;512.3083836820292;567.4325674325675;609.7652403824526;624.2259598098359;31460.842695786545;38714.64498742545;38782.61217387825;38808.61191388085;38935.99999999996;39299.803500982525;39858;39890.40164397536;39926.401103983466;39937.40093898594;9353.438793672385;9546.427214367126;9555.474448905308;9607.375520591175;9626.470544120106;9400;9612.086851749073;9650.662226822064;9672.758181045496;9675.129238368545;9612.18296444803;9643.469609171492;9675.951620241889;9676.564554595025;9694.030596940285;9610.183134433546;9666;9671.90328096717;9685;9716.31985760997;9422;9464.668736594185;9556.856647150298;9561.904380956172;9578.473183974878;38556.614433855866;38703.806480967694;38777.61222387768;38842.80578597117;39352.80323598392;2204.206485665159;2562.910298139575;3012.7439167670773;3035.7874948753597;3183.6338821035647;28165.57751633727;28647.856760716273;28687.856560717064;29500.8524957376;29759.25601859949;2718.2796558911905;2762.6822915364787;2785.456835916997;2855.1006432973545;2878.0070875547904;334468;338188;340323;340635.2968235168;341100;35902.28195436077;36389;36613.26773464516;61216.08175877365;61594.69202653958;35242.47136292957;35736.46395304072;35840.462393064125;35960.82019589885;35991.4601280981;13238.602841914759;15470;15559;15790;16033.118178500294;13145.60563183106;13246.40391182399;13262.668433289246;13266.07137500385;13269;8035;8239.711610093618;8257.504549727035;8266.214709602578;8300.916990830136;3320.4189266878197;3439.105832483572;3478.0783092480515;3481.1201950946206;3487.5989261234777;774.4501404003221;960.6973803251954;965.3918031640022;976.998576459135;1104.4864138175788;27688.861555692092;28504.002359917304;28589.857050714614;28648.570271445944;29077.38303552648;859.2610355094738;874.9518776467355;977.0766625538939;1000.5447521377756;1056.054830926332;14578.708425831424;14578.927105364406;14658.780118298235;15089;16345.836541634431;618776;1006030;1022084;1023060;1024852;61255;61368;61430;62200.37799621946;62260;1346.4344975110478;1522.2540954932224;1526.5954522051666;1533.4479587348546;1533.463287849244;28672.569911451763;29951;30366.848165759915;30629.23426914345;30830.53754193644;43866.56133438625;52258.73870630775;52600;52993.73503132614;53301.73349133229;599082;600780;602925;607384;607974;22419.43951401228;26920;27552;27847.16458506248;28220.85889570539;44932.550674492835;44947.77526112348;46078.769606153095;46328.53671463242;46506.604801855996;37410.43884341683;37453.62546374501;37658.62341376551;37851.810740946115;38260.808695956344;33344.99965001054;36936.26127477382;37198.44202337021;37379.626203738706;37875;30189.245268867577;34883;36323.45514817333;37021.81489092537;37122.70070547626;29824.701752982193;30122.69877301199;30640;30723.231919201306;30845.537316939815;19920;20656.79343206609;22739.886300568392;23539.764602353756;23924 04:06:05 INFO - PROCESS | 1962 | |2;jquery.html;354.82790846439474;406.8067667857768;412.0543353004854;412.9359949207874;418.80106949199137;15149.5633678345;16139.919300403482;18499.722504162433;19777.208911643535;20432.489187770312;20281.69577456339;20449.488762780937;20688.689669654967;21065.789342106575;21674.24140155095;5647.887042259173;5830.621009634381;6106.542009349294;6108.908366374508;6127.35662755411;5576.972115139419;5891.764329426839;5929.229200203968;5942.732577034022;5947.6134051286745;5830.679312637803;5876.9118463223085;5925.229720136376;5926.99241129007;5935.436133567304;5251;5343.973280133594;6067.120267561223;6095.725692343877;6106.755729770804;5914.733836977303;5921.111833225051;5921.792737254218;5926.259217597801;5928.140419639129;19512.609747804963;25288;25944.221673349828;26120.34699132518;26300.605490917653;2088.718023066882;2220.655798351258;2589.795744978594;2669.506141363854;2732.4808286425514;9875.40747555149;10205.48972551377;10682.412467314294;11609.477573509212;15203.31585078663;1793.99536259695;1833.3949796567101;1845.732368806529;1859.6745569525278;1898.6677331466938;111115.44442277736;114092;117801.41099294534;118420;118954.40522797417;11682.70793230176;11903.702407439883;11947.940260298818;11952.223105498115;12097.274163550215;15365.231738413035;15544.689106218038;15553.611159721098;15566.299516521784;15568.377264909277;8780.034196238408;8985.146411090936;8990.910090899139;9049.592768325441;9108.180263776292;8840.983286922086;8853.114688531095;8889.977652569907;8959.641614335354;8969.865452018224;4729.43246810385;4793.976030119827;4806.615470762331;4824.372831531879;4873.561379475864;1941.3011315926256;2028.9970843151923;2038.888805600951;2061.5567652954687;2063.4222417723063;814.608987685914;840.5671079394117;914.7575892388522;921.3918813583003;964.8070385922844;18861.81138188628;19751.802481975283;19968.10143543544;20145.798542014687;20371.4907127323;848.0967769325648;864.7751584588052;875.4790899414838;885.7254251182026;957.2389949989774;7764.572948487775;10562.419066951392;10610.522526486327;10633.946830265953;10635.308704934308;541977;547768;552935;613670;623461;77459;89876.20247595142;131682.68317316705;138805;139516.3024184893;928.5218112671979;984.2372161574837;1008.8284991551307;1014.6195176808696;1017.4658304390107;17438.47684569465;18987.71518427252;19047.14287857022;19334;19410.320638777575;52301.95396092133;53349.19976200283;53992;54776.17835732387;54801.725991369785;72461.27538724756;74096.25903741109;77495.61252193703;78088.60955695412;80916.59541702255;6998.6850591724515;8124.796880078045;8201.753947381589;8333.708320208763;8356.540390278593;9081.863772043293;12028.819567706667;12256;12476.563320283747;12578.245305281713;9608;10323.845142323022;10391.272610917164;10584.84122738175;10690.251682382159;10552.947235263775;10620.734481638021;10623;10666.573337066431;11144.996950274513;10303.072723454925;10715.946420268161;10868.782624347627;10951.890481095406;11013.174011948975;8124.025116985763;8189.836203276021;8212.83574328522;8274.048484424367;8401.453905496122;8000.59997000136;9661.323707340418;10640;10924.617638382619;10937 04:06:05 INFO - PROCESS | 1962 | |3;mootools.html;1569.4193148535041;1619.287513494063;1691.8054423741266;1851.9074046297683;1937.5156210947264;7869.567173805438;8555.87166192506;8734.344924130692;8960.14878586534;8970.461772293667;8674.91325086749;8692.608832602533;8770.780730481725;8797.384183107186;8821.426607270523;2465.445274813166;2475.2945410557904;2479.429731161834;2479.528889510987;2479.925602231927;2421.794147497465;2480.08236952327;2483.801295896324;2485.0680994627014;2485.8508489490596;2427.3446169534213;2453.472503411766;2490.340059884123;2490.5890528062846;2497.5878979968284;2443.389152711831;2454.5459090068307;2458.483718419134;2474;2479.1570865905496;2432.4891772727747;2433.415980164748;2462.9532448709406;2468.987655061731;2483.552960467115;8844.867326990101;8852.601632926533;8904.688335908277;8906;8906.421082629608;5362.302900622933;5382.67703937765;5403.297571315743;5499.065158922993;5504.972475137599;3512.701420379271;3602.837872295753;3626.34725749365;3654.26914617075;3658.8536458541626;1042.8957104289586;1055.9155267578576;1057.133150816331;1058.560697310615;1062.5590379992293;12091.637250882488;12241.93879030608;12242.693932651666;12266.693332666666;12273.956713679348;4833.202521583969;4895.877603059952;4900.828471003498;4930.137225985438;4989.700617962933;9199.310051746146;10269;10275.383476991405;10300.484975751182;10301.021402966706;5254.4220135785035;5463.48096930791;5475;5485.807996720096;5512.173174023928;3595.227026189382;4757.5718185363485;4814.253790662453;4851.078295123915;4927.162382394929;3426.8286585670617;4045.1707399982934;4045.959540404558;4103.85636502721;4178.811953462041;1281.3144967442374;1361.5234667866366;1372.9176249425066;1375.7454870848726;1375.8142650742222;725.6226762083687;844.0883845446903;850.9234168924827;941.1341565759518;941.7363138321281;5677.489025987681;5736.913946290809;5816.47651711348;5849.94150058494;5927.9407205928255;908.42315129893;954.2556805691572;975.8097171051719;1004.4324956399611;1032.9173666106694;3430.691237788611;3732.496113024761;3871.8838434847;3917.5298964124486;3955.110100227426;10412.271141020055;10901.509432075576;10939.453027348602;10952.288101273392;11040.951109644571;10783.352998820095;10810.783784324269;10824.837627435594;10857.565697372174;10863;8324.66701331952;8514.57427128641;8537.786555336043;8563.229309362312;8576.485410875368;5214.374275087014;5504.201890725903;5585.245991791218;5819.447152520504;5870.53035757147;6852.6916288768125;9592.66425675098;9665.806683866424;9669.564869580745;9716.076972687584;9494.525273736424;9812.215022798016;9833.213342932406;9857.30998830075;9857.852132217879;6635.601863888182;7294.927050729425;7452.850942981218;7510.812229694083;7610.733624323122;8246.257836794717;8270.958645206736;8313.875291870747;8322.20939010793;8333.999920009395;6460.741570337322;7261.056062711814;7314.96342518284;7376.668049937658;7607.847843042998;7244.782656520313;7323.963380183065;7341.889871652036;7345.632718364167;7395.852082958419;6882.174139103339;6897.793066208022;6926.376626103674;6961.860762784817;6964.129483814523;4761.952380476289;4763.666543341933;4768.809247630056;4876.268559716071;4918.655694101379;4838.298446725275;4848.5636292733825;4866.853994380174;4885.731284779371;5097.719625420637 04:06:05 INFO - PROCESS | 1962 | |4;prototype.html;324.74020783373305;325.7882376455304;330.0609765217954;330.8560776062413;335.3644843022474;4810.687305325151;6614.702338394772;6653.401193892555;6686.866262674744;6716.6305853178055;6211.906821397672;6611.537192396534;6620.5365624406195;6627.204735431755;6643.236027856798;4652.860414187571;4675.485696573374;4754.310624959396;4769.236922092466;4790.640701947351;4715.3634259374885;4750.382450281459;4760.90478190436;4763;4768.90462190756;4749.501302363236;4773.641976851733;4774.904501909959;4785.808567657307;4790.904181916377;4475.731456112644;4773.355596994414;4795.328653988409;4798;4810.542998415145;4135.193637240709;4137.8758637240935;4138.441310423114;4138.813753381105;4143.3784932260105;5982.760689572412;6238.313785483593;6273.110671383725;6294.716737746812;6314;5559.777608895639;5611.775528978836;5622.943770562283;5630.521405680522;5642.548596112301;7004.894926576106;7018.578885266849;7032.331928466788;7035.753748618774;7066.470014748863;4111.7048129839;4519.909601807945;4540.79566419512;4551.180787458289;4560.1335746208115;6027.638341699512;6766.661666916634;6792.45660347176;7488.101427828641;7501.28737769911;3712.2204337089515;4150.335946248646;4333.959849636065;4352.455943007124;4491.820327186876;4577.725336479755;4618.261078227468;4625.167469855391;4625.537446255347;4639.188142075123;5492.533134683516;5509.696966666872;5516.3380394352935;5517.813670060957;5549.833504994856;7019.122609673791;7094.184168820549;7101.041359416413;7103.644817759091;7119.786406407712;1334.2794890759076;1650.3893559383105;1656.213298683116;1656.3126302584533;1656.8343165683336;1010.1464262698008;1034.3018462537757;1046.5133712823422;1050.9159267258754;1065.6057258814287;5442.727863606804;6436.871262574816;6543.411093001652;6588.308227636125;6589.143411356493;7050.788476345718;7343.302386273295;7347.926520734831;7352.154502232205;7360.2271761465345;6515.934840651627;6647.734090636417;6647.767328143492;6684.498662600325;6696.1629796275465;3199.4241036613635;3223.968330134381;3371.1403592084353;3383.796972181677;3999.8400063997115;6370.534777001223;7676.040494938133;7785.014821220552;7799.727009554752;7838.490498117604;6792.558483698544;7726.652300646484;7741.651625676858;7755.3407960324;7762.534247945141;7717.57553334572;7748.457607967501;7752.418568607377;7785.922140778633;7847.372210223169;7388.371988381048;7412.962185294171;7423.665935033045;7425.8143546411775;7435.330820226206;5965.552583556338;7281.235470275757;7319.377852882564;7331.890021649786;7350.448716346188;7387.519811212254;7728.613569321624;7738.458307918617;7748.92251077482;7779.92220077792;4600.769961501845;4625.722456652477;4663.27719203524;4679.46186188579;4688.34363189147;7382.52013619113;7391.889121663072;7404.481686281909;7406.07424071991;7422.4433167513735;7121.181064177478;7137.678804453708;7141.285871412817;7164.247753985965;7181.06646135999;6905.827354316182;7014.263502332182;7101.183363913216;7121.216666166614;7149.14210294768;6840.794776156723;6896.965515172392;6915.93084069173;6948.131483564554;6970.860582788215;7088.149422069385;7113.146422429341;7113.430925525842;7118.644067796693;7120.359167674934;6941.757038503628;6946.027556142043;6954.374106330454;6968.651567421507;6980.581165130108 04:06:05 INFO - PROCESS | 1962 | |5;yui.html;674.5480528046212;681.2778454837876;711.1075600121846;731.8316787138958;747.5813544415128;16679.416220432286;16867.83132168678;17007.40474083409;17038.914805425986;17127.143642817857;14802.629934251649;14899.180545070018;14900.031497952625;14938.850611493881;14981.850181498181;716.330231233798;902.6389444222298;905.9184673379394;930.1721467893547;931.3945935142144;909.9454032758023;911.0525053943891;913.6482454255126;916.4501299220481;922.8431166701677;929.6699671616581;954.6849539651932;957.8467445208768;960.8894977077621;963.0610155098758;941.580996456577;946.0539460539461;948.7580666929889;951.6336210558916;953.7138858342539;705.3334598804132;710.8862581986857;712.9679164437613;714.0824041107204;716.0119035730659;14818.40726370944;15166.848331516654;15228.390864365412;15330.310136043905;15366.615834604112;12274.754504909852;12358.876411235862;12414.565490207799;12451.377431128498;12497.00023998087;8174.182581741838;9953.552090155961;10036.046575575308;10073.597056117747;10233.232507561963;2969.539721343195;3106.502959526465;3395.79449295499;3413.9146521336916;3463.2207753255566;73450.63274683646;79086.81369779458;79330;79740.60129699373;80162;9097.954510227539;9170.95414522923;9287.860682089775;9351.018143095012;9371.62513499466;17037.914810425867;17399.391021314193;21198.47003824886;21218.78781212199;21363.893180533996;7077.752278670325;7274.745383911538;7489.325960663457;7492.438067144986;7525.548467091992;3637.5453068366455;4097.242010228118;4100.938485922714;4103.938440923389;4125.8968525787095;3657.8719744809337;3665.0287673766475;3665.743397962117;3728.6084961079237;3746.5129533160516;685.7291369908958;688.1501345837886;689.3106893106893;706.9222385537689;721.6644260418931;498.9925151122736;514.8609875333639;553.8587660146637;579.9623024503394;607.7477846693573;7587;7937.841243175104;8013.879791803128;8068.677252909935;8359.540225287546;492.22228878372084;500.41289008223504;567.767215441667;568.6303902463432;603.3333166850567;3708.925821483609;3751.2872554214614;3769;3773.24535092977;3917.177392747498;81280.59359703281;84699;86331.56834215789;87444;87841.56079219563;72916.63541682364;73344;77614.61192694001;77662;78522;68473.31526684768;69215.65392173007;69568.30431695719;69759;70280.29719702738;6318.715657795318;7280.708771649075;7285.635718214174;7445.4043676506835;7449.404047676281;46810.95728777618;47756.04487910292;47828.52171478241;48193;48306.27540586824;65548.34451655422;66491.33508664851;66941.66529167323;67412.32587674061;67496;8255.958720206361;9954.651587194414;10020.849687254842;10024.599016039278;10269.229807764597;10762.354258744188;11095.500702468247;11155.944220278847;11266.211365204357;11349.659510214708;9709.417434953926;9760.121589056735;9811.116999470081;10189.74525636865;10794.40630765284;10237.48812559384;10366.740831479274;10415.895841041796;10690.89309106899;11062;10128.59485620567;10156.593736250763;10297.897021029694;10426.530806113895;10762.730931726457;8927.687530936386;8995.730128096167;9428.62285508572;9619.134277915022;9653.903460965581;9445.386049906734;9518.286128540249;9527.857082143912;9540.399966347872;9619.90380096218 04:06:05 INFO - PROCESS | 1962 | __end_tp_report 04:06:05 INFO - PROCESS | 1962 | __start_cc_report 04:06:05 INFO - PROCESS | 1962 | _x_x_mozilla_cycle_collect,421 04:06:05 INFO - PROCESS | 1962 | __end_cc_report 04:06:05 INFO - PROCESS | 1962 | __startTimestamp1462964765373__endTimestamp 04:06:05 INFO - PROCESS | 1962 | 04:06:05 INFO - PROCESS | 1962 | ------- Summary: start ------- 04:06:05 INFO - PROCESS | 1962 | Number of tests: 6 04:06:05 INFO - PROCESS | 1962 | 04:06:05 INFO - PROCESS | 1962 | [#0] dojo.html Cycles:165 Average:5756.04 Median:5967.79 stddev:662.27 (11.1%) stddev-sans-first:639.25 04:06:05 INFO - PROCESS | 1962 | Values: 3455.6 3472.3 3515.7 3517.4 3550.7 5813.0 5836.9 5854.1 5855.3 5862.5 5831.6 5839.0 5859.6 5879.4 5927.9 5252.9 5253.8 5307.2 5333.0 5346.1 5216.1 5302.1 5317.9 5318.0 5371.9 5315.0 5326.6 5330.6 5357.0 5371.9 5284.6 5322.8 5331.3 5363.1 5365.9 5138.9 5151.3 5231.4 5253.5 5257.4 5589.4 5710.7 5731.0 5765.7 5813.4 5286.8 6201.5 6241.3 6272.0 6282.1 6275.9 6301.1 6321.2 6322.5 6350.9 4499.7 4984.1 5985.4 6019.5 6044.9 4810.3 6305.9 6410.3 6444.3 6453.4 6216.2 6244.2 6251.3 6254.1 6263.7 6282.8 6314.0 6320.9 6337.3 6342.0 5505.3 6014.8 6129.3 6176.4 6224.3 5550.3 5892.4 6176.8 6250.7 6254.3 3762.6 4985.1 5047.8 5049.8 5073.6 4395.2 4427.1 4510.9 4516.4 4546.7 5861.3 5901.3 5917.8 5944.9 6024.6 5604.0 6310.3 6339.5 6353.2 6376.5 5893.9 5931.0 5935.5 5983.2 6012.4 5462.0 5484.9 5521.0 5532.3 5537.2 5383.8 5501.8 6043.5 6414.7 6483.6 5717.1 6233.7 6289.3 6314.2 6323.7 6470.1 6474.4 6486.7 6501.0 6502.2 5673.8 6185.9 6267.7 6275.1 6281.5 6204.5 6279.6 6294.7 6295.3 6315.0 6155.1 6203.8 6213.3 6232.5 6237.0 5148.3 5988.9 6045.9 6081.2 6119.7 4824.6 6113.1 6129.5 6156.9 6177.8 6224.3 6227.5 6230.3 6232.2 6240.0 5025.8 5967.8 6098.1 6141.7 6159.5 04:06:05 INFO - PROCESS | 1962 | 04:06:05 INFO - PROCESS | 1962 | [#1] ext.html Cycles:180 Average:72969.10 Median:36979.04 stddev:184707.35 (499.5%) stddev-sans-first:185145.34 04:06:05 INFO - PROCESS | 1962 | Values: 489.5 512.3 567.4 609.8 624.2 31460.8 38714.6 38782.6 38808.6 38936.0 39299.8 39858.0 39890.4 39926.4 39937.4 9353.4 9546.4 9555.5 9607.4 9626.5 9400.0 9612.1 9650.7 9672.8 9675.1 9612.2 9643.5 9676.0 9676.6 9694.0 9610.2 9666.0 9671.9 9685.0 9716.3 9422.0 9464.7 9556.9 9561.9 9578.5 38556.6 38703.8 38777.6 38842.8 39352.8 2204.2 2562.9 3012.7 3035.8 3183.6 28165.6 28647.9 28687.9 29500.9 29759.3 2718.3 2762.7 2785.5 2855.1 2878.0 334468.0 338188.0 340323.0 340635.3 341100.0 35902.3 36389.0 36613.3 61216.1 61594.7 35242.5 35736.5 35840.5 35960.8 35991.5 13238.6 15470.0 15559.0 15790.0 16033.1 13145.6 13246.4 13262.7 13266.1 13269.0 8035.0 8239.7 8257.5 8266.2 8300.9 3320.4 3439.1 3478.1 3481.1 3487.6 774.5 960.7 965.4 977.0 1104.5 27688.9 28504.0 28589.9 28648.6 29077.4 859.3 875.0 977.1 1000.5 1056.1 14578.7 14578.9 14658.8 15089.0 16345.8 618776.0 1006030.0 1022084.0 1023060.0 1024852.0 61255.0 61368.0 61430.0 62200.4 62260.0 1346.4 1522.3 1526.6 1533.4 1533.5 28672.6 29951.0 30366.8 30629.2 30830.5 43866.6 52258.7 52600.0 52993.7 53301.7 599082.0 600780.0 602925.0 607384.0 607974.0 22419.4 26920.0 27552.0 27847.2 28220.9 44932.6 44947.8 46078.8 46328.5 46506.6 37410.4 37453.6 37658.6 37851.8 38260.8 33345.0 36936.3 37198.4 37379.6 37875.0 30189.2 34883.0 36323.5 37021.8 37122.7 29824.7 30122.7 30640.0 30723.2 30845.5 19920.0 20656.8 22739.9 23539.8 23924.0 04:06:05 INFO - PROCESS | 1962 | 04:06:05 INFO - PROCESS | 1962 | [#2] jquery.html Cycles:180 Average:33901.84 Median:380.82 stddev:96257.91 (25276.7%) stddev-sans-first:96494.98 04:06:05 INFO - PROCESS | 1962 | Values: 354.8 406.8 412.1 412.9 418.8 15149.6 16139.9 18499.7 19777.2 20432.5 20281.7 20449.5 20688.7 21065.8 21674.2 5647.9 5830.6 6106.5 6108.9 6127.4 5577.0 5891.8 5929.2 5942.7 5947.6 5830.7 5876.9 5925.2 5927.0 5935.4 5251.0 5344.0 6067.1 6095.7 6106.8 5914.7 5921.1 5921.8 5926.3 5928.1 19512.6 25288.0 25944.2 26120.3 26300.6 2088.7 2220.7 2589.8 2669.5 2732.5 9875.4 10205.5 10682.4 11609.5 15203.3 1794.0 1833.4 1845.7 1859.7 1898.7 111115.4 114092.0 117801.4 118420.0 118954.4 11682.7 11903.7 11947.9 11952.2 12097.3 15365.2 15544.7 15553.6 15566.3 15568.4 8780.0 8985.1 8990.9 9049.6 9108.2 8841.0 8853.1 8890.0 8959.6 8969.9 4729.4 4794.0 4806.6 4824.4 4873.6 1941.3 2029.0 2038.9 2061.6 2063.4 814.6 840.6 914.8 921.4 964.8 18861.8 19751.8 19968.1 20145.8 20371.5 848.1 864.8 875.5 885.7 957.2 7764.6 10562.4 10610.5 10633.9 10635.3 541977.0 547768.0 552935.0 613670.0 623461.0 77459.0 89876.2 131682.7 138805.0 139516.3 928.5 984.2 1008.8 1014.6 1017.5 17438.5 18987.7 19047.1 19334.0 19410.3 52302.0 53349.2 53992.0 54776.2 54801.7 72461.3 74096.3 77495.6 78088.6 80916.6 6998.7 8124.8 8201.8 8333.7 8356.5 9081.9 12028.8 12256.0 12476.6 12578.2 9608.0 10323.8 10391.3 10584.8 10690.3 10552.9 10620.7 10623.0 10666.6 11145.0 10303.1 10715.9 10868.8 10951.9 11013.2 8124.0 8189.8 8212.8 8274.0 8401.5 8000.6 9661.3 10640.0 10924.6 10937.0 04:06:05 INFO - PROCESS | 1962 | 04:06:05 INFO - PROCESS | 1962 | [#3] mootools.html Cycles:180 Average:5717.15 Median:4909.74 stddev:3194.23 (65.1%) stddev-sans-first:3187.99 04:06:05 INFO - PROCESS | 1962 | Values: 1569.4 1619.3 1691.8 1851.9 1937.5 7869.6 8555.9 8734.3 8960.1 8970.5 8674.9 8692.6 8770.8 8797.4 8821.4 2465.4 2475.3 2479.4 2479.5 2479.9 2421.8 2480.1 2483.8 2485.1 2485.9 2427.3 2453.5 2490.3 2490.6 2497.6 2443.4 2454.5 2458.5 2474.0 2479.2 2432.5 2433.4 2463.0 2469.0 2483.6 8844.9 8852.6 8904.7 8906.0 8906.4 5362.3 5382.7 5403.3 5499.1 5505.0 3512.7 3602.8 3626.3 3654.3 3658.9 1042.9 1055.9 1057.1 1058.6 1062.6 12091.6 12241.9 12242.7 12266.7 12274.0 4833.2 4895.9 4900.8 4930.1 4989.7 9199.3 10269.0 10275.4 10300.5 10301.0 5254.4 5463.5 5475.0 5485.8 5512.2 3595.2 4757.6 4814.3 4851.1 4927.2 3426.8 4045.2 4046.0 4103.9 4178.8 1281.3 1361.5 1372.9 1375.7 1375.8 725.6 844.1 850.9 941.1 941.7 5677.5 5736.9 5816.5 5849.9 5927.9 908.4 954.3 975.8 1004.4 1032.9 3430.7 3732.5 3871.9 3917.5 3955.1 10412.3 10901.5 10939.5 10952.3 11041.0 10783.4 10810.8 10824.8 10857.6 10863.0 8324.7 8514.6 8537.8 8563.2 8576.5 5214.4 5504.2 5585.2 5819.4 5870.5 6852.7 9592.7 9665.8 9669.6 9716.1 9494.5 9812.2 9833.2 9857.3 9857.9 6635.6 7294.9 7452.9 7510.8 7610.7 8246.3 8271.0 8313.9 8322.2 8334.0 6460.7 7261.1 7315.0 7376.7 7607.8 7244.8 7324.0 7341.9 7345.6 7395.9 6882.2 6897.8 6926.4 6961.9 6964.1 4762.0 4763.7 4768.8 4876.3 4918.7 4838.3 4848.6 4866.9 4885.7 5097.7 04:06:05 INFO - PROCESS | 1962 | 04:06:05 INFO - PROCESS | 1962 | [#4] prototype.html Cycles:180 Average:5735.72 Median:6600.34 stddev:1895.50 (28.7%) stddev-sans-first:1856.80 04:06:05 INFO - PROCESS | 1962 | Values: 324.7 325.8 330.1 330.9 335.4 4810.7 6614.7 6653.4 6686.9 6716.6 6211.9 6611.5 6620.5 6627.2 6643.2 4652.9 4675.5 4754.3 4769.2 4790.6 4715.4 4750.4 4760.9 4763.0 4768.9 4749.5 4773.6 4774.9 4785.8 4790.9 4475.7 4773.4 4795.3 4798.0 4810.5 4135.2 4137.9 4138.4 4138.8 4143.4 5982.8 6238.3 6273.1 6294.7 6314.0 5559.8 5611.8 5622.9 5630.5 5642.5 7004.9 7018.6 7032.3 7035.8 7066.5 4111.7 4519.9 4540.8 4551.2 4560.1 6027.6 6766.7 6792.5 7488.1 7501.3 3712.2 4150.3 4334.0 4352.5 4491.8 4577.7 4618.3 4625.2 4625.5 4639.2 5492.5 5509.7 5516.3 5517.8 5549.8 7019.1 7094.2 7101.0 7103.6 7119.8 1334.3 1650.4 1656.2 1656.3 1656.8 1010.1 1034.3 1046.5 1050.9 1065.6 5442.7 6436.9 6543.4 6588.3 6589.1 7050.8 7343.3 7347.9 7352.2 7360.2 6515.9 6647.7 6647.8 6684.5 6696.2 3199.4 3224.0 3371.1 3383.8 3999.8 6370.5 7676.0 7785.0 7799.7 7838.5 6792.6 7726.7 7741.7 7755.3 7762.5 7717.6 7748.5 7752.4 7785.9 7847.4 7388.4 7413.0 7423.7 7425.8 7435.3 5965.6 7281.2 7319.4 7331.9 7350.4 7387.5 7728.6 7738.5 7748.9 7779.9 4600.8 4625.7 4663.3 4679.5 4688.3 7382.5 7391.9 7404.5 7406.1 7422.4 7121.2 7137.7 7141.3 7164.2 7181.1 6905.8 7014.3 7101.2 7121.2 7149.1 6840.8 6897.0 6915.9 6948.1 6970.9 7088.1 7113.1 7113.4 7118.6 7120.4 6941.8 6946.0 6954.4 6968.7 6980.6 04:06:05 INFO - PROCESS | 1962 | 04:06:05 INFO - PROCESS | 1962 | [#5] yui.html Cycles:180 Average:17687.92 Median:34577.30 stddev:24768.83 (71.6%) stddev-sans-first:24805.36 04:06:05 INFO - PROCESS | 1962 | Values: 674.5 681.3 711.1 731.8 747.6 16679.4 16867.8 17007.4 17038.9 17127.1 14802.6 14899.2 14900.0 14938.9 14981.9 716.3 902.6 905.9 930.2 931.4 909.9 911.1 913.6 916.5 922.8 929.7 954.7 957.8 960.9 963.1 941.6 946.1 948.8 951.6 953.7 705.3 710.9 713.0 714.1 716.0 14818.4 15166.8 15228.4 15330.3 15366.6 12274.8 12358.9 12414.6 12451.4 12497.0 8174.2 9953.6 10036.0 10073.6 10233.2 2969.5 3106.5 3395.8 3413.9 3463.2 73450.6 79086.8 79330.0 79740.6 80162.0 9098.0 9171.0 9287.9 9351.0 9371.6 17037.9 17399.4 21198.5 21218.8 21363.9 7077.8 7274.7 7489.3 7492.4 7525.5 3637.5 4097.2 4100.9 4103.9 4125.9 3657.9 3665.0 3665.7 3728.6 3746.5 685.7 688.2 689.3 706.9 721.7 499.0 514.9 553.9 580.0 607.7 7587.0 7937.8 8013.9 8068.7 8359.5 492.2 500.4 567.8 568.6 603.3 3708.9 3751.3 3769.0 3773.2 3917.2 81280.6 84699.0 86331.6 87444.0 87841.6 72916.6 73344.0 77614.6 77662.0 78522.0 68473.3 69215.7 69568.3 69759.0 70280.3 6318.7 7280.7 7285.6 7445.4 7449.4 46811.0 47756.0 47828.5 48193.0 48306.3 65548.3 66491.3 66941.7 67412.3 67496.0 8256.0 9954.7 10020.8 10024.6 10269.2 10762.4 11095.5 11155.9 11266.2 11349.7 9709.4 9760.1 9811.1 10189.7 10794.4 10237.5 10366.7 10415.9 10690.9 11062.0 10128.6 10156.6 10297.9 10426.5 10762.7 8927.7 8995.7 9428.6 9619.1 9653.9 9445.4 9518.3 9527.9 9540.4 9619.9 04:06:05 INFO - PROCESS | 1962 | -------- Summary: end -------- 04:06:05 INFO - PROCESS | 1962 | 04:06:05 INFO - TEST-INFO | 1962: exit 0 04:06:05 INFO - TEST-OK | dromaeo_css | took 1112235ms 04:06:05 INFO - TEST-START | kraken 04:06:05 INFO - operating with platform_type : mac_ 04:06:05 INFO - Initialising browser for kraken test... 04:06:05 INFO - TEST-INFO | started process 1972 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/tmp4ExP5X/profile http://localhost:51167/getInfo.html) 04:06:08 INFO - PROCESS | 1972 | __metrics Screen width/height:1600/1200 04:06:08 INFO - PROCESS | 1972 | colorDepth:24 04:06:08 INFO - PROCESS | 1972 | Browser inner width/height: 1024/689 04:06:08 INFO - PROCESS | 1972 | __metrics 04:06:08 ERROR - PROCESS | 1972 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 04:06:09 INFO - TEST-INFO | 1972: exit 0 04:06:09 INFO - Browser initialized. 04:06:09 INFO - Running cycle 1/1 for kraken test... 04:06:09 INFO - TEST-INFO | started process 1975 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/tmp4ExP5X/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:06:21 INFO - PROCESS | 1975 | RSS: Main: 220540928 04:06:21 INFO - PROCESS | 1975 | 04:06:24 INFO - PROCESS | 1975 | Cycle 1(1): loaded http://localhost:51167/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51167/tests/kraken/driver.html?testName=audio-beat-detection) 04:06:25 INFO - PROCESS | 1975 | RSS: Main: 232620032 04:06:25 INFO - PROCESS | 1975 | 04:06:27 INFO - PROCESS | 1975 | Cycle 1(1): loaded http://localhost:51167/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51167/tests/kraken/driver.html?testName=audio-dft) 04:06:28 INFO - PROCESS | 1975 | RSS: Main: 241639424 04:06:28 INFO - PROCESS | 1975 | 04:06:31 INFO - PROCESS | 1975 | Cycle 1(1): loaded http://localhost:51167/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51167/tests/kraken/driver.html?testName=audio-fft) 04:06:32 INFO - PROCESS | 1975 | RSS: Main: 245178368 04:06:32 INFO - PROCESS | 1975 | 04:06:34 INFO - PROCESS | 1975 | Cycle 1(1): loaded http://localhost:51167/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51167/tests/kraken/driver.html?testName=audio-oscillator) 04:06:35 INFO - PROCESS | 1975 | RSS: Main: 247144448 04:06:35 INFO - PROCESS | 1975 | 04:06:37 INFO - PROCESS | 1975 | Cycle 1(1): loaded http://localhost:51167/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51167/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:06:37 INFO - PROCESS | 1975 | RSS: Main: 247152640 04:06:37 INFO - PROCESS | 1975 | 04:06:40 INFO - PROCESS | 1975 | Cycle 1(1): loaded http://localhost:51167/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51167/tests/kraken/driver.html?testName=imaging-darkroom) 04:06:41 INFO - PROCESS | 1975 | RSS: Main: 247517184 04:06:41 INFO - PROCESS | 1975 | 04:06:44 INFO - PROCESS | 1975 | Cycle 1(1): loaded http://localhost:51167/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51167/tests/kraken/driver.html?testName=imaging-desaturate) 04:06:44 INFO - PROCESS | 1975 | RSS: Main: 247717888 04:06:44 INFO - PROCESS | 1975 | 04:06:47 INFO - PROCESS | 1975 | Cycle 1(1): loaded http://localhost:51167/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51167/tests/kraken/driver.html?testName=json-parse-financial) 04:06:48 INFO - PROCESS | 1975 | RSS: Main: 247791616 04:06:48 INFO - PROCESS | 1975 | 04:06:49 INFO - PROCESS | 1975 | Cycle 1(1): loaded http://localhost:51167/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51167/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:06:50 INFO - PROCESS | 1975 | RSS: Main: 247640064 04:06:50 INFO - PROCESS | 1975 | 04:06:52 INFO - PROCESS | 1975 | Cycle 1(1): loaded http://localhost:51167/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51167/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:06:53 INFO - PROCESS | 1975 | RSS: Main: 247246848 04:06:53 INFO - PROCESS | 1975 | 04:06:55 INFO - PROCESS | 1975 | Cycle 1(1): loaded http://localhost:51167/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51167/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:06:55 INFO - PROCESS | 1975 | RSS: Main: 247537664 04:06:55 INFO - PROCESS | 1975 | 04:06:58 INFO - PROCESS | 1975 | Cycle 1(1): loaded http://localhost:51167/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51167/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:06:59 INFO - PROCESS | 1975 | RSS: Main: 247549952 04:06:59 INFO - PROCESS | 1975 | 04:07:02 INFO - PROCESS | 1975 | Cycle 1(1): loaded http://localhost:51167/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51167/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:07:02 INFO - PROCESS | 1975 | RSS: Main: 247537664 04:07:02 INFO - PROCESS | 1975 | 04:07:04 INFO - PROCESS | 1975 | Cycle 1(1): loaded http://localhost:51167/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51167/tests/kraken/driver.html?testName=ai-astar) 04:07:04 INFO - PROCESS | 1975 | RSS: Main: 249163776 04:07:04 INFO - PROCESS | 1975 | 04:07:04 INFO - PROCESS | 1975 | __start_tp_report 04:07:04 INFO - PROCESS | 1975 | _x_x_mozilla_page_load 04:07:04 INFO - PROCESS | 1975 | _x_x_mozilla_page_load_details 04:07:04 INFO - PROCESS | 1975 | |i|pagename|runs| 04:07:04 INFO - PROCESS | 1975 | |0;ai-astar;105;173;157;101;168;177;135;115;117;116 04:07:04 INFO - PROCESS | 1975 | |1;audio-beat-detection;156;151;168;136;133;168;177;111;203;151 04:07:04 INFO - PROCESS | 1975 | |2;audio-dft;247;194;199;278;249;197;194;202;195;197 04:07:04 INFO - PROCESS | 1975 | |3;audio-fft;66;83;72;148;56;56;59;72;161;56 04:07:04 INFO - PROCESS | 1975 | |4;audio-oscillator;77;84;124;78;77;81;114;80;80;115 04:07:04 INFO - PROCESS | 1975 | |5;imaging-gaussian-blur;89;87;87;89;92;86;89;87;90;85 04:07:04 INFO - PROCESS | 1975 | |6;imaging-darkroom;95;107;96;102;128;95;150;147;95;94 04:07:04 INFO - PROCESS | 1975 | |7;imaging-desaturate;91;92;91;89;90;90;91;93;89;89 04:07:04 INFO - PROCESS | 1975 | |8;json-parse-financial;60;71;62;66;67;66;65;82;62;62 04:07:04 INFO - PROCESS | 1975 | |9;json-stringify-tinderbox;63;67;72;71;69;75;74;68;68;71 04:07:04 INFO - PROCESS | 1975 | |10;stanford-crypto-aes;83;82;80;84;81;83;80;84;84;84 04:07:04 INFO - PROCESS | 1975 | |11;stanford-crypto-ccm;152;157;150;126;124;148;154;150;147;146 04:07:04 INFO - PROCESS | 1975 | |12;stanford-crypto-pbkdf2;173;167;168;168;166;167;165;166;168;168 04:07:04 INFO - PROCESS | 1975 | |13;stanford-crypto-sha256-iterative;70;65;70;66;70;69;65;73;68;66 04:07:04 INFO - PROCESS | 1975 | __end_tp_report 04:07:04 INFO - PROCESS | 1975 | __start_cc_report 04:07:04 INFO - PROCESS | 1975 | _x_x_mozilla_cycle_collect,1088 04:07:04 INFO - PROCESS | 1975 | __end_cc_report 04:07:04 INFO - PROCESS | 1975 | __startTimestamp1462964824588__endTimestamp 04:07:04 INFO - PROCESS | 1975 | 04:07:04 INFO - PROCESS | 1975 | ------- Summary: start ------- 04:07:04 INFO - PROCESS | 1975 | Number of tests: 14 04:07:04 INFO - PROCESS | 1975 | 04:07:04 INFO - PROCESS | 1975 | [#0] ai-astar Cycles:10 Average:136.40 Median:146.00 stddev:29.63 (20.3%) stddev-sans-first:29.17 04:07:04 INFO - PROCESS | 1975 | Values: 105.0 173.0 157.0 101.0 168.0 177.0 135.0 115.0 117.0 116.0 04:07:04 INFO - PROCESS | 1975 | 04:07:04 INFO - PROCESS | 1975 | [#1] audio-beat-detection Cycles:10 Average:155.40 Median:162.00 stddev:25.69 (15.9%) stddev-sans-first:27.24 04:07:04 INFO - PROCESS | 1975 | Values: 156.0 151.0 168.0 136.0 133.0 168.0 177.0 111.0 203.0 151.0 04:07:04 INFO - PROCESS | 1975 | 04:07:04 INFO - PROCESS | 1975 | [#2] audio-dft Cycles:10 Average:215.20 Median:200.50 stddev:30.74 (15.3%) stddev-sans-first:30.37 04:07:04 INFO - PROCESS | 1975 | Values: 247.0 194.0 199.0 278.0 249.0 197.0 194.0 202.0 195.0 197.0 04:07:04 INFO - PROCESS | 1975 | 04:07:04 INFO - PROCESS | 1975 | [#3] audio-fft Cycles:10 Average:82.90 Median:62.50 stddev:38.88 (62.2%) stddev-sans-first:40.75 04:07:04 INFO - PROCESS | 1975 | Values: 66.0 83.0 72.0 148.0 56.0 56.0 59.0 72.0 161.0 56.0 04:07:04 INFO - PROCESS | 1975 | 04:07:04 INFO - PROCESS | 1975 | [#4] audio-oscillator Cycles:10 Average:91.00 Median:79.00 stddev:18.70 (23.7%) stddev-sans-first:19.13 04:07:04 INFO - PROCESS | 1975 | Values: 77.0 84.0 124.0 78.0 77.0 81.0 114.0 80.0 80.0 115.0 04:07:04 INFO - PROCESS | 1975 | 04:07:04 INFO - PROCESS | 1975 | [#5] imaging-gaussian-blur Cycles:10 Average:88.10 Median:89.00 stddev:2.08 (2.3%) stddev-sans-first:2.18 04:07:04 INFO - PROCESS | 1975 | Values: 89.0 87.0 87.0 89.0 92.0 86.0 89.0 87.0 90.0 85.0 04:07:04 INFO - PROCESS | 1975 | 04:07:04 INFO - PROCESS | 1975 | [#6] imaging-darkroom Cycles:10 Average:110.90 Median:94.50 stddev:22.32 (23.6%) stddev-sans-first:22.92 04:07:04 INFO - PROCESS | 1975 | Values: 95.0 107.0 96.0 102.0 128.0 95.0 150.0 147.0 95.0 94.0 04:07:04 INFO - PROCESS | 1975 | 04:07:04 INFO - PROCESS | 1975 | [#7] imaging-desaturate Cycles:10 Average:90.50 Median:91.00 stddev:1.35 (1.5%) stddev-sans-first:1.42 04:07:04 INFO - PROCESS | 1975 | Values: 91.0 92.0 91.0 89.0 90.0 90.0 91.0 93.0 89.0 89.0 04:07:04 INFO - PROCESS | 1975 | 04:07:04 INFO - PROCESS | 1975 | [#8] json-parse-financial Cycles:10 Average:66.30 Median:66.00 stddev:6.38 (9.7%) stddev-sans-first:6.34 04:07:04 INFO - PROCESS | 1975 | Values: 60.0 71.0 62.0 66.0 67.0 66.0 65.0 82.0 62.0 62.0 04:07:04 INFO - PROCESS | 1975 | 04:07:04 INFO - PROCESS | 1975 | [#9] json-stringify-tinderbox Cycles:10 Average:69.80 Median:71.00 stddev:3.55 (5.0%) stddev-sans-first:2.79 04:07:04 INFO - PROCESS | 1975 | Values: 63.0 67.0 72.0 71.0 69.0 75.0 74.0 68.0 68.0 71.0 04:07:04 INFO - PROCESS | 1975 | 04:07:04 INFO - PROCESS | 1975 | [#10] stanford-crypto-aes Cycles:10 Average:82.50 Median:83.50 stddev:1.65 (2.0%) stddev-sans-first:1.74 04:07:04 INFO - PROCESS | 1975 | Values: 83.0 82.0 80.0 84.0 81.0 83.0 80.0 84.0 84.0 84.0 04:07:04 INFO - PROCESS | 1975 | 04:07:04 INFO - PROCESS | 1975 | [#11] stanford-crypto-ccm Cycles:10 Average:145.40 Median:150.00 stddev:11.25 (7.5%) stddev-sans-first:11.67 04:07:04 INFO - PROCESS | 1975 | Values: 152.0 157.0 150.0 126.0 124.0 148.0 154.0 150.0 147.0 146.0 04:07:04 INFO - PROCESS | 1975 | 04:07:04 INFO - PROCESS | 1975 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.60 Median:168.00 stddev:2.17 (1.3%) stddev-sans-first:1.12 04:07:04 INFO - PROCESS | 1975 | Values: 173.0 167.0 168.0 168.0 166.0 167.0 165.0 166.0 168.0 168.0 04:07:04 INFO - PROCESS | 1975 | 04:07:04 INFO - PROCESS | 1975 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.20 Median:69.50 stddev:2.66 (3.8%) stddev-sans-first:2.74 04:07:04 INFO - PROCESS | 1975 | Values: 70.0 65.0 70.0 66.0 70.0 69.0 65.0 73.0 68.0 66.0 04:07:04 INFO - PROCESS | 1975 | -------- Summary: end -------- 04:07:04 INFO - PROCESS | 1975 | 04:07:05 INFO - TEST-INFO | 1975: exit 0 04:07:05 INFO - TEST-OK | kraken | took 59449ms 04:07:05 INFO - SUITE-END | took 1171s 04:07:05 INFO - Completed test suite (00:19:32) 04:07:05 INFO - javascript benchmark 04:07:05 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3455.6026057003446, 3472.2534655049167, 3515.7187425006, 3517.4196257617496, 3550.6981906537944, 5813.040848260042, 5836.9124463132985, 5854.063349864023, 5855.32663743669, 5862.530997520199, 5831.567849292307, 5838.970805145979, 5859.648421094737, 5879.441453061952, 5927.88144237115, 5252.921206181911, 5253.763580638871, 5307.230451584518, 5332.973335133319, 5346.144616861303, 5216.139337009407, 5302.072137375943, 5317.8936421271555, 5318.0, 5371.946280537203, 5315.0, 5326.573874090082, 5330.573554115662, 5357.0, 5371.946280537184, 5284.630075894689, 5322.76047577858, 5331.253624492574, 5363.115086010804, 5365.919511207335, 5138.922916156261, 5151.330327057459, 5231.398389185254, 5253.474652534755, 5257.447967963347, 5589.413111623302, 5710.7430165642645, 5731.0, 5765.740541675635, 5813.360530341659, 5286.784039670859, 6201.5348848836065, 6241.313455519889, 6271.968640156816, 6282.089097080943, 6275.937240627627, 6301.117843501914, 6321.241451025906, 6322.462590679797, 6350.920343541609, 4499.662525310615, 4984.053029924303, 5985.40145985398, 6019.518438524908, 6044.939550604482, 4810.326554282403, 6305.873882522324, 6410.326915673787, 6444.2589102252905, 6453.354664533509, 6216.160818289563, 6244.219472565929, 6251.343608921089, 6254.093156492283, 6263.655498947511, 6282.842928926722, 6313.96843015782, 6320.936790632127, 6337.33457986914, 6341.968290158519, 5505.284313039279, 6014.827108713759, 6129.267100814801, 6176.3514830943, 6224.253089629183, 5550.333959924835, 5892.356345844061, 6176.845578860474, 6250.749970001149, 6254.2807577129215, 3762.6049264826797, 4985.102681517289, 5047.848564543069, 5049.798008079636, 5073.619478539124, 4395.1868904252815, 4427.092446048552, 4510.864674059783, 4516.412866327357, 4546.65900057497, 5861.267341582302, 5901.321348044944, 5917.763289468459, 5944.881102377928, 6024.57827952048, 5604.0, 6310.305866354697, 6339.4611458025665, 6353.205849268841, 6376.521760867953, 5893.882122357529, 5931.0, 5935.495482883917, 5983.19226904371, 6012.39876012404, 5462.0, 5484.890302193934, 5521.00621888064, 5532.2531458253425, 5537.224788529609, 5383.842473868215, 5501.762103526675, 6043.456088952015, 6414.711337989802, 6483.578567393104, 5717.113847353667, 6233.719482623203, 6289.308176100717, 6314.179156709783, 6323.747050117944, 6470.094186813756, 6474.449671777952, 6486.675666216576, 6501.0, 6502.219733631994, 5673.801416950387, 6185.917464443614, 6267.749290028348, 6275.121482992476, 6281.497480201481, 6204.472619827366, 6279.591826531261, 6294.6852657366035, 6295.276043255084, 6314.968425157845, 6155.1382806406245, 6203.8138855834395, 6213.285472170758, 6232.501399887908, 6237.039479930368, 5148.253503241934, 5988.940110598838, 6045.909311360245, 6081.209442772411, 6119.715820550657, 4824.614030877591, 6113.113598528277, 6129.544705212454, 6156.938430615636, 6177.81466556004, 6224.346443623535, 6227.501799856092, 6230.283517395557, 6232.220972378453, 6240.0, 5025.84922452327, 5967.791127310524, 6098.115773212948, 6141.723622437091, 6159.476444502267], "value": 5718.821226204664, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [489.5447234072311, 512.3083836820292, 567.4325674325675, 609.7652403824526, 624.2259598098359, 31460.842695786545, 38714.64498742545, 38782.61217387825, 38808.61191388085, 38935.99999999996, 39299.803500982525, 39858.0, 39890.40164397536, 39926.401103983466, 39937.40093898594, 9353.438793672385, 9546.427214367126, 9555.474448905308, 9607.375520591175, 9626.470544120106, 9400.0, 9612.086851749073, 9650.662226822064, 9672.758181045496, 9675.129238368545, 9612.18296444803, 9643.469609171492, 9675.951620241889, 9676.564554595025, 9694.030596940285, 9610.183134433546, 9666.0, 9671.90328096717, 9685.0, 9716.31985760997, 9422.0, 9464.668736594185, 9556.856647150298, 9561.904380956172, 9578.473183974878, 38556.614433855866, 38703.806480967694, 38777.61222387768, 38842.80578597117, 39352.80323598392, 2204.206485665159, 2562.910298139575, 3012.7439167670773, 3035.7874948753597, 3183.6338821035647, 28165.57751633727, 28647.856760716273, 28687.856560717064, 29500.8524957376, 29759.25601859949, 2718.2796558911905, 2762.6822915364787, 2785.456835916997, 2855.1006432973545, 2878.0070875547904, 334468.0, 338188.0, 340323.0, 340635.2968235168, 341100.0, 35902.28195436077, 36389.0, 36613.26773464516, 61216.08175877365, 61594.69202653958, 35242.47136292957, 35736.46395304072, 35840.462393064125, 35960.82019589885, 35991.4601280981, 13238.602841914759, 15470.0, 15559.0, 15790.0, 16033.118178500294, 13145.60563183106, 13246.40391182399, 13262.668433289246, 13266.07137500385, 13269.0, 8035.0, 8239.711610093618, 8257.504549727035, 8266.214709602578, 8300.916990830136, 3320.4189266878197, 3439.105832483572, 3478.0783092480515, 3481.1201950946206, 3487.5989261234777, 774.4501404003221, 960.6973803251954, 965.3918031640022, 976.998576459135, 1104.4864138175788, 27688.861555692092, 28504.002359917304, 28589.857050714614, 28648.570271445944, 29077.38303552648, 859.2610355094738, 874.9518776467355, 977.0766625538939, 1000.5447521377756, 1056.054830926332, 14578.708425831424, 14578.927105364406, 14658.780118298235, 15089.0, 16345.836541634431, 618776.0, 1006030.0, 1022084.0, 1023060.0, 1024852.0, 61255.0, 61368.0, 61430.0, 62200.37799621946, 62260.0, 1346.4344975110478, 1522.2540954932224, 1526.5954522051666, 1533.4479587348546, 1533.463287849244, 28672.569911451763, 29951.0, 30366.848165759915, 30629.23426914345, 30830.53754193644, 43866.56133438625, 52258.73870630775, 52600.0, 52993.73503132614, 53301.73349133229, 599082.0, 600780.0, 602925.0, 607384.0, 607974.0, 22419.43951401228, 26920.0, 27552.0, 27847.16458506248, 28220.85889570539, 44932.550674492835, 44947.77526112348, 46078.769606153095, 46328.53671463242, 46506.604801855996, 37410.43884341683, 37453.62546374501, 37658.62341376551, 37851.810740946115, 38260.808695956344, 33344.99965001054, 36936.26127477382, 37198.44202337021, 37379.626203738706, 37875.0, 30189.245268867577, 34883.0, 36323.45514817333, 37021.81489092537, 37122.70070547626, 29824.701752982193, 30122.69877301199, 30640.0, 30723.231919201306, 30845.537316939815, 19920.0, 20656.79343206609, 22739.886300568392, 23539.764602353756, 23924.0], "value": 18219.08410337789, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.82790846439474, 406.8067667857768, 412.0543353004854, 412.9359949207874, 418.80106949199137, 15149.5633678345, 16139.919300403482, 18499.722504162433, 19777.208911643535, 20432.489187770312, 20281.69577456339, 20449.488762780937, 20688.689669654967, 21065.789342106575, 21674.24140155095, 5647.887042259173, 5830.621009634381, 6106.542009349294, 6108.908366374508, 6127.35662755411, 5576.972115139419, 5891.764329426839, 5929.229200203968, 5942.732577034022, 5947.6134051286745, 5830.679312637803, 5876.9118463223085, 5925.229720136376, 5926.99241129007, 5935.436133567304, 5251.0, 5343.973280133594, 6067.120267561223, 6095.725692343877, 6106.755729770804, 5914.733836977303, 5921.111833225051, 5921.792737254218, 5926.259217597801, 5928.140419639129, 19512.609747804963, 25288.0, 25944.221673349828, 26120.34699132518, 26300.605490917653, 2088.718023066882, 2220.655798351258, 2589.795744978594, 2669.506141363854, 2732.4808286425514, 9875.40747555149, 10205.48972551377, 10682.412467314294, 11609.477573509212, 15203.31585078663, 1793.99536259695, 1833.3949796567101, 1845.732368806529, 1859.6745569525278, 1898.6677331466938, 111115.44442277736, 114092.0, 117801.41099294534, 118420.0, 118954.40522797417, 11682.70793230176, 11903.702407439883, 11947.940260298818, 11952.223105498115, 12097.274163550215, 15365.231738413035, 15544.689106218038, 15553.611159721098, 15566.299516521784, 15568.377264909277, 8780.034196238408, 8985.146411090936, 8990.910090899139, 9049.592768325441, 9108.180263776292, 8840.983286922086, 8853.114688531095, 8889.977652569907, 8959.641614335354, 8969.865452018224, 4729.43246810385, 4793.976030119827, 4806.615470762331, 4824.372831531879, 4873.561379475864, 1941.3011315926256, 2028.9970843151923, 2038.888805600951, 2061.5567652954687, 2063.4222417723063, 814.608987685914, 840.5671079394117, 914.7575892388522, 921.3918813583003, 964.8070385922844, 18861.81138188628, 19751.802481975283, 19968.10143543544, 20145.798542014687, 20371.4907127323, 848.0967769325648, 864.7751584588052, 875.4790899414838, 885.7254251182026, 957.2389949989774, 7764.572948487775, 10562.419066951392, 10610.522526486327, 10633.946830265953, 10635.308704934308, 541977.0, 547768.0, 552935.0, 613670.0, 623461.0, 77459.0, 89876.20247595142, 131682.68317316705, 138805.0, 139516.3024184893, 928.5218112671979, 984.2372161574837, 1008.8284991551307, 1014.6195176808696, 1017.4658304390107, 17438.47684569465, 18987.71518427252, 19047.14287857022, 19334.0, 19410.320638777575, 52301.95396092133, 53349.19976200283, 53992.0, 54776.17835732387, 54801.725991369785, 72461.27538724756, 74096.25903741109, 77495.61252193703, 78088.60955695412, 80916.59541702255, 6998.6850591724515, 8124.796880078045, 8201.753947381589, 8333.708320208763, 8356.540390278593, 9081.863772043293, 12028.819567706667, 12256.0, 12476.563320283747, 12578.245305281713, 9608.0, 10323.845142323022, 10391.272610917164, 10584.84122738175, 10690.251682382159, 10552.947235263775, 10620.734481638021, 10623.0, 10666.573337066431, 11144.996950274513, 10303.072723454925, 10715.946420268161, 10868.782624347627, 10951.890481095406, 11013.174011948975, 8124.025116985763, 8189.836203276021, 8212.83574328522, 8274.048484424367, 8401.453905496122, 8000.59997000136, 9661.323707340418, 10640.0, 10924.617638382619, 10937.0], "value": 9520.292290368745, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1569.4193148535041, 1619.287513494063, 1691.8054423741266, 1851.9074046297683, 1937.5156210947264, 7869.567173805438, 8555.87166192506, 8734.344924130692, 8960.14878586534, 8970.461772293667, 8674.91325086749, 8692.608832602533, 8770.780730481725, 8797.384183107186, 8821.426607270523, 2465.445274813166, 2475.2945410557904, 2479.429731161834, 2479.528889510987, 2479.925602231927, 2421.794147497465, 2480.08236952327, 2483.801295896324, 2485.0680994627014, 2485.8508489490596, 2427.3446169534213, 2453.472503411766, 2490.340059884123, 2490.5890528062846, 2497.5878979968284, 2443.389152711831, 2454.5459090068307, 2458.483718419134, 2474.0, 2479.1570865905496, 2432.4891772727747, 2433.415980164748, 2462.9532448709406, 2468.987655061731, 2483.552960467115, 8844.867326990101, 8852.601632926533, 8904.688335908277, 8906.0, 8906.421082629608, 5362.302900622933, 5382.67703937765, 5403.297571315743, 5499.065158922993, 5504.972475137599, 3512.701420379271, 3602.837872295753, 3626.34725749365, 3654.26914617075, 3658.8536458541626, 1042.8957104289586, 1055.9155267578576, 1057.133150816331, 1058.560697310615, 1062.5590379992293, 12091.637250882488, 12241.93879030608, 12242.693932651666, 12266.693332666666, 12273.956713679348, 4833.202521583969, 4895.877603059952, 4900.828471003498, 4930.137225985438, 4989.700617962933, 9199.310051746146, 10269.0, 10275.383476991405, 10300.484975751182, 10301.021402966706, 5254.4220135785035, 5463.48096930791, 5475.0, 5485.807996720096, 5512.173174023928, 3595.227026189382, 4757.5718185363485, 4814.253790662453, 4851.078295123915, 4927.162382394929, 3426.8286585670617, 4045.1707399982934, 4045.959540404558, 4103.85636502721, 4178.811953462041, 1281.3144967442374, 1361.5234667866366, 1372.9176249425066, 1375.7454870848726, 1375.8142650742222, 725.6226762083687, 844.0883845446903, 850.9234168924827, 941.1341565759518, 941.7363138321281, 5677.489025987681, 5736.913946290809, 5816.47651711348, 5849.94150058494, 5927.9407205928255, 908.42315129893, 954.2556805691572, 975.8097171051719, 1004.4324956399611, 1032.9173666106694, 3430.691237788611, 3732.496113024761, 3871.8838434847, 3917.5298964124486, 3955.110100227426, 10412.271141020055, 10901.509432075576, 10939.453027348602, 10952.288101273392, 11040.951109644571, 10783.352998820095, 10810.783784324269, 10824.837627435594, 10857.565697372174, 10863.0, 8324.66701331952, 8514.57427128641, 8537.786555336043, 8563.229309362312, 8576.485410875368, 5214.374275087014, 5504.201890725903, 5585.245991791218, 5819.447152520504, 5870.53035757147, 6852.6916288768125, 9592.66425675098, 9665.806683866424, 9669.564869580745, 9716.076972687584, 9494.525273736424, 9812.215022798016, 9833.213342932406, 9857.30998830075, 9857.852132217879, 6635.601863888182, 7294.927050729425, 7452.850942981218, 7510.812229694083, 7610.733624323122, 8246.257836794717, 8270.958645206736, 8313.875291870747, 8322.20939010793, 8333.999920009395, 6460.741570337322, 7261.056062711814, 7314.96342518284, 7376.668049937658, 7607.847843042998, 7244.782656520313, 7323.963380183065, 7341.889871652036, 7345.632718364167, 7395.852082958419, 6882.174139103339, 6897.793066208022, 6926.376626103674, 6961.860762784817, 6964.129483814523, 4761.952380476289, 4763.666543341933, 4768.809247630056, 4876.268559716071, 4918.655694101379, 4838.298446725275, 4848.5636292733825, 4866.853994380174, 4885.731284779371, 5097.719625420637], "value": 4621.446643796899, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [324.74020783373305, 325.7882376455304, 330.0609765217954, 330.8560776062413, 335.3644843022474, 4810.687305325151, 6614.702338394772, 6653.401193892555, 6686.866262674744, 6716.6305853178055, 6211.906821397672, 6611.537192396534, 6620.5365624406195, 6627.204735431755, 6643.236027856798, 4652.860414187571, 4675.485696573374, 4754.310624959396, 4769.236922092466, 4790.640701947351, 4715.3634259374885, 4750.382450281459, 4760.90478190436, 4763.0, 4768.90462190756, 4749.501302363236, 4773.641976851733, 4774.904501909959, 4785.808567657307, 4790.904181916377, 4475.731456112644, 4773.355596994414, 4795.328653988409, 4798.0, 4810.542998415145, 4135.193637240709, 4137.8758637240935, 4138.441310423114, 4138.813753381105, 4143.3784932260105, 5982.760689572412, 6238.313785483593, 6273.110671383725, 6294.716737746812, 6314.0, 5559.777608895639, 5611.775528978836, 5622.943770562283, 5630.521405680522, 5642.548596112301, 7004.894926576106, 7018.578885266849, 7032.331928466788, 7035.753748618774, 7066.470014748863, 4111.7048129839, 4519.909601807945, 4540.79566419512, 4551.180787458289, 4560.1335746208115, 6027.638341699512, 6766.661666916634, 6792.45660347176, 7488.101427828641, 7501.28737769911, 3712.2204337089515, 4150.335946248646, 4333.959849636065, 4352.455943007124, 4491.820327186876, 4577.725336479755, 4618.261078227468, 4625.167469855391, 4625.537446255347, 4639.188142075123, 5492.533134683516, 5509.696966666872, 5516.3380394352935, 5517.813670060957, 5549.833504994856, 7019.122609673791, 7094.184168820549, 7101.041359416413, 7103.644817759091, 7119.786406407712, 1334.2794890759076, 1650.3893559383105, 1656.213298683116, 1656.3126302584533, 1656.8343165683336, 1010.1464262698008, 1034.3018462537757, 1046.5133712823422, 1050.9159267258754, 1065.6057258814287, 5442.727863606804, 6436.871262574816, 6543.411093001652, 6588.308227636125, 6589.143411356493, 7050.788476345718, 7343.302386273295, 7347.926520734831, 7352.154502232205, 7360.2271761465345, 6515.934840651627, 6647.734090636417, 6647.767328143492, 6684.498662600325, 6696.1629796275465, 3199.4241036613635, 3223.968330134381, 3371.1403592084353, 3383.796972181677, 3999.8400063997115, 6370.534777001223, 7676.040494938133, 7785.014821220552, 7799.727009554752, 7838.490498117604, 6792.558483698544, 7726.652300646484, 7741.651625676858, 7755.3407960324, 7762.534247945141, 7717.57553334572, 7748.457607967501, 7752.418568607377, 7785.922140778633, 7847.372210223169, 7388.371988381048, 7412.962185294171, 7423.665935033045, 7425.8143546411775, 7435.330820226206, 5965.552583556338, 7281.235470275757, 7319.377852882564, 7331.890021649786, 7350.448716346188, 7387.519811212254, 7728.613569321624, 7738.458307918617, 7748.92251077482, 7779.92220077792, 4600.769961501845, 4625.722456652477, 4663.27719203524, 4679.46186188579, 4688.34363189147, 7382.52013619113, 7391.889121663072, 7404.481686281909, 7406.07424071991, 7422.4433167513735, 7121.181064177478, 7137.678804453708, 7141.285871412817, 7164.247753985965, 7181.06646135999, 6905.827354316182, 7014.263502332182, 7101.183363913216, 7121.216666166614, 7149.14210294768, 6840.794776156723, 6896.965515172392, 6915.93084069173, 6948.131483564554, 6970.860582788215, 7088.149422069385, 7113.146422429341, 7113.430925525842, 7118.644067796693, 7120.359167674934, 6941.757038503628, 6946.027556142043, 6954.374106330454, 6968.651567421507, 6980.581165130108], "value": 5101.718003749639, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [674.5480528046212, 681.2778454837876, 711.1075600121846, 731.8316787138958, 747.5813544415128, 16679.416220432286, 16867.83132168678, 17007.40474083409, 17038.914805425986, 17127.143642817857, 14802.629934251649, 14899.180545070018, 14900.031497952625, 14938.850611493881, 14981.850181498181, 716.330231233798, 902.6389444222298, 905.9184673379394, 930.1721467893547, 931.3945935142144, 909.9454032758023, 911.0525053943891, 913.6482454255126, 916.4501299220481, 922.8431166701677, 929.6699671616581, 954.6849539651932, 957.8467445208768, 960.8894977077621, 963.0610155098758, 941.580996456577, 946.0539460539461, 948.7580666929889, 951.6336210558916, 953.7138858342539, 705.3334598804132, 710.8862581986857, 712.9679164437613, 714.0824041107204, 716.0119035730659, 14818.40726370944, 15166.848331516654, 15228.390864365412, 15330.310136043905, 15366.615834604112, 12274.754504909852, 12358.876411235862, 12414.565490207799, 12451.377431128498, 12497.00023998087, 8174.182581741838, 9953.552090155961, 10036.046575575308, 10073.597056117747, 10233.232507561963, 2969.539721343195, 3106.502959526465, 3395.79449295499, 3413.9146521336916, 3463.2207753255566, 73450.63274683646, 79086.81369779458, 79330.0, 79740.60129699373, 80162.0, 9097.954510227539, 9170.95414522923, 9287.860682089775, 9351.018143095012, 9371.62513499466, 17037.914810425867, 17399.391021314193, 21198.47003824886, 21218.78781212199, 21363.893180533996, 7077.752278670325, 7274.745383911538, 7489.325960663457, 7492.438067144986, 7525.548467091992, 3637.5453068366455, 4097.242010228118, 4100.938485922714, 4103.938440923389, 4125.8968525787095, 3657.8719744809337, 3665.0287673766475, 3665.743397962117, 3728.6084961079237, 3746.5129533160516, 685.7291369908958, 688.1501345837886, 689.3106893106893, 706.9222385537689, 721.6644260418931, 498.9925151122736, 514.8609875333639, 553.8587660146637, 579.9623024503394, 607.7477846693573, 7587.0, 7937.841243175104, 8013.879791803128, 8068.677252909935, 8359.540225287546, 492.22228878372084, 500.41289008223504, 567.767215441667, 568.6303902463432, 603.3333166850567, 3708.925821483609, 3751.2872554214614, 3769.0, 3773.24535092977, 3917.177392747498, 81280.59359703281, 84699.0, 86331.56834215789, 87444.0, 87841.56079219563, 72916.63541682364, 73344.0, 77614.61192694001, 77662.0, 78522.0, 68473.31526684768, 69215.65392173007, 69568.30431695719, 69759.0, 70280.29719702738, 6318.715657795318, 7280.708771649075, 7285.635718214174, 7445.4043676506835, 7449.404047676281, 46810.95728777618, 47756.04487910292, 47828.52171478241, 48193.0, 48306.27540586824, 65548.34451655422, 66491.33508664851, 66941.66529167323, 67412.32587674061, 67496.0, 8255.958720206361, 9954.651587194414, 10020.849687254842, 10024.599016039278, 10269.229807764597, 10762.354258744188, 11095.500702468247, 11155.944220278847, 11266.211365204357, 11349.659510214708, 9709.417434953926, 9760.121589056735, 9811.116999470081, 10189.74525636865, 10794.40630765284, 10237.48812559384, 10366.740831479274, 10415.895841041796, 10690.89309106899, 11062.0, 10128.59485620567, 10156.593736250763, 10297.897021029694, 10426.530806113895, 10762.730931726457, 8927.687530936386, 8995.730128096167, 9428.62285508572, 9619.134277915022, 9653.903460965581, 9445.386049906734, 9518.286128540249, 9527.857082143912, 9540.399966347872, 9619.90380096218], "value": 6716.846198544011, "unit": "score"}], "name": "dromaeo_css", "value": 7345.639174760556, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 173.0, 157.0, 101.0, 168.0, 177.0, 135.0, 115.0, 117.0, 116.0], "value": 136.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 151.0, 168.0, 136.0, 133.0, 168.0, 177.0, 111.0, 203.0, 151.0], "value": 155.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [247.0, 194.0, 199.0, 278.0, 249.0, 197.0, 194.0, 202.0, 195.0, 197.0], "value": 215.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 83.0, 72.0, 148.0, 56.0, 56.0, 59.0, 72.0, 161.0, 56.0], "value": 82.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 84.0, 124.0, 78.0, 77.0, 81.0, 114.0, 80.0, 80.0, 115.0], "value": 91.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 87.0, 89.0, 92.0, 86.0, 89.0, 87.0, 90.0, 85.0], "value": 88.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 107.0, 96.0, 102.0, 128.0, 95.0, 150.0, 147.0, 95.0, 94.0], "value": 110.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 91.0, 89.0, 90.0, 90.0, 91.0, 93.0, 89.0, 89.0], "value": 90.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 71.0, 62.0, 66.0, 67.0, 66.0, 65.0, 82.0, 62.0, 62.0], "value": 66.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 67.0, 72.0, 71.0, 69.0, 75.0, 74.0, 68.0, 68.0, 71.0], "value": 69.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 80.0, 84.0, 81.0, 83.0, 80.0, 84.0, 84.0, 84.0], "value": 82.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 157.0, 150.0, 126.0, 124.0, 148.0, 154.0, 150.0, 147.0, 146.0], "value": 145.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 167.0, 168.0, 168.0, 166.0, 167.0, 165.0, 166.0, 168.0, 168.0], "value": 167.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 65.0, 70.0, 66.0, 70.0, 69.0, 65.0, 73.0, 68.0, 66.0], "value": 68.2, "unit": "score"}], "name": "kraken", "value": 1570.2, "alertThreshold": 2.0}]} 04:07:05 INFO - Return code: 0 04:07:05 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 04:07:05 ERROR - # TBPL SUCCESS # 04:07:05 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:07:05 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:07:05 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:07:05 INFO - 2016-05-11 04:07:05.908 screenresolution[1980:16315] starting screenresolution argv=screenresolution get 04:07:05 INFO - 2016-05-11 04:07:05.910 screenresolution[1980:16315] Display 0: 1600x1200x32@60 04:07:05 INFO - 2016-05-11 04:07:05.916 screenresolution[1981:16318] starting screenresolution argv=screenresolution list 04:07:05 INFO - Available Modes on Display 0 04:07:05 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:07:05 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:07:05 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:07:05 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:07:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:07:06 INFO - Intel Iris: 04:07:06 INFO - Chipset Model: Intel Iris 04:07:06 INFO - Type: GPU 04:07:06 INFO - Bus: Built-In 04:07:06 INFO - VRAM (Dynamic, Max): 1536 MB 04:07:06 INFO - Vendor: Intel (0x8086) 04:07:06 INFO - Device ID: 0x0a2e 04:07:06 INFO - Revision ID: 0x0009 04:07:06 INFO - Displays: 04:07:06 INFO - Display: 04:07:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:07:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:07:06 INFO - Display Serial Number: 1600x1200 60 04:07:06 INFO - Main Display: Yes 04:07:06 INFO - Mirror: Off 04:07:06 INFO - Online: Yes 04:07:06 INFO - Rotation: Supported 04:07:06 INFO - Return code: 0 04:07:06 INFO - Running post-action listener: _resource_record_post_action 04:07:06 INFO - Running post-run listener: _resource_record_post_run 04:07:07 INFO - Total resource usage - Wall time: 1205s; CPU: 53.0%; Read bytes: 851968; Write bytes: 656340992; Read time: 15; Write time: 1860 04:07:07 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 354439680; Write bytes: 355960320; Read time: 27798; Write time: 721 04:07:07 INFO - run-tests - Wall time: 1174s; CPU: 53.0%; Read bytes: 749568; Write bytes: 299532800; Read time: 12; Write time: 1136 04:07:07 INFO - Running post-run listener: _upload_blobber_files 04:07:07 INFO - Blob upload gear active. 04:07:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:07:07 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 04:07:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:07:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:07:07 INFO - (blobuploader) - INFO - Open directory for files ... 04:07:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:07:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:07:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:07:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:07:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:07:08 INFO - (blobuploader) - INFO - Done attempting. 04:07:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:07:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:07:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:07:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:07:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:07:09 INFO - (blobuploader) - INFO - Done attempting. 04:07:09 INFO - (blobuploader) - INFO - Iteration through files over. 04:07:09 INFO - Return code: 0 04:07:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:07:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:07:09 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5e7e957cf47bd4b8f9f5ca42e7de0f470e8a34c19e5e42c6ded13b280d4e478c28dbd7540a3e0cd5e21f5e102e5fe98fc35dc768cf6816c34d86780904a1bf76", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a1a10cea7ee02749c2325ef571f182e78a1e927ec18cc6184fdb20ae07520af657fca7135362fe8f5ed14274b3639080a38fee1f886c9f59603b90c8f50dfc7c"} 04:07:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:07:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:07:09 INFO - Contents: 04:07:09 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5e7e957cf47bd4b8f9f5ca42e7de0f470e8a34c19e5e42c6ded13b280d4e478c28dbd7540a3e0cd5e21f5e102e5fe98fc35dc768cf6816c34d86780904a1bf76", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a1a10cea7ee02749c2325ef571f182e78a1e927ec18cc6184fdb20ae07520af657fca7135362fe8f5ed14274b3639080a38fee1f886c9f59603b90c8f50dfc7c"} 04:07:09 INFO - Running post-run listener: copy_logs_to_upload_dir 04:07:09 INFO - Copying logs to upload dir... 04:07:09 INFO - mkdir: /builds/slave/test/build/upload/logs 04:07:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.872341 ========= master_lag: 5.09 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-05-11 04:07:14.875653) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 04:07:14.880130) ========= 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.CoGeYFnCDh/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.tStY6duBFS/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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/Mozilla-Aurora/sha512/5e7e957cf47bd4b8f9f5ca42e7de0f470e8a34c19e5e42c6ded13b280d4e478c28dbd7540a3e0cd5e21f5e102e5fe98fc35dc768cf6816c34d86780904a1bf76", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a1a10cea7ee02749c2325ef571f182e78a1e927ec18cc6184fdb20ae07520af657fca7135362fe8f5ed14274b3639080a38fee1f886c9f59603b90c8f50dfc7c"} build_url:https://queue.taskcluster.net/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011241 build_url: 'https://queue.taskcluster.net/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5e7e957cf47bd4b8f9f5ca42e7de0f470e8a34c19e5e42c6ded13b280d4e478c28dbd7540a3e0cd5e21f5e102e5fe98fc35dc768cf6816c34d86780904a1bf76", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a1a10cea7ee02749c2325ef571f182e78a1e927ec18cc6184fdb20ae07520af657fca7135362fe8f5ed14274b3639080a38fee1f886c9f59603b90c8f50dfc7c"}' ========= master_lag: 0.37 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 04:07:15.262022) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 04:07:15.262304) ========= 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.CoGeYFnCDh/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.tStY6duBFS/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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.004596 ========= master_lag: 0.03 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 04:07:15.296997) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 04:07:15.297297) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 04:07:15.302244) ========= ========= Total master_lag: 5.56 =========