builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0125 starttime: 1463119738.57 results: success (0) buildid: 20160512210646 builduid: 4f280fd461904b8cb3948fbfd70a91ac revision: f4db6cf4303fd75de4e539b281a65d016b4cc8dd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 23:08:58.571424) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 23:08:58.571840) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 23:08:58.572134) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 23:08:58.594781) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 23:08:58.595090) ========= 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.M1Geu1So7x/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.MgIBKEdIin/Listeners TMPDIR=/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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-12 23:08:58-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 148M=0s 2016-05-12 23:08:58 (148 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.107256 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 23:08:58.723358) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 23:08:58.723672) ========= 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.M1Geu1So7x/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.MgIBKEdIin/Listeners TMPDIR=/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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.027602 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 23:08:58.771583) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-12 23:08:58.771912) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev f4db6cf4303fd75de4e539b281a65d016b4cc8dd --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-beta --rev f4db6cf4303fd75de4e539b281a65d016b4cc8dd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.M1Geu1So7x/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.MgIBKEdIin/Listeners TMPDIR=/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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-12 23:08:58,829 truncating revision to first 12 chars 2016-05-12 23:08:58,829 Setting DEBUG logging. 2016-05-12 23:08:58,829 attempt 1/10 2016-05-12 23:08:58,829 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/f4db6cf4303f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-12 23:08:59,995 unpacking tar archive at: mozilla-beta-f4db6cf4303f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.412843 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-12 23:09:00.208082) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 23:09:00.208423) ========= script_repo_revision: f4db6cf4303fd75de4e539b281a65d016b4cc8dd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 23:09:00.208849) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 23:09:00.209135) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 23:09:00.224677) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-05-12 23:09:00.224964) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.M1Geu1So7x/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.MgIBKEdIin/Listeners TMPDIR=/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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 23:09:00 INFO - MultiFileLogger online at 20160512 23:09:00 in /builds/slave/test 23:09:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 23:09:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:09:00 INFO - {'append_to_log': False, 23:09:00 INFO - 'base_work_dir': '/builds/slave/test', 23:09:00 INFO - 'blob_upload_branch': 'Mozilla-Beta', 23:09:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:09:00 INFO - 'branch': 'Mozilla-Beta', 23:09:00 INFO - 'buildbot_json_path': 'buildprops.json', 23:09:00 INFO - 'config_files': ('talos/mac_config.py',), 23:09:00 INFO - 'default_actions': ('clobber', 23:09:00 INFO - 'read-buildbot-config', 23:09:00 INFO - 'download-and-extract', 23:09:00 INFO - 'populate-webroot', 23:09:00 INFO - 'create-virtualenv', 23:09:00 INFO - 'install', 23:09:00 INFO - 'run-tests'), 23:09:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:09:00 INFO - 'download_minidump_stackwalk': True, 23:09:00 INFO - 'download_symbols': 'ondemand', 23:09:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:09:00 INFO - 'tooltool.py': '/tools/tooltool.py', 23:09:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:09:00 INFO - '/tools/misc-python/virtualenv.py')}, 23:09:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:09:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:09:00 INFO - 'installer_path': 'installer.exe', 23:09:00 INFO - 'log_level': 'info', 23:09:00 INFO - 'log_name': 'talos', 23:09:00 INFO - 'log_to_console': True, 23:09:00 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 23:09:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 23:09:00 INFO - 'opt_config_files': (), 23:09:00 INFO - 'pip_index': False, 23:09:00 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:09:00 INFO - 'cmd': ('bash', 23:09:00 INFO - '-c', 23:09:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:09:00 INFO - 'enabled': True, 23:09:00 INFO - 'halt_on_failure': False, 23:09:00 INFO - 'name': 'check_screen_resolution'},), 23:09:00 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:09:00 INFO - 'cmd': ('bash', 23:09:00 INFO - '-c', 23:09:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:09:00 INFO - 'enabled': True, 23:09:00 INFO - 'halt_on_failure': False, 23:09:00 INFO - 'name': 'check_screen_resolution'},), 23:09:00 INFO - 'run_cmd_checks_enabled': True, 23:09:00 INFO - 'sps_profile': False, 23:09:00 INFO - 'sps_profile_interval': 0, 23:09:00 INFO - 'suite': 'dromaeojs-e10s', 23:09:00 INFO - 'system_bits': '32', 23:09:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:09:00 INFO - 'title': 't-yosemite-r7-0125', 23:09:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:09:00 INFO - 'use_talos_json': True, 23:09:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:09:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:09:00 INFO - 'work_dir': 'build'} 23:09:00 INFO - ##### 23:09:00 INFO - ##### Running clobber step. 23:09:00 INFO - ##### 23:09:00 INFO - Running pre-action listener: _resource_record_pre_action 23:09:00 INFO - Running main action method: clobber 23:09:00 INFO - rmtree: /builds/slave/test/build 23:09:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:09:02 INFO - Running post-action listener: _resource_record_post_action 23:09:02 INFO - ##### 23:09:02 INFO - ##### Running read-buildbot-config step. 23:09:02 INFO - ##### 23:09:02 INFO - Running pre-action listener: _resource_record_pre_action 23:09:02 INFO - Running main action method: read_buildbot_config 23:09:02 INFO - Using buildbot properties: 23:09:02 INFO - { 23:09:02 INFO - "project": "", 23:09:02 INFO - "product": "firefox", 23:09:02 INFO - "script_repo_revision": "production", 23:09:02 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 23:09:02 INFO - "repository": "", 23:09:02 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 23:09:02 INFO - "buildid": "20160512210646", 23:09:02 INFO - "pgo_build": "False", 23:09:02 INFO - "basedir": "/builds/slave/test", 23:09:02 INFO - "buildnumber": 24, 23:09:02 INFO - "slavename": "t-yosemite-r7-0125", 23:09:02 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 23:09:02 INFO - "platform": "yosemite_r7", 23:09:02 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 23:09:02 INFO - "branch": "mozilla-beta", 23:09:02 INFO - "repo_path": "releases/mozilla-beta", 23:09:02 INFO - "revision": "f4db6cf4303fd75de4e539b281a65d016b4cc8dd", 23:09:02 INFO - "stage_platform": "macosx64", 23:09:02 INFO - "builduid": "4f280fd461904b8cb3948fbfd70a91ac", 23:09:02 INFO - "slavebuilddir": "test" 23:09:02 INFO - } 23:09:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/KOpzZRu0SYGza3ypIyiE6w/artifacts/public/build/firefox-47.0.en-US.mac.dmg. 23:09:02 INFO - Running post-action listener: _resource_record_post_action 23:09:02 INFO - ##### 23:09:02 INFO - ##### Running download-and-extract step. 23:09:02 INFO - ##### 23:09:02 INFO - Running pre-action listener: _resource_record_pre_action 23:09:02 INFO - Running main action method: download_and_extract 23:09:02 INFO - mkdir: /builds/slave/test/build/tests 23:09:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:09:02 INFO - https://queue.taskcluster.net/v1/task/KOpzZRu0SYGza3ypIyiE6w/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 23:09:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOpzZRu0SYGza3ypIyiE6w/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 23:09:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOpzZRu0SYGza3ypIyiE6w/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 23:09:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOpzZRu0SYGza3ypIyiE6w/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 23:09:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOpzZRu0SYGza3ypIyiE6w/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json'}, attempt #1 23:09:03 INFO - Downloaded 1241 bytes. 23:09:03 INFO - Reading from file /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 23:09:03 INFO - Using the following test package requirements: 23:09:03 INFO - {u'common': [u'firefox-47.0.en-US.mac.common.tests.zip'], 23:09:03 INFO - u'cppunittest': [u'firefox-47.0.en-US.mac.common.tests.zip', 23:09:03 INFO - u'firefox-47.0.en-US.mac.cppunittest.tests.zip'], 23:09:03 INFO - u'gtest': [u'firefox-47.0.en-US.mac.common.tests.zip', 23:09:03 INFO - u'firefox-47.0.en-US.mac.gtest.tests.zip'], 23:09:03 INFO - u'jittest': [u'firefox-47.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 23:09:03 INFO - u'mochitest': [u'firefox-47.0.en-US.mac.common.tests.zip', 23:09:03 INFO - u'firefox-47.0.en-US.mac.mochitest.tests.zip'], 23:09:03 INFO - u'mozbase': [u'firefox-47.0.en-US.mac.common.tests.zip'], 23:09:03 INFO - u'reftest': [u'firefox-47.0.en-US.mac.common.tests.zip', 23:09:03 INFO - u'firefox-47.0.en-US.mac.reftest.tests.zip'], 23:09:03 INFO - u'talos': [u'firefox-47.0.en-US.mac.common.tests.zip', 23:09:03 INFO - u'firefox-47.0.en-US.mac.talos.tests.zip'], 23:09:03 INFO - u'web-platform': [u'firefox-47.0.en-US.mac.common.tests.zip', 23:09:03 INFO - u'firefox-47.0.en-US.mac.web-platform.tests.zip'], 23:09:03 INFO - u'webapprt': [u'firefox-47.0.en-US.mac.common.tests.zip'], 23:09:03 INFO - u'xpcshell': [u'firefox-47.0.en-US.mac.common.tests.zip', 23:09:03 INFO - u'firefox-47.0.en-US.mac.xpcshell.tests.zip']} 23:09:03 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip'] for test suite category: common 23:09:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:09:03 INFO - https://queue.taskcluster.net/v1/task/KOpzZRu0SYGza3ypIyiE6w/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 23:09:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOpzZRu0SYGza3ypIyiE6w/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 23:09:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOpzZRu0SYGza3ypIyiE6w/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 23:09:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOpzZRu0SYGza3ypIyiE6w/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip 23:09:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOpzZRu0SYGza3ypIyiE6w/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #1 23:09:04 INFO - Downloaded 17747496 bytes. 23:09:04 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:09:04 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 23:09:07 INFO - Return code: 0 23:09:07 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip', u'firefox-47.0.en-US.mac.talos.tests.zip'] for test suite category: talos 23:09:07 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')]} 23:09:07 INFO - https://queue.taskcluster.net/v1/task/KOpzZRu0SYGza3ypIyiE6w/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 23:09:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOpzZRu0SYGza3ypIyiE6w/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 23:09:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOpzZRu0SYGza3ypIyiE6w/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 23:09:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOpzZRu0SYGza3ypIyiE6w/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip 23:09:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOpzZRu0SYGza3ypIyiE6w/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #1 23:09:09 INFO - Downloaded 17747496 bytes. 23:09:09 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:09:09 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 23:09:12 INFO - Return code: 0 23:09:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:09:12 INFO - https://queue.taskcluster.net/v1/task/KOpzZRu0SYGza3ypIyiE6w/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 23:09:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOpzZRu0SYGza3ypIyiE6w/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 23:09:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOpzZRu0SYGza3ypIyiE6w/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 23:09:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOpzZRu0SYGza3ypIyiE6w/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip 23:09:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOpzZRu0SYGza3ypIyiE6w/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip'}, attempt #1 23:09:14 INFO - Downloaded 10845194 bytes. 23:09:14 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 23:09:14 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 23:09:14 INFO - Return code: 0 23:09:14 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')]} 23:09:14 INFO - https://queue.taskcluster.net/v1/task/KOpzZRu0SYGza3ypIyiE6w/artifacts/public/build/firefox-47.0.en-US.mac.dmg matches https://queue.taskcluster.net 23:09:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOpzZRu0SYGza3ypIyiE6w/artifacts/public/build/firefox-47.0.en-US.mac.dmg 23:09:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOpzZRu0SYGza3ypIyiE6w/artifacts/public/build/firefox-47.0.en-US.mac.dmg 23:09:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOpzZRu0SYGza3ypIyiE6w/artifacts/public/build/firefox-47.0.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0.en-US.mac.dmg 23:09:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOpzZRu0SYGza3ypIyiE6w/artifacts/public/build/firefox-47.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.dmg'}, attempt #1 23:09:17 INFO - Downloaded 88268645 bytes. 23:09:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KOpzZRu0SYGza3ypIyiE6w/artifacts/public/build/firefox-47.0.en-US.mac.dmg 23:09:17 INFO - mkdir: /builds/slave/test/properties 23:09:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:09:17 INFO - Writing to file /builds/slave/test/properties/build_url 23:09:17 INFO - Contents: 23:09:17 INFO - build_url:https://queue.taskcluster.net/v1/task/KOpzZRu0SYGza3ypIyiE6w/artifacts/public/build/firefox-47.0.en-US.mac.dmg 23:09:18 INFO - Running post-action listener: _resource_record_post_action 23:09:18 INFO - Running post-action listener: set_extra_try_arguments 23:09:18 INFO - ##### 23:09:18 INFO - ##### Running populate-webroot step. 23:09:18 INFO - ##### 23:09:18 INFO - Running pre-action listener: _resource_record_pre_action 23:09:18 INFO - Running main action method: populate_webroot 23:09:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:09:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:09:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:09:18 INFO - '--tppagecycles', 23:09:18 INFO - '7'], 23:09:18 INFO - 'tests': ['tsvgm']}}, 23:09:18 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 23:09:18 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 23:09:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:09:18 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 23:09:18 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 23:09:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:09:18 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:09:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:09:18 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:09:18 INFO - 'talos_options': ['--e10s'], 23:09:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:09:18 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:09:18 INFO - 'tests': ['damp', 'tps']}, 23:09:18 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:09:18 INFO - 'talos_options': ['--e10s'], 23:09:18 INFO - 'tests': ['damp', 'tps']}, 23:09:18 INFO - 'g3': {'tests': ['dromaeo_dom']}, 23:09:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:09:18 INFO - 'other': {'tests': ['a11yr', 23:09:18 INFO - 'ts_paint', 23:09:18 INFO - 'tpaint', 23:09:18 INFO - 'sessionrestore', 23:09:18 INFO - 'sessionrestore_no_auto_restore', 23:09:18 INFO - 'tabpaint']}, 23:09:18 INFO - 'other-e10s': {'talos_options': ['--e10s'], 23:09:18 INFO - 'tests': ['a11yr', 23:09:18 INFO - 'ts_paint', 23:09:18 INFO - 'tpaint', 23:09:18 INFO - 'sessionrestore', 23:09:18 INFO - 'sessionrestore_no_auto_restore', 23:09:18 INFO - 'tabpaint']}, 23:09:18 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 23:09:18 INFO - 'tests': ['a11yr', 23:09:18 INFO - 'ts_paint', 23:09:18 INFO - 'tpaint', 23:09:18 INFO - 'sessionrestore', 23:09:18 INFO - 'sessionrestore_no_auto_restore', 23:09:18 INFO - 'tabpaint']}, 23:09:18 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 23:09:18 INFO - 'tests': ['a11yr', 23:09:18 INFO - 'ts_paint', 23:09:18 INFO - 'tpaint', 23:09:18 INFO - 'sessionrestore', 23:09:18 INFO - 'sessionrestore_no_auto_restore', 23:09:18 INFO - 'tabpaint']}, 23:09:18 INFO - 'other_l64': {'tests': ['a11yr', 23:09:18 INFO - 'ts_paint', 23:09:18 INFO - 'tpaint', 23:09:18 INFO - 'sessionrestore', 23:09:18 INFO - 'sessionrestore_no_auto_restore', 23:09:18 INFO - 'tabpaint']}, 23:09:18 INFO - 'other_nol64': {'tests': ['a11yr', 23:09:18 INFO - 'ts_paint', 23:09:18 INFO - 'tpaint', 23:09:18 INFO - 'sessionrestore', 23:09:18 INFO - 'sessionrestore_no_auto_restore', 23:09:18 INFO - 'tabpaint']}, 23:09:18 INFO - 'svgr': {'tests': ['tsvgx', 23:09:18 INFO - 'tsvgr_opacity', 23:09:18 INFO - 'tart', 23:09:18 INFO - 'tscrollx', 23:09:18 INFO - 'cart']}, 23:09:18 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 23:09:18 INFO - 'tests': ['tsvgx', 23:09:18 INFO - 'tsvgr_opacity', 23:09:18 INFO - 'tart', 23:09:18 INFO - 'tscrollx', 23:09:18 INFO - 'cart']}, 23:09:18 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:09:18 INFO - 'tests': ['tp5o']}, 23:09:18 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:09:18 INFO - 'talos_options': ['--e10s'], 23:09:18 INFO - 'tests': ['tp5o']}, 23:09:18 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:09:18 INFO - 'talos_options': ['--xperf_path', 23:09:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:09:18 INFO - 'tests': ['tp5n']}, 23:09:18 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:09:18 INFO - 'talos_options': ['--e10s', 23:09:18 INFO - '--xperf_path', 23:09:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:09:18 INFO - 'tests': ['tp5n']}}, 23:09:18 INFO - 'talos.zip': {'path': '', 23:09:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:09:18 INFO - Running post-action listener: _resource_record_post_action 23:09:18 INFO - ##### 23:09:18 INFO - ##### Running create-virtualenv step. 23:09:18 INFO - ##### 23:09:18 INFO - Running pre-action listener: _resource_record_pre_action 23:09:18 INFO - Running main action method: create_virtualenv 23:09:18 INFO - Creating virtualenv /builds/slave/test/build/venv 23:09:18 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 23:09:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:09:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:09:18 INFO - Using real prefix '/tools/python27' 23:09:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:09:19 INFO - Installing distribute.............................................................................................................................................................................................done. 23:09:21 INFO - Installing pip.................done. 23:09:22 INFO - Return code: 0 23:09:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:09: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')]} 23:09:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:09:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:09: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')]} 23:09:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:09:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:09:22 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 0x102de1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102de84c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd911d8e650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c85d30>, '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 0x102ae9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102c7cb20>, '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.M1Geu1So7x/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.MgIBKEdIin/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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 23:09:22 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 23:09:22 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 23:09:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.M1Geu1So7x/Render', 23:09:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:09:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:09:22 INFO - 'HOME': '/Users/cltbld', 23:09:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:09:22 INFO - 'LOGNAME': 'cltbld', 23:09:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:09:22 INFO - 'MOZ_NO_REMOTE': '1', 23:09:22 INFO - 'NO_EM_RESTART': '1', 23:09:22 INFO - 'PAGER': '/bin/cat', 23:09:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:09:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:09:22 INFO - 'PWD': '/builds/slave/test', 23:09:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:09:22 INFO - 'SHELL': '/bin/bash', 23:09:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MgIBKEdIin/Listeners', 23:09:22 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 23:09:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:09:22 INFO - 'USER': 'cltbld', 23:09:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:09:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:09:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:09:22 INFO - 'XPC_FLAGS': '0x0', 23:09:22 INFO - 'XPC_SERVICE_NAME': '0', 23:09:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:09:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:09:22 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 23:09:22 INFO - Cleaning up... 23:09:22 INFO - Return code: 0 23:09:22 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 23:09: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')]} 23:09:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:09:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:09: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')]} 23:09:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:09:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:09:22 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 0x102de1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102de84c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd911d8e650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c85d30>, '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 0x102ae9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102c7cb20>, '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.M1Geu1So7x/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.MgIBKEdIin/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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 23:09:22 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 23:09:22 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 23:09:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.M1Geu1So7x/Render', 23:09:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:09:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:09:22 INFO - 'HOME': '/Users/cltbld', 23:09:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:09:22 INFO - 'LOGNAME': 'cltbld', 23:09:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:09:22 INFO - 'MOZ_NO_REMOTE': '1', 23:09:22 INFO - 'NO_EM_RESTART': '1', 23:09:22 INFO - 'PAGER': '/bin/cat', 23:09:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:09:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:09:22 INFO - 'PWD': '/builds/slave/test', 23:09:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:09:22 INFO - 'SHELL': '/bin/bash', 23:09:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MgIBKEdIin/Listeners', 23:09:22 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 23:09:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:09:22 INFO - 'USER': 'cltbld', 23:09:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:09:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:09:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:09:22 INFO - 'XPC_FLAGS': '0x0', 23:09:22 INFO - 'XPC_SERVICE_NAME': '0', 23:09:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:09:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:09:22 INFO - Downloading/unpacking psutil>=0.7.1 23:09:22 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 23:09:22 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 23:09:22 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 23:09:22 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 23:09:22 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 23:09:22 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 23:09:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:09:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:09:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:09:25 INFO - Installing collected packages: psutil 23:09:25 INFO - Running setup.py install for psutil 23:09:25 INFO - building 'psutil._psutil_osx' extension 23:09:25 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 23:09:26 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 23:09:26 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 23:09:26 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 23:09:26 INFO - building 'psutil._psutil_posix' extension 23:09:26 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 23:09:26 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 23:09:26 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 23:09:26 INFO - ^ 23:09:26 INFO - 1 warning generated. 23:09:26 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 23:09:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:09:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:09:26 INFO - Successfully installed psutil 23:09:26 INFO - Cleaning up... 23:09:26 INFO - Return code: 0 23:09:26 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 23:09:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:09:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:09:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:09:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:09:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:09:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:09:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102de1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102de84c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd911d8e650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c85d30>, '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 0x102ae9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102c7cb20>, '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.M1Geu1So7x/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.MgIBKEdIin/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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 23:09:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 23:09:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 23:09:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.M1Geu1So7x/Render', 23:09:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:09:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:09:26 INFO - 'HOME': '/Users/cltbld', 23:09:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:09:26 INFO - 'LOGNAME': 'cltbld', 23:09:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:09:26 INFO - 'MOZ_NO_REMOTE': '1', 23:09:26 INFO - 'NO_EM_RESTART': '1', 23:09:26 INFO - 'PAGER': '/bin/cat', 23:09:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:09:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:09:26 INFO - 'PWD': '/builds/slave/test', 23:09:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:09:26 INFO - 'SHELL': '/bin/bash', 23:09:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MgIBKEdIin/Listeners', 23:09:26 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 23:09:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:09:26 INFO - 'USER': 'cltbld', 23:09:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:09:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:09:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:09:26 INFO - 'XPC_FLAGS': '0x0', 23:09:26 INFO - 'XPC_SERVICE_NAME': '0', 23:09:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:09:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:09:27 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 23:09:27 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 23:09:27 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 23:09:27 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 23:09:27 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 23:09:27 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 23:09:27 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 23:09:29 INFO - Downloading mozsystemmonitor-0.0.tar.gz 23:09:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:09:29 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 23:09:29 INFO - Installing collected packages: mozsystemmonitor 23:09:29 INFO - Running setup.py install for mozsystemmonitor 23:09:29 INFO - Successfully installed mozsystemmonitor 23:09:29 INFO - Cleaning up... 23:09:29 INFO - Return code: 0 23:09:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:09:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:09:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:09:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:09:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:09:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:09:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:09:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102de1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102de84c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd911d8e650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c85d30>, '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 0x102ae9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102c7cb20>, '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.M1Geu1So7x/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.MgIBKEdIin/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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 23:09:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 23:09:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 23:09:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.M1Geu1So7x/Render', 23:09:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:09:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:09:29 INFO - 'HOME': '/Users/cltbld', 23:09:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:09:29 INFO - 'LOGNAME': 'cltbld', 23:09:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:09:29 INFO - 'MOZ_NO_REMOTE': '1', 23:09:29 INFO - 'NO_EM_RESTART': '1', 23:09:29 INFO - 'PAGER': '/bin/cat', 23:09:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:09:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:09:29 INFO - 'PWD': '/builds/slave/test', 23:09:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:09:29 INFO - 'SHELL': '/bin/bash', 23:09:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MgIBKEdIin/Listeners', 23:09:29 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 23:09:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:09:29 INFO - 'USER': 'cltbld', 23:09:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:09:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:09:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:09:29 INFO - 'XPC_FLAGS': '0x0', 23:09:29 INFO - 'XPC_SERVICE_NAME': '0', 23:09:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:09:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:09:30 INFO - Downloading/unpacking blobuploader==1.2.4 23:09: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 23:09: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 23:09: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 23:09: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 23:09: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 23:09: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 23:09:32 INFO - Downloading blobuploader-1.2.4.tar.gz 23:09:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:09:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:09:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:09:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:09:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:09:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:09:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:09:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:09:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:09:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:09:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:09:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:09:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:09:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:09:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:09:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:09:33 INFO - Downloading docopt-0.6.1.tar.gz 23:09:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:09:33 INFO - Installing collected packages: blobuploader, requests, docopt 23:09:33 INFO - Running setup.py install for blobuploader 23:09:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:09:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:09:33 INFO - Running setup.py install for requests 23:09:34 INFO - Running setup.py install for docopt 23:09:34 INFO - Successfully installed blobuploader requests docopt 23:09:34 INFO - Cleaning up... 23:09:34 INFO - Return code: 0 23:09:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:09: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')]} 23:09:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:09:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:09: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')]} 23:09:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:09:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:09:34 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 0x102de1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102de84c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd911d8e650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c85d30>, '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 0x102ae9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102c7cb20>, '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.M1Geu1So7x/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.MgIBKEdIin/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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 23:09:34 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 23:09:34 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 23:09:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.M1Geu1So7x/Render', 23:09:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:09:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:09:34 INFO - 'HOME': '/Users/cltbld', 23:09:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:09:34 INFO - 'LOGNAME': 'cltbld', 23:09:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:09:34 INFO - 'MOZ_NO_REMOTE': '1', 23:09:34 INFO - 'NO_EM_RESTART': '1', 23:09:34 INFO - 'PAGER': '/bin/cat', 23:09:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:09:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:09:34 INFO - 'PWD': '/builds/slave/test', 23:09:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:09:34 INFO - 'SHELL': '/bin/bash', 23:09:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MgIBKEdIin/Listeners', 23:09:34 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 23:09:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:09:34 INFO - 'USER': 'cltbld', 23:09:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:09:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:09:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:09:34 INFO - 'XPC_FLAGS': '0x0', 23:09:34 INFO - 'XPC_SERVICE_NAME': '0', 23:09:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:09:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:09:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:09:34 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-ucYu0Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:09:35 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-OEXxC1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:09:35 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-LQSWIk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:09:35 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-c3TpBR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:09:35 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-bzMFMm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:09:35 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-5wXfeD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:09:35 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-vA98ge-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:09:35 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-zVHz8o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:09:35 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-NjET9Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:09:35 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-vDtLWr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:09:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:09:35 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-qHI5vF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:09:36 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-8U7MgB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:09:36 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-35MIbD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:09:36 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-R_UmNg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:09:36 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-FeMyLH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:09:36 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-9TnTq9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:09:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:09:36 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-MM8biY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:09:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:09:36 INFO - Running setup.py install for manifestparser 23:09:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:09:36 INFO - Running setup.py install for mozcrash 23:09:36 INFO - Running setup.py install for mozdebug 23:09:37 INFO - Running setup.py install for mozdevice 23:09:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:09:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:09:37 INFO - Running setup.py install for mozfile 23:09:37 INFO - Running setup.py install for mozhttpd 23:09:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:09:37 INFO - Running setup.py install for mozinfo 23:09:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:09:37 INFO - Running setup.py install for mozInstall 23:09:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:09:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:09:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:09:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:09:37 INFO - Running setup.py install for mozleak 23:09:37 INFO - Running setup.py install for mozlog 23:09:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:09:38 INFO - Running setup.py install for moznetwork 23:09:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:09:38 INFO - Running setup.py install for mozprocess 23:09:38 INFO - Running setup.py install for mozprofile 23:09:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:09:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:09:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:09:38 INFO - Running setup.py install for mozrunner 23:09:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:09:38 INFO - Running setup.py install for mozscreenshot 23:09:38 INFO - Running setup.py install for moztest 23:09:39 INFO - Running setup.py install for mozversion 23:09:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:09:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:09:39 INFO - Cleaning up... 23:09:39 INFO - Return code: 0 23:09:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:09:39 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')]} 23:09:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:09:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:09:39 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')]} 23:09:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:09:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:09:39 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 0x102de1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102de84c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd911d8e650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c85d30>, '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 0x102ae9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102c7cb20>, '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.M1Geu1So7x/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.MgIBKEdIin/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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 23:09:39 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 23:09:39 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 23:09:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.M1Geu1So7x/Render', 23:09:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:09:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:09:39 INFO - 'HOME': '/Users/cltbld', 23:09:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:09:39 INFO - 'LOGNAME': 'cltbld', 23:09:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:09:39 INFO - 'MOZ_NO_REMOTE': '1', 23:09:39 INFO - 'NO_EM_RESTART': '1', 23:09:39 INFO - 'PAGER': '/bin/cat', 23:09:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:09:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:09:39 INFO - 'PWD': '/builds/slave/test', 23:09:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:09:39 INFO - 'SHELL': '/bin/bash', 23:09:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MgIBKEdIin/Listeners', 23:09:39 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 23:09:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:09:39 INFO - 'USER': 'cltbld', 23:09:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:09:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:09:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:09:39 INFO - 'XPC_FLAGS': '0x0', 23:09:39 INFO - 'XPC_SERVICE_NAME': '0', 23:09:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:09:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:09:39 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-1EsnJr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:09:39 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)) 23:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:09:39 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-X0776l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:09:39 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)) 23:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:09:39 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-BaRdnL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:09:39 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)) 23:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:09:39 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-QYUT9I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:09:39 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)) 23:09:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:09:39 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-IOVNDG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:09:40 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)) 23:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:09:40 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-OEs_si-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:09:40 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)) 23:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:09:40 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-IOONKr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:09:40 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)) 23:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:09:40 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-LH7rEA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:09:40 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)) 23:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:09:40 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-dShQuI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:09:40 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)) 23:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:09:40 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-syWX9e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:09:40 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)) 23:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:09:40 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-T33pFn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:09:40 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)) 23:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:09:40 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-BajKEP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:09:40 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)) 23:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:09:40 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-3SsQUk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:09:40 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)) 23:09:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:09:40 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-0yzQf5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:09:41 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)) 23:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:09:41 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-5af_Dn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:09:41 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)) 23:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:09:41 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-HyByFh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:09:41 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)) 23:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:09:41 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-Oiw6D4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:09:41 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)) 23:09:41 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)) 23:09:41 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)) 23:09:41 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)) 23:09:41 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)) 23:09:41 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)) 23:09:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:09:41 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 23:09:41 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 23:09:41 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 23:09:41 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 23:09:41 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 23:09:41 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 23:09:43 INFO - Downloading blessings-1.6.tar.gz 23:09:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:09:43 INFO - Installing collected packages: blessings 23:09:43 INFO - Running setup.py install for blessings 23:09:43 INFO - Successfully installed blessings 23:09:43 INFO - Cleaning up... 23:09:44 INFO - Return code: 0 23:09:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:09:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:09:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:09:44 INFO - Reading from file tmpfile_stdout 23:09:44 INFO - Current package versions: 23:09:44 INFO - blessings == 1.6 23:09:44 INFO - blobuploader == 1.2.4 23:09:44 INFO - docopt == 0.6.1 23:09:44 INFO - manifestparser == 1.1 23:09:44 INFO - mozInstall == 1.12 23:09:44 INFO - mozcrash == 0.17 23:09:44 INFO - mozdebug == 0.1 23:09:44 INFO - mozdevice == 0.48 23:09:44 INFO - mozfile == 1.2 23:09:44 INFO - mozhttpd == 0.7 23:09:44 INFO - mozinfo == 0.9 23:09:44 INFO - mozleak == 0.1 23:09:44 INFO - mozlog == 3.1 23:09:44 INFO - moznetwork == 0.27 23:09:44 INFO - mozprocess == 0.22 23:09:44 INFO - mozprofile == 0.28 23:09:44 INFO - mozrunner == 6.11 23:09:44 INFO - mozscreenshot == 0.1 23:09:44 INFO - mozsystemmonitor == 0.0 23:09:44 INFO - moztest == 0.7 23:09:44 INFO - mozversion == 1.4 23:09:44 INFO - psutil == 3.1.1 23:09:44 INFO - requests == 1.2.3 23:09:44 INFO - wsgiref == 0.1.2 23:09:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:09:44 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')]} 23:09:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:09:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:09:44 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')]} 23:09:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:09:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:09:44 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 0x102de1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102de84c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd911d8e650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c85d30>, '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 0x102ae9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102c7cb20>, '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.M1Geu1So7x/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.MgIBKEdIin/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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 23:09:44 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 23:09:44 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 23:09:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.M1Geu1So7x/Render', 23:09:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:09:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:09:44 INFO - 'HOME': '/Users/cltbld', 23:09:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:09:44 INFO - 'LOGNAME': 'cltbld', 23:09:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:09:44 INFO - 'MOZ_NO_REMOTE': '1', 23:09:44 INFO - 'NO_EM_RESTART': '1', 23:09:44 INFO - 'PAGER': '/bin/cat', 23:09:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:09:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:09:44 INFO - 'PWD': '/builds/slave/test', 23:09:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:09:44 INFO - 'SHELL': '/bin/bash', 23:09:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MgIBKEdIin/Listeners', 23:09:44 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 23:09:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:09:44 INFO - 'USER': 'cltbld', 23:09:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:09:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:09:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:09:44 INFO - 'XPC_FLAGS': '0x0', 23:09:44 INFO - 'XPC_SERVICE_NAME': '0', 23:09:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:09:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:09:44 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)) 23:09:44 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)) 23:09:44 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)) 23:09:44 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)) 23:09:44 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)) 23:09:44 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)) 23:09:44 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)) 23:09:44 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)) 23:09:44 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)) 23:09:44 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)) 23:09:44 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)) 23:09:44 INFO - Cleaning up... 23:09:44 INFO - Return code: 0 23:09:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:09:44 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')]} 23:09:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:09:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:09:44 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')]} 23:09:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:09:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:09:44 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 0x102de1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102de84c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd911d8e650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c85d30>, '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 0x102ae9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102c7cb20>, '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.M1Geu1So7x/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.MgIBKEdIin/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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 23:09:44 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 23:09:44 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 23:09:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.M1Geu1So7x/Render', 23:09:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:09:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:09:44 INFO - 'HOME': '/Users/cltbld', 23:09:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:09:44 INFO - 'LOGNAME': 'cltbld', 23:09:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:09:44 INFO - 'MOZ_NO_REMOTE': '1', 23:09:44 INFO - 'NO_EM_RESTART': '1', 23:09:44 INFO - 'PAGER': '/bin/cat', 23:09:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:09:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:09:44 INFO - 'PWD': '/builds/slave/test', 23:09:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:09:44 INFO - 'SHELL': '/bin/bash', 23:09:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MgIBKEdIin/Listeners', 23:09:44 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 23:09:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:09:44 INFO - 'USER': 'cltbld', 23:09:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:09:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:09:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:09:44 INFO - 'XPC_FLAGS': '0x0', 23:09:44 INFO - 'XPC_SERVICE_NAME': '0', 23:09:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:09:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:09:44 INFO - Downloading/unpacking jsonschema 23:09:44 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 23:09:44 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 23:09:44 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 23:09:44 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 23:09:44 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 23:09:44 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 23:09:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:09:47 INFO - Downloading/unpacking functools32 (from jsonschema) 23:09:47 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 23:09:47 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 23:09:47 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 23:09:47 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 23:09:47 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 23:09:47 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 23:09:47 INFO - Downloading functools32-3.2.3-2.tar.gz 23:09:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:09:47 INFO - warning: no files found matching '*.txt' 23:09:47 INFO - no previously-included directories found matching 'build' 23:09:47 INFO - no previously-included directories found matching 'dist' 23:09:47 INFO - no previously-included directories found matching '.git*' 23:09:47 INFO - Installing collected packages: jsonschema, functools32 23:09:47 INFO - Running setup.py install for functools32 23:09:47 INFO - warning: no files found matching '*.txt' 23:09:47 INFO - no previously-included directories found matching 'build' 23:09:47 INFO - no previously-included directories found matching 'dist' 23:09:47 INFO - no previously-included directories found matching '.git*' 23:09:47 INFO - Successfully installed jsonschema functools32 23:09:47 INFO - Cleaning up... 23:09:48 INFO - Return code: 0 23:09:48 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 23:09:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:09:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:09:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:09:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 23:09:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:09:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:09:48 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 0x102de1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102de84c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd911d8e650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c85d30>, '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 0x102ae9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102c7cb20>, '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.M1Geu1So7x/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.MgIBKEdIin/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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 23:09:48 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 23:09:48 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 23:09:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.M1Geu1So7x/Render', 23:09:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:09:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:09:48 INFO - 'HOME': '/Users/cltbld', 23:09:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:09:48 INFO - 'LOGNAME': 'cltbld', 23:09:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:09:48 INFO - 'MOZ_NO_REMOTE': '1', 23:09:48 INFO - 'NO_EM_RESTART': '1', 23:09:48 INFO - 'PAGER': '/bin/cat', 23:09:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:09:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:09:48 INFO - 'PWD': '/builds/slave/test', 23:09:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:09:48 INFO - 'SHELL': '/bin/bash', 23:09:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MgIBKEdIin/Listeners', 23:09:48 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 23:09:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:09:48 INFO - 'USER': 'cltbld', 23:09:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:09:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:09:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:09:48 INFO - 'XPC_FLAGS': '0x0', 23:09:48 INFO - 'XPC_SERVICE_NAME': '0', 23:09:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:09:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:09:48 INFO - Downloading/unpacking flake8 23:09: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 23:09: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 23:09: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 23:09: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 23:09: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 23:09: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 23:09:50 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 23:09:50 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 23:09:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:09:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:09:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:09:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:09:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:09:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:09:51 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 23:09:51 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 23:09:51 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 23:09:51 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 23:09:51 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 23:09:51 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 23:09:51 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 23:09:51 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 23:09:51 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 23:09:51 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 23:09:51 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 23:09:51 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 23:09:51 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 23:09:51 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 23:09:51 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 23:09:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 23:09:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:09:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:09:51 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:09:51 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:09:51 INFO - no previously-included directories found matching 'docs/_build' 23:09:51 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 23:09:52 INFO - Running setup.py install for pep8 23:09:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:09:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:09:52 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:09:52 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:09:52 INFO - no previously-included directories found matching 'docs/_build' 23:09:52 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 23:09:52 INFO - Successfully installed flake8 mccabe pyflakes pep8 23:09:52 INFO - Cleaning up... 23:09:52 INFO - Return code: 0 23:09:52 INFO - Running post-action listener: _resource_record_post_action 23:09:52 INFO - Running post-action listener: _start_resource_monitoring 23:09:52 INFO - Starting resource monitoring. 23:09:52 INFO - ##### 23:09:52 INFO - ##### Running install step. 23:09:52 INFO - ##### 23:09:52 INFO - Running pre-action listener: _resource_record_pre_action 23:09:52 INFO - Running main action method: install 23:09:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:09:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:09:52 INFO - Reading from file tmpfile_stdout 23:09:52 INFO - Detecting whether we're running mozinstall >=1.0... 23:09:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:09:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:09:52 INFO - Reading from file tmpfile_stdout 23:09:52 INFO - Output received: 23:09:52 INFO - Usage: mozinstall [options] installer 23:09:52 INFO - Options: 23:09:52 INFO - -h, --help show this help message and exit 23:09:52 INFO - -d DEST, --destination=DEST 23:09:52 INFO - Directory to install application into. [default: 23:09:52 INFO - "/builds/slave/test"] 23:09:52 INFO - --app=APP Application being installed. [default: firefox] 23:09:52 INFO - mkdir: /builds/slave/test/build/application 23:09:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 23:09:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0.en-US.mac.dmg --destination /builds/slave/test/build/application 23:10:15 INFO - Reading from file tmpfile_stdout 23:10:15 INFO - Output received: 23:10:15 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 23:10:15 INFO - Running post-action listener: _resource_record_post_action 23:10:15 INFO - ##### 23:10:15 INFO - ##### Running run-tests step. 23:10:15 INFO - ##### 23:10:15 INFO - Running pre-action listener: _resource_record_pre_action 23:10:15 INFO - Running main action method: run_tests 23:10:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:10:15 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:10:15 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:10:15 INFO - 2016-05-12 23:10:15.498 screenresolution[1647:7999] starting screenresolution argv=screenresolution get 23:10:15 INFO - 2016-05-12 23:10:15.501 screenresolution[1647:7999] Display 0: 1600x1200x32@60 23:10:15 INFO - 2016-05-12 23:10:15.507 screenresolution[1648:8002] starting screenresolution argv=screenresolution list 23:10:15 INFO - Available Modes on Display 0 23:10:15 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:10:15 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:10:15 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:10:15 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:10:15 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:10:15 INFO - Intel Iris: 23:10:15 INFO - Chipset Model: Intel Iris 23:10:15 INFO - Type: GPU 23:10:15 INFO - Bus: Built-In 23:10:15 INFO - VRAM (Dynamic, Max): 1536 MB 23:10:15 INFO - Vendor: Intel (0x8086) 23:10:15 INFO - Device ID: 0x0a2e 23:10:15 INFO - Revision ID: 0x0009 23:10:15 INFO - Displays: 23:10:15 INFO - Display: 23:10:15 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:10:15 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:10:15 INFO - Display Serial Number: 1600x1200 60 23:10:15 INFO - Main Display: Yes 23:10:15 INFO - Mirror: Off 23:10:15 INFO - Online: Yes 23:10:15 INFO - Rotation: Supported 23:10:15 INFO - Return code: 0 23:10:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:10:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:10:15 INFO - Python 2.7.3 23:10:15 INFO - Return code: 0 23:10:15 INFO - grabbing minidump binary from tooltool 23:10: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')]} 23:10:15 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102c85d30>, '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 0x102ae9db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102c7cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:10:15 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 23:10:15 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 23:10:15 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:10:15 INFO - Return code: 0 23:10:15 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 23:10:15 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:10:15 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:10:15 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 23:10:15 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:10:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 23:10:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 23:10:17 INFO - Return code: 0 23:10:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KOpzZRu0SYGza3ypIyiE6w/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0125', '--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 23:10:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KOpzZRu0SYGza3ypIyiE6w/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0125 --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 23:10:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.M1Geu1So7x/Render', 23:10:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:10:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:10:17 INFO - 'HOME': '/Users/cltbld', 23:10:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:10:17 INFO - 'LOGNAME': 'cltbld', 23:10:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:10:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 23:10:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:10:17 INFO - 'MOZ_NO_REMOTE': '1', 23:10:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:10:17 INFO - 'NO_EM_RESTART': '1', 23:10:17 INFO - 'PAGER': '/bin/cat', 23:10:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:10:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:10:17 INFO - 'PWD': '/builds/slave/test', 23:10:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:10:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:10:17 INFO - 'SHELL': '/bin/bash', 23:10:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MgIBKEdIin/Listeners', 23:10:17 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 23:10:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:10:17 INFO - 'USER': 'cltbld', 23:10:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:10:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:10:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:10:17 INFO - 'XPC_FLAGS': '0x0', 23:10:17 INFO - 'XPC_SERVICE_NAME': '0', 23:10:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:10:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KOpzZRu0SYGza3ypIyiE6w/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0125', '--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 23:10:17 INFO - mozversion application_buildid: 20160512210646 23:10:17 INFO - mozversion application_changeset: f4db6cf4303fd75de4e539b281a65d016b4cc8dd 23:10:17 INFO - mozversion application_display_name: Firefox 23:10:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:10:17 INFO - mozversion application_name: Firefox 23:10:17 INFO - mozversion application_remotingname: firefox 23:10:17 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 23:10:17 INFO - mozversion application_vendor: Mozilla 23:10:17 INFO - mozversion application_version: 47.0 23:10:17 INFO - mozversion platform_buildid: 20160512210646 23:10:17 INFO - mozversion platform_changeset: f4db6cf4303fd75de4e539b281a65d016b4cc8dd 23:10:17 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 23:10:17 INFO - mozversion platform_version: 47.0 23:10:17 INFO - using testdate: 1463119817 23:10:17 INFO - actual date: 1463119817 23:10:17 INFO - starting webserver on 'localhost:49281' 23:10:17 INFO - SUITE-START | Running 2 tests 23:10:17 INFO - TEST-START | dromaeo_css 23:10:17 INFO - operating with platform_type : mac_ 23:10:17 INFO - Initialising browser for dromaeo_css test... 23:10:17 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/tmpg_pJD7/profile http://localhost:49281/getInfo.html) 23:10:20 INFO - PROCESS | 1666 | __metrics Screen width/height:1600/1200 23:10:20 INFO - PROCESS | 1666 | colorDepth:24 23:10:20 INFO - PROCESS | 1666 | Browser inner width/height: 1024/689 23:10:20 INFO - PROCESS | 1666 | __metrics 23:10:20 ERROR - PROCESS | 1666 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2859: TypeError: this.tabs is undefined 23:10:20 INFO - TEST-INFO | 1666: exit 0 23:10:20 INFO - Browser initialized. 23:10:20 INFO - Running cycle 1/1 for dromaeo_css test... 23:10:20 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/tmpg_pJD7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:10:32 INFO - PROCESS | 1670 | RSS: Main: 167989248 23:10:32 INFO - PROCESS | 1670 | 23:11:22 INFO - PROCESS | 1670 | 1463119882472 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] 23:13:34 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49281/tests/dromaeo/cssquery-ext.html) 23:13:35 INFO - PROCESS | 1670 | RSS: Main: 180183040 23:13:35 INFO - PROCESS | 1670 | 23:16:37 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-ext.html (next: http://localhost:49281/tests/dromaeo/cssquery-jquery.html) 23:16:37 INFO - PROCESS | 1670 | RSS: Main: 191836160 23:16:37 INFO - PROCESS | 1670 | 23:19:39 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49281/tests/dromaeo/cssquery-mootools.html) 23:19:39 INFO - PROCESS | 1670 | RSS: Main: 193314816 23:19:39 INFO - PROCESS | 1670 | 23:22:41 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49281/tests/dromaeo/cssquery-prototype.html) 23:22:42 INFO - PROCESS | 1670 | RSS: Main: 196894720 23:22:42 INFO - PROCESS | 1670 | 23:25:44 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49281/tests/dromaeo/cssquery-yui.html) 23:25:45 INFO - PROCESS | 1670 | RSS: Main: 196149248 23:25:45 INFO - PROCESS | 1670 | 23:28:47 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-yui.html (next: http://localhost:49281/tests/dromaeo/cssquery-dojo.html) 23:28:47 INFO - PROCESS | 1670 | RSS: Main: 201674752 23:28:47 INFO - PROCESS | 1670 | 23:28:47 INFO - PROCESS | 1670 | __start_tp_report 23:28:47 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 23:28:47 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 23:28:47 INFO - PROCESS | 1670 | |i|pagename|runs| 23:28:47 INFO - PROCESS | 1670 | |0;dojo.html;3342.465205567109;3692.1138926657595;3734.8692795752154;3754.3429899767543;3760.9811950940243;6061.878762424743;6070.696465176746;6093.756249750005;6096.573239873211;6096.878062438748;6008.068749343848;6096.725647345869;6102.633841969485;6109.5723299369065;6128.264608247016;5387.595930305223;5463.180522921573;5474.753636086366;5519.530839878616;5536.584756143306;4427.424434823469;5529.917051244234;5548.58385621078;5609.887802243953;5654.349749778767;5406.351237851443;5541.224228607998;5544.805931792388;5590.972045139769;5603.2435621191225;5522.144067669497;5542.889142217133;5577.079781835992;5582.330120385539;5636.408177141423;5340.973295133538;5365.953639040389;5438.755256013489;5442.945570544323;5463.235147079372;5902.498287645512;5943.138244954457;5953.345132035516;6010.9098363524145;6021.2473440819895;6289.99360102386;6418.807435776934;6426.036094585849;6432.035194720783;6478.74085036598;5987.820365389045;6334.081558174039;6393.2328120625825;6416.711247993851;6451.903221451682;6212.9068063979075;6228.906566401508;6233.0338797486465;6235.688215589203;6242.219722534728;6422.614643121427;6490.967545162244;6603.7688680895935;6609.636469994197;6620.768273110418;6258.530610204253;6326.335734747877;6343.968280158662;6351.269603995507;6359.3958573936325;6475.643839588868;6481.935180648227;6488.513361497907;6514.348565143542;6520.836979075561;6242.687865606701;6298.716557754912;6322.936770632326;6324.146240257598;6383.202099737533;6248.781292654735;6312.936870631235;6331.081993110973;6413.518986076063;6413.967930160413;5065.39215294167;5098.286239926413;5106.591472682177;5107.897842043139;5124.026434977342;4147.771872546979;4534.274516077412;4591.17358875399;4609.469910960215;4648.279516674921;6228.28374736902;6242.344553821783;6243.906341404882;6245.125682404467;6298.149749783812;5854.7072646367515;6414.711337989802;6473.773417930444;6513.902291465632;6524.836879078061;5826.184334193216;6112.138751317739;6145.678679084019;6225.470834979077;6243.188385509855;5649;5650.463205995506;5653.717314134359;5658.660480371191;5678.921005009035;6540.836479087966;6560.343965603593;6569.277379488157;6570.178727659043;6585.6377899216;6318;6415.422611964946;6426.325235850169;6493.642849643315;6506;6483.351664833479;6537.346265373425;6569.540132190701;6582.76960306387;6584.275729669636;6255.124282600348;6333.936660633335;6406.23125224976;6412;6413.390727880844;6406.519511036784;6483;6523.869522609616;6528.412442880163;6532.444742196776;6315.463185629089;6325.177726895474;6326.936730632635;6352.650604216812;6357.14178585885;6178.41305076017;6238.313785483502;6285.402886725754;6323.08315294289;6364.93635063662;5045.772940217626;5671.376148623731;6025.578209525291;6121.143039974496;6225.346338634559;6214.005759078525;6251.369385519515;6252.468540174136;6267.905981410191;6285.717142728679;6166.876662466815;6189.04069869171;6244.344343844013;6256.5307601930945;6279.372062793684 23:28:47 INFO - PROCESS | 1670 | |1;ext.html;455.1829466108337;505.36828963795256;634.9396807303305;673.9217252396166;679.5039621076616;39665.206695866065;40330.79007629776;40331.39502907451;40348.79825600868;40588.59411405885;40558.39162412566;41119.38320925181;41197.17605647885;41255.762327130156;41292;9685.079917407835;9828.410295382264;9888.11007009369;9893.30746847721;9918.801623967516;9778.315517913748;10071.244656650744;10076.294659373847;10115.393076415401;10205.897941020568;10013.048760367754;10021.198304135653;10085.344452610594;10140.89859101407;10153.136983356386;10007.849882251772;10044.19646428284;10055.49722513875;10065.144462720678;10129.290949633529;9897.15874150698;10002.199824014062;10031.74920626983;10043.397396156253;10045.89954100457;35046.474302885224;40359.7982010091;40654;40992.97517562055;41195.588044119475;3216.260260140181;3437.3984552703178;3460.498227756986;3511.262634846685;3538.0093573799377;29646.962356317425;29867.701322986708;29926.401471970657;30332.54501182484;30491;2318.5247024359965;2716.0765339784566;2737.438825040862;2768.529350010503;2854.52900271457;413346;420002;422439;423505;506137;50486.74756626193;81054.59472702717;83099;83136.58431707803;83378.58310708408;50946.23580646293;51540.96918061618;51574.74212628988;51888.22167667488;52098.73950630298;16825.747613785803;17086.145692715316;17205;17374.218160182823;17445.65108697819;13250.867491325156;13390.665233369049;13432.865671343357;13485.123466974615;13668.179909205479;16391.42630007944;16598.66802663964;16678.249478773483;16690.915090519076;16735.581610459834;6079.878402431927;7140.500164988401;7149.285071492809;7200.459965502609;7219.91701244807;994.0953732103751;1048.7483004078965;1078.1321036565482;1114.0530549033258;1161.6863446869297;30964.380712385628;31241.218969525944;31526.581303841383;31585.052448426584;31591.368172636878;1143.5940241214416;1145.2269717940524;1205.1864991131126;1220.2922305063041;1265.8987281017496;15511.301991410413;15702.450414235675;15776.447824326093;15847.762283565755;16078.276477558538;522953;525077.3746131245;525360;530372;531842;61635;62499;64314.035289470696;64479.71040579162;64725.705485890016;1509.532045066011;1547.2418514927829;1556.509699444694;1558.618138556061;1569.7174508588564;30421.543676845307;30640.540391893694;30737.365368682757;30948.69051309458;31039.534406984367;53626.463735362144;53963;54147.91704165973;54373;54634;482930;483549;487145;488555;489053;32608.51087233646;33555.49666755049;35997.28005439929;36322.4551631731;36911.815440922626;46619.533804662875;47366.052678946915;48805.51194488152;49019.75490122669;49678.751606241734;38878.266411728204;39284;39403.80298098491;39754.80122599369;39855.00362490961;38646.22707545777;38878.611213887496;38924.80537597294;39210.2157956845;39898.40152397658;32505.512417313286;38652;38943.805280973414;39255.80372098121;40022.59977400305;33535.496967545994;34721.305573887876;34978.47532286967;34983.300333993684;35099.94700158999;25925;26040;26265.343366415993;26410.410450169493;26586 23:28:47 INFO - PROCESS | 1670 | |2;jquery.html;349.78488229738707;379.6621007303499;388.776453539215;391.6436043200688;393.48257041989797;20183.697244541345;20680.172793088277;21144;21552.99999999998;21650.0257488413;11290.491927863246;12335.62993110206;12339.197952133105;12478.064145189124;12630.30533320667;5183.118869792135;6212.689365531728;6220.6267623942485;6238.968805155968;6239.15771370866;6116.143739876421;6174.876502469971;6175.258968923713;6207.906881396782;6252.843678908041;5896.23348964634;6035.155078289043;6060.696965151747;6125.387461253861;6131.724072416752;5476.726163691839;6061.878762424749;6088.1476593276975;6109.7861574844665;6127.785527506516;5232.973835130838;5253.711045892474;5311.893762124736;5328.413874473844;5337.06601344767;11709.53161873524;12187.268763874195;23350;24550.754492455024;25421.237362879143;2217.6008318502663;2220.7668194839634;2338.660894170353;2347.6009078456705;2376.429656882353;9848.65529706464;9966.554849546848;10102.343347682377;10431.21765867563;10559.947200264027;1611.798525184352;1780.813014633458;1851.2039822876159;1932.1498540642071;2000.4798752324355;101842.49078754633;102308;104267.47866260695;104692.95307046908;106375;9701.51492425376;11855.703607409883;11901.404929753478;11932.761344773056;11960.102992275613;14496.275186240646;14569.927150364181;14604.707905841824;14620.488282910048;14631.048981816148;8989.775255618662;9026.45841249527;9051.547422628973;9127.315451341176;9162.221211197122;8051.758447246592;8813.515256660818;9094.272458203328;9243.630254789734;9265.72202833916;4588.197065513522;4593.448786145684;4611.16998940194;4614.123316569908;4674.9247673034715;1988.2544045982756;2007.447951813263;2011.4870707969374;2017.5662232620057;2029.9289524866558;848.8259906719107;888.9911100889037;968.4528241543505;1026.8357062869904;1081.3349789879167;19452.902735486234;20879.791202087785;20888.582228355346;21153.15387384518;21224.78775212259;1020.5254556631202;1032.5921261101973;1036.6009086501751;1085.218642577343;1090.0625462102585;10990.780184396268;11405.714857128638;11457.656270311903;11461.885381146249;11467.942660286646;657274;673911;678726;690399;703337;83593.58203208946;115409.42295288469;119979.8002019986;120615.39692301658;121773;1052.4106500359824;1099.9725006874892;1100.785346857355;1102.1898904305328;1102.6747109602527;18131.54671133232;18219.271229150687;18245.908770456062;18527.0736463179;18835.623287534447;53391;53738.46261537335;54452.72773636106;55110;55905.32284031485;61446;75517;76969.61515192388;77096;77659;6358.872822543616;8115.553644549606;8275.131111233233;8320.334373250247;8380.16198380157;12364.701706321066;12504.937475312565;13224.338783061;13712.52006179779;13743.793843092162;9756.85364719544;9825.705228843146;11430.942845286054;11928.58249961282;12076.698082548006;11064.391458469861;11362.659120226075;11395.487203076047;11686.415679216176;12000;10496.632617858339;11342.20604557673;11894.286342819112;12167.939160304142;12235;8212.630431630472;8238.011438627404;8310.916890831015;8355.623996920032;8456.788580285542;8842.602082906413;11556.364399958084;11656.125790565571;11751.236169649288;12027.579034733742 23:28:47 INFO - PROCESS | 1670 | |3;mootools.html;1669.5492217101382;1681.6048228666264;2043.9882258282153;2106.2312256026553;2108.409645299316;8403;9011.909880901188;9077.546122693864;9276.165145136936;9305.34862559621;8953.820923581525;9061;9065.864012039825;9171;9182.449053056822;2210.4363387336207;2211.94470138247;2633.934151646205;2634.196570046135;2634.8419094854275;2574.935626609331;2610;2613.8562379069144;2641.6037594360905;2645.7486538778785;2605.2054123492326;2617.882195301216;2641.696204936428;2646.4045589742345;2664.134156399178;2598.1036542392844;2618.5941179117362;2628.6582744243224;2652.2308530526125;2660.9600855987173;2272.6931864198286;2607.478504299129;2621.3708709909674;2645.973540264592;2663.8268512546624;9169.449833010041;9335.393199442015;9359.672411465566;9368;9401.153896149379;5789.739461724233;5870.20752198452;5972.850678733023;5989.9101513477335;6005.927954855568;3661.6887564557046;3743.7005039596766;3767.1523907120954;3784.413415920537;3801.0877389426614;1081.3619964221068;1084.7233955341337;1093.557109370706;1133.5239199536215;1135.5628083188033;12161.635150945485;12241.143119981605;12279.75440491194;12340.38298085101;12344.13591048627;5069.518395752398;5131.281620573122;5178.689278643294;5201.947980520146;5247.895042099137;10506.789864202827;10607.946960265304;10629.787404251872;10675.359478431164;10683.198760093024;5876.41235876409;5922.466977972003;5952.642841429528;5968.671723055274;5969.164316995624;4699.177643912301;5156.74216289184;5222.477752224823;5224.791008359623;5278.129108697099;4125.339945608688;4134.359174327984;4135.545090040093;4135.58644135584;4197.727147735387;1369.8082268482422;1381.8894488441101;1397.9021468497108;1405.6907480354305;1406.606150277924;1150.8618965724168;1177.4524845946676;1188.3226560860398;1230.809224570193;1233.9383030848421;8496.660133594587;8499.192576705203;8522.914770852336;8552.61513231906;8577.485350878967;1125.3810404277615;1142.331735934471;1310.5806142034648;1315.8420989481324;1330.1952318847152;6441.355864413615;6459.870802583922;6465.15952926126;6541.47668186544;6564.949608062687;8749.47503149813;9197;9322.86015709765;11229.27009744364;11263.324200547993;8620.568971551398;9117.544122793834;10682.57269709203;11205.60780372683;11267.380294083903;8390.993080830218;8393.916060839314;8424.241818236389;8480.957595212109;8613.65545378178;7768.262015108556;7948.801279968047;8380.748577542683;8433.19884610961;8468.618912149092;9865.654702085392;9880.703578892644;10143.797124057624;10151.035651613085;10154.796904062025;10152.54313555877;10195;10220.488975551045;10244.538995745359;10636.946815265874;7672.5012874163;7807.687692492464;7976.800579985546;7979.441439099207;8062.7581172564915;8544.572771361283;8681.8697719533;8684.565771711263;8701.260392866425;8720.127987201482;7531.209223031503;7753.147153813189;7816.413768967236;7853.56805375687;7864.134945155914;6782.982552617147;7161;7543.924560754542;7573.431992600687;7761.301482866569;7932;7951.522908625501;8122.431429800094;8122.837543248984;8143.837123257384;7163.964180179066;7265.09186351706;7297.124345078624;7316.1952185258515;7328.670209840463;7417.925820741723;7440.851182976418;7470.8505829882015;7471.028766260351;7479.813004674927 23:28:47 INFO - PROCESS | 1670 | |4;prototype.html;345.64053384480144;347.65234765234766;349.34323471872875;349.902027432319;354.00348020322787;6766.729330826774;6832.214295356035;6842;6873.106496155493;6901.275366086557;6739.730410783563;6830.077939478181;6847.349501797334;6859.931400685991;6874.725010999567;4845.127876982142;4931.556159945604;5049.3183420238165;5056.595472362221;5131.615128865331;5010.498950104997;5035.1188542005;5036.118679231138;5041.8235361762345;5058.544730974211;4978.502149785028;4986.077575648498;4992.650514463989;4994.076095922248;5010.72226582221;5067.695938243699;5087.720175390352;5094.796208151688;5096.413912400084;5099.184130539096;4377.846775362847;4379.58393952574;4379.58393952574;4388.648908087345;4393.275109606911;6365.936340636581;6606.735730570771;6635.469162466991;6636.601803891782;6655.134832471747;4710.811567537295;4800.591949684281;5467.797084641412;5943.821685349403;5943.910841337383;7349.77950661481;7363.889541656879;7377.77866664001;7380.520266182681;7399.001134846781;4731.219348807477;4747.287906813971;4753.382060332169;4754.0967216229155;4775.761211939423;6620.86758264832;7625.923740762577;7694.576798276093;7713.730019449293;7768.844623107506;4256.936145957813;4689.718616882998;4730.172219861579;4740.19416699162;4765.428148622187;4888.755562221874;4897.828575999894;4901.607871370282;4914.385701787277;4917.680350777188;5824.621399609012;5864.501517370986;5902.734376953047;5903.704814759245;5905.379935106752;7301.306375894282;7313.305236002571;7338.266173382619;7342.889856652155;7372.668229929666;1608.774771531963;1652.7520871869315;1655.4950740024408;1668.3326669332364;1687.2660392729201;1061.3843970497248;1070.5236169904317;1081.2053140941402;1083.3445765311874;1084.9945750271197;6900.516963812485;6935.618540980194;6946.9652651736415;6961.408280296231;6962.512624116364;5775.480206781326;6504.739810407531;7567.583782891884;7571.129320128146;7612;6282.685865706696;6917.27368626287;6952.443804495628;6953.235144134142;7001.614911179832;3234.256121092162;3878.4182372643763;3996.7002474814503;4017.819198136091;4026.7382620129597;7518.323350898446;7653.234676532414;7755.72854950074;7773.805654858674;7806.921930780733;7860.292573668397;7861.685532578747;7864.5281283123195;7881.408894332833;7892.289693927574;7745.883811742829;7784.625689164746;7797.610119494003;7828.647710853025;7860.489068210548;7302.744403945836;7586.962065189638;7616.657250423634;7676.73536306802;7700.383969282556;7450.701971921062;7454.589997550187;7457.7389791357045;7477.588732619541;7511.286427789351;7715.57564333967;7724.652390642547;7739.496932699357;7744.728934487492;7754.844903102019;4769.451513075902;4804.303376010529;4834.984653222863;4835.564799168092;4850.733209673502;6247.562670613013;7353.117625884928;7585.089789225328;7594.544327340156;7607.429442791923;5502.504774570148;7377.188509264084;7414.22150674193;7421.851562968818;7451.813704657427;7395.371393431618;7402.925970740223;7408.111026676834;7417.147028091838;7455.508898220486;7169.7849064528145;7174.713011479691;7186.425085993213;7221.566705997657;7227.205007449282;5958.433948774859;7058.54119482232;7202.459815513753;7256.1655409626455;7287.089113860768;7160.427165826617;7194.89207661875;7196.8920466192;7205.243449437734;7206.243344448757 23:28:47 INFO - PROCESS | 1670 | |5;yui.html;699.3181647893308;716.5987047253539;728.8615163119007;757.8029712274807;767.6046835879521;12886.742265154691;13438.798418023725;15317.770233446507;15404.152771597572;15614.921925390358;14468.20424876634;14851.480198193065;15004.474843380483;15011.6997660047;15012.399504019826;814.0963530481191;827.7971896885272;970.141424839019;981.543582234261;1009.2027298434228;968.1963969904997;994.1351024608565;997.6209040564576;1012.5342342522412;1012.5392946209494;1034.4724190662778;1036.533559898049;1050.396022287184;1051.8369652703843;1053.0469924718104;985.7437066362726;999.0908273471143;1003.8092762375126;1013.908748212657;1043.7025447747355;748.1060133140899;749.0113050772982;750.4934169435663;759.5632511306021;771.4098714483424;15422.768658470131;15564.455244066516;15875.285612147482;15895.920520397323;15997.440089596925;13180;13220.603381898462;13264.469421223139;13372.197668139943;13417.46330146793;10295.227857910686;10310.687944748834;10347.120494757955;10370.429626370547;10668.733281667943;3558.857645694169;3589.5154154189113;3613.217633456829;3702;3829.0235989822695;81382;82283;84998;85657;87352.56323718405;9617.663381781604;9695.515224238901;9716.562754676057;9808.754781130528;9956.850647240297;18145.546361341072;22060;22122;22131.446713832283;22390;7777.300042996045;7796;7829.6085195741125;7973.362131029504;8161;3643.417053271464;4161.812718427617;4193.475815523059;4197.685173611992;4222.873313800591;3770;3786.962130378716;3812.046988252937;3815.7901315427916;3816.5610954740005;655.3675702946584;663.8141320430191;667.0694381338006;694.7221111555317;701.7263267325746;494.5965328783102;557.0335467963081;604.6281536854802;625.7872323410062;633.3951076721812;7159.284071592799;8663.78340541479;8688.739337819876;8712.78218044554;8747.912520874837;526.6918852471347;596.0612036043249;596.8627215740404;635.2630948100274;638.7157714816861;3883.1457079442475;4023.3964905264443;4121.732087414309;4148.979255103705;4197.517285512145;92597.07402925885;95105;95770.52114739381;96468.51765741267;97087;81331.5600531985;82997;83131;83928.58035709904;84291.7356239657;73379;73425.6328718353;73738.63130684312;75014;75104.87342689864;7996.92003079985;8187.877181842397;8195.303399211134;8371.497710137412;8443.155684431507;50999.98000040052;51810.48189518207;52373.7381313091;52560;54018;68325.65837170782;71986.92019619605;72507.63746181235;72574;72897;8692.181028890875;10128.544215510467;10358.689239322832;10369.792604148026;10521.526531305957;11251.49368278413;11288.43557822122;11409.60066397672;11538.192326537062;12129.393530323272;10661.41362225054;10775.245732798629;10877.782444351227;11222;11291.209615326849;8838.513881736566;10728;10737.892621074001;10945.945270273598;11010.50452729613;10586.258961872594;10596.470176491299;10614.257002009785;10891.237613366988;11009.339439633648;7712.642198746581;9026.729198124067;9158.313126515404;9164.541772911194;9211.631534738535;9128.908710912805;9168.816623667622;9253.86119208199;9299.628014879328;9367.297452691211 23:28:47 INFO - PROCESS | 1670 | __end_tp_report 23:28:47 INFO - PROCESS | 1670 | __start_cc_report 23:28:47 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,362 23:28:47 INFO - PROCESS | 1670 | __end_cc_report 23:28:47 INFO - PROCESS | 1670 | __startTimestamp1463120927628__endTimestamp 23:28:47 INFO - PROCESS | 1670 | 23:28:47 INFO - PROCESS | 1670 | ------- Summary: start ------- 23:28:47 INFO - PROCESS | 1670 | Number of tests: 6 23:28:47 INFO - PROCESS | 1670 | 23:28:47 INFO - PROCESS | 1670 | [#0] dojo.html Cycles:165 Average:5982.99 Median:6242.34 stddev:644.91 (10.3%) stddev-sans-first:612.72 23:28:47 INFO - PROCESS | 1670 | Values: 3342.5 3692.1 3734.9 3754.3 3761.0 6061.9 6070.7 6093.8 6096.6 6096.9 6008.1 6096.7 6102.6 6109.6 6128.3 5387.6 5463.2 5474.8 5519.5 5536.6 4427.4 5529.9 5548.6 5609.9 5654.3 5406.4 5541.2 5544.8 5591.0 5603.2 5522.1 5542.9 5577.1 5582.3 5636.4 5341.0 5366.0 5438.8 5442.9 5463.2 5902.5 5943.1 5953.3 6010.9 6021.2 6290.0 6418.8 6426.0 6432.0 6478.7 5987.8 6334.1 6393.2 6416.7 6451.9 6212.9 6228.9 6233.0 6235.7 6242.2 6422.6 6491.0 6603.8 6609.6 6620.8 6258.5 6326.3 6344.0 6351.3 6359.4 6475.6 6481.9 6488.5 6514.3 6520.8 6242.7 6298.7 6322.9 6324.1 6383.2 6248.8 6312.9 6331.1 6413.5 6414.0 5065.4 5098.3 5106.6 5107.9 5124.0 4147.8 4534.3 4591.2 4609.5 4648.3 6228.3 6242.3 6243.9 6245.1 6298.1 5854.7 6414.7 6473.8 6513.9 6524.8 5826.2 6112.1 6145.7 6225.5 6243.2 5649.0 5650.5 5653.7 5658.7 5678.9 6540.8 6560.3 6569.3 6570.2 6585.6 6318.0 6415.4 6426.3 6493.6 6506.0 6483.4 6537.3 6569.5 6582.8 6584.3 6255.1 6333.9 6406.2 6412.0 6413.4 6406.5 6483.0 6523.9 6528.4 6532.4 6315.5 6325.2 6326.9 6352.7 6357.1 6178.4 6238.3 6285.4 6323.1 6364.9 5045.8 5671.4 6025.6 6121.1 6225.3 6214.0 6251.4 6252.5 6267.9 6285.7 6166.9 6189.0 6244.3 6256.5 6279.4 23:28:47 INFO - PROCESS | 1670 | 23:28:47 INFO - PROCESS | 1670 | [#1] ext.html Cycles:180 Average:63900.54 Median:19767.64 stddev:128859.67 (651.9%) stddev-sans-first:129133.11 23:28:47 INFO - PROCESS | 1670 | Values: 455.2 505.4 634.9 673.9 679.5 39665.2 40330.8 40331.4 40348.8 40588.6 40558.4 41119.4 41197.2 41255.8 41292.0 9685.1 9828.4 9888.1 9893.3 9918.8 9778.3 10071.2 10076.3 10115.4 10205.9 10013.0 10021.2 10085.3 10140.9 10153.1 10007.8 10044.2 10055.5 10065.1 10129.3 9897.2 10002.2 10031.7 10043.4 10045.9 35046.5 40359.8 40654.0 40993.0 41195.6 3216.3 3437.4 3460.5 3511.3 3538.0 29647.0 29867.7 29926.4 30332.5 30491.0 2318.5 2716.1 2737.4 2768.5 2854.5 413346.0 420002.0 422439.0 423505.0 506137.0 50486.7 81054.6 83099.0 83136.6 83378.6 50946.2 51541.0 51574.7 51888.2 52098.7 16825.7 17086.1 17205.0 17374.2 17445.7 13250.9 13390.7 13432.9 13485.1 13668.2 16391.4 16598.7 16678.2 16690.9 16735.6 6079.9 7140.5 7149.3 7200.5 7219.9 994.1 1048.7 1078.1 1114.1 1161.7 30964.4 31241.2 31526.6 31585.1 31591.4 1143.6 1145.2 1205.2 1220.3 1265.9 15511.3 15702.5 15776.4 15847.8 16078.3 522953.0 525077.4 525360.0 530372.0 531842.0 61635.0 62499.0 64314.0 64479.7 64725.7 1509.5 1547.2 1556.5 1558.6 1569.7 30421.5 30640.5 30737.4 30948.7 31039.5 53626.5 53963.0 54147.9 54373.0 54634.0 482930.0 483549.0 487145.0 488555.0 489053.0 32608.5 33555.5 35997.3 36322.5 36911.8 46619.5 47366.1 48805.5 49019.8 49678.8 38878.3 39284.0 39403.8 39754.8 39855.0 38646.2 38878.6 38924.8 39210.2 39898.4 32505.5 38652.0 38943.8 39255.8 40022.6 33535.5 34721.3 34978.5 34983.3 35099.9 25925.0 26040.0 26265.3 26410.4 26586.0 23:28:47 INFO - PROCESS | 1670 | 23:28:47 INFO - PROCESS | 1670 | [#2] jquery.html Cycles:180 Average:36136.50 Median:21601.51 stddev:112393.18 (520.3%) stddev-sans-first:112676.35 23:28:47 INFO - PROCESS | 1670 | Values: 349.8 379.7 388.8 391.6 393.5 20183.7 20680.2 21144.0 21553.0 21650.0 11290.5 12335.6 12339.2 12478.1 12630.3 5183.1 6212.7 6220.6 6239.0 6239.2 6116.1 6174.9 6175.3 6207.9 6252.8 5896.2 6035.2 6060.7 6125.4 6131.7 5476.7 6061.9 6088.1 6109.8 6127.8 5233.0 5253.7 5311.9 5328.4 5337.1 11709.5 12187.3 23350.0 24550.8 25421.2 2217.6 2220.8 2338.7 2347.6 2376.4 9848.7 9966.6 10102.3 10431.2 10559.9 1611.8 1780.8 1851.2 1932.1 2000.5 101842.5 102308.0 104267.5 104693.0 106375.0 9701.5 11855.7 11901.4 11932.8 11960.1 14496.3 14569.9 14604.7 14620.5 14631.0 8989.8 9026.5 9051.5 9127.3 9162.2 8051.8 8813.5 9094.3 9243.6 9265.7 4588.2 4593.4 4611.2 4614.1 4674.9 1988.3 2007.4 2011.5 2017.6 2029.9 848.8 889.0 968.5 1026.8 1081.3 19452.9 20879.8 20888.6 21153.2 21224.8 1020.5 1032.6 1036.6 1085.2 1090.1 10990.8 11405.7 11457.7 11461.9 11467.9 657274.0 673911.0 678726.0 690399.0 703337.0 83593.6 115409.4 119979.8 120615.4 121773.0 1052.4 1100.0 1100.8 1102.2 1102.7 18131.5 18219.3 18245.9 18527.1 18835.6 53391.0 53738.5 54452.7 55110.0 55905.3 61446.0 75517.0 76969.6 77096.0 77659.0 6358.9 8115.6 8275.1 8320.3 8380.2 12364.7 12504.9 13224.3 13712.5 13743.8 9756.9 9825.7 11430.9 11928.6 12076.7 11064.4 11362.7 11395.5 11686.4 12000.0 10496.6 11342.2 11894.3 12167.9 12235.0 8212.6 8238.0 8310.9 8355.6 8456.8 8842.6 11556.4 11656.1 11751.2 12027.6 23:28:47 INFO - PROCESS | 1670 | 23:28:47 INFO - PROCESS | 1670 | [#3] mootools.html Cycles:180 Average:6298.96 Median:5236.34 stddev:3236.75 (61.8%) stddev-sans-first:3227.12 23:28:47 INFO - PROCESS | 1670 | Values: 1669.5 1681.6 2044.0 2106.2 2108.4 8403.0 9011.9 9077.5 9276.2 9305.3 8953.8 9061.0 9065.9 9171.0 9182.4 2210.4 2211.9 2633.9 2634.2 2634.8 2574.9 2610.0 2613.9 2641.6 2645.7 2605.2 2617.9 2641.7 2646.4 2664.1 2598.1 2618.6 2628.7 2652.2 2661.0 2272.7 2607.5 2621.4 2646.0 2663.8 9169.4 9335.4 9359.7 9368.0 9401.2 5789.7 5870.2 5972.9 5989.9 6005.9 3661.7 3743.7 3767.2 3784.4 3801.1 1081.4 1084.7 1093.6 1133.5 1135.6 12161.6 12241.1 12279.8 12340.4 12344.1 5069.5 5131.3 5178.7 5201.9 5247.9 10506.8 10607.9 10629.8 10675.4 10683.2 5876.4 5922.5 5952.6 5968.7 5969.2 4699.2 5156.7 5222.5 5224.8 5278.1 4125.3 4134.4 4135.5 4135.6 4197.7 1369.8 1381.9 1397.9 1405.7 1406.6 1150.9 1177.5 1188.3 1230.8 1233.9 8496.7 8499.2 8522.9 8552.6 8577.5 1125.4 1142.3 1310.6 1315.8 1330.2 6441.4 6459.9 6465.2 6541.5 6564.9 8749.5 9197.0 9322.9 11229.3 11263.3 8620.6 9117.5 10682.6 11205.6 11267.4 8391.0 8393.9 8424.2 8481.0 8613.7 7768.3 7948.8 8380.7 8433.2 8468.6 9865.7 9880.7 10143.8 10151.0 10154.8 10152.5 10195.0 10220.5 10244.5 10636.9 7672.5 7807.7 7976.8 7979.4 8062.8 8544.6 8681.9 8684.6 8701.3 8720.1 7531.2 7753.1 7816.4 7853.6 7864.1 6783.0 7161.0 7543.9 7573.4 7761.3 7932.0 7951.5 8122.4 8122.8 8143.8 7164.0 7265.1 7297.1 7316.2 7328.7 7417.9 7440.9 7470.9 7471.0 7479.8 23:28:47 INFO - PROCESS | 1670 | 23:28:47 INFO - PROCESS | 1670 | [#4] prototype.html Cycles:180 Average:5950.67 Median:6837.11 stddev:1911.76 (28.0%) stddev-sans-first:1870.26 23:28:47 INFO - PROCESS | 1670 | Values: 345.6 347.7 349.3 349.9 354.0 6766.7 6832.2 6842.0 6873.1 6901.3 6739.7 6830.1 6847.3 6859.9 6874.7 4845.1 4931.6 5049.3 5056.6 5131.6 5010.5 5035.1 5036.1 5041.8 5058.5 4978.5 4986.1 4992.7 4994.1 5010.7 5067.7 5087.7 5094.8 5096.4 5099.2 4377.8 4379.6 4379.6 4388.6 4393.3 6365.9 6606.7 6635.5 6636.6 6655.1 4710.8 4800.6 5467.8 5943.8 5943.9 7349.8 7363.9 7377.8 7380.5 7399.0 4731.2 4747.3 4753.4 4754.1 4775.8 6620.9 7625.9 7694.6 7713.7 7768.8 4256.9 4689.7 4730.2 4740.2 4765.4 4888.8 4897.8 4901.6 4914.4 4917.7 5824.6 5864.5 5902.7 5903.7 5905.4 7301.3 7313.3 7338.3 7342.9 7372.7 1608.8 1652.8 1655.5 1668.3 1687.3 1061.4 1070.5 1081.2 1083.3 1085.0 6900.5 6935.6 6947.0 6961.4 6962.5 5775.5 6504.7 7567.6 7571.1 7612.0 6282.7 6917.3 6952.4 6953.2 7001.6 3234.3 3878.4 3996.7 4017.8 4026.7 7518.3 7653.2 7755.7 7773.8 7806.9 7860.3 7861.7 7864.5 7881.4 7892.3 7745.9 7784.6 7797.6 7828.6 7860.5 7302.7 7587.0 7616.7 7676.7 7700.4 7450.7 7454.6 7457.7 7477.6 7511.3 7715.6 7724.7 7739.5 7744.7 7754.8 4769.5 4804.3 4835.0 4835.6 4850.7 6247.6 7353.1 7585.1 7594.5 7607.4 5502.5 7377.2 7414.2 7421.9 7451.8 7395.4 7402.9 7408.1 7417.1 7455.5 7169.8 7174.7 7186.4 7221.6 7227.2 5958.4 7058.5 7202.5 7256.2 7287.1 7160.4 7194.9 7196.9 7205.2 7206.2 23:28:47 INFO - PROCESS | 1670 | 23:28:47 INFO - PROCESS | 1670 | [#5] yui.html Cycles:180 Average:18896.19 Median:27287.52 stddev:27046.95 (99.1%) stddev-sans-first:27088.31 23:28:47 INFO - PROCESS | 1670 | Values: 699.3 716.6 728.9 757.8 767.6 12886.7 13438.8 15317.8 15404.2 15614.9 14468.2 14851.5 15004.5 15011.7 15012.4 814.1 827.8 970.1 981.5 1009.2 968.2 994.1 997.6 1012.5 1012.5 1034.5 1036.5 1050.4 1051.8 1053.0 985.7 999.1 1003.8 1013.9 1043.7 748.1 749.0 750.5 759.6 771.4 15422.8 15564.5 15875.3 15895.9 15997.4 13180.0 13220.6 13264.5 13372.2 13417.5 10295.2 10310.7 10347.1 10370.4 10668.7 3558.9 3589.5 3613.2 3702.0 3829.0 81382.0 82283.0 84998.0 85657.0 87352.6 9617.7 9695.5 9716.6 9808.8 9956.9 18145.5 22060.0 22122.0 22131.4 22390.0 7777.3 7796.0 7829.6 7973.4 8161.0 3643.4 4161.8 4193.5 4197.7 4222.9 3770.0 3787.0 3812.0 3815.8 3816.6 655.4 663.8 667.1 694.7 701.7 494.6 557.0 604.6 625.8 633.4 7159.3 8663.8 8688.7 8712.8 8747.9 526.7 596.1 596.9 635.3 638.7 3883.1 4023.4 4121.7 4149.0 4197.5 92597.1 95105.0 95770.5 96468.5 97087.0 81331.6 82997.0 83131.0 83928.6 84291.7 73379.0 73425.6 73738.6 75014.0 75104.9 7996.9 8187.9 8195.3 8371.5 8443.2 51000.0 51810.5 52373.7 52560.0 54018.0 68325.7 71986.9 72507.6 72574.0 72897.0 8692.2 10128.5 10358.7 10369.8 10521.5 11251.5 11288.4 11409.6 11538.2 12129.4 10661.4 10775.2 10877.8 11222.0 11291.2 8838.5 10728.0 10737.9 10945.9 11010.5 10586.3 10596.5 10614.3 10891.2 11009.3 7712.6 9026.7 9158.3 9164.5 9211.6 9128.9 9168.8 9253.9 9299.6 9367.3 23:28:47 INFO - PROCESS | 1670 | -------- Summary: end -------- 23:28:47 INFO - PROCESS | 1670 | 23:28:48 INFO - TEST-INFO | 1670: exit 0 23:28:48 INFO - TEST-OK | dromaeo_css | took 1110868ms 23:28:48 INFO - TEST-START | kraken 23:28:48 INFO - operating with platform_type : mac_ 23:28:48 INFO - Initialising browser for kraken test... 23:28:48 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/tmpIi0ygw/profile http://localhost:49281/getInfo.html) 23:28:50 INFO - PROCESS | 1679 | __metrics Screen width/height:1600/1200 23:28:50 INFO - PROCESS | 1679 | colorDepth:24 23:28:50 INFO - PROCESS | 1679 | Browser inner width/height: 1024/689 23:28:50 INFO - PROCESS | 1679 | __metrics 23:28:50 ERROR - PROCESS | 1679 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2859: TypeError: this.tabs is undefined 23:28:51 INFO - TEST-INFO | 1679: exit 0 23:28:51 INFO - Browser initialized. 23:28:51 INFO - Running cycle 1/1 for kraken test... 23:28:51 INFO - TEST-INFO | started process 1683 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/tmpIi0ygw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:29:03 INFO - PROCESS | 1683 | RSS: Main: 167968768 23:29:03 INFO - PROCESS | 1683 | 23:29:06 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-beat-detection) 23:29:06 INFO - PROCESS | 1683 | RSS: Main: 179523584 23:29:06 INFO - PROCESS | 1683 | 23:29:09 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-dft) 23:29:09 INFO - PROCESS | 1683 | RSS: Main: 188973056 23:29:09 INFO - PROCESS | 1683 | 23:29:13 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-fft) 23:29:14 INFO - PROCESS | 1683 | RSS: Main: 191311872 23:29:14 INFO - PROCESS | 1683 | 23:29:16 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-oscillator) 23:29:16 INFO - PROCESS | 1683 | RSS: Main: 192987136 23:29:16 INFO - PROCESS | 1683 | 23:29:19 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49281/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:29:19 INFO - PROCESS | 1683 | RSS: Main: 193744896 23:29:19 INFO - PROCESS | 1683 | 23:29:22 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49281/tests/kraken/driver.html?testName=imaging-darkroom) 23:29:23 INFO - PROCESS | 1683 | RSS: Main: 194510848 23:29:23 INFO - PROCESS | 1683 | 23:29:25 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49281/tests/kraken/driver.html?testName=imaging-desaturate) 23:29:26 INFO - PROCESS | 1683 | RSS: Main: 194482176 23:29:26 INFO - PROCESS | 1683 | 23:29:29 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49281/tests/kraken/driver.html?testName=json-parse-financial) 23:29:30 INFO - PROCESS | 1683 | RSS: Main: 195297280 23:29:30 INFO - PROCESS | 1683 | 23:29:31 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49281/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:29:32 INFO - PROCESS | 1683 | RSS: Main: 194486272 23:29:32 INFO - PROCESS | 1683 | 23:29:34 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:29:34 INFO - PROCESS | 1683 | RSS: Main: 193904640 23:29:34 INFO - PROCESS | 1683 | 23:29:36 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:29:37 INFO - PROCESS | 1683 | RSS: Main: 194797568 23:29:37 INFO - PROCESS | 1683 | 23:29:39 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:29:40 INFO - PROCESS | 1683 | RSS: Main: 194568192 23:29:40 INFO - PROCESS | 1683 | 23:29:42 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:29:43 INFO - PROCESS | 1683 | RSS: Main: 194469888 23:29:43 INFO - PROCESS | 1683 | 23:29:45 INFO - PROCESS | 1683 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49281/tests/kraken/driver.html?testName=ai-astar) 23:29:45 INFO - PROCESS | 1683 | RSS: Main: 195751936 23:29:45 INFO - PROCESS | 1683 | 23:29:45 INFO - PROCESS | 1683 | __start_tp_report 23:29:45 INFO - PROCESS | 1683 | _x_x_mozilla_page_load 23:29:45 INFO - PROCESS | 1683 | _x_x_mozilla_page_load_details 23:29:45 INFO - PROCESS | 1683 | |i|pagename|runs| 23:29:45 INFO - PROCESS | 1683 | |0;ai-astar;130;156;99;154;166;190;121;114;114;116 23:29:45 INFO - PROCESS | 1683 | |1;audio-beat-detection;150;126;178;135;109;159;144;139;175;152 23:29:45 INFO - PROCESS | 1683 | |2;audio-dft;220;267;192;277;179;271;196;274;271;200 23:29:45 INFO - PROCESS | 1683 | |3;audio-fft;65;107;104;155;47;84;83;55;145;81 23:29:45 INFO - PROCESS | 1683 | |4;audio-oscillator;83;91;121;85;86;66;118;100;110;149 23:29:45 INFO - PROCESS | 1683 | |5;imaging-gaussian-blur;89;88;105;87;77;127;134;98;89;89 23:29:45 INFO - PROCESS | 1683 | |6;imaging-darkroom;95;117;95;95;96;94;96;125;95;149 23:29:45 INFO - PROCESS | 1683 | |7;imaging-desaturate;145;148;91;91;90;112;146;142;90;92 23:29:45 INFO - PROCESS | 1683 | |8;json-parse-financial;57;60;57;58;59;56;58;60;58;56 23:29:45 INFO - PROCESS | 1683 | |9;json-stringify-tinderbox;63;65;70;75;73;71;67;68;72;84 23:29:45 INFO - PROCESS | 1683 | |10;stanford-crypto-aes;82;83;82;82;82;82;82;83;83;94 23:29:45 INFO - PROCESS | 1683 | |11;stanford-crypto-ccm;131;134;130;119;117;122;107;140;110;130 23:29:45 INFO - PROCESS | 1683 | |12;stanford-crypto-pbkdf2;124;136;135;140;160;128;132;135;134;122 23:29:45 INFO - PROCESS | 1683 | |13;stanford-crypto-sha256-iterative;66;67;67;66;83;77;88;92;98;63 23:29:45 INFO - PROCESS | 1683 | __end_tp_report 23:29:45 INFO - PROCESS | 1683 | __start_cc_report 23:29:45 INFO - PROCESS | 1683 | _x_x_mozilla_cycle_collect,905 23:29:45 INFO - PROCESS | 1683 | __end_cc_report 23:29:45 INFO - PROCESS | 1683 | __startTimestamp1463120985587__endTimestamp 23:29:45 INFO - PROCESS | 1683 | 23:29:45 INFO - PROCESS | 1683 | ------- Summary: start ------- 23:29:45 INFO - PROCESS | 1683 | Number of tests: 14 23:29:45 INFO - PROCESS | 1683 | 23:29:45 INFO - PROCESS | 1683 | [#0] ai-astar Cycles:10 Average:136.00 Median:155.00 stddev:28.94 (18.7%) stddev-sans-first:30.61 23:29:45 INFO - PROCESS | 1683 | Values: 130.0 156.0 99.0 154.0 166.0 190.0 121.0 114.0 114.0 116.0 23:29:45 INFO - PROCESS | 1683 | 23:29:45 INFO - PROCESS | 1683 | [#1] audio-beat-detection Cycles:10 Average:146.70 Median:151.00 stddev:21.15 (14.0%) stddev-sans-first:22.39 23:29:45 INFO - PROCESS | 1683 | Values: 150.0 126.0 178.0 135.0 109.0 159.0 144.0 139.0 175.0 152.0 23:29:45 INFO - PROCESS | 1683 | 23:29:45 INFO - PROCESS | 1683 | [#2] audio-dft Cycles:10 Average:234.70 Median:269.00 stddev:40.63 (15.1%) stddev-sans-first:42.74 23:29:45 INFO - PROCESS | 1683 | Values: 220.0 267.0 192.0 277.0 179.0 271.0 196.0 274.0 271.0 200.0 23:29:45 INFO - PROCESS | 1683 | 23:29:45 INFO - PROCESS | 1683 | [#3] audio-fft Cycles:10 Average:92.60 Median:60.00 stddev:35.80 (59.7%) stddev-sans-first:36.55 23:29:45 INFO - PROCESS | 1683 | Values: 65.0 107.0 104.0 155.0 47.0 84.0 83.0 55.0 145.0 81.0 23:29:45 INFO - PROCESS | 1683 | 23:29:45 INFO - PROCESS | 1683 | [#4] audio-oscillator Cycles:10 Average:100.90 Median:74.50 stddev:24.05 (32.3%) stddev-sans-first:24.62 23:29:45 INFO - PROCESS | 1683 | Values: 83.0 91.0 121.0 85.0 86.0 66.0 118.0 100.0 110.0 149.0 23:29:45 INFO - PROCESS | 1683 | 23:29:45 INFO - PROCESS | 1683 | [#5] imaging-gaussian-blur Cycles:10 Average:98.30 Median:88.50 stddev:18.53 (20.9%) stddev-sans-first:19.35 23:29:45 INFO - PROCESS | 1683 | Values: 89.0 88.0 105.0 87.0 77.0 127.0 134.0 98.0 89.0 89.0 23:29:45 INFO - PROCESS | 1683 | 23:29:45 INFO - PROCESS | 1683 | [#6] imaging-darkroom Cycles:10 Average:105.70 Median:95.00 stddev:18.73 (19.7%) stddev-sans-first:19.46 23:29:45 INFO - PROCESS | 1683 | Values: 95.0 117.0 95.0 95.0 96.0 94.0 96.0 125.0 95.0 149.0 23:29:45 INFO - PROCESS | 1683 | 23:29:45 INFO - PROCESS | 1683 | [#7] imaging-desaturate Cycles:10 Average:114.70 Median:90.00 stddev:27.12 (30.1%) stddev-sans-first:26.45 23:29:45 INFO - PROCESS | 1683 | Values: 145.0 148.0 91.0 91.0 90.0 112.0 146.0 142.0 90.0 92.0 23:29:45 INFO - PROCESS | 1683 | 23:29:45 INFO - PROCESS | 1683 | [#8] json-parse-financial Cycles:10 Average:57.90 Median:58.00 stddev:1.45 (2.5%) stddev-sans-first:1.50 23:29:45 INFO - PROCESS | 1683 | Values: 57.0 60.0 57.0 58.0 59.0 56.0 58.0 60.0 58.0 56.0 23:29:45 INFO - PROCESS | 1683 | 23:29:45 INFO - PROCESS | 1683 | [#9] json-stringify-tinderbox Cycles:10 Average:70.80 Median:71.50 stddev:5.92 (8.3%) stddev-sans-first:5.57 23:29:45 INFO - PROCESS | 1683 | Values: 63.0 65.0 70.0 75.0 73.0 71.0 67.0 68.0 72.0 84.0 23:29:45 INFO - PROCESS | 1683 | 23:29:45 INFO - PROCESS | 1683 | [#10] stanford-crypto-aes Cycles:10 Average:83.50 Median:82.50 stddev:3.72 (4.5%) stddev-sans-first:3.91 23:29:45 INFO - PROCESS | 1683 | Values: 82.0 83.0 82.0 82.0 82.0 82.0 82.0 83.0 83.0 94.0 23:29:45 INFO - PROCESS | 1683 | 23:29:45 INFO - PROCESS | 1683 | [#11] stanford-crypto-ccm Cycles:10 Average:124.00 Median:130.00 stddev:10.75 (8.3%) stddev-sans-first:11.10 23:29:45 INFO - PROCESS | 1683 | Values: 131.0 134.0 130.0 119.0 117.0 122.0 107.0 140.0 110.0 130.0 23:29:45 INFO - PROCESS | 1683 | 23:29:45 INFO - PROCESS | 1683 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:134.60 Median:135.00 stddev:10.53 (7.8%) stddev-sans-first:10.45 23:29:45 INFO - PROCESS | 1683 | Values: 124.0 136.0 135.0 140.0 160.0 128.0 132.0 135.0 134.0 122.0 23:29:45 INFO - PROCESS | 1683 | 23:29:45 INFO - PROCESS | 1683 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.70 Median:80.00 stddev:12.74 (15.9%) stddev-sans-first:12.91 23:29:45 INFO - PROCESS | 1683 | Values: 66.0 67.0 67.0 66.0 83.0 77.0 88.0 92.0 98.0 63.0 23:29:45 INFO - PROCESS | 1683 | -------- Summary: end -------- 23:29:45 INFO - PROCESS | 1683 | 23:29:46 INFO - TEST-INFO | 1683: exit 0 23:29:46 INFO - TEST-OK | kraken | took 58245ms 23:29:46 INFO - SUITE-END | took 1169s 23:29:46 INFO - Completed test suite (00:19:29) 23:29:46 INFO - javascript benchmark 23:29:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3342.465205567109, 3692.1138926657595, 3734.8692795752154, 3754.3429899767543, 3760.9811950940243, 6061.878762424743, 6070.696465176746, 6093.756249750005, 6096.573239873211, 6096.878062438748, 6008.068749343848, 6096.725647345869, 6102.633841969485, 6109.5723299369065, 6128.264608247016, 5387.595930305223, 5463.180522921573, 5474.753636086366, 5519.530839878616, 5536.584756143306, 4427.424434823469, 5529.917051244234, 5548.58385621078, 5609.887802243953, 5654.349749778767, 5406.351237851443, 5541.224228607998, 5544.805931792388, 5590.972045139769, 5603.2435621191225, 5522.144067669497, 5542.889142217133, 5577.079781835992, 5582.330120385539, 5636.408177141423, 5340.973295133538, 5365.953639040389, 5438.755256013489, 5442.945570544323, 5463.235147079372, 5902.498287645512, 5943.138244954457, 5953.345132035516, 6010.9098363524145, 6021.2473440819895, 6289.99360102386, 6418.807435776934, 6426.036094585849, 6432.035194720783, 6478.74085036598, 5987.820365389045, 6334.081558174039, 6393.2328120625825, 6416.711247993851, 6451.903221451682, 6212.9068063979075, 6228.906566401508, 6233.0338797486465, 6235.688215589203, 6242.219722534728, 6422.614643121427, 6490.967545162244, 6603.7688680895935, 6609.636469994197, 6620.768273110418, 6258.530610204253, 6326.335734747877, 6343.968280158662, 6351.269603995507, 6359.3958573936325, 6475.643839588868, 6481.935180648227, 6488.513361497907, 6514.348565143542, 6520.836979075561, 6242.687865606701, 6298.716557754912, 6322.936770632326, 6324.146240257598, 6383.202099737533, 6248.781292654735, 6312.936870631235, 6331.081993110973, 6413.518986076063, 6413.967930160413, 5065.39215294167, 5098.286239926413, 5106.591472682177, 5107.897842043139, 5124.026434977342, 4147.771872546979, 4534.274516077412, 4591.17358875399, 4609.469910960215, 4648.279516674921, 6228.28374736902, 6242.344553821783, 6243.906341404882, 6245.125682404467, 6298.149749783812, 5854.7072646367515, 6414.711337989802, 6473.773417930444, 6513.902291465632, 6524.836879078061, 5826.184334193216, 6112.138751317739, 6145.678679084019, 6225.470834979077, 6243.188385509855, 5649.0, 5650.463205995506, 5653.717314134359, 5658.660480371191, 5678.921005009035, 6540.836479087966, 6560.343965603593, 6569.277379488157, 6570.178727659043, 6585.6377899216, 6318.0, 6415.422611964946, 6426.325235850169, 6493.642849643315, 6506.0, 6483.351664833479, 6537.346265373425, 6569.540132190701, 6582.76960306387, 6584.275729669636, 6255.124282600348, 6333.936660633335, 6406.23125224976, 6412.0, 6413.390727880844, 6406.519511036784, 6483.0, 6523.869522609616, 6528.412442880163, 6532.444742196776, 6315.463185629089, 6325.177726895474, 6326.936730632635, 6352.650604216812, 6357.14178585885, 6178.41305076017, 6238.313785483502, 6285.402886725754, 6323.08315294289, 6364.93635063662, 5045.772940217626, 5671.376148623731, 6025.578209525291, 6121.143039974496, 6225.346338634559, 6214.005759078525, 6251.369385519515, 6252.468540174136, 6267.905981410191, 6285.717142728679, 6166.876662466815, 6189.04069869171, 6244.344343844013, 6256.5307601930945, 6279.372062793684], "unit": "score", "name": "dojo.html", "value": 5943.9117402055535}, {"lowerIsBetter": false, "replicates": [455.1829466108337, 505.36828963795256, 634.9396807303305, 673.9217252396166, 679.5039621076616, 39665.206695866065, 40330.79007629776, 40331.39502907451, 40348.79825600868, 40588.59411405885, 40558.39162412566, 41119.38320925181, 41197.17605647885, 41255.762327130156, 41292.0, 9685.079917407835, 9828.410295382264, 9888.11007009369, 9893.30746847721, 9918.801623967516, 9778.315517913748, 10071.244656650744, 10076.294659373847, 10115.393076415401, 10205.897941020568, 10013.048760367754, 10021.198304135653, 10085.344452610594, 10140.89859101407, 10153.136983356386, 10007.849882251772, 10044.19646428284, 10055.49722513875, 10065.144462720678, 10129.290949633529, 9897.15874150698, 10002.199824014062, 10031.74920626983, 10043.397396156253, 10045.89954100457, 35046.474302885224, 40359.7982010091, 40654.0, 40992.97517562055, 41195.588044119475, 3216.260260140181, 3437.3984552703178, 3460.498227756986, 3511.262634846685, 3538.0093573799377, 29646.962356317425, 29867.701322986708, 29926.401471970657, 30332.54501182484, 30491.0, 2318.5247024359965, 2716.0765339784566, 2737.438825040862, 2768.529350010503, 2854.52900271457, 413346.0, 420002.0, 422439.0, 423505.0, 506137.0, 50486.74756626193, 81054.59472702717, 83099.0, 83136.58431707803, 83378.58310708408, 50946.23580646293, 51540.96918061618, 51574.74212628988, 51888.22167667488, 52098.73950630298, 16825.747613785803, 17086.145692715316, 17205.0, 17374.218160182823, 17445.65108697819, 13250.867491325156, 13390.665233369049, 13432.865671343357, 13485.123466974615, 13668.179909205479, 16391.42630007944, 16598.66802663964, 16678.249478773483, 16690.915090519076, 16735.581610459834, 6079.878402431927, 7140.500164988401, 7149.285071492809, 7200.459965502609, 7219.91701244807, 994.0953732103751, 1048.7483004078965, 1078.1321036565482, 1114.0530549033258, 1161.6863446869297, 30964.380712385628, 31241.218969525944, 31526.581303841383, 31585.052448426584, 31591.368172636878, 1143.5940241214416, 1145.2269717940524, 1205.1864991131126, 1220.2922305063041, 1265.8987281017496, 15511.301991410413, 15702.450414235675, 15776.447824326093, 15847.762283565755, 16078.276477558538, 522953.0, 525077.3746131245, 525360.0, 530372.0, 531842.0, 61635.0, 62499.0, 64314.035289470696, 64479.71040579162, 64725.705485890016, 1509.532045066011, 1547.2418514927829, 1556.509699444694, 1558.618138556061, 1569.7174508588564, 30421.543676845307, 30640.540391893694, 30737.365368682757, 30948.69051309458, 31039.534406984367, 53626.463735362144, 53963.0, 54147.91704165973, 54373.0, 54634.0, 482930.0, 483549.0, 487145.0, 488555.0, 489053.0, 32608.51087233646, 33555.49666755049, 35997.28005439929, 36322.4551631731, 36911.815440922626, 46619.533804662875, 47366.052678946915, 48805.51194488152, 49019.75490122669, 49678.751606241734, 38878.266411728204, 39284.0, 39403.80298098491, 39754.80122599369, 39855.00362490961, 38646.22707545777, 38878.611213887496, 38924.80537597294, 39210.2157956845, 39898.40152397658, 32505.512417313286, 38652.0, 38943.805280973414, 39255.80372098121, 40022.59977400305, 33535.496967545994, 34721.305573887876, 34978.47532286967, 34983.300333993684, 35099.94700158999, 25925.0, 26040.0, 26265.343366415993, 26410.410450169493, 26586.0], "unit": "score", "name": "ext.html", "value": 20310.719338556482}, {"lowerIsBetter": false, "replicates": [349.78488229738707, 379.6621007303499, 388.776453539215, 391.6436043200688, 393.48257041989797, 20183.697244541345, 20680.172793088277, 21144.0, 21552.99999999998, 21650.0257488413, 11290.491927863246, 12335.62993110206, 12339.197952133105, 12478.064145189124, 12630.30533320667, 5183.118869792135, 6212.689365531728, 6220.6267623942485, 6238.968805155968, 6239.15771370866, 6116.143739876421, 6174.876502469971, 6175.258968923713, 6207.906881396782, 6252.843678908041, 5896.23348964634, 6035.155078289043, 6060.696965151747, 6125.387461253861, 6131.724072416752, 5476.726163691839, 6061.878762424749, 6088.1476593276975, 6109.7861574844665, 6127.785527506516, 5232.973835130838, 5253.711045892474, 5311.893762124736, 5328.413874473844, 5337.06601344767, 11709.53161873524, 12187.268763874195, 23350.0, 24550.754492455024, 25421.237362879143, 2217.6008318502663, 2220.7668194839634, 2338.660894170353, 2347.6009078456705, 2376.429656882353, 9848.65529706464, 9966.554849546848, 10102.343347682377, 10431.21765867563, 10559.947200264027, 1611.798525184352, 1780.813014633458, 1851.2039822876159, 1932.1498540642071, 2000.4798752324355, 101842.49078754633, 102308.0, 104267.47866260695, 104692.95307046908, 106375.0, 9701.51492425376, 11855.703607409883, 11901.404929753478, 11932.761344773056, 11960.102992275613, 14496.275186240646, 14569.927150364181, 14604.707905841824, 14620.488282910048, 14631.048981816148, 8989.775255618662, 9026.45841249527, 9051.547422628973, 9127.315451341176, 9162.221211197122, 8051.758447246592, 8813.515256660818, 9094.272458203328, 9243.630254789734, 9265.72202833916, 4588.197065513522, 4593.448786145684, 4611.16998940194, 4614.123316569908, 4674.9247673034715, 1988.2544045982756, 2007.447951813263, 2011.4870707969374, 2017.5662232620057, 2029.9289524866558, 848.8259906719107, 888.9911100889037, 968.4528241543505, 1026.8357062869904, 1081.3349789879167, 19452.902735486234, 20879.791202087785, 20888.582228355346, 21153.15387384518, 21224.78775212259, 1020.5254556631202, 1032.5921261101973, 1036.6009086501751, 1085.218642577343, 1090.0625462102585, 10990.780184396268, 11405.714857128638, 11457.656270311903, 11461.885381146249, 11467.942660286646, 657274.0, 673911.0, 678726.0, 690399.0, 703337.0, 83593.58203208946, 115409.42295288469, 119979.8002019986, 120615.39692301658, 121773.0, 1052.4106500359824, 1099.9725006874892, 1100.785346857355, 1102.1898904305328, 1102.6747109602527, 18131.54671133232, 18219.271229150687, 18245.908770456062, 18527.0736463179, 18835.623287534447, 53391.0, 53738.46261537335, 54452.72773636106, 55110.0, 55905.32284031485, 61446.0, 75517.0, 76969.61515192388, 77096.0, 77659.0, 6358.872822543616, 8115.553644549606, 8275.131111233233, 8320.334373250247, 8380.16198380157, 12364.701706321066, 12504.937475312565, 13224.338783061, 13712.52006179779, 13743.793843092162, 9756.85364719544, 9825.705228843146, 11430.942845286054, 11928.58249961282, 12076.698082548006, 11064.391458469861, 11362.659120226075, 11395.487203076047, 11686.415679216176, 12000.0, 10496.632617858339, 11342.20604557673, 11894.286342819112, 12167.939160304142, 12235.0, 8212.630431630472, 8238.011438627404, 8310.916890831015, 8355.623996920032, 8456.788580285542, 8842.602082906413, 11556.364399958084, 11656.125790565571, 11751.236169649288, 12027.579034733742], "unit": "score", "name": "jquery.html", "value": 9501.337828275175}, {"lowerIsBetter": false, "replicates": [1669.5492217101382, 1681.6048228666264, 2043.9882258282153, 2106.2312256026553, 2108.409645299316, 8403.0, 9011.909880901188, 9077.546122693864, 9276.165145136936, 9305.34862559621, 8953.820923581525, 9061.0, 9065.864012039825, 9171.0, 9182.449053056822, 2210.4363387336207, 2211.94470138247, 2633.934151646205, 2634.196570046135, 2634.8419094854275, 2574.935626609331, 2610.0, 2613.8562379069144, 2641.6037594360905, 2645.7486538778785, 2605.2054123492326, 2617.882195301216, 2641.696204936428, 2646.4045589742345, 2664.134156399178, 2598.1036542392844, 2618.5941179117362, 2628.6582744243224, 2652.2308530526125, 2660.9600855987173, 2272.6931864198286, 2607.478504299129, 2621.3708709909674, 2645.973540264592, 2663.8268512546624, 9169.449833010041, 9335.393199442015, 9359.672411465566, 9368.0, 9401.153896149379, 5789.739461724233, 5870.20752198452, 5972.850678733023, 5989.9101513477335, 6005.927954855568, 3661.6887564557046, 3743.7005039596766, 3767.1523907120954, 3784.413415920537, 3801.0877389426614, 1081.3619964221068, 1084.7233955341337, 1093.557109370706, 1133.5239199536215, 1135.5628083188033, 12161.635150945485, 12241.143119981605, 12279.75440491194, 12340.38298085101, 12344.13591048627, 5069.518395752398, 5131.281620573122, 5178.689278643294, 5201.947980520146, 5247.895042099137, 10506.789864202827, 10607.946960265304, 10629.787404251872, 10675.359478431164, 10683.198760093024, 5876.41235876409, 5922.466977972003, 5952.642841429528, 5968.671723055274, 5969.164316995624, 4699.177643912301, 5156.74216289184, 5222.477752224823, 5224.791008359623, 5278.129108697099, 4125.339945608688, 4134.359174327984, 4135.545090040093, 4135.58644135584, 4197.727147735387, 1369.8082268482422, 1381.8894488441101, 1397.9021468497108, 1405.6907480354305, 1406.606150277924, 1150.8618965724168, 1177.4524845946676, 1188.3226560860398, 1230.809224570193, 1233.9383030848421, 8496.660133594587, 8499.192576705203, 8522.914770852336, 8552.61513231906, 8577.485350878967, 1125.3810404277615, 1142.331735934471, 1310.5806142034648, 1315.8420989481324, 1330.1952318847152, 6441.355864413615, 6459.870802583922, 6465.15952926126, 6541.47668186544, 6564.949608062687, 8749.47503149813, 9197.0, 9322.86015709765, 11229.27009744364, 11263.324200547993, 8620.568971551398, 9117.544122793834, 10682.57269709203, 11205.60780372683, 11267.380294083903, 8390.993080830218, 8393.916060839314, 8424.241818236389, 8480.957595212109, 8613.65545378178, 7768.262015108556, 7948.801279968047, 8380.748577542683, 8433.19884610961, 8468.618912149092, 9865.654702085392, 9880.703578892644, 10143.797124057624, 10151.035651613085, 10154.796904062025, 10152.54313555877, 10195.0, 10220.488975551045, 10244.538995745359, 10636.946815265874, 7672.5012874163, 7807.687692492464, 7976.800579985546, 7979.441439099207, 8062.7581172564915, 8544.572771361283, 8681.8697719533, 8684.565771711263, 8701.260392866425, 8720.127987201482, 7531.209223031503, 7753.147153813189, 7816.413768967236, 7853.56805375687, 7864.134945155914, 6782.982552617147, 7161.0, 7543.924560754542, 7573.431992600687, 7761.301482866569, 7932.0, 7951.522908625501, 8122.431429800094, 8122.837543248984, 8143.837123257384, 7163.964180179066, 7265.09186351706, 7297.124345078624, 7316.1952185258515, 7328.670209840463, 7417.925820741723, 7440.851182976418, 7470.8505829882015, 7471.028766260351, 7479.813004674927], "unit": "score", "name": "mootools.html", "value": 5174.871691798342}, {"lowerIsBetter": false, "replicates": [345.64053384480144, 347.65234765234766, 349.34323471872875, 349.902027432319, 354.00348020322787, 6766.729330826774, 6832.214295356035, 6842.0, 6873.106496155493, 6901.275366086557, 6739.730410783563, 6830.077939478181, 6847.349501797334, 6859.931400685991, 6874.725010999567, 4845.127876982142, 4931.556159945604, 5049.3183420238165, 5056.595472362221, 5131.615128865331, 5010.498950104997, 5035.1188542005, 5036.118679231138, 5041.8235361762345, 5058.544730974211, 4978.502149785028, 4986.077575648498, 4992.650514463989, 4994.076095922248, 5010.72226582221, 5067.695938243699, 5087.720175390352, 5094.796208151688, 5096.413912400084, 5099.184130539096, 4377.846775362847, 4379.58393952574, 4379.58393952574, 4388.648908087345, 4393.275109606911, 6365.936340636581, 6606.735730570771, 6635.469162466991, 6636.601803891782, 6655.134832471747, 4710.811567537295, 4800.591949684281, 5467.797084641412, 5943.821685349403, 5943.910841337383, 7349.77950661481, 7363.889541656879, 7377.77866664001, 7380.520266182681, 7399.001134846781, 4731.219348807477, 4747.287906813971, 4753.382060332169, 4754.0967216229155, 4775.761211939423, 6620.86758264832, 7625.923740762577, 7694.576798276093, 7713.730019449293, 7768.844623107506, 4256.936145957813, 4689.718616882998, 4730.172219861579, 4740.19416699162, 4765.428148622187, 4888.755562221874, 4897.828575999894, 4901.607871370282, 4914.385701787277, 4917.680350777188, 5824.621399609012, 5864.501517370986, 5902.734376953047, 5903.704814759245, 5905.379935106752, 7301.306375894282, 7313.305236002571, 7338.266173382619, 7342.889856652155, 7372.668229929666, 1608.774771531963, 1652.7520871869315, 1655.4950740024408, 1668.3326669332364, 1687.2660392729201, 1061.3843970497248, 1070.5236169904317, 1081.2053140941402, 1083.3445765311874, 1084.9945750271197, 6900.516963812485, 6935.618540980194, 6946.9652651736415, 6961.408280296231, 6962.512624116364, 5775.480206781326, 6504.739810407531, 7567.583782891884, 7571.129320128146, 7612.0, 6282.685865706696, 6917.27368626287, 6952.443804495628, 6953.235144134142, 7001.614911179832, 3234.256121092162, 3878.4182372643763, 3996.7002474814503, 4017.819198136091, 4026.7382620129597, 7518.323350898446, 7653.234676532414, 7755.72854950074, 7773.805654858674, 7806.921930780733, 7860.292573668397, 7861.685532578747, 7864.5281283123195, 7881.408894332833, 7892.289693927574, 7745.883811742829, 7784.625689164746, 7797.610119494003, 7828.647710853025, 7860.489068210548, 7302.744403945836, 7586.962065189638, 7616.657250423634, 7676.73536306802, 7700.383969282556, 7450.701971921062, 7454.589997550187, 7457.7389791357045, 7477.588732619541, 7511.286427789351, 7715.57564333967, 7724.652390642547, 7739.496932699357, 7744.728934487492, 7754.844903102019, 4769.451513075902, 4804.303376010529, 4834.984653222863, 4835.564799168092, 4850.733209673502, 6247.562670613013, 7353.117625884928, 7585.089789225328, 7594.544327340156, 7607.429442791923, 5502.504774570148, 7377.188509264084, 7414.22150674193, 7421.851562968818, 7451.813704657427, 7395.371393431618, 7402.925970740223, 7408.111026676834, 7417.147028091838, 7455.508898220486, 7169.7849064528145, 7174.713011479691, 7186.425085993213, 7221.566705997657, 7227.205007449282, 5958.433948774859, 7058.54119482232, 7202.459815513753, 7256.1655409626455, 7287.089113860768, 7160.427165826617, 7194.89207661875, 7196.8920466192, 7205.243449437734, 7206.243344448757], "unit": "score", "name": "prototype.html", "value": 5312.398263234953}, {"lowerIsBetter": false, "replicates": [699.3181647893308, 716.5987047253539, 728.8615163119007, 757.8029712274807, 767.6046835879521, 12886.742265154691, 13438.798418023725, 15317.770233446507, 15404.152771597572, 15614.921925390358, 14468.20424876634, 14851.480198193065, 15004.474843380483, 15011.6997660047, 15012.399504019826, 814.0963530481191, 827.7971896885272, 970.141424839019, 981.543582234261, 1009.2027298434228, 968.1963969904997, 994.1351024608565, 997.6209040564576, 1012.5342342522412, 1012.5392946209494, 1034.4724190662778, 1036.533559898049, 1050.396022287184, 1051.8369652703843, 1053.0469924718104, 985.7437066362726, 999.0908273471143, 1003.8092762375126, 1013.908748212657, 1043.7025447747355, 748.1060133140899, 749.0113050772982, 750.4934169435663, 759.5632511306021, 771.4098714483424, 15422.768658470131, 15564.455244066516, 15875.285612147482, 15895.920520397323, 15997.440089596925, 13180.0, 13220.603381898462, 13264.469421223139, 13372.197668139943, 13417.46330146793, 10295.227857910686, 10310.687944748834, 10347.120494757955, 10370.429626370547, 10668.733281667943, 3558.857645694169, 3589.5154154189113, 3613.217633456829, 3702.0, 3829.0235989822695, 81382.0, 82283.0, 84998.0, 85657.0, 87352.56323718405, 9617.663381781604, 9695.515224238901, 9716.562754676057, 9808.754781130528, 9956.850647240297, 18145.546361341072, 22060.0, 22122.0, 22131.446713832283, 22390.0, 7777.300042996045, 7796.0, 7829.6085195741125, 7973.362131029504, 8161.0, 3643.417053271464, 4161.812718427617, 4193.475815523059, 4197.685173611992, 4222.873313800591, 3770.0, 3786.962130378716, 3812.046988252937, 3815.7901315427916, 3816.5610954740005, 655.3675702946584, 663.8141320430191, 667.0694381338006, 694.7221111555317, 701.7263267325746, 494.5965328783102, 557.0335467963081, 604.6281536854802, 625.7872323410062, 633.3951076721812, 7159.284071592799, 8663.78340541479, 8688.739337819876, 8712.78218044554, 8747.912520874837, 526.6918852471347, 596.0612036043249, 596.8627215740404, 635.2630948100274, 638.7157714816861, 3883.1457079442475, 4023.3964905264443, 4121.732087414309, 4148.979255103705, 4197.517285512145, 92597.07402925885, 95105.0, 95770.52114739381, 96468.51765741267, 97087.0, 81331.5600531985, 82997.0, 83131.0, 83928.58035709904, 84291.7356239657, 73379.0, 73425.6328718353, 73738.63130684312, 75014.0, 75104.87342689864, 7996.92003079985, 8187.877181842397, 8195.303399211134, 8371.497710137412, 8443.155684431507, 50999.98000040052, 51810.48189518207, 52373.7381313091, 52560.0, 54018.0, 68325.65837170782, 71986.92019619605, 72507.63746181235, 72574.0, 72897.0, 8692.181028890875, 10128.544215510467, 10358.689239322832, 10369.792604148026, 10521.526531305957, 11251.49368278413, 11288.43557822122, 11409.60066397672, 11538.192326537062, 12129.393530323272, 10661.41362225054, 10775.245732798629, 10877.782444351227, 11222.0, 11291.209615326849, 8838.513881736566, 10728.0, 10737.892621074001, 10945.945270273598, 11010.50452729613, 10586.258961872594, 10596.470176491299, 10614.257002009785, 10891.237613366988, 11009.339439633648, 7712.642198746581, 9026.729198124067, 9158.313126515404, 9164.541772911194, 9211.631534738535, 9128.908710912805, 9168.816623667622, 9253.86119208199, 9299.628014879328, 9367.297452691211], "unit": "score", "name": "yui.html", "value": 7050.625750427675}], "name": "dromaeo_css", "value": 7783.45026909327}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [130.0, 156.0, 99.0, 154.0, 166.0, 190.0, 121.0, 114.0, 114.0, 116.0], "unit": "score", "name": "ai-astar", "value": 136.0}, {"lowerIsBetter": true, "replicates": [150.0, 126.0, 178.0, 135.0, 109.0, 159.0, 144.0, 139.0, 175.0, 152.0], "unit": "score", "name": "audio-beat-detection", "value": 146.7}, {"lowerIsBetter": true, "replicates": [220.0, 267.0, 192.0, 277.0, 179.0, 271.0, 196.0, 274.0, 271.0, 200.0], "unit": "score", "name": "audio-dft", "value": 234.7}, {"lowerIsBetter": true, "replicates": [65.0, 107.0, 104.0, 155.0, 47.0, 84.0, 83.0, 55.0, 145.0, 81.0], "unit": "score", "name": "audio-fft", "value": 92.6}, {"lowerIsBetter": true, "replicates": [83.0, 91.0, 121.0, 85.0, 86.0, 66.0, 118.0, 100.0, 110.0, 149.0], "unit": "score", "name": "audio-oscillator", "value": 100.9}, {"lowerIsBetter": true, "replicates": [89.0, 88.0, 105.0, 87.0, 77.0, 127.0, 134.0, 98.0, 89.0, 89.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 98.3}, {"lowerIsBetter": true, "replicates": [95.0, 117.0, 95.0, 95.0, 96.0, 94.0, 96.0, 125.0, 95.0, 149.0], "unit": "score", "name": "imaging-darkroom", "value": 105.7}, {"lowerIsBetter": true, "replicates": [145.0, 148.0, 91.0, 91.0, 90.0, 112.0, 146.0, 142.0, 90.0, 92.0], "unit": "score", "name": "imaging-desaturate", "value": 114.7}, {"lowerIsBetter": true, "replicates": [57.0, 60.0, 57.0, 58.0, 59.0, 56.0, 58.0, 60.0, 58.0, 56.0], "unit": "score", "name": "json-parse-financial", "value": 57.9}, {"lowerIsBetter": true, "replicates": [63.0, 65.0, 70.0, 75.0, 73.0, 71.0, 67.0, 68.0, 72.0, 84.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 70.8}, {"lowerIsBetter": true, "replicates": [82.0, 83.0, 82.0, 82.0, 82.0, 82.0, 82.0, 83.0, 83.0, 94.0], "unit": "score", "name": "stanford-crypto-aes", "value": 83.5}, {"lowerIsBetter": true, "replicates": [131.0, 134.0, 130.0, 119.0, 117.0, 122.0, 107.0, 140.0, 110.0, 130.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 124.0}, {"lowerIsBetter": true, "replicates": [124.0, 136.0, 135.0, 140.0, 160.0, 128.0, 132.0, 135.0, 134.0, 122.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 134.6}, {"lowerIsBetter": true, "replicates": [66.0, 67.0, 67.0, 66.0, 83.0, 77.0, 88.0, 92.0, 98.0, 63.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 76.7}], "name": "kraken", "value": 1577.1}]} 23:29:46 INFO - Return code: 0 23:29:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 23:29:46 ERROR - # TBPL SUCCESS # 23:29:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:29:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:29:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:29:46 INFO - 2016-05-12 23:29:46.909 screenresolution[1688:10780] starting screenresolution argv=screenresolution get 23:29:46 INFO - 2016-05-12 23:29:46.912 screenresolution[1688:10780] Display 0: 1600x1200x32@60 23:29:46 INFO - 2016-05-12 23:29:46.918 screenresolution[1689:10783] starting screenresolution argv=screenresolution list 23:29:46 INFO - Available Modes on Display 0 23:29:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:29:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:29:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:29:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:29:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:29:47 INFO - Intel Iris: 23:29:47 INFO - Chipset Model: Intel Iris 23:29:47 INFO - Type: GPU 23:29:47 INFO - Bus: Built-In 23:29:47 INFO - VRAM (Dynamic, Max): 1536 MB 23:29:47 INFO - Vendor: Intel (0x8086) 23:29:47 INFO - Device ID: 0x0a2e 23:29:47 INFO - Revision ID: 0x0009 23:29:47 INFO - Displays: 23:29:47 INFO - Display: 23:29:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:29:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:29:47 INFO - Display Serial Number: 1600x1200 60 23:29:47 INFO - Main Display: Yes 23:29:47 INFO - Mirror: Off 23:29:47 INFO - Online: Yes 23:29:47 INFO - Rotation: Supported 23:29:47 INFO - Return code: 0 23:29:47 INFO - Running post-action listener: _resource_record_post_action 23:29:47 INFO - Running post-run listener: _resource_record_post_run 23:29:48 INFO - Total resource usage - Wall time: 1194s; CPU: 53.0%; Read bytes: 37774848; Write bytes: 510013952; Read time: 392; Write time: 1897 23:29:48 INFO - install - Wall time: 23s; CPU: 55.0%; Read bytes: 210137600; Write bytes: 197193728; Read time: 18858; Write time: 404 23:29:48 INFO - run-tests - Wall time: 1172s; CPU: 53.0%; Read bytes: 30645760; Write bytes: 300674048; Read time: 305; Write time: 1438 23:29:48 INFO - Running post-run listener: _upload_blobber_files 23:29:48 INFO - Blob upload gear active. 23:29:48 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:29:48 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 23:29:48 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:29:48 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:29:48 INFO - (blobuploader) - INFO - Open directory for files ... 23:29:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 23:29:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:29:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:29:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 23:29:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:29:49 INFO - (blobuploader) - INFO - Done attempting. 23:29:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 23:29:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:29:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:29:50 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 23:29:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:29:50 INFO - (blobuploader) - INFO - Done attempting. 23:29:50 INFO - (blobuploader) - INFO - Iteration through files over. 23:29:50 INFO - Return code: 0 23:29:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:29:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:29:50 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b9607ebd56189109ca87b87fa944f139834975b655422f9a32cafcae349c3a443c64a5307f76b16be94225538ab1628ecd15c7a2f071ca9b5cd2fffc90c4e2e4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5ab0c23ad4b17167ee132b7ec098f0ed824d2eccb58f86ebd6b03007e190a432a2ce0f8f64290388c3dbac2a944f925816488c326177d534d3a39b31da4a85fa"} 23:29:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:29:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:29:50 INFO - Contents: 23:29:50 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b9607ebd56189109ca87b87fa944f139834975b655422f9a32cafcae349c3a443c64a5307f76b16be94225538ab1628ecd15c7a2f071ca9b5cd2fffc90c4e2e4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5ab0c23ad4b17167ee132b7ec098f0ed824d2eccb58f86ebd6b03007e190a432a2ce0f8f64290388c3dbac2a944f925816488c326177d534d3a39b31da4a85fa"} 23:29:50 INFO - Running post-run listener: copy_logs_to_upload_dir 23:29:50 INFO - Copying logs to upload dir... 23:29:50 INFO - mkdir: /builds/slave/test/build/upload/logs 23:29:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.117008 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-05-12 23:29:50.405502) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 23:29:50.406214) ========= 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.M1Geu1So7x/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.MgIBKEdIin/Listeners TMPDIR=/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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-Beta/sha512/b9607ebd56189109ca87b87fa944f139834975b655422f9a32cafcae349c3a443c64a5307f76b16be94225538ab1628ecd15c7a2f071ca9b5cd2fffc90c4e2e4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5ab0c23ad4b17167ee132b7ec098f0ed824d2eccb58f86ebd6b03007e190a432a2ce0f8f64290388c3dbac2a944f925816488c326177d534d3a39b31da4a85fa"} build_url:https://queue.taskcluster.net/v1/task/KOpzZRu0SYGza3ypIyiE6w/artifacts/public/build/firefox-47.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010822 build_url: 'https://queue.taskcluster.net/v1/task/KOpzZRu0SYGza3ypIyiE6w/artifacts/public/build/firefox-47.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b9607ebd56189109ca87b87fa944f139834975b655422f9a32cafcae349c3a443c64a5307f76b16be94225538ab1628ecd15c7a2f071ca9b5cd2fffc90c4e2e4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5ab0c23ad4b17167ee132b7ec098f0ed824d2eccb58f86ebd6b03007e190a432a2ce0f8f64290388c3dbac2a944f925816488c326177d534d3a39b31da4a85fa"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 23:29:50.444075) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 23:29:50.444392) ========= 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.M1Geu1So7x/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.MgIBKEdIin/Listeners TMPDIR=/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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.005301 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 23:29:50.492590) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 23:29:50.492880) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 23:29:50.493172) ========= ========= Total master_lag: 0.20 =========