builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0119 starttime: 1463652187.87 results: success (0) buildid: 20160519004038 builduid: 60029db4a31e478bb3de011137e28f13 revision: c41ee7132426780f38bbffc58435548d92d2b327 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-19 03:03:07.866335) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-19 03:03:07.866799) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-19 03:03:07.867071) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-19 03:03:07.887051) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 03:03:07.887419) ========= 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.QcB2U57zaF/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.aZGfgWJzCB/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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-19 03:03:07-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 53.4M=0s 2016-05-19 03:03:07 (53.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.083113 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 03:03:07.989437) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 03:03:07.989753) ========= 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.QcB2U57zaF/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.aZGfgWJzCB/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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.033244 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 03:03:08.038583) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-19 03:03:08.038938) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c41ee7132426780f38bbffc58435548d92d2b327 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev c41ee7132426780f38bbffc58435548d92d2b327 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QcB2U57zaF/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.aZGfgWJzCB/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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-19 03:03:08,114 truncating revision to first 12 chars 2016-05-19 03:03:08,115 Setting DEBUG logging. 2016-05-19 03:03:08,115 attempt 1/10 2016-05-19 03:03:08,115 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/c41ee7132426?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-19 03:03:10,004 unpacking tar archive at: mozilla-aurora-c41ee7132426/testing/mozharness/ program finished with exit code 0 elapsedTime=2.141525 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-19 03:03:10.197152) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-19 03:03:10.197466) ========= script_repo_revision: c41ee7132426780f38bbffc58435548d92d2b327 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-19 03:03:10.197865) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-19 03:03:10.198145) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-19 03:03:10.211661) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-05-19 03:03:10.211947) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.QcB2U57zaF/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.aZGfgWJzCB/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 03:03:10 INFO - MultiFileLogger online at 20160519 03:03:10 in /builds/slave/test 03:03:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:03:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:03:10 INFO - {'append_to_log': False, 03:03:10 INFO - 'base_work_dir': '/builds/slave/test', 03:03:10 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:03:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:03:10 INFO - 'branch': 'Mozilla-Aurora', 03:03:10 INFO - 'buildbot_json_path': 'buildprops.json', 03:03:10 INFO - 'config_files': ('talos/mac_config.py',), 03:03:10 INFO - 'default_actions': ('clobber', 03:03:10 INFO - 'read-buildbot-config', 03:03:10 INFO - 'download-and-extract', 03:03:10 INFO - 'populate-webroot', 03:03:10 INFO - 'create-virtualenv', 03:03:10 INFO - 'install', 03:03:10 INFO - 'run-tests'), 03:03:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:03:10 INFO - 'download_minidump_stackwalk': True, 03:03:10 INFO - 'download_symbols': 'ondemand', 03:03:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:03:10 INFO - 'tooltool.py': '/tools/tooltool.py', 03:03:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:03:10 INFO - '/tools/misc-python/virtualenv.py')}, 03:03:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:03:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:03:10 INFO - 'installer_path': 'installer.exe', 03:03:10 INFO - 'log_level': 'info', 03:03:10 INFO - 'log_name': 'talos', 03:03:10 INFO - 'log_to_console': True, 03:03:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:03:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:03:10 INFO - 'opt_config_files': (), 03:03:10 INFO - 'pip_index': False, 03:03:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:03:10 INFO - 'cmd': ('bash', 03:03:10 INFO - '-c', 03:03:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:03:10 INFO - 'enabled': True, 03:03:10 INFO - 'halt_on_failure': False, 03:03:10 INFO - 'name': 'check_screen_resolution'},), 03:03:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:03:10 INFO - 'cmd': ('bash', 03:03:10 INFO - '-c', 03:03:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:03:10 INFO - 'enabled': True, 03:03:10 INFO - 'halt_on_failure': False, 03:03:10 INFO - 'name': 'check_screen_resolution'},), 03:03:10 INFO - 'run_cmd_checks_enabled': True, 03:03:10 INFO - 'sps_profile': False, 03:03:10 INFO - 'sps_profile_interval': 0, 03:03:10 INFO - 'suite': 'dromaeojs-e10s', 03:03:10 INFO - 'system_bits': '32', 03:03:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:03:10 INFO - 'title': 't-yosemite-r7-0119', 03:03:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:03:10 INFO - 'use_talos_json': True, 03:03:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:03:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:03:10 INFO - 'work_dir': 'build'} 03:03:10 INFO - ##### 03:03:10 INFO - ##### Running clobber step. 03:03:10 INFO - ##### 03:03:10 INFO - Running pre-action listener: _resource_record_pre_action 03:03:10 INFO - Running main action method: clobber 03:03:10 INFO - rmtree: /builds/slave/test/build 03:03:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:03:12 INFO - Running post-action listener: _resource_record_post_action 03:03:12 INFO - ##### 03:03:12 INFO - ##### Running read-buildbot-config step. 03:03:12 INFO - ##### 03:03:12 INFO - Running pre-action listener: _resource_record_pre_action 03:03:12 INFO - Running main action method: read_buildbot_config 03:03:12 INFO - Using buildbot properties: 03:03:12 INFO - { 03:03:12 INFO - "project": "", 03:03:12 INFO - "product": "firefox", 03:03:12 INFO - "script_repo_revision": "production", 03:03:12 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 03:03:12 INFO - "repository": "", 03:03:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 03:03:12 INFO - "buildid": "20160519004038", 03:03:12 INFO - "stage_platform": "macosx64", 03:03:12 INFO - "basedir": "/builds/slave/test", 03:03:12 INFO - "nightly_build": "True", 03:03:12 INFO - "buildnumber": 3, 03:03:12 INFO - "slavename": "t-yosemite-r7-0119", 03:03:12 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 03:03:12 INFO - "platform": "yosemite_r7", 03:03:12 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:03:12 INFO - "branch": "mozilla-aurora", 03:03:12 INFO - "repo_path": "releases/mozilla-aurora", 03:03:12 INFO - "revision": "c41ee7132426780f38bbffc58435548d92d2b327", 03:03:12 INFO - "pgo_build": "False", 03:03:12 INFO - "builduid": "60029db4a31e478bb3de011137e28f13", 03:03:12 INFO - "slavebuilddir": "test" 03:03:12 INFO - } 03:03:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 03:03:12 INFO - Running post-action listener: _resource_record_post_action 03:03:12 INFO - ##### 03:03:12 INFO - ##### Running download-and-extract step. 03:03:12 INFO - ##### 03:03:12 INFO - Running pre-action listener: _resource_record_pre_action 03:03:12 INFO - Running main action method: download_and_extract 03:03:12 INFO - mkdir: /builds/slave/test/build/tests 03:03: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')]} 03:03:12 INFO - https://queue.taskcluster.net/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 03:03:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 03:03:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 03:03:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 03:03:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 03:03:14 INFO - Downloaded 1199 bytes. 03:03:14 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 03:03:14 INFO - Using the following test package requirements: 03:03:14 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 03:03:14 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:03:14 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 03:03:14 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:03:14 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 03:03:14 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:03:14 INFO - u'jsshell-mac.zip'], 03:03:14 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:03:14 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 03:03:14 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 03:03:14 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:03:14 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 03:03:14 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:03:14 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 03:03:14 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:03:14 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 03:03:14 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:03:14 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 03:03:14 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:03: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')]} 03:03:14 INFO - https://queue.taskcluster.net/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:03:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:03:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:03:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:03:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:03:15 INFO - Downloaded 18102566 bytes. 03:03:15 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:03:15 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:03:19 INFO - Return code: 0 03:03:19 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 03:03:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:03:19 INFO - https://queue.taskcluster.net/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:03:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:03:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:03:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:03:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:03:20 INFO - Downloaded 18102566 bytes. 03:03:20 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:03:20 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:03:23 INFO - Return code: 0 03:03:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:03:23 INFO - https://queue.taskcluster.net/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 03:03:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 03:03:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 03:03:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 03:03:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 03:03:24 INFO - Downloaded 10840145 bytes. 03:03:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:03:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 03:03:24 INFO - Return code: 0 03:03:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:03:24 INFO - https://queue.taskcluster.net/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 03:03:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:03:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:03:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 03:03:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 03:03:27 INFO - Downloaded 114262454 bytes. 03:03:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:03:27 INFO - mkdir: /builds/slave/test/properties 03:03:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:03:27 INFO - Writing to file /builds/slave/test/properties/build_url 03:03:27 INFO - Contents: 03:03:27 INFO - build_url:https://queue.taskcluster.net/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:03:29 INFO - Running post-action listener: _resource_record_post_action 03:03:29 INFO - Running post-action listener: set_extra_try_arguments 03:03:29 INFO - ##### 03:03:29 INFO - ##### Running populate-webroot step. 03:03:29 INFO - ##### 03:03:29 INFO - Running pre-action listener: _resource_record_pre_action 03:03:29 INFO - Running main action method: populate_webroot 03:03:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:03:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:03:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:03:29 INFO - '--tppagecycles', 03:03:29 INFO - '7'], 03:03:29 INFO - 'tests': ['tsvgm']}}, 03:03:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:03:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:03:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:03:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:03:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:03:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:03:29 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:03:29 INFO - 'talos_options': ['--disable-e10s'], 03:03:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:03:29 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:03:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:03:29 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:03:29 INFO - 'talos_options': ['--disable-e10s'], 03:03:29 INFO - 'tests': ['damp', 'tps']}, 03:03:29 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:03:29 INFO - 'tests': ['damp', 'tps']}, 03:03:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:03:29 INFO - 'tests': ['dromaeo_dom']}, 03:03:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:03:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:03:29 INFO - 'tests': ['a11yr', 03:03:29 INFO - 'ts_paint', 03:03:29 INFO - 'tpaint', 03:03:29 INFO - 'sessionrestore', 03:03:29 INFO - 'sessionrestore_no_auto_restore', 03:03:29 INFO - 'tabpaint']}, 03:03:29 INFO - 'other-e10s': {'tests': ['a11yr', 03:03:29 INFO - 'ts_paint', 03:03:29 INFO - 'tpaint', 03:03:29 INFO - 'sessionrestore', 03:03:29 INFO - 'sessionrestore_no_auto_restore', 03:03:29 INFO - 'tabpaint']}, 03:03:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:03:29 INFO - 'ts_paint', 03:03:29 INFO - 'tpaint', 03:03:29 INFO - 'sessionrestore', 03:03:29 INFO - 'sessionrestore_no_auto_restore', 03:03:29 INFO - 'tabpaint']}, 03:03:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:03:29 INFO - 'ts_paint', 03:03:29 INFO - 'tpaint', 03:03:29 INFO - 'sessionrestore', 03:03:29 INFO - 'sessionrestore_no_auto_restore', 03:03:29 INFO - 'tabpaint']}, 03:03:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:03:29 INFO - 'tests': ['a11yr', 03:03:29 INFO - 'ts_paint', 03:03:29 INFO - 'tpaint', 03:03:29 INFO - 'sessionrestore', 03:03:29 INFO - 'sessionrestore_no_auto_restore', 03:03:29 INFO - 'tabpaint']}, 03:03:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:03:29 INFO - 'tests': ['a11yr', 03:03:29 INFO - 'ts_paint', 03:03:29 INFO - 'tpaint', 03:03:29 INFO - 'sessionrestore', 03:03:29 INFO - 'sessionrestore_no_auto_restore', 03:03:29 INFO - 'tabpaint']}, 03:03:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:03:29 INFO - 'tests': ['tsvgx', 03:03:29 INFO - 'tsvgr_opacity', 03:03:29 INFO - 'tart', 03:03:29 INFO - 'tscrollx', 03:03:29 INFO - 'cart']}, 03:03:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:03:29 INFO - 'tsvgr_opacity', 03:03:29 INFO - 'tart', 03:03:29 INFO - 'tscrollx', 03:03:29 INFO - 'cart']}, 03:03:29 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:03:29 INFO - 'talos_options': ['--disable-e10s'], 03:03:29 INFO - 'tests': ['tp5o']}, 03:03:29 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:03:29 INFO - 'tests': ['tp5o']}, 03:03:29 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:03:29 INFO - 'talos_options': ['--disable-e10s', 03:03:29 INFO - '--xperf_path', 03:03:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:03:29 INFO - 'tests': ['tp5n']}, 03:03:29 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:03:29 INFO - 'talos_options': ['--xperf_path', 03:03:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:03:29 INFO - 'tests': ['tp5n']}}, 03:03:29 INFO - 'talos.zip': {'path': '', 03:03:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:03:29 INFO - Running post-action listener: _resource_record_post_action 03:03:29 INFO - ##### 03:03:29 INFO - ##### Running create-virtualenv step. 03:03:29 INFO - ##### 03:03:29 INFO - Running pre-action listener: _resource_record_pre_action 03:03:29 INFO - Running main action method: create_virtualenv 03:03:29 INFO - Creating virtualenv /builds/slave/test/build/venv 03:03:29 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:03:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:03:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:03:29 INFO - Using real prefix '/tools/python27' 03:03:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:03:29 INFO - Installing distribute.............................................................................................................................................................................................done. 03:03:32 INFO - Installing pip.................done. 03:03:32 INFO - Return code: 0 03:03:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:03:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:03:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:03:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:03:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:03:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1043b0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043c0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4ca4623e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10424fe00>, '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 0x1040b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10424ab20>, '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.QcB2U57zaF/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.aZGfgWJzCB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:03:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:03:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:03:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QcB2U57zaF/Render', 03:03:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:03:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:03:32 INFO - 'HOME': '/Users/cltbld', 03:03:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:03:32 INFO - 'LOGNAME': 'cltbld', 03:03:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:32 INFO - 'MOZ_NO_REMOTE': '1', 03:03:32 INFO - 'NO_EM_RESTART': '1', 03:03:32 INFO - 'PAGER': '/bin/cat', 03:03:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:03:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:32 INFO - 'PWD': '/builds/slave/test', 03:03:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:03:32 INFO - 'SHELL': '/bin/bash', 03:03:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aZGfgWJzCB/Listeners', 03:03:32 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 03:03:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:03:32 INFO - 'USER': 'cltbld', 03:03:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:03:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:03:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:32 INFO - 'XPC_FLAGS': '0x0', 03:03:32 INFO - 'XPC_SERVICE_NAME': '0', 03:03:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:03:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:03:33 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 03:03:33 INFO - Cleaning up... 03:03:33 INFO - Return code: 0 03:03:33 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 03:03:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:03:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:03:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:03:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:03:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1043b0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043c0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4ca4623e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10424fe00>, '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 0x1040b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10424ab20>, '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.QcB2U57zaF/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.aZGfgWJzCB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:03:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 03:03:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 03:03:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QcB2U57zaF/Render', 03:03:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:03:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:03:33 INFO - 'HOME': '/Users/cltbld', 03:03:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:03:33 INFO - 'LOGNAME': 'cltbld', 03:03:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:33 INFO - 'MOZ_NO_REMOTE': '1', 03:03:33 INFO - 'NO_EM_RESTART': '1', 03:03:33 INFO - 'PAGER': '/bin/cat', 03:03:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:03:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:33 INFO - 'PWD': '/builds/slave/test', 03:03:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:03:33 INFO - 'SHELL': '/bin/bash', 03:03:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aZGfgWJzCB/Listeners', 03:03:33 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 03:03:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:03:33 INFO - 'USER': 'cltbld', 03:03:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:03:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:03:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:33 INFO - 'XPC_FLAGS': '0x0', 03:03:33 INFO - 'XPC_SERVICE_NAME': '0', 03:03:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:03:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:03:33 INFO - Downloading/unpacking psutil>=0.7.1 03:03: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 03:03: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 03:03: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 03:03: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 03:03: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 03:03: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 03:03:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:03:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:03:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:03:36 INFO - Installing collected packages: psutil 03:03:36 INFO - Running setup.py install for psutil 03:03:36 INFO - building 'psutil._psutil_osx' extension 03:03:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 03:03:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 03:03:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 03:03:37 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 03:03:37 INFO - building 'psutil._psutil_posix' extension 03:03:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 03:03:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:03:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:03:37 INFO - ^ 03:03:37 INFO - 1 warning generated. 03:03:37 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 03:03:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:03:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:03:37 INFO - Successfully installed psutil 03:03:37 INFO - Cleaning up... 03:03:37 INFO - Return code: 0 03:03:37 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 03:03:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:03:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:03:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:03:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:03:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1043b0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043c0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4ca4623e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10424fe00>, '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 0x1040b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10424ab20>, '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.QcB2U57zaF/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.aZGfgWJzCB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:03:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 03:03:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 03:03:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QcB2U57zaF/Render', 03:03:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:03:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:03:37 INFO - 'HOME': '/Users/cltbld', 03:03:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:03:37 INFO - 'LOGNAME': 'cltbld', 03:03:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:37 INFO - 'MOZ_NO_REMOTE': '1', 03:03:37 INFO - 'NO_EM_RESTART': '1', 03:03:37 INFO - 'PAGER': '/bin/cat', 03:03:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:03:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:37 INFO - 'PWD': '/builds/slave/test', 03:03:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:03:37 INFO - 'SHELL': '/bin/bash', 03:03:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aZGfgWJzCB/Listeners', 03:03:37 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 03:03:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:03:37 INFO - 'USER': 'cltbld', 03:03:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:03:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:03:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:37 INFO - 'XPC_FLAGS': '0x0', 03:03:37 INFO - 'XPC_SERVICE_NAME': '0', 03:03:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:03:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:03:37 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 03:03:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:03:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:03:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:03:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:03:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:03:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:03:40 INFO - Downloading mozsystemmonitor-0.0.tar.gz 03:03:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:03:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 03:03:40 INFO - Installing collected packages: mozsystemmonitor 03:03:40 INFO - Running setup.py install for mozsystemmonitor 03:03:40 INFO - Successfully installed mozsystemmonitor 03:03:40 INFO - Cleaning up... 03:03:40 INFO - Return code: 0 03:03:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:03:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:03:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:03:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:03:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:03:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:40 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 0x1043b0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043c0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4ca4623e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10424fe00>, '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 0x1040b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10424ab20>, '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.QcB2U57zaF/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.aZGfgWJzCB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:03:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:03:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:03:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QcB2U57zaF/Render', 03:03:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:03:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:03:40 INFO - 'HOME': '/Users/cltbld', 03:03:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:03:40 INFO - 'LOGNAME': 'cltbld', 03:03:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:40 INFO - 'MOZ_NO_REMOTE': '1', 03:03:40 INFO - 'NO_EM_RESTART': '1', 03:03:40 INFO - 'PAGER': '/bin/cat', 03:03:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:03:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:40 INFO - 'PWD': '/builds/slave/test', 03:03:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:03:40 INFO - 'SHELL': '/bin/bash', 03:03:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aZGfgWJzCB/Listeners', 03:03:40 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 03:03:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:03:40 INFO - 'USER': 'cltbld', 03:03:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:03:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:03:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:40 INFO - 'XPC_FLAGS': '0x0', 03:03:40 INFO - 'XPC_SERVICE_NAME': '0', 03:03:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:03:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:03:40 INFO - Downloading/unpacking blobuploader==1.2.4 03:03:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:03:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:03:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:03:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:03:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:03:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:03:43 INFO - Downloading blobuploader-1.2.4.tar.gz 03:03:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:03:43 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:03:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:03:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:03:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:03:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:03:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:03:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:03:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:03:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:03: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 03:03: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 03:03: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 03:03: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 03:03: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 03:03: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 03:03:44 INFO - Downloading docopt-0.6.1.tar.gz 03:03:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:03:44 INFO - Installing collected packages: blobuploader, requests, docopt 03:03:44 INFO - Running setup.py install for blobuploader 03:03:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:03:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:03:44 INFO - Running setup.py install for requests 03:03:45 INFO - Running setup.py install for docopt 03:03:45 INFO - Successfully installed blobuploader requests docopt 03:03:45 INFO - Cleaning up... 03:03:45 INFO - Return code: 0 03:03:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:03:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:03:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:03:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:03:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:03:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:45 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 0x1043b0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043c0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4ca4623e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10424fe00>, '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 0x1040b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10424ab20>, '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.QcB2U57zaF/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.aZGfgWJzCB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:03:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:03:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:03:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QcB2U57zaF/Render', 03:03:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:03:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:03:45 INFO - 'HOME': '/Users/cltbld', 03:03:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:03:45 INFO - 'LOGNAME': 'cltbld', 03:03:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:45 INFO - 'MOZ_NO_REMOTE': '1', 03:03:45 INFO - 'NO_EM_RESTART': '1', 03:03:45 INFO - 'PAGER': '/bin/cat', 03:03:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:03:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:45 INFO - 'PWD': '/builds/slave/test', 03:03:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:03:45 INFO - 'SHELL': '/bin/bash', 03:03:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aZGfgWJzCB/Listeners', 03:03:45 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 03:03:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:03:45 INFO - 'USER': 'cltbld', 03:03:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:03:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:03:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:45 INFO - 'XPC_FLAGS': '0x0', 03:03:45 INFO - 'XPC_SERVICE_NAME': '0', 03:03:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:03:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:03:45 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-ZLgMZd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:03:45 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-liMkxo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:03:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:03:45 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-3MPoE3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:03:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:03:46 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-urbV73-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:03:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:03:46 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-A02dzd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:03:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:03:46 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-KtLgQU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:03:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:03:46 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-3SbgTM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:03:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:03:46 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-TXb6cQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:03:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:03:46 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-qlkkjU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:03:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:03:46 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-C4qxjZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:03:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:03:46 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-DRr787-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:03:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:03:46 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-YRtpTv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:03:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:03:46 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-0NOr59-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:03:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:03:47 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-eINIVd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:03:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:03:47 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-ZpACZN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:03:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:03:47 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-Nw6AdC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:03:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:03:47 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-BA7HKP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:03:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:03:47 INFO - Running setup.py install for manifestparser 03:03:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:03:47 INFO - Running setup.py install for mozcrash 03:03:47 INFO - Running setup.py install for mozdebug 03:03:47 INFO - Running setup.py install for mozdevice 03:03:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:03:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:03:48 INFO - Running setup.py install for mozfile 03:03:48 INFO - Running setup.py install for mozhttpd 03:03:48 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:03:48 INFO - Running setup.py install for mozinfo 03:03:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:03:48 INFO - Running setup.py install for mozInstall 03:03:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:03:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:03:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:03:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:03:48 INFO - Running setup.py install for mozleak 03:03:48 INFO - Running setup.py install for mozlog 03:03:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:03:48 INFO - Running setup.py install for moznetwork 03:03:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:03:49 INFO - Running setup.py install for mozprocess 03:03:49 INFO - Running setup.py install for mozprofile 03:03:49 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:03:49 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:03:49 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:03:49 INFO - Running setup.py install for mozrunner 03:03:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:03:49 INFO - Running setup.py install for mozscreenshot 03:03:49 INFO - Running setup.py install for moztest 03:03:49 INFO - Running setup.py install for mozversion 03:03:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:03:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:03:50 INFO - Cleaning up... 03:03:50 INFO - Return code: 0 03:03:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:03:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:03:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:03:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:03:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:03:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:50 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 0x1043b0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043c0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4ca4623e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10424fe00>, '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 0x1040b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10424ab20>, '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.QcB2U57zaF/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.aZGfgWJzCB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:03:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:03:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:03:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QcB2U57zaF/Render', 03:03:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:03:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:03:50 INFO - 'HOME': '/Users/cltbld', 03:03:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:03:50 INFO - 'LOGNAME': 'cltbld', 03:03:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:50 INFO - 'MOZ_NO_REMOTE': '1', 03:03:50 INFO - 'NO_EM_RESTART': '1', 03:03:50 INFO - 'PAGER': '/bin/cat', 03:03:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:03:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:50 INFO - 'PWD': '/builds/slave/test', 03:03:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:03:50 INFO - 'SHELL': '/bin/bash', 03:03:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aZGfgWJzCB/Listeners', 03:03:50 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 03:03:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:03:50 INFO - 'USER': 'cltbld', 03:03:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:03:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:03:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:50 INFO - 'XPC_FLAGS': '0x0', 03:03:50 INFO - 'XPC_SERVICE_NAME': '0', 03:03:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:03:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:03:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:03:50 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-tpLfcX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:03:50 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:03:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:03:50 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-z9YJmU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:03:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:03:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:03:50 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-BKFoWh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:03:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:03:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:03:50 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-21ntUm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:03:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:03:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:03:50 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-UVTM1l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:03:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:03:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:03:50 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-TY2Put-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:03:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:03:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:03:50 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-yjKXRn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:03:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:03:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:03:51 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-ZiOk9p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:03:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:03:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:03:51 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-rV2TJL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:03:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:03:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:03:51 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-IrXujp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:03:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:03:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:03:51 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-Z6LuF5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:03:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:03:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:03:51 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-j8UrOO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:03:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:03:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:03:51 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-6e84aX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:03:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:03:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:03:51 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-Jeqyk9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:03:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:03:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:03:51 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-piW0BD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:03:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:03:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:03:51 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-ElZNKa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:03:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:03:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:03:52 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-2ArkFj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:03:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:03:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:03:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:03:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:03:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:03:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:03:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:03:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:03:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:03:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:03:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:03:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:03:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:03:54 INFO - Downloading blessings-1.6.tar.gz 03:03:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:03:54 INFO - Installing collected packages: blessings 03:03:54 INFO - Running setup.py install for blessings 03:03:54 INFO - Successfully installed blessings 03:03:54 INFO - Cleaning up... 03:03:54 INFO - Return code: 0 03:03:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:03:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:03:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:03:55 INFO - Reading from file tmpfile_stdout 03:03:55 INFO - Current package versions: 03:03:55 INFO - blessings == 1.6 03:03:55 INFO - blobuploader == 1.2.4 03:03:55 INFO - docopt == 0.6.1 03:03:55 INFO - manifestparser == 1.1 03:03:55 INFO - mozInstall == 1.12 03:03:55 INFO - mozcrash == 0.17 03:03:55 INFO - mozdebug == 0.1 03:03:55 INFO - mozdevice == 0.48 03:03:55 INFO - mozfile == 1.2 03:03:55 INFO - mozhttpd == 0.7 03:03:55 INFO - mozinfo == 0.9 03:03:55 INFO - mozleak == 0.1 03:03:55 INFO - mozlog == 3.1 03:03:55 INFO - moznetwork == 0.27 03:03:55 INFO - mozprocess == 0.22 03:03:55 INFO - mozprofile == 0.28 03:03:55 INFO - mozrunner == 6.11 03:03:55 INFO - mozscreenshot == 0.1 03:03:55 INFO - mozsystemmonitor == 0.0 03:03:55 INFO - moztest == 0.7 03:03:55 INFO - mozversion == 1.4 03:03:55 INFO - psutil == 3.1.1 03:03:55 INFO - requests == 1.2.3 03:03:55 INFO - wsgiref == 0.1.2 03:03:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:03:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:03:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:03:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:03:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:03:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:55 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 0x1043b0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043c0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4ca4623e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10424fe00>, '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 0x1040b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10424ab20>, '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.QcB2U57zaF/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.aZGfgWJzCB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:03:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:03:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:03:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QcB2U57zaF/Render', 03:03:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:03:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:03:55 INFO - 'HOME': '/Users/cltbld', 03:03:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:03:55 INFO - 'LOGNAME': 'cltbld', 03:03:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:55 INFO - 'MOZ_NO_REMOTE': '1', 03:03:55 INFO - 'NO_EM_RESTART': '1', 03:03:55 INFO - 'PAGER': '/bin/cat', 03:03:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:03:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:55 INFO - 'PWD': '/builds/slave/test', 03:03:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:03:55 INFO - 'SHELL': '/bin/bash', 03:03:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aZGfgWJzCB/Listeners', 03:03:55 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 03:03:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:03:55 INFO - 'USER': 'cltbld', 03:03:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:03:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:03:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:55 INFO - 'XPC_FLAGS': '0x0', 03:03:55 INFO - 'XPC_SERVICE_NAME': '0', 03:03:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:03:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:03:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:03:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:03:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:03:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:03:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:03:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:03:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:03:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:03:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:03:55 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:03:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:03:55 INFO - Cleaning up... 03:03:55 INFO - Return code: 0 03:03:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:03:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:03:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:03:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:03:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:03:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:56 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 0x1043b0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043c0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4ca4623e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10424fe00>, '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 0x1040b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10424ab20>, '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.QcB2U57zaF/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.aZGfgWJzCB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:03:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:03:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:03:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QcB2U57zaF/Render', 03:03:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:03:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:03:56 INFO - 'HOME': '/Users/cltbld', 03:03:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:03:56 INFO - 'LOGNAME': 'cltbld', 03:03:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:56 INFO - 'MOZ_NO_REMOTE': '1', 03:03:56 INFO - 'NO_EM_RESTART': '1', 03:03:56 INFO - 'PAGER': '/bin/cat', 03:03:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:03:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:56 INFO - 'PWD': '/builds/slave/test', 03:03:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:03:56 INFO - 'SHELL': '/bin/bash', 03:03:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aZGfgWJzCB/Listeners', 03:03:56 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 03:03:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:03:56 INFO - 'USER': 'cltbld', 03:03:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:03:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:03:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:56 INFO - 'XPC_FLAGS': '0x0', 03:03:56 INFO - 'XPC_SERVICE_NAME': '0', 03:03:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:03:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:03:56 INFO - Downloading/unpacking jsonschema 03:03:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:03:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:03:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:03:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:03:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:03:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:03:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:03:59 INFO - Downloading/unpacking functools32 (from jsonschema) 03:03:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:03:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:03:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:03:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:03:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:03:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:03:59 INFO - Downloading functools32-3.2.3-2.tar.gz 03:03:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:03:59 INFO - warning: no files found matching '*.txt' 03:03:59 INFO - no previously-included directories found matching 'build' 03:03:59 INFO - no previously-included directories found matching 'dist' 03:03:59 INFO - no previously-included directories found matching '.git*' 03:03:59 INFO - Installing collected packages: jsonschema, functools32 03:03:59 INFO - Running setup.py install for functools32 03:03:59 INFO - warning: no files found matching '*.txt' 03:03:59 INFO - no previously-included directories found matching 'build' 03:03:59 INFO - no previously-included directories found matching 'dist' 03:03:59 INFO - no previously-included directories found matching '.git*' 03:03:59 INFO - Successfully installed jsonschema functools32 03:03:59 INFO - Cleaning up... 03:04:00 INFO - Return code: 0 03:04:00 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:04:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:04:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:04:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:04:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:04:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:04:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:04:00 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 0x1043b0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1043c0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd4ca4623e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10424fe00>, '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 0x1040b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10424ab20>, '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.QcB2U57zaF/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.aZGfgWJzCB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:04:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 03:04:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 03:04:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QcB2U57zaF/Render', 03:04:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:04:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:04:00 INFO - 'HOME': '/Users/cltbld', 03:04:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:04:00 INFO - 'LOGNAME': 'cltbld', 03:04:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:04:00 INFO - 'MOZ_NO_REMOTE': '1', 03:04:00 INFO - 'NO_EM_RESTART': '1', 03:04:00 INFO - 'PAGER': '/bin/cat', 03:04:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:04:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:04:00 INFO - 'PWD': '/builds/slave/test', 03:04:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:04:00 INFO - 'SHELL': '/bin/bash', 03:04:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aZGfgWJzCB/Listeners', 03:04:00 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 03:04:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:04:00 INFO - 'USER': 'cltbld', 03:04:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:04:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:04:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:04:00 INFO - 'XPC_FLAGS': '0x0', 03:04:00 INFO - 'XPC_SERVICE_NAME': '0', 03:04:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:04:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:04:00 INFO - Downloading/unpacking flake8 03:04:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:04:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:04:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:04:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:04:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:04:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:04:02 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:04:02 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:04:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:04:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:04:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:04:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:04:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:04:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:04:03 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:04:03 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:04:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:04:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:04:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:04:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:04:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:04:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:04:03 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:04:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:04:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:04:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:04:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:04:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:04:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:04:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:04:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:04:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:04:03 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:04:03 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:04:03 INFO - no previously-included directories found matching 'docs/_build' 03:04:03 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:04:04 INFO - Running setup.py install for pep8 03:04:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:04:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:04:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:04:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:04:04 INFO - no previously-included directories found matching 'docs/_build' 03:04:04 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:04:04 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:04:04 INFO - Cleaning up... 03:04:04 INFO - Return code: 0 03:04:04 INFO - Running post-action listener: _resource_record_post_action 03:04:04 INFO - Running post-action listener: _start_resource_monitoring 03:04:04 INFO - Starting resource monitoring. 03:04:04 INFO - ##### 03:04:04 INFO - ##### Running install step. 03:04:04 INFO - ##### 03:04:04 INFO - Running pre-action listener: _resource_record_pre_action 03:04:04 INFO - Running main action method: install 03:04:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:04:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:04:04 INFO - Reading from file tmpfile_stdout 03:04:04 INFO - Detecting whether we're running mozinstall >=1.0... 03:04:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:04:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:04:04 INFO - Reading from file tmpfile_stdout 03:04:04 INFO - Output received: 03:04:04 INFO - Usage: mozinstall [options] installer 03:04:04 INFO - Options: 03:04:04 INFO - -h, --help show this help message and exit 03:04:04 INFO - -d DEST, --destination=DEST 03:04:04 INFO - Directory to install application into. [default: 03:04:04 INFO - "/builds/slave/test"] 03:04:04 INFO - --app=APP Application being installed. [default: firefox] 03:04:04 INFO - mkdir: /builds/slave/test/build/application 03:04:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:04:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 03:04:36 INFO - Reading from file tmpfile_stdout 03:04:36 INFO - Output received: 03:04:36 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:04:36 INFO - Running post-action listener: _resource_record_post_action 03:04:36 INFO - ##### 03:04:36 INFO - ##### Running run-tests step. 03:04:36 INFO - ##### 03:04:36 INFO - Running pre-action listener: _resource_record_pre_action 03:04:36 INFO - Running main action method: run_tests 03:04:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:04:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:04:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:04:36 INFO - 2016-05-19 03:04:36.372 screenresolution[1647:7546] starting screenresolution argv=screenresolution get 03:04:36 INFO - 2016-05-19 03:04:36.375 screenresolution[1647:7546] Display 0: 1600x1200x32@60 03:04:36 INFO - 2016-05-19 03:04:36.381 screenresolution[1648:7549] starting screenresolution argv=screenresolution list 03:04:36 INFO - Available Modes on Display 0 03:04:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:04:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:04:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:04:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:04:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:04:36 INFO - Intel Iris: 03:04:36 INFO - Chipset Model: Intel Iris 03:04:36 INFO - Type: GPU 03:04:36 INFO - Bus: Built-In 03:04:36 INFO - VRAM (Dynamic, Max): 1536 MB 03:04:36 INFO - Vendor: Intel (0x8086) 03:04:36 INFO - Device ID: 0x0a2e 03:04:36 INFO - Revision ID: 0x0009 03:04:36 INFO - Displays: 03:04:36 INFO - Display: 03:04:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:04:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:04:36 INFO - Display Serial Number: 1600x1200 60 03:04:36 INFO - Main Display: Yes 03:04:36 INFO - Mirror: Off 03:04:36 INFO - Online: Yes 03:04:36 INFO - Rotation: Supported 03:04:36 INFO - Return code: 0 03:04:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:04:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:04:36 INFO - Python 2.7.3 03:04:36 INFO - Return code: 0 03:04:36 INFO - grabbing minidump binary from tooltool 03:04:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:04:36 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 0x10424fe00>, '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 0x1040b8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10424ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:04:36 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:04:36 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 03:04:36 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:04:36 INFO - Return code: 0 03:04:36 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:04:36 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:04:36 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:04:36 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:04:36 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:04:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:04:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:04:37 INFO - Return code: 0 03:04:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0119', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 03:04:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0119 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 03:04:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.QcB2U57zaF/Render', 03:04:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:04:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:04:37 INFO - 'HOME': '/Users/cltbld', 03:04:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:04:37 INFO - 'LOGNAME': 'cltbld', 03:04:37 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:04:37 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:04:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:04:37 INFO - 'MOZ_NO_REMOTE': '1', 03:04:37 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:04:37 INFO - 'NO_EM_RESTART': '1', 03:04:37 INFO - 'PAGER': '/bin/cat', 03:04:37 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:04:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:04:37 INFO - 'PWD': '/builds/slave/test', 03:04:37 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:04:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:04:37 INFO - 'SHELL': '/bin/bash', 03:04:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.aZGfgWJzCB/Listeners', 03:04:37 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 03:04:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:04:37 INFO - 'USER': 'cltbld', 03:04:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:04:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:04:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:04:37 INFO - 'XPC_FLAGS': '0x0', 03:04:37 INFO - 'XPC_SERVICE_NAME': '0', 03:04:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:04:37 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0119', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 03:04:38 INFO - mozversion application_buildid: 20160519004038 03:04:38 INFO - mozversion application_changeset: c41ee7132426780f38bbffc58435548d92d2b327 03:04:38 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:04:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:04:38 INFO - mozversion application_name: Firefox 03:04:38 INFO - mozversion application_remotingname: firefox-dev 03:04:38 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:04:38 INFO - mozversion application_vendor: Mozilla 03:04:38 INFO - mozversion application_version: 48.0a2 03:04:38 INFO - mozversion platform_buildid: 20160519004038 03:04:38 INFO - mozversion platform_changeset: c41ee7132426780f38bbffc58435548d92d2b327 03:04:38 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:04:38 INFO - mozversion platform_version: 48.0a2 03:04:38 INFO - using testdate: 1463652278 03:04:38 INFO - actual date: 1463652278 03:04:38 INFO - starting webserver on 'localhost:49275' 03:04:38 INFO - SUITE-START | Running 2 tests 03:04:38 INFO - TEST-START | dromaeo_css 03:04:38 INFO - operating with platform_type : mac_ 03:04:38 INFO - Initialising browser for dromaeo_css test... 03:04:38 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/tmpK7njjX/profile http://localhost:49275/getInfo.html) 03:04:41 INFO - PROCESS | 1666 | __metrics Screen width/height:1600/1200 03:04:41 INFO - PROCESS | 1666 | colorDepth:24 03:04:41 INFO - PROCESS | 1666 | Browser inner width/height: 1024/689 03:04:41 INFO - PROCESS | 1666 | __metrics 03:04:41 ERROR - PROCESS | 1666 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 03:04:42 INFO - TEST-INFO | 1666: exit 0 03:04:42 INFO - Browser initialized. 03:04:42 INFO - Running cycle 1/1 for dromaeo_css test... 03:04:42 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/tmpK7njjX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:04:53 INFO - PROCESS | 1670 | RSS: Main: 221548544 03:04:53 INFO - PROCESS | 1670 | 03:05:43 INFO - PROCESS | 1670 | 1463652343628 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 03:07:55 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 03:07:56 INFO - PROCESS | 1670 | RSS: Main: 231489536 03:07:56 INFO - PROCESS | 1670 | 03:10:58 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 03:10:59 INFO - PROCESS | 1670 | RSS: Main: 243077120 03:10:59 INFO - PROCESS | 1670 | 03:14:01 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 03:14:01 INFO - PROCESS | 1670 | RSS: Main: 244674560 03:14:01 INFO - PROCESS | 1670 | 03:17:03 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 03:17:04 INFO - PROCESS | 1670 | RSS: Main: 247250944 03:17:04 INFO - PROCESS | 1670 | 03:20:06 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 03:20:07 INFO - PROCESS | 1670 | RSS: Main: 249430016 03:20:07 INFO - PROCESS | 1670 | 03:23:09 INFO - PROCESS | 1670 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 03:23:10 INFO - PROCESS | 1670 | RSS: Main: 250368000 03:23:10 INFO - PROCESS | 1670 | 03:23:10 INFO - PROCESS | 1670 | __start_tp_report 03:23:10 INFO - PROCESS | 1670 | _x_x_mozilla_page_load 03:23:10 INFO - PROCESS | 1670 | _x_x_mozilla_page_load_details 03:23:10 INFO - PROCESS | 1670 | |i|pagename|runs| 03:23:10 INFO - PROCESS | 1670 | |0;dojo.html;3234.805911645301;3472.0451875734184;3507.228409749854;3512.596051454083;3513.894583162504;5617.8033768818095;5694.288213973254;5716.085426331788;5725.227094342263;5727.455891690288;5269.320259679436;5315.309009828727;5710.14347847822;5718.313802343714;5832.504237139849;5134.460881607442;5177.844664660048;5199.402068762103;5242.711650859202;5245.186996015605;5105.719185444799;5254.133068043769;5257.974694934489;5261.76322065508;5269.736513174345;5259.158534634459;5261.842144735645;5274.2879711238875;5278.973605131969;5303.893922121574;5150;5241.73791310433;5246.1606143017125;5248.107821670306;5260.2372655965055;4834.492378300263;5080.872978175576;5250.97374513125;5255.86860328491;5319.882824606837;5683.090705487102;5704.8859022819315;5709.657420554739;5720.885582288331;5770.365259821417;6068.180795592582;6165.568410211287;6167.7532898684;6253.874922501524;6261.499080073629;5105.5149760772665;6208.6585237811905;6247.4377306042215;6255.437010669061;6258.123862659231;5847.970760146214;5875.588708790429;5890.057590785496;5893.44012318829;5918.319393269786;6160.014397696347;6188.44304012632;6228.532860035425;6259.530535209878;6263.655498947511;6169.722362493699;6179.474744646755;6189.257289125334;6200.162977997912;6201.286852011986;5366.490183432567;6263.6241825490615;6267.185265915402;6267.843303917347;6273.435390814849;6033.42682445167;6125.938740612537;6177.536684748662;6212.875742485125;6217.658028808459;5080.949190508121;6230.968845155836;6238.625682459067;6246.812595622138;6256.092866534327;4851.126797176472;4894.951050489521;4905.141600219948;4918.9670169264855;4946.109700253989;4310.43964284641;4387.758673273001;4419.160359531678;4424.469063712375;4461.665375096881;5753.108268218349;5764;5764.193012978187;5778.942210577925;5779.450952159622;6179;6228.22147231596;6230.6573138476915;6244;6257.530685198628;5543.057680194376;5635.408282130399;5707.514861236758;5710.172025056344;5716.742746576414;5352.437994010651;5378.139497680352;5384.19237114436;5385.353757549041;5400.405955344909;6163.8150855474405;6291.559590828598;6320.652364119926;6337.429631333202;6347.936520634827;6136.120845438751;6195.845103872259;6251.031090181029;6251.562390632612;6261.092141639528;6239.126522286792;6246.125542424155;6246.968765156145;6247.187865577446;6258.186435763504;6111.236095488064;6112.297085835008;6129.233845769279;6141;6172.69136543162;6149.938500614937;6226.439620434183;6234.563580549318;6243.065759093219;6255.718492667932;5946.167536544974;6109.572329937039;6123.8162855114415;6124.6325220486915;6126.509879209563;5374.274472946258;5954.732037058429;6129.172561704291;6137.6931153441255;6144.938550614436;5892.646441213541;5938.613990090803;5951.7619295227705;5978.940210597838;6017.127516509994;6106.1146133811235;6107.6640784757265;6115.480184184395;6125.479334256638;6138.600990935575;4722.669413141047;5641.153826925994;5739.2252045973255;5850.912236316543;6035.275766907999 03:23:10 INFO - PROCESS | 1670 | |1;ext.html;502.314340924638;545.1141894421565;556.5770014788759;591.1339887065449;595.0479233226836;36693.44959825601;37736.24527509448;37788.62211377885;37836;38037.04907377314;40275.798621006856;41015.58984410155;41036;41061.7946910265;42035.78982105085;9379.531023448817;9524.380915240521;9548.092931171528;9602.759931001747;9612.615495380176;8412.621432035574;9499.52502374882;9748.171405430547;9754.951225243864;9771.853422198672;8334.374921880853;9760.511974401286;9770.853437198448;9771.169450596708;9803.31376803624;9679.612815487371;9736.75658108546;9756.756081097994;9763;9781.510924453749;9528.047195280486;9714.90285097147;9722.708318750449;9736.464494452803;9757.804843903154;38175.854724358316;38539.42190867139;38793.22413551713;38811;38911.61088389108;3155.25851424915;3338.4658454647383;3730.4404339349044;3788.9431658525145;3906.1797022625283;27126.321841954108;27665.446691066267;27779.305517362227;28483.145505634864;29308.8534557328;2725.795565332588;2782.986085069562;2804.761595264405;2827.6465441819773;2875.324298789783;455553;464448;465144;465656;468001;35984.92045238647;58278.41721582814;58580.70709646425;58664;58853.70573147107;35107.64892351044;35320.76377326781;35635;35653.108672282884;35743.927682169575;15042.623934401727;15266.236688165547;15289.235538223043;15296.388144474318;15299.388024478896;12492.375381231084;12508.999280057573;12817.679558011125;13024.869751302555;13043.543475978295;8066.637001334954;8174.509529428254;8192;8245.8350832983;8295.211954864279;2991.6559595646345;3411.2154204533094;3450.654934506529;3455.930881382358;3467.601225859008;1024.5287167902848;1034.886162522122;1077.2887402896945;1117.2570240789785;1138.8405623212757;25055.87472062628;30062;30113.849430752707;30139.397212055635;30603.69396306053;962.4465932089076;1127.9887201128047;1135.7160709822545;1139.481535901163;1263.1915574032625;15210.771838422432;15424.614384640474;15549.689006219813;15575.688486230212;15785.605359865865;653016;657361;658989;667549;672576.6371168112;50905.74547127315;59791.80416391648;59952.800943980874;60006.69996649989;60266.79466410647;1464.1288433382122;1473.2265560580781;1476.60131764423;1483.762597984339;1485.3241774992352;29273.268168295963;29544.556831647973;29905.102846914626;30209.640566174134;30407.39185216239;51109.744451277504;51221.48778512167;51850;52083.479165207864;52125;577370;596307;596528;597232.0276797176;600119;25965.22104336873;25975.22074337773;26025.21924342273;26524.204273871816;26848.59727104134;45180.77409612931;45595.77202113968;46576.7671161642;46614.533854662375;46998.76500617475;36716;37555.624443755216;37856.43215351717;38190.42714359342;38433.807830960664;29814;33552.993410197734;37596.436053459765;37605.43591846179;38233.4264986031;33717;37316.440253396766;37347.81326093352;38090.428643569816;38637.80681096577;30485.390292193588;31197.844010780707;31553.684463155074;31679;31760;23729.64405533884;24126.63810042886;24173.637395439433;24440.777961101518;25090.498190036462 03:23:10 INFO - PROCESS | 1670 | |2;jquery.html;322.5629272335984;390.5333126913336;397.7056977836401;401.51416785689315;404.16741512484305;19137.42587722367;20188.697169542433;20288.289909853138;20716.378508644728;20868.060937257804;20068.698969515433;20827;21095.15619375223;21132.577348453062;21195.940202989827;3879.7090218233675;5376.704281264529;5638.238837756912;5915.438033386821;5922.082077278007;4258.339957306623;4404.889877753066;6478.384553467413;6480.870382592346;6485;6379.07503412005;6420.390062944036;6449.355064493537;6452.64510451925;6452.870942581169;5598.580106492009;5805.506531944748;5929.495992840613;5933.465988061095;5945.583809133363;5576.553875689935;5745.2243947067445;5756.942430575725;5757.798477053283;5778.624389414675;13242.867571324356;13532.458701651922;13583.117097388638;22220.777792222034;24827.62758558623;2513.6355228491766;2584.431425086478;2809.2555472799722;2851.600775891377;2860.599516067752;7885.881711774328;9581.185599224074;9834.213262938947;9962.651307204213;10146.797064058752;1727.8963262204181;1736.6179440523065;1771.9202635881416;1848.574827789616;1879.8120187981228;108643;110299;110473.34289985737;112237.43881280623;124667.37666311701;10326.586936522604;11648.301101933741;11851.348175850411;11950.462229199708;12094.395280235953;14928.701425971636;15001.399944002336;15076.54770356869;15084.396624134912;15089.849101508844;7853.332466740277;8206.58967051657;8340.62467188978;8804.383693141546;8827.117288271122;8803.987541432689;8804.383693141419;8829.911700883038;8852.822943541221;8856.690015849414;4637.211674015425;4663.230566956482;4668.089722504147;4670.859874203779;4713.080949214938;1986.8708533945248;1989.9204031838854;2006.3547890729462;2013.104168644939;2041.7549894012814;861.6122744764881;931.5295775633261;947.9052094790466;957.683964291782;1071.1056268016293;19216.135273912707;19426.514337141685;20323.983800810198;20393;20570.58858822815;960.8558716192628;976.6142373762324;1018.098982400566;1031.3450958641167;1054.831227003691;10506.632267870587;10720.035196832167;10780.946095269474;10804;10851.51168197433;623418;629063;640483;648174;649939;120563.58872822669;122537;123528.38235808763;124257;131240;924.8993697499819;935.7133940831357;961.240619910263;992.2805965674828;1019.6991887393236;16805.495835124966;17609.471715848544;17867.374641887473;18088.095595219922;18110.36613718566;52286.73856630692;52602.736986316355;53204.46795531995;53627;54036;64596;79570;80516.59741701254;81117.59441202757;81429;8094.83810323802;8153.796155095933;8239.340852731886;8310.085890552155;8449.662013519635;11251.887481125084;11484.36835973996;11936.76126477483;12544.310062946626;12583.433745481292;10472.424016679155;10649.414282214242;10668;10750.462476875968;10807.567697292021;10419.322744021614;10426.27016108865;10462.267641265038;10537.209709271681;10836.837447438453;10637.468126593794;10801.459927003776;11162.55349786032;11254.718632034264;11297.039751621205;5890.705464726832;8134.145914678874;8234.464759790595;8255.380846436661;8259.091499935123;9887.55506002246;10823.404712740874;11022.338659680445;11034.889651103385;11035.062019728413 03:23:10 INFO - PROCESS | 1670 | |3;mootools.html;1444.2417730691388;1608.1396452897695;1711.5892185875393;1864.1797609052016;1956.0513151121713;7960.442769006172;8565.443246189008;8685.652573897045;8975.596098175572;8991.010988791237;8343.249107580332;8380;8449.704260350889;8482.024567174776;8489.745307640766;2415.4565222824854;2431.416460049593;2440.5606990741658;2445.1686426614947;2457.7542245775367;2415.2754173747894;2415.577273977056;2449.289705985262;2451.399407145252;2463.3718401807514;2433.4768024874647;2441.206607852455;2453.251758213744;2463.4457247119435;2468.7654672806057;2431.8662473563945;2441.56051910656;2452.264320703782;2455.042533411971;2460.7047154341503;2216;2414.4326083370393;2415.987920060406;2435.866027368494;2441.279822452381;8471.745847624581;8531.274841638467;8587.270082043035;8594.441361311494;8607.569621518898;5390.649607775482;5477.1510415885605;5551.472610102034;5557.444255574451;5558.777648894039;3502.912427189315;3598.982005089984;3623.474596183565;3643.981780091109;3676.0258531489176;1055.456439933435;1074.0816601805423;1074.8495210670512;1074.897884700952;1076.8923107689247;12082.879171208264;12189.878101219052;12197.39013049344;12223.14437989341;12223.877761222362;4812.422509298906;4844.539768721966;4896.583790377857;4922.606191504671;4937.432195297515;9680.886698029675;10202.897971020344;10226.846597300897;10244.64143754965;10306.175505959505;4995.650304478651;5369.570434365241;5441.8095366662765;5483.341998960151;5542.196381524737;4429.911401771947;4822.300766388854;4919.557239848431;4951.885825689264;5000.399952005782;4027.7583344999393;4038.757674539478;4103.671706263492;4135.400366946836;4169.770662613585;1330.9534166304327;1343.147101590497;1348.0833033537092;1350.2776014832214;1354.343143575365;863.8617821148586;908.795521007768;920.8204400141908;928.1368327455531;981.6809536900536;5866.530677545871;5910.70446477683;5933.110033495011;5952.821415357546;6096.02463605821;931.701855406277;937.8745505393555;992.3549692699659;997.1723469520281;1039.3348257115451;3894.7857867816974;3949.940750888739;3958.7228893977144;3979.6617287530307;3985.1631157456677;10503.212259080447;10812.78374432507;10852.945735271272;10862;11007.779844403227;10660.626878059385;10766.977137171956;10825.025747682746;10865.185111116698;10911.23621346514;7641.923580764233;7968.203179681986;8346.874796878052;8469.280111190617;8529.957350213333;4693;5734.856628584319;5819.94180058194;5910.468057874812;5954.345022047658;9471.905280947103;9692.22462203008;9784.80430391374;9863.457509837028;9907.405555666683;9577.616895324109;9862.704118876445;9870.703878883645;9930.602775888883;10014.64948726791;6416.197975253093;6833.2483426824;7016.36852683261;7081.362677359062;7210.603416811916;8244.587770611566;8275.793105172419;8290.544020078712;8339.416240863082;8374.665013399639;7329.780106596811;7350.007748953823;7477.738279160203;7563.810904727426;7615.048118985127;7087.7164913402885;7272.745453909088;7281.235470275545;7311.23232060626;7400.407967362706;7002.089728335284;7012.894806577803;7035.894461583182;7036.648167591702;7055.717771289296;4712.552307530779;4772.403449568804;4842.83050093245;4847.321375007431;4875.756212189305;4310.698251122517;4833.178359678934;4997.625178111583;5014.297998280316;5090.109230884655 03:23:10 INFO - PROCESS | 1670 | |4;prototype.html;274.21027440969993;328.8355822088956;329.03756512201807;335.05180339638815;335.0735217124652;5563.165525171226;6735.629540375284;6865.897011544825;6888.690008949597;6893.3795958363735;6766.458683305336;6776.35624615662;6781.76263830766;6785.830354241147;6833.052580014697;4708.6468514861435;4793.640476964224;4898.314236006961;4908.9263661045115;4969.900601987958;4938.407391113072;4976.452590215092;4986.753311672082;4995.725235112068;5005.224190250498;3994.3609022556398;4926.926096108561;4945.950540494585;4952.158133117378;4961.479044700309;4859.514048595148;4906.239532872411;4923.58149557288;4940.1107800596055;4948.381452318425;3119.485284927984;4240.809163587646;4243.766592837393;4251.383549385322;4255.55316691746;5244.951009798056;5252.185911183773;6176.629402235881;6483.513736469784;6530.542861999662;4658.790354434059;4822.228443449067;5413.944280865233;5776.566757493205;5824.912626310609;7195.208527062019;7203.963980180118;7219.747308844166;7222.277772222788;7231.3130252625915;4624.583787459112;4642.489326174118;4647.651426143019;4652.906941861143;4657.115148121846;7585.582792946386;7654.693812247503;7659.885101723478;7671.6164191790185;7691.9230807691765;4496.662750293675;4668.789904454307;4670.1126785910565;4672.672912896132;4685.367475390847;3932.292187406237;4286.7856607169515;4751.881202969954;4886.120498310267;4912.066707325596;5786.392428794916;5788.18965344852;5812.680302583399;5823;5827.096799996008;7262.600556969417;7301.744438944612;7330.376917961927;7333.449991250677;7345.22875098107;1632.910189939541;1663.4344322930262;1670.8579770719382;1671.9414820481225;1673.397576872325;1015.7003683913264;1049.9632512862013;1057.6826951914395;1058.941758203306;1072.0726571515584;5654.321481422174;6722.260551339349;6822.4200942919415;6837.521373503807;6843.794686159423;6762.763303284361;7008.668353012911;7256.927430725731;7512.474126811071;7541.283578060185;6710.563813352116;6845.828854278683;6867.416269617039;6868.244493105754;6936.445084393236;3971.1065010373013;4003.159336539301;4004.739691920016;4006.318925782624;4026.8389264429093;7879.330256928225;7880.408969327209;7886.290233878864;7895.881561776578;7908.644111015018;7420.814479637944;7742.090244426641;7824.491408058458;7865.174156713577;7873.842523149505;7815.843683126305;7854.607269636381;7877.803054923673;7886.64510097047;7900.367970562341;6287.779927702509;6973.814451543351;7406.1482929461645;7546.735864244725;7574.166841647304;6565.803025909039;7365.631718414165;7392.778216653509;7412.888806667796;7435.776926692208;6396.872062558816;7581.583012934341;7817.491863028885;7874.21257874208;7929.929459523121;4608.654350923627;4652.464966528893;4671.929921051119;4692.460367057832;4723.598494128038;7538.886916696144;7541.43439242048;7544.8491030180185;7572.545647261182;7632.5038872473115;7272.200057993722;7272.709091636275;7287.052683151156;7300.780976570712;7319.268073192638;7041;7250.41996640257;7258.891116633149;7271.2365201653065;7311.780646580611;5486.478784515464;7040.788776336718;7054.753083642048;7104.751333703295;7111.751088711871;7262.055932728711;7267.454940879561;7270.709171633075;7278.235785242473;7279.890801637874;5494.835154945358;5695.003374409544;5766.509846662912;7121.715131394686;7307.3058059484265 03:23:10 INFO - PROCESS | 1670 | |5;yui.html;650.2619526837038;713.9714411423544;740.1044735869598;754.3060384446309;757.4054367321654;14435.566932992015;14652.633684157901;14665.780013299809;14814.407423703038;14839.777403338945;13478.326083695825;13517.459301627947;13531.661708457294;13587.116837405561;13629.045966782329;726.9963650181755;929.604917909889;932.3706498113746;949.7625593601599;956.3974695941547;758.29478584916;930.5765876526198;942.4109931292942;943.8820147481565;947.4741518457254;986.9309148359617;989.6486747204747;1001.0690058245865;1002.0280328081785;1002.4486532407184;955.3790036476312;960.313375936202;993.4536005197112;995.233670074039;1000.0149852395384;691.401937324216;709.3190537084405;710.1974768511549;712.0316369737153;718.9101362329709;14413.207273599948;14626.414943402358;14654.706905861824;14680.779788303184;14909;12636;12723.045771567076;12825.807612885814;12850.742985140338;13018.283994380305;9909.058639429242;10044.296899217057;10233.385996840214;10388.896111038945;10486.423246721428;3495.2834668892815;3503.7197024238;3520.383932811748;3580.6777390034763;3773.5660399054186;80388.59805700992;80832.19167808365;81336.18663813345;81613;82284;9521.333506654468;9547.665831695856;9606.27952903535;9689.41863488193;9755.512224388753;20237;20401.591968160552;20507.28224512135;20564.485887852923;20614.896925515575;7318.121825380882;7499.812504687427;7517.588338608977;7532.058492688414;7734.187910269453;4010.3382941814275;4067.5525692173837;4079.755214687128;4130.56629053951;4132.152908653718;3606.2426890353318;3624.909377265537;3642.3807952648112;3662.853485860536;3682.024263570156;653;656.6158797103652;663.8207683925409;674.3492529708757;675.6419097878132;556.4852511426914;568.4855206038459;576.8702042040508;601.5578549765919;619.7118339971935;7995.2804247618;8086.393520485987;8184.6316915738935;8231.711890083938;8239.505629662239;558.6840765149938;599.5623195067625;614.7540983606593;615.8329964716926;628.5851338116912;3554.3424466473784;3721.292954338667;3732.122951106488;3784.299904517673;3800.467934489174;87071;89132.5543372292;89914;90542;91492.54253728689;79326.20673793188;79890;79894.60052699815;80622;80812.19187808047;67636.6618166906;69407.30592694008;69786.65106674535;70214;72304;5797.304323481209;6864.450843932574;6942.861142777015;7064.470164737562;7105.005299258005;45358;49287.260691088944;50259;50360.496395037044;52666.47333526616;65463;68176.6591167041;69796.6510167446;71606;72305;10972.396518191576;11017.944910275397;11200.607978720705;11366.374849383363;11622.012128969132;11812.645620631394;12001.039916806809;12032;12115.515379384726;12400.81398779037;11217.943910280397;11487.081033517132;11642.24325418845;11704;11745.706357341134;8870.556472176495;10097.040781125781;11177.105831533621;11366.43167841588;11651.941740291244;10403.53184106732;10680.878507756885;10962.725931851768;11340;11605.709857253298;9010.50442225684;9284.396514226553;9328.160465558132;9387.436753794795;9407.670731524364;8844.778880528038;8885.378023538291;9469;9567.569459374206;9740.220782337254 03:23:10 INFO - PROCESS | 1670 | __end_tp_report 03:23:10 INFO - PROCESS | 1670 | __start_cc_report 03:23:10 INFO - PROCESS | 1670 | _x_x_mozilla_cycle_collect,422 03:23:10 INFO - PROCESS | 1670 | __end_cc_report 03:23:10 INFO - PROCESS | 1670 | __startTimestamp1463653390000__endTimestamp 03:23:10 INFO - PROCESS | 1670 | 03:23:10 INFO - PROCESS | 1670 | ------- Summary: start ------- 03:23:10 INFO - PROCESS | 1670 | Number of tests: 6 03:23:10 INFO - PROCESS | 1670 | 03:23:10 INFO - PROCESS | 1670 | [#0] dojo.html Cycles:165 Average:5709.72 Median:5893.44 stddev:632.80 (10.7%) stddev-sans-first:604.22 03:23:10 INFO - PROCESS | 1670 | Values: 3234.8 3472.0 3507.2 3512.6 3513.9 5617.8 5694.3 5716.1 5725.2 5727.5 5269.3 5315.3 5710.1 5718.3 5832.5 5134.5 5177.8 5199.4 5242.7 5245.2 5105.7 5254.1 5258.0 5261.8 5269.7 5259.2 5261.8 5274.3 5279.0 5303.9 5150.0 5241.7 5246.2 5248.1 5260.2 4834.5 5080.9 5251.0 5255.9 5319.9 5683.1 5704.9 5709.7 5720.9 5770.4 6068.2 6165.6 6167.8 6253.9 6261.5 5105.5 6208.7 6247.4 6255.4 6258.1 5848.0 5875.6 5890.1 5893.4 5918.3 6160.0 6188.4 6228.5 6259.5 6263.7 6169.7 6179.5 6189.3 6200.2 6201.3 5366.5 6263.6 6267.2 6267.8 6273.4 6033.4 6125.9 6177.5 6212.9 6217.7 5080.9 6231.0 6238.6 6246.8 6256.1 4851.1 4895.0 4905.1 4919.0 4946.1 4310.4 4387.8 4419.2 4424.5 4461.7 5753.1 5764.0 5764.2 5778.9 5779.5 6179.0 6228.2 6230.7 6244.0 6257.5 5543.1 5635.4 5707.5 5710.2 5716.7 5352.4 5378.1 5384.2 5385.4 5400.4 6163.8 6291.6 6320.7 6337.4 6347.9 6136.1 6195.8 6251.0 6251.6 6261.1 6239.1 6246.1 6247.0 6247.2 6258.2 6111.2 6112.3 6129.2 6141.0 6172.7 6149.9 6226.4 6234.6 6243.1 6255.7 5946.2 6109.6 6123.8 6124.6 6126.5 5374.3 5954.7 6129.2 6137.7 6144.9 5892.6 5938.6 5951.8 5978.9 6017.1 6106.1 6107.7 6115.5 6125.5 6138.6 4722.7 5641.2 5739.2 5850.9 6035.3 03:23:10 INFO - PROCESS | 1670 | 03:23:10 INFO - PROCESS | 1670 | [#1] ext.html Cycles:180 Average:68747.77 Median:37846.22 stddev:155221.65 (410.1%) stddev-sans-first:155572.52 03:23:10 INFO - PROCESS | 1670 | Values: 502.3 545.1 556.6 591.1 595.0 36693.4 37736.2 37788.6 37836.0 38037.0 40275.8 41015.6 41036.0 41061.8 42035.8 9379.5 9524.4 9548.1 9602.8 9612.6 8412.6 9499.5 9748.2 9755.0 9771.9 8334.4 9760.5 9770.9 9771.2 9803.3 9679.6 9736.8 9756.8 9763.0 9781.5 9528.0 9714.9 9722.7 9736.5 9757.8 38175.9 38539.4 38793.2 38811.0 38911.6 3155.3 3338.5 3730.4 3788.9 3906.2 27126.3 27665.4 27779.3 28483.1 29308.9 2725.8 2783.0 2804.8 2827.6 2875.3 455553.0 464448.0 465144.0 465656.0 468001.0 35984.9 58278.4 58580.7 58664.0 58853.7 35107.6 35320.8 35635.0 35653.1 35743.9 15042.6 15266.2 15289.2 15296.4 15299.4 12492.4 12509.0 12817.7 13024.9 13043.5 8066.6 8174.5 8192.0 8245.8 8295.2 2991.7 3411.2 3450.7 3455.9 3467.6 1024.5 1034.9 1077.3 1117.3 1138.8 25055.9 30062.0 30113.8 30139.4 30603.7 962.4 1128.0 1135.7 1139.5 1263.2 15210.8 15424.6 15549.7 15575.7 15785.6 653016.0 657361.0 658989.0 667549.0 672576.6 50905.7 59791.8 59952.8 60006.7 60266.8 1464.1 1473.2 1476.6 1483.8 1485.3 29273.3 29544.6 29905.1 30209.6 30407.4 51109.7 51221.5 51850.0 52083.5 52125.0 577370.0 596307.0 596528.0 597232.0 600119.0 25965.2 25975.2 26025.2 26524.2 26848.6 45180.8 45595.8 46576.8 46614.5 46998.8 36716.0 37555.6 37856.4 38190.4 38433.8 29814.0 33553.0 37596.4 37605.4 38233.4 33717.0 37316.4 37347.8 38090.4 38637.8 30485.4 31197.8 31553.7 31679.0 31760.0 23729.6 24126.6 24173.6 24440.8 25090.5 03:23:10 INFO - PROCESS | 1670 | 03:23:10 INFO - PROCESS | 1670 | [#2] jquery.html Cycles:180 Average:35590.66 Median:2549.03 stddev:106046.01 (4160.2%) stddev-sans-first:106310.44 03:23:10 INFO - PROCESS | 1670 | Values: 322.6 390.5 397.7 401.5 404.2 19137.4 20188.7 20288.3 20716.4 20868.1 20068.7 20827.0 21095.2 21132.6 21195.9 3879.7 5376.7 5638.2 5915.4 5922.1 4258.3 4404.9 6478.4 6480.9 6485.0 6379.1 6420.4 6449.4 6452.6 6452.9 5598.6 5805.5 5929.5 5933.5 5945.6 5576.6 5745.2 5756.9 5757.8 5778.6 13242.9 13532.5 13583.1 22220.8 24827.6 2513.6 2584.4 2809.3 2851.6 2860.6 7885.9 9581.2 9834.2 9962.7 10146.8 1727.9 1736.6 1771.9 1848.6 1879.8 108643.0 110299.0 110473.3 112237.4 124667.4 10326.6 11648.3 11851.3 11950.5 12094.4 14928.7 15001.4 15076.5 15084.4 15089.8 7853.3 8206.6 8340.6 8804.4 8827.1 8804.0 8804.4 8829.9 8852.8 8856.7 4637.2 4663.2 4668.1 4670.9 4713.1 1986.9 1989.9 2006.4 2013.1 2041.8 861.6 931.5 947.9 957.7 1071.1 19216.1 19426.5 20324.0 20393.0 20570.6 960.9 976.6 1018.1 1031.3 1054.8 10506.6 10720.0 10780.9 10804.0 10851.5 623418.0 629063.0 640483.0 648174.0 649939.0 120563.6 122537.0 123528.4 124257.0 131240.0 924.9 935.7 961.2 992.3 1019.7 16805.5 17609.5 17867.4 18088.1 18110.4 52286.7 52602.7 53204.5 53627.0 54036.0 64596.0 79570.0 80516.6 81117.6 81429.0 8094.8 8153.8 8239.3 8310.1 8449.7 11251.9 11484.4 11936.8 12544.3 12583.4 10472.4 10649.4 10668.0 10750.5 10807.6 10419.3 10426.3 10462.3 10537.2 10836.8 10637.5 10801.5 11162.6 11254.7 11297.0 5890.7 8134.1 8234.5 8255.4 8259.1 9887.6 10823.4 11022.3 11034.9 11035.1 03:23:10 INFO - PROCESS | 1670 | 03:23:10 INFO - PROCESS | 1670 | [#3] mootools.html Cycles:180 Average:5721.96 Median:4930.02 stddev:3181.37 (64.5%) stddev-sans-first:3174.06 03:23:10 INFO - PROCESS | 1670 | Values: 1444.2 1608.1 1711.6 1864.2 1956.1 7960.4 8565.4 8685.7 8975.6 8991.0 8343.2 8380.0 8449.7 8482.0 8489.7 2415.5 2431.4 2440.6 2445.2 2457.8 2415.3 2415.6 2449.3 2451.4 2463.4 2433.5 2441.2 2453.3 2463.4 2468.8 2431.9 2441.6 2452.3 2455.0 2460.7 2216.0 2414.4 2416.0 2435.9 2441.3 8471.7 8531.3 8587.3 8594.4 8607.6 5390.6 5477.2 5551.5 5557.4 5558.8 3502.9 3599.0 3623.5 3644.0 3676.0 1055.5 1074.1 1074.8 1074.9 1076.9 12082.9 12189.9 12197.4 12223.1 12223.9 4812.4 4844.5 4896.6 4922.6 4937.4 9680.9 10202.9 10226.8 10244.6 10306.2 4995.7 5369.6 5441.8 5483.3 5542.2 4429.9 4822.3 4919.6 4951.9 5000.4 4027.8 4038.8 4103.7 4135.4 4169.8 1331.0 1343.1 1348.1 1350.3 1354.3 863.9 908.8 920.8 928.1 981.7 5866.5 5910.7 5933.1 5952.8 6096.0 931.7 937.9 992.4 997.2 1039.3 3894.8 3949.9 3958.7 3979.7 3985.2 10503.2 10812.8 10852.9 10862.0 11007.8 10660.6 10767.0 10825.0 10865.2 10911.2 7641.9 7968.2 8346.9 8469.3 8530.0 4693.0 5734.9 5819.9 5910.5 5954.3 9471.9 9692.2 9784.8 9863.5 9907.4 9577.6 9862.7 9870.7 9930.6 10014.6 6416.2 6833.2 7016.4 7081.4 7210.6 8244.6 8275.8 8290.5 8339.4 8374.7 7329.8 7350.0 7477.7 7563.8 7615.0 7087.7 7272.7 7281.2 7311.2 7400.4 7002.1 7012.9 7035.9 7036.6 7055.7 4712.6 4772.4 4842.8 4847.3 4875.8 4310.7 4833.2 4997.6 5014.3 5090.1 03:23:10 INFO - PROCESS | 1670 | 03:23:10 INFO - PROCESS | 1670 | [#4] prototype.html Cycles:180 Average:5836.74 Median:6728.95 stddev:1917.58 (28.5%) stddev-sans-first:1876.96 03:23:10 INFO - PROCESS | 1670 | Values: 274.2 328.8 329.0 335.1 335.1 5563.2 6735.6 6865.9 6888.7 6893.4 6766.5 6776.4 6781.8 6785.8 6833.1 4708.6 4793.6 4898.3 4908.9 4969.9 4938.4 4976.5 4986.8 4995.7 5005.2 3994.4 4926.9 4946.0 4952.2 4961.5 4859.5 4906.2 4923.6 4940.1 4948.4 3119.5 4240.8 4243.8 4251.4 4255.6 5245.0 5252.2 6176.6 6483.5 6530.5 4658.8 4822.2 5413.9 5776.6 5824.9 7195.2 7204.0 7219.7 7222.3 7231.3 4624.6 4642.5 4647.7 4652.9 4657.1 7585.6 7654.7 7659.9 7671.6 7691.9 4496.7 4668.8 4670.1 4672.7 4685.4 3932.3 4286.8 4751.9 4886.1 4912.1 5786.4 5788.2 5812.7 5823.0 5827.1 7262.6 7301.7 7330.4 7333.4 7345.2 1632.9 1663.4 1670.9 1671.9 1673.4 1015.7 1050.0 1057.7 1058.9 1072.1 5654.3 6722.3 6822.4 6837.5 6843.8 6762.8 7008.7 7256.9 7512.5 7541.3 6710.6 6845.8 6867.4 6868.2 6936.4 3971.1 4003.2 4004.7 4006.3 4026.8 7879.3 7880.4 7886.3 7895.9 7908.6 7420.8 7742.1 7824.5 7865.2 7873.8 7815.8 7854.6 7877.8 7886.6 7900.4 6287.8 6973.8 7406.1 7546.7 7574.2 6565.8 7365.6 7392.8 7412.9 7435.8 6396.9 7581.6 7817.5 7874.2 7929.9 4608.7 4652.5 4671.9 4692.5 4723.6 7538.9 7541.4 7544.8 7572.5 7632.5 7272.2 7272.7 7287.1 7300.8 7319.3 7041.0 7250.4 7258.9 7271.2 7311.8 5486.5 7040.8 7054.8 7104.8 7111.8 7262.1 7267.5 7270.7 7278.2 7279.9 5494.8 5695.0 5766.5 7121.7 7307.3 03:23:10 INFO - PROCESS | 1670 | 03:23:10 INFO - PROCESS | 1670 | [#5] yui.html Cycles:180 Average:18209.66 Median:617.77 stddev:25753.71 (4168.8%) stddev-sans-first:25792.21 03:23:10 INFO - PROCESS | 1670 | Values: 650.3 714.0 740.1 754.3 757.4 14435.6 14652.6 14665.8 14814.4 14839.8 13478.3 13517.5 13531.7 13587.1 13629.0 727.0 929.6 932.4 949.8 956.4 758.3 930.6 942.4 943.9 947.5 986.9 989.6 1001.1 1002.0 1002.4 955.4 960.3 993.5 995.2 1000.0 691.4 709.3 710.2 712.0 718.9 14413.2 14626.4 14654.7 14680.8 14909.0 12636.0 12723.0 12825.8 12850.7 13018.3 9909.1 10044.3 10233.4 10388.9 10486.4 3495.3 3503.7 3520.4 3580.7 3773.6 80388.6 80832.2 81336.2 81613.0 82284.0 9521.3 9547.7 9606.3 9689.4 9755.5 20237.0 20401.6 20507.3 20564.5 20614.9 7318.1 7499.8 7517.6 7532.1 7734.2 4010.3 4067.6 4079.8 4130.6 4132.2 3606.2 3624.9 3642.4 3662.9 3682.0 653.0 656.6 663.8 674.3 675.6 556.5 568.5 576.9 601.6 619.7 7995.3 8086.4 8184.6 8231.7 8239.5 558.7 599.6 614.8 615.8 628.6 3554.3 3721.3 3732.1 3784.3 3800.5 87071.0 89132.6 89914.0 90542.0 91492.5 79326.2 79890.0 79894.6 80622.0 80812.2 67636.7 69407.3 69786.7 70214.0 72304.0 5797.3 6864.5 6942.9 7064.5 7105.0 45358.0 49287.3 50259.0 50360.5 52666.5 65463.0 68176.7 69796.7 71606.0 72305.0 10972.4 11017.9 11200.6 11366.4 11622.0 11812.6 12001.0 12032.0 12115.5 12400.8 11217.9 11487.1 11642.2 11704.0 11745.7 8870.6 10097.0 11177.1 11366.4 11651.9 10403.5 10680.9 10962.7 11340.0 11605.7 9010.5 9284.4 9328.2 9387.4 9407.7 8844.8 8885.4 9469.0 9567.6 9740.2 03:23:10 INFO - PROCESS | 1670 | -------- Summary: end -------- 03:23:10 INFO - PROCESS | 1670 | 03:23:10 INFO - TEST-INFO | 1670: exit 0 03:23:10 INFO - TEST-OK | dromaeo_css | took 1112541ms 03:23:10 INFO - TEST-START | kraken 03:23:10 INFO - operating with platform_type : mac_ 03:23:10 INFO - Initialising browser for kraken test... 03:23:10 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/tmpxIJZDa/profile http://localhost:49275/getInfo.html) 03:23:13 INFO - PROCESS | 1678 | __metrics Screen width/height:1600/1200 03:23:13 INFO - PROCESS | 1678 | colorDepth:24 03:23:13 INFO - PROCESS | 1678 | Browser inner width/height: 1024/689 03:23:13 INFO - PROCESS | 1678 | __metrics 03:23:13 ERROR - PROCESS | 1678 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 03:23:13 INFO - TEST-INFO | 1678: exit 0 03:23:13 INFO - Browser initialized. 03:23:13 INFO - Running cycle 1/1 for kraken test... 03:23:13 INFO - TEST-INFO | started process 1682 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/tmpxIJZDa/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:23:26 INFO - PROCESS | 1682 | RSS: Main: 220831744 03:23:26 INFO - PROCESS | 1682 | 03:23:28 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 03:23:29 INFO - PROCESS | 1682 | RSS: Main: 232968192 03:23:29 INFO - PROCESS | 1682 | 03:23:32 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 03:23:33 INFO - PROCESS | 1682 | RSS: Main: 241741824 03:23:33 INFO - PROCESS | 1682 | 03:23:36 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 03:23:37 INFO - PROCESS | 1682 | RSS: Main: 246124544 03:23:37 INFO - PROCESS | 1682 | 03:23:39 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 03:23:39 INFO - PROCESS | 1682 | RSS: Main: 247476224 03:23:39 INFO - PROCESS | 1682 | 03:23:41 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:23:42 INFO - PROCESS | 1682 | RSS: Main: 247197696 03:23:42 INFO - PROCESS | 1682 | 03:23:45 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 03:23:45 INFO - PROCESS | 1682 | RSS: Main: 247545856 03:23:45 INFO - PROCESS | 1682 | 03:23:48 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 03:23:49 INFO - PROCESS | 1682 | RSS: Main: 247664640 03:23:49 INFO - PROCESS | 1682 | 03:23:52 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 03:23:52 INFO - PROCESS | 1682 | RSS: Main: 247877632 03:23:52 INFO - PROCESS | 1682 | 03:23:54 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:23:55 INFO - PROCESS | 1682 | RSS: Main: 247484416 03:23:55 INFO - PROCESS | 1682 | 03:23:57 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:23:57 INFO - PROCESS | 1682 | RSS: Main: 247521280 03:23:57 INFO - PROCESS | 1682 | 03:23:59 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:24:00 INFO - PROCESS | 1682 | RSS: Main: 247455744 03:24:00 INFO - PROCESS | 1682 | 03:24:03 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:24:03 INFO - PROCESS | 1682 | RSS: Main: 247504896 03:24:03 INFO - PROCESS | 1682 | 03:24:06 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:24:07 INFO - PROCESS | 1682 | RSS: Main: 247562240 03:24:07 INFO - PROCESS | 1682 | 03:24:09 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 03:24:09 INFO - PROCESS | 1682 | RSS: Main: 249397248 03:24:09 INFO - PROCESS | 1682 | 03:24:09 INFO - PROCESS | 1682 | __start_tp_report 03:24:09 INFO - PROCESS | 1682 | _x_x_mozilla_page_load 03:24:09 INFO - PROCESS | 1682 | _x_x_mozilla_page_load_details 03:24:09 INFO - PROCESS | 1682 | |i|pagename|runs| 03:24:09 INFO - PROCESS | 1682 | |0;ai-astar;113;119;108;117;120;118;108;114;114;116 03:24:09 INFO - PROCESS | 1682 | |1;audio-beat-detection;149;113;164;186;151;144;123;152;155;124 03:24:09 INFO - PROCESS | 1682 | |2;audio-dft;196;199;236;260;275;197;200;199;197;197 03:24:09 INFO - PROCESS | 1682 | |3;audio-fft;65;70;81;143;56;57;56;62;130;57 03:24:09 INFO - PROCESS | 1682 | |4;audio-oscillator;79;80;127;110;111;88;123;79;79;121 03:24:09 INFO - PROCESS | 1682 | |5;imaging-gaussian-blur;90;87;90;87;90;87;89;88;90;87 03:24:09 INFO - PROCESS | 1682 | |6;imaging-darkroom;96;98;96;96;105;95;95;98;95;96 03:24:09 INFO - PROCESS | 1682 | |7;imaging-desaturate;91;94;91;91;93;91;92;106;151;92 03:24:09 INFO - PROCESS | 1682 | |8;json-parse-financial;60;62;74;67;66;67;65;86;100;93 03:24:09 INFO - PROCESS | 1682 | |9;json-stringify-tinderbox;65;91;70;75;101;106;106;73;68;73 03:24:09 INFO - PROCESS | 1682 | |10;stanford-crypto-aes;81;81;82;83;82;84;80;83;84;84 03:24:09 INFO - PROCESS | 1682 | |11;stanford-crypto-ccm;131;154;141;131;136;129;127;133;149;145 03:24:09 INFO - PROCESS | 1682 | |12;stanford-crypto-pbkdf2;168;167;171;169;165;184;224;222;171;161 03:24:09 INFO - PROCESS | 1682 | |13;stanford-crypto-sha256-iterative;67;68;70;66;71;67;70;69;70;72 03:24:09 INFO - PROCESS | 1682 | __end_tp_report 03:24:09 INFO - PROCESS | 1682 | __start_cc_report 03:24:09 INFO - PROCESS | 1682 | _x_x_mozilla_cycle_collect,1101 03:24:09 INFO - PROCESS | 1682 | __end_cc_report 03:24:09 INFO - PROCESS | 1682 | __startTimestamp1463653449438__endTimestamp 03:24:09 INFO - PROCESS | 1682 | 03:24:09 INFO - PROCESS | 1682 | ------- Summary: start ------- 03:24:09 INFO - PROCESS | 1682 | Number of tests: 14 03:24:09 INFO - PROCESS | 1682 | 03:24:09 INFO - PROCESS | 1682 | [#0] ai-astar Cycles:10 Average:114.70 Median:116.50 stddev:4.19 (3.6%) stddev-sans-first:4.40 03:24:09 INFO - PROCESS | 1682 | Values: 113.0 119.0 108.0 117.0 120.0 118.0 108.0 114.0 114.0 116.0 03:24:09 INFO - PROCESS | 1682 | 03:24:09 INFO - PROCESS | 1682 | [#1] audio-beat-detection Cycles:10 Average:146.10 Median:151.50 stddev:21.55 (14.2%) stddev-sans-first:22.84 03:24:09 INFO - PROCESS | 1682 | Values: 149.0 113.0 164.0 186.0 151.0 144.0 123.0 152.0 155.0 124.0 03:24:09 INFO - PROCESS | 1682 | 03:24:09 INFO - PROCESS | 1682 | [#2] audio-dft Cycles:10 Average:215.60 Median:199.50 stddev:30.06 (15.1%) stddev-sans-first:31.04 03:24:09 INFO - PROCESS | 1682 | Values: 196.0 199.0 236.0 260.0 275.0 197.0 200.0 199.0 197.0 197.0 03:24:09 INFO - PROCESS | 1682 | 03:24:09 INFO - PROCESS | 1682 | [#3] audio-fft Cycles:10 Average:77.70 Median:59.50 stddev:32.10 (54.0%) stddev-sans-first:33.72 03:24:09 INFO - PROCESS | 1682 | Values: 65.0 70.0 81.0 143.0 56.0 57.0 56.0 62.0 130.0 57.0 03:24:09 INFO - PROCESS | 1682 | 03:24:09 INFO - PROCESS | 1682 | [#4] audio-oscillator Cycles:10 Average:99.70 Median:79.00 stddev:20.51 (26.0%) stddev-sans-first:20.34 03:24:09 INFO - PROCESS | 1682 | Values: 79.0 80.0 127.0 110.0 111.0 88.0 123.0 79.0 79.0 121.0 03:24:09 INFO - PROCESS | 1682 | 03:24:09 INFO - PROCESS | 1682 | [#5] imaging-gaussian-blur Cycles:10 Average:88.50 Median:89.50 stddev:1.43 (1.6%) stddev-sans-first:1.41 03:24:09 INFO - PROCESS | 1682 | Values: 90.0 87.0 90.0 87.0 90.0 87.0 89.0 88.0 90.0 87.0 03:24:09 INFO - PROCESS | 1682 | 03:24:09 INFO - PROCESS | 1682 | [#6] imaging-darkroom Cycles:10 Average:97.00 Median:96.00 stddev:3.02 (3.1%) stddev-sans-first:3.18 03:24:09 INFO - PROCESS | 1682 | Values: 96.0 98.0 96.0 96.0 105.0 95.0 95.0 98.0 95.0 96.0 03:24:09 INFO - PROCESS | 1682 | 03:24:09 INFO - PROCESS | 1682 | [#7] imaging-desaturate Cycles:10 Average:99.20 Median:91.50 stddev:18.76 (20.5%) stddev-sans-first:19.66 03:24:09 INFO - PROCESS | 1682 | Values: 91.0 94.0 91.0 91.0 93.0 91.0 92.0 106.0 151.0 92.0 03:24:09 INFO - PROCESS | 1682 | 03:24:09 INFO - PROCESS | 1682 | [#8] json-parse-financial Cycles:10 Average:74.00 Median:67.00 stddev:14.00 (20.9%) stddev-sans-first:13.90 03:24:09 INFO - PROCESS | 1682 | Values: 60.0 62.0 74.0 67.0 66.0 67.0 65.0 86.0 100.0 93.0 03:24:09 INFO - PROCESS | 1682 | 03:24:09 INFO - PROCESS | 1682 | [#9] json-stringify-tinderbox Cycles:10 Average:82.80 Median:71.50 stddev:16.42 (23.0%) stddev-sans-first:16.11 03:24:09 INFO - PROCESS | 1682 | Values: 65.0 91.0 70.0 75.0 101.0 106.0 106.0 73.0 68.0 73.0 03:24:09 INFO - PROCESS | 1682 | 03:24:09 INFO - PROCESS | 1682 | [#10] stanford-crypto-aes Cycles:10 Average:82.40 Median:83.00 stddev:1.43 (1.7%) stddev-sans-first:1.42 03:24:09 INFO - PROCESS | 1682 | Values: 81.0 81.0 82.0 83.0 82.0 84.0 80.0 83.0 84.0 84.0 03:24:09 INFO - PROCESS | 1682 | 03:24:09 INFO - PROCESS | 1682 | [#11] stanford-crypto-ccm Cycles:10 Average:137.60 Median:138.50 stddev:9.20 (6.6%) stddev-sans-first:9.45 03:24:09 INFO - PROCESS | 1682 | Values: 131.0 154.0 141.0 131.0 136.0 129.0 127.0 133.0 149.0 145.0 03:24:09 INFO - PROCESS | 1682 | 03:24:09 INFO - PROCESS | 1682 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.20 Median:171.00 stddev:23.33 (13.6%) stddev-sans-first:24.32 03:24:09 INFO - PROCESS | 1682 | Values: 168.0 167.0 171.0 169.0 165.0 184.0 224.0 222.0 171.0 161.0 03:24:09 INFO - PROCESS | 1682 | 03:24:09 INFO - PROCESS | 1682 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.00 Median:70.00 stddev:1.94 (2.8%) stddev-sans-first:1.92 03:24:09 INFO - PROCESS | 1682 | Values: 67.0 68.0 70.0 66.0 71.0 67.0 70.0 69.0 70.0 72.0 03:24:09 INFO - PROCESS | 1682 | -------- Summary: end -------- 03:24:09 INFO - PROCESS | 1682 | 03:24:10 INFO - TEST-INFO | 1682: exit 0 03:24:10 INFO - TEST-OK | kraken | took 59652ms 03:24:10 INFO - SUITE-END | took 1172s 03:24:10 INFO - Completed test suite (00:19:32) 03:24:10 INFO - javascript benchmark 03:24:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3234.805911645301, 3472.0451875734184, 3507.228409749854, 3512.596051454083, 3513.894583162504, 5617.8033768818095, 5694.288213973254, 5716.085426331788, 5725.227094342263, 5727.455891690288, 5269.320259679436, 5315.309009828727, 5710.14347847822, 5718.313802343714, 5832.504237139849, 5134.460881607442, 5177.844664660048, 5199.402068762103, 5242.711650859202, 5245.186996015605, 5105.719185444799, 5254.133068043769, 5257.974694934489, 5261.76322065508, 5269.736513174345, 5259.158534634459, 5261.842144735645, 5274.2879711238875, 5278.973605131969, 5303.893922121574, 5150.0, 5241.73791310433, 5246.1606143017125, 5248.107821670306, 5260.2372655965055, 4834.492378300263, 5080.872978175576, 5250.97374513125, 5255.86860328491, 5319.882824606837, 5683.090705487102, 5704.8859022819315, 5709.657420554739, 5720.885582288331, 5770.365259821417, 6068.180795592582, 6165.568410211287, 6167.7532898684, 6253.874922501524, 6261.499080073629, 5105.5149760772665, 6208.6585237811905, 6247.4377306042215, 6255.437010669061, 6258.123862659231, 5847.970760146214, 5875.588708790429, 5890.057590785496, 5893.44012318829, 5918.319393269786, 6160.014397696347, 6188.44304012632, 6228.532860035425, 6259.530535209878, 6263.655498947511, 6169.722362493699, 6179.474744646755, 6189.257289125334, 6200.162977997912, 6201.286852011986, 5366.490183432567, 6263.6241825490615, 6267.185265915402, 6267.843303917347, 6273.435390814849, 6033.42682445167, 6125.938740612537, 6177.536684748662, 6212.875742485125, 6217.658028808459, 5080.949190508121, 6230.968845155836, 6238.625682459067, 6246.812595622138, 6256.092866534327, 4851.126797176472, 4894.951050489521, 4905.141600219948, 4918.9670169264855, 4946.109700253989, 4310.43964284641, 4387.758673273001, 4419.160359531678, 4424.469063712375, 4461.665375096881, 5753.108268218349, 5764.0, 5764.193012978187, 5778.942210577925, 5779.450952159622, 6179.0, 6228.22147231596, 6230.6573138476915, 6244.0, 6257.530685198628, 5543.057680194376, 5635.408282130399, 5707.514861236758, 5710.172025056344, 5716.742746576414, 5352.437994010651, 5378.139497680352, 5384.19237114436, 5385.353757549041, 5400.405955344909, 6163.8150855474405, 6291.559590828598, 6320.652364119926, 6337.429631333202, 6347.936520634827, 6136.120845438751, 6195.845103872259, 6251.031090181029, 6251.562390632612, 6261.092141639528, 6239.126522286792, 6246.125542424155, 6246.968765156145, 6247.187865577446, 6258.186435763504, 6111.236095488064, 6112.297085835008, 6129.233845769279, 6141.0, 6172.69136543162, 6149.938500614937, 6226.439620434183, 6234.563580549318, 6243.065759093219, 6255.718492667932, 5946.167536544974, 6109.572329937039, 6123.8162855114415, 6124.6325220486915, 6126.509879209563, 5374.274472946258, 5954.732037058429, 6129.172561704291, 6137.6931153441255, 6144.938550614436, 5892.646441213541, 5938.613990090803, 5951.7619295227705, 5978.940210597838, 6017.127516509994, 6106.1146133811235, 6107.6640784757265, 6115.480184184395, 6125.479334256638, 6138.600990935575, 4722.669413141047, 5641.153826925994, 5739.2252045973255, 5850.912236316543, 6035.275766907999], "value": 5671.69032301711, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [502.314340924638, 545.1141894421565, 556.5770014788759, 591.1339887065449, 595.0479233226836, 36693.44959825601, 37736.24527509448, 37788.62211377885, 37836.0, 38037.04907377314, 40275.798621006856, 41015.58984410155, 41036.0, 41061.7946910265, 42035.78982105085, 9379.531023448817, 9524.380915240521, 9548.092931171528, 9602.759931001747, 9612.615495380176, 8412.621432035574, 9499.52502374882, 9748.171405430547, 9754.951225243864, 9771.853422198672, 8334.374921880853, 9760.511974401286, 9770.853437198448, 9771.169450596708, 9803.31376803624, 9679.612815487371, 9736.75658108546, 9756.756081097994, 9763.0, 9781.510924453749, 9528.047195280486, 9714.90285097147, 9722.708318750449, 9736.464494452803, 9757.804843903154, 38175.854724358316, 38539.42190867139, 38793.22413551713, 38811.0, 38911.61088389108, 3155.25851424915, 3338.4658454647383, 3730.4404339349044, 3788.9431658525145, 3906.1797022625283, 27126.321841954108, 27665.446691066267, 27779.305517362227, 28483.145505634864, 29308.8534557328, 2725.795565332588, 2782.986085069562, 2804.761595264405, 2827.6465441819773, 2875.324298789783, 455553.0, 464448.0, 465144.0, 465656.0, 468001.0, 35984.92045238647, 58278.41721582814, 58580.70709646425, 58664.0, 58853.70573147107, 35107.64892351044, 35320.76377326781, 35635.0, 35653.108672282884, 35743.927682169575, 15042.623934401727, 15266.236688165547, 15289.235538223043, 15296.388144474318, 15299.388024478896, 12492.375381231084, 12508.999280057573, 12817.679558011125, 13024.869751302555, 13043.543475978295, 8066.637001334954, 8174.509529428254, 8192.0, 8245.8350832983, 8295.211954864279, 2991.6559595646345, 3411.2154204533094, 3450.654934506529, 3455.930881382358, 3467.601225859008, 1024.5287167902848, 1034.886162522122, 1077.2887402896945, 1117.2570240789785, 1138.8405623212757, 25055.87472062628, 30062.0, 30113.849430752707, 30139.397212055635, 30603.69396306053, 962.4465932089076, 1127.9887201128047, 1135.7160709822545, 1139.481535901163, 1263.1915574032625, 15210.771838422432, 15424.614384640474, 15549.689006219813, 15575.688486230212, 15785.605359865865, 653016.0, 657361.0, 658989.0, 667549.0, 672576.6371168112, 50905.74547127315, 59791.80416391648, 59952.800943980874, 60006.69996649989, 60266.79466410647, 1464.1288433382122, 1473.2265560580781, 1476.60131764423, 1483.762597984339, 1485.3241774992352, 29273.268168295963, 29544.556831647973, 29905.102846914626, 30209.640566174134, 30407.39185216239, 51109.744451277504, 51221.48778512167, 51850.0, 52083.479165207864, 52125.0, 577370.0, 596307.0, 596528.0, 597232.0276797176, 600119.0, 25965.22104336873, 25975.22074337773, 26025.21924342273, 26524.204273871816, 26848.59727104134, 45180.77409612931, 45595.77202113968, 46576.7671161642, 46614.533854662375, 46998.76500617475, 36716.0, 37555.624443755216, 37856.43215351717, 38190.42714359342, 38433.807830960664, 29814.0, 33552.993410197734, 37596.436053459765, 37605.43591846179, 38233.4264986031, 33717.0, 37316.440253396766, 37347.81326093352, 38090.428643569816, 38637.80681096577, 30485.390292193588, 31197.844010780707, 31553.684463155074, 31679.0, 31760.0, 23729.64405533884, 24126.63810042886, 24173.637395439433, 24440.777961101518, 25090.498190036462], "value": 18569.0243026668, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [322.5629272335984, 390.5333126913336, 397.7056977836401, 401.51416785689315, 404.16741512484305, 19137.42587722367, 20188.697169542433, 20288.289909853138, 20716.378508644728, 20868.060937257804, 20068.698969515433, 20827.0, 21095.15619375223, 21132.577348453062, 21195.940202989827, 3879.7090218233675, 5376.704281264529, 5638.238837756912, 5915.438033386821, 5922.082077278007, 4258.339957306623, 4404.889877753066, 6478.384553467413, 6480.870382592346, 6485.0, 6379.07503412005, 6420.390062944036, 6449.355064493537, 6452.64510451925, 6452.870942581169, 5598.580106492009, 5805.506531944748, 5929.495992840613, 5933.465988061095, 5945.583809133363, 5576.553875689935, 5745.2243947067445, 5756.942430575725, 5757.798477053283, 5778.624389414675, 13242.867571324356, 13532.458701651922, 13583.117097388638, 22220.777792222034, 24827.62758558623, 2513.6355228491766, 2584.431425086478, 2809.2555472799722, 2851.600775891377, 2860.599516067752, 7885.881711774328, 9581.185599224074, 9834.213262938947, 9962.651307204213, 10146.797064058752, 1727.8963262204181, 1736.6179440523065, 1771.9202635881416, 1848.574827789616, 1879.8120187981228, 108643.0, 110299.0, 110473.34289985737, 112237.43881280623, 124667.37666311701, 10326.586936522604, 11648.301101933741, 11851.348175850411, 11950.462229199708, 12094.395280235953, 14928.701425971636, 15001.399944002336, 15076.54770356869, 15084.396624134912, 15089.849101508844, 7853.332466740277, 8206.58967051657, 8340.62467188978, 8804.383693141546, 8827.117288271122, 8803.987541432689, 8804.383693141419, 8829.911700883038, 8852.822943541221, 8856.690015849414, 4637.211674015425, 4663.230566956482, 4668.089722504147, 4670.859874203779, 4713.080949214938, 1986.8708533945248, 1989.9204031838854, 2006.3547890729462, 2013.104168644939, 2041.7549894012814, 861.6122744764881, 931.5295775633261, 947.9052094790466, 957.683964291782, 1071.1056268016293, 19216.135273912707, 19426.514337141685, 20323.983800810198, 20393.0, 20570.58858822815, 960.8558716192628, 976.6142373762324, 1018.098982400566, 1031.3450958641167, 1054.831227003691, 10506.632267870587, 10720.035196832167, 10780.946095269474, 10804.0, 10851.51168197433, 623418.0, 629063.0, 640483.0, 648174.0, 649939.0, 120563.58872822669, 122537.0, 123528.38235808763, 124257.0, 131240.0, 924.8993697499819, 935.7133940831357, 961.240619910263, 992.2805965674828, 1019.6991887393236, 16805.495835124966, 17609.471715848544, 17867.374641887473, 18088.095595219922, 18110.36613718566, 52286.73856630692, 52602.736986316355, 53204.46795531995, 53627.0, 54036.0, 64596.0, 79570.0, 80516.59741701254, 81117.59441202757, 81429.0, 8094.83810323802, 8153.796155095933, 8239.340852731886, 8310.085890552155, 8449.662013519635, 11251.887481125084, 11484.36835973996, 11936.76126477483, 12544.310062946626, 12583.433745481292, 10472.424016679155, 10649.414282214242, 10668.0, 10750.462476875968, 10807.567697292021, 10419.322744021614, 10426.27016108865, 10462.267641265038, 10537.209709271681, 10836.837447438453, 10637.468126593794, 10801.459927003776, 11162.55349786032, 11254.718632034264, 11297.039751621205, 5890.705464726832, 8134.145914678874, 8234.464759790595, 8255.380846436661, 8259.091499935123, 9887.55506002246, 10823.404712740874, 11022.338659680445, 11034.889651103385, 11035.062019728413], "value": 9464.115020237055, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1444.2417730691388, 1608.1396452897695, 1711.5892185875393, 1864.1797609052016, 1956.0513151121713, 7960.442769006172, 8565.443246189008, 8685.652573897045, 8975.596098175572, 8991.010988791237, 8343.249107580332, 8380.0, 8449.704260350889, 8482.024567174776, 8489.745307640766, 2415.4565222824854, 2431.416460049593, 2440.5606990741658, 2445.1686426614947, 2457.7542245775367, 2415.2754173747894, 2415.577273977056, 2449.289705985262, 2451.399407145252, 2463.3718401807514, 2433.4768024874647, 2441.206607852455, 2453.251758213744, 2463.4457247119435, 2468.7654672806057, 2431.8662473563945, 2441.56051910656, 2452.264320703782, 2455.042533411971, 2460.7047154341503, 2216.0, 2414.4326083370393, 2415.987920060406, 2435.866027368494, 2441.279822452381, 8471.745847624581, 8531.274841638467, 8587.270082043035, 8594.441361311494, 8607.569621518898, 5390.649607775482, 5477.1510415885605, 5551.472610102034, 5557.444255574451, 5558.777648894039, 3502.912427189315, 3598.982005089984, 3623.474596183565, 3643.981780091109, 3676.0258531489176, 1055.456439933435, 1074.0816601805423, 1074.8495210670512, 1074.897884700952, 1076.8923107689247, 12082.879171208264, 12189.878101219052, 12197.39013049344, 12223.14437989341, 12223.877761222362, 4812.422509298906, 4844.539768721966, 4896.583790377857, 4922.606191504671, 4937.432195297515, 9680.886698029675, 10202.897971020344, 10226.846597300897, 10244.64143754965, 10306.175505959505, 4995.650304478651, 5369.570434365241, 5441.8095366662765, 5483.341998960151, 5542.196381524737, 4429.911401771947, 4822.300766388854, 4919.557239848431, 4951.885825689264, 5000.399952005782, 4027.7583344999393, 4038.757674539478, 4103.671706263492, 4135.400366946836, 4169.770662613585, 1330.9534166304327, 1343.147101590497, 1348.0833033537092, 1350.2776014832214, 1354.343143575365, 863.8617821148586, 908.795521007768, 920.8204400141908, 928.1368327455531, 981.6809536900536, 5866.530677545871, 5910.70446477683, 5933.110033495011, 5952.821415357546, 6096.02463605821, 931.701855406277, 937.8745505393555, 992.3549692699659, 997.1723469520281, 1039.3348257115451, 3894.7857867816974, 3949.940750888739, 3958.7228893977144, 3979.6617287530307, 3985.1631157456677, 10503.212259080447, 10812.78374432507, 10852.945735271272, 10862.0, 11007.779844403227, 10660.626878059385, 10766.977137171956, 10825.025747682746, 10865.185111116698, 10911.23621346514, 7641.923580764233, 7968.203179681986, 8346.874796878052, 8469.280111190617, 8529.957350213333, 4693.0, 5734.856628584319, 5819.94180058194, 5910.468057874812, 5954.345022047658, 9471.905280947103, 9692.22462203008, 9784.80430391374, 9863.457509837028, 9907.405555666683, 9577.616895324109, 9862.704118876445, 9870.703878883645, 9930.602775888883, 10014.64948726791, 6416.197975253093, 6833.2483426824, 7016.36852683261, 7081.362677359062, 7210.603416811916, 8244.587770611566, 8275.793105172419, 8290.544020078712, 8339.416240863082, 8374.665013399639, 7329.780106596811, 7350.007748953823, 7477.738279160203, 7563.810904727426, 7615.048118985127, 7087.7164913402885, 7272.745453909088, 7281.235470275545, 7311.23232060626, 7400.407967362706, 7002.089728335284, 7012.894806577803, 7035.894461583182, 7036.648167591702, 7055.717771289296, 4712.552307530779, 4772.403449568804, 4842.83050093245, 4847.321375007431, 4875.756212189305, 4310.698251122517, 4833.178359678934, 4997.625178111583, 5014.297998280316, 5090.109230884655], "value": 4633.030105003043, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [274.21027440969993, 328.8355822088956, 329.03756512201807, 335.05180339638815, 335.0735217124652, 5563.165525171226, 6735.629540375284, 6865.897011544825, 6888.690008949597, 6893.3795958363735, 6766.458683305336, 6776.35624615662, 6781.76263830766, 6785.830354241147, 6833.052580014697, 4708.6468514861435, 4793.640476964224, 4898.314236006961, 4908.9263661045115, 4969.900601987958, 4938.407391113072, 4976.452590215092, 4986.753311672082, 4995.725235112068, 5005.224190250498, 3994.3609022556398, 4926.926096108561, 4945.950540494585, 4952.158133117378, 4961.479044700309, 4859.514048595148, 4906.239532872411, 4923.58149557288, 4940.1107800596055, 4948.381452318425, 3119.485284927984, 4240.809163587646, 4243.766592837393, 4251.383549385322, 4255.55316691746, 5244.951009798056, 5252.185911183773, 6176.629402235881, 6483.513736469784, 6530.542861999662, 4658.790354434059, 4822.228443449067, 5413.944280865233, 5776.566757493205, 5824.912626310609, 7195.208527062019, 7203.963980180118, 7219.747308844166, 7222.277772222788, 7231.3130252625915, 4624.583787459112, 4642.489326174118, 4647.651426143019, 4652.906941861143, 4657.115148121846, 7585.582792946386, 7654.693812247503, 7659.885101723478, 7671.6164191790185, 7691.9230807691765, 4496.662750293675, 4668.789904454307, 4670.1126785910565, 4672.672912896132, 4685.367475390847, 3932.292187406237, 4286.7856607169515, 4751.881202969954, 4886.120498310267, 4912.066707325596, 5786.392428794916, 5788.18965344852, 5812.680302583399, 5823.0, 5827.096799996008, 7262.600556969417, 7301.744438944612, 7330.376917961927, 7333.449991250677, 7345.22875098107, 1632.910189939541, 1663.4344322930262, 1670.8579770719382, 1671.9414820481225, 1673.397576872325, 1015.7003683913264, 1049.9632512862013, 1057.6826951914395, 1058.941758203306, 1072.0726571515584, 5654.321481422174, 6722.260551339349, 6822.4200942919415, 6837.521373503807, 6843.794686159423, 6762.763303284361, 7008.668353012911, 7256.927430725731, 7512.474126811071, 7541.283578060185, 6710.563813352116, 6845.828854278683, 6867.416269617039, 6868.244493105754, 6936.445084393236, 3971.1065010373013, 4003.159336539301, 4004.739691920016, 4006.318925782624, 4026.8389264429093, 7879.330256928225, 7880.408969327209, 7886.290233878864, 7895.881561776578, 7908.644111015018, 7420.814479637944, 7742.090244426641, 7824.491408058458, 7865.174156713577, 7873.842523149505, 7815.843683126305, 7854.607269636381, 7877.803054923673, 7886.64510097047, 7900.367970562341, 6287.779927702509, 6973.814451543351, 7406.1482929461645, 7546.735864244725, 7574.166841647304, 6565.803025909039, 7365.631718414165, 7392.778216653509, 7412.888806667796, 7435.776926692208, 6396.872062558816, 7581.583012934341, 7817.491863028885, 7874.21257874208, 7929.929459523121, 4608.654350923627, 4652.464966528893, 4671.929921051119, 4692.460367057832, 4723.598494128038, 7538.886916696144, 7541.43439242048, 7544.8491030180185, 7572.545647261182, 7632.5038872473115, 7272.200057993722, 7272.709091636275, 7287.052683151156, 7300.780976570712, 7319.268073192638, 7041.0, 7250.41996640257, 7258.891116633149, 7271.2365201653065, 7311.780646580611, 5486.478784515464, 7040.788776336718, 7054.753083642048, 7104.751333703295, 7111.751088711871, 7262.055932728711, 7267.454940879561, 7270.709171633075, 7278.235785242473, 7279.890801637874, 5494.835154945358, 5695.003374409544, 5766.509846662912, 7121.715131394686, 7307.3058059484265], "value": 5195.395860825021, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [650.2619526837038, 713.9714411423544, 740.1044735869598, 754.3060384446309, 757.4054367321654, 14435.566932992015, 14652.633684157901, 14665.780013299809, 14814.407423703038, 14839.777403338945, 13478.326083695825, 13517.459301627947, 13531.661708457294, 13587.116837405561, 13629.045966782329, 726.9963650181755, 929.604917909889, 932.3706498113746, 949.7625593601599, 956.3974695941547, 758.29478584916, 930.5765876526198, 942.4109931292942, 943.8820147481565, 947.4741518457254, 986.9309148359617, 989.6486747204747, 1001.0690058245865, 1002.0280328081785, 1002.4486532407184, 955.3790036476312, 960.313375936202, 993.4536005197112, 995.233670074039, 1000.0149852395384, 691.401937324216, 709.3190537084405, 710.1974768511549, 712.0316369737153, 718.9101362329709, 14413.207273599948, 14626.414943402358, 14654.706905861824, 14680.779788303184, 14909.0, 12636.0, 12723.045771567076, 12825.807612885814, 12850.742985140338, 13018.283994380305, 9909.058639429242, 10044.296899217057, 10233.385996840214, 10388.896111038945, 10486.423246721428, 3495.2834668892815, 3503.7197024238, 3520.383932811748, 3580.6777390034763, 3773.5660399054186, 80388.59805700992, 80832.19167808365, 81336.18663813345, 81613.0, 82284.0, 9521.333506654468, 9547.665831695856, 9606.27952903535, 9689.41863488193, 9755.512224388753, 20237.0, 20401.591968160552, 20507.28224512135, 20564.485887852923, 20614.896925515575, 7318.121825380882, 7499.812504687427, 7517.588338608977, 7532.058492688414, 7734.187910269453, 4010.3382941814275, 4067.5525692173837, 4079.755214687128, 4130.56629053951, 4132.152908653718, 3606.2426890353318, 3624.909377265537, 3642.3807952648112, 3662.853485860536, 3682.024263570156, 653.0, 656.6158797103652, 663.8207683925409, 674.3492529708757, 675.6419097878132, 556.4852511426914, 568.4855206038459, 576.8702042040508, 601.5578549765919, 619.7118339971935, 7995.2804247618, 8086.393520485987, 8184.6316915738935, 8231.711890083938, 8239.505629662239, 558.6840765149938, 599.5623195067625, 614.7540983606593, 615.8329964716926, 628.5851338116912, 3554.3424466473784, 3721.292954338667, 3732.122951106488, 3784.299904517673, 3800.467934489174, 87071.0, 89132.5543372292, 89914.0, 90542.0, 91492.54253728689, 79326.20673793188, 79890.0, 79894.60052699815, 80622.0, 80812.19187808047, 67636.6618166906, 69407.30592694008, 69786.65106674535, 70214.0, 72304.0, 5797.304323481209, 6864.450843932574, 6942.861142777015, 7064.470164737562, 7105.005299258005, 45358.0, 49287.260691088944, 50259.0, 50360.496395037044, 52666.47333526616, 65463.0, 68176.6591167041, 69796.6510167446, 71606.0, 72305.0, 10972.396518191576, 11017.944910275397, 11200.607978720705, 11366.374849383363, 11622.012128969132, 11812.645620631394, 12001.039916806809, 12032.0, 12115.515379384726, 12400.81398779037, 11217.943910280397, 11487.081033517132, 11642.24325418845, 11704.0, 11745.706357341134, 8870.556472176495, 10097.040781125781, 11177.105831533621, 11366.43167841588, 11651.941740291244, 10403.53184106732, 10680.878507756885, 10962.725931851768, 11340.0, 11605.709857253298, 9010.50442225684, 9284.396514226553, 9328.160465558132, 9387.436753794795, 9407.670731524364, 8844.778880528038, 8885.378023538291, 9469.0, 9567.569459374206, 9740.220782337254], "value": 6865.470929384459, "unit": "score"}], "name": "dromaeo_css", "value": 7403.919667181194, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 119.0, 108.0, 117.0, 120.0, 118.0, 108.0, 114.0, 114.0, 116.0], "value": 114.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 113.0, 164.0, 186.0, 151.0, 144.0, 123.0, 152.0, 155.0, 124.0], "value": 146.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 199.0, 236.0, 260.0, 275.0, 197.0, 200.0, 199.0, 197.0, 197.0], "value": 215.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 70.0, 81.0, 143.0, 56.0, 57.0, 56.0, 62.0, 130.0, 57.0], "value": 77.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 127.0, 110.0, 111.0, 88.0, 123.0, 79.0, 79.0, 121.0], "value": 99.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 90.0, 87.0, 90.0, 87.0, 89.0, 88.0, 90.0, 87.0], "value": 88.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 98.0, 96.0, 96.0, 105.0, 95.0, 95.0, 98.0, 95.0, 96.0], "value": 97.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 94.0, 91.0, 91.0, 93.0, 91.0, 92.0, 106.0, 151.0, 92.0], "value": 99.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 62.0, 74.0, 67.0, 66.0, 67.0, 65.0, 86.0, 100.0, 93.0], "value": 74.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 91.0, 70.0, 75.0, 101.0, 106.0, 106.0, 73.0, 68.0, 73.0], "value": 82.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 81.0, 82.0, 83.0, 82.0, 84.0, 80.0, 83.0, 84.0, 84.0], "value": 82.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 154.0, 141.0, 131.0, 136.0, 129.0, 127.0, 133.0, 149.0, 145.0], "value": 137.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 167.0, 171.0, 169.0, 165.0, 184.0, 224.0, 222.0, 171.0, 161.0], "value": 180.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 70.0, 66.0, 71.0, 67.0, 70.0, 69.0, 70.0, 72.0], "value": 69.0, "unit": "score"}], "name": "kraken", "value": 1564.5000000000002, "alertThreshold": 2.0}]} 03:24:10 INFO - Return code: 0 03:24:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 03:24:10 ERROR - # TBPL SUCCESS # 03:24:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:24:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:24:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:24:10 INFO - 2016-05-19 03:24:10.734 screenresolution[1687:10338] starting screenresolution argv=screenresolution get 03:24:10 INFO - 2016-05-19 03:24:10.737 screenresolution[1687:10338] Display 0: 1600x1200x32@60 03:24:10 INFO - 2016-05-19 03:24:10.743 screenresolution[1688:10341] starting screenresolution argv=screenresolution list 03:24:10 INFO - Available Modes on Display 0 03:24:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:24:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:24:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:24:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:24:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:24:10 INFO - Intel Iris: 03:24:10 INFO - Chipset Model: Intel Iris 03:24:10 INFO - Type: GPU 03:24:10 INFO - Bus: Built-In 03:24:10 INFO - VRAM (Dynamic, Max): 1536 MB 03:24:10 INFO - Vendor: Intel (0x8086) 03:24:10 INFO - Device ID: 0x0a2e 03:24:10 INFO - Revision ID: 0x0009 03:24:10 INFO - Displays: 03:24:10 INFO - Display: 03:24:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:24:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:24:10 INFO - Display Serial Number: 1600x1200 60 03:24:10 INFO - Main Display: Yes 03:24:10 INFO - Mirror: Off 03:24:10 INFO - Online: Yes 03:24:10 INFO - Rotation: Supported 03:24:10 INFO - Return code: 0 03:24:10 INFO - Running post-action listener: _resource_record_post_action 03:24:10 INFO - Running post-run listener: _resource_record_post_run 03:24:12 INFO - Total resource usage - Wall time: 1206s; CPU: 54.0%; Read bytes: 37905920; Write bytes: 655726080; Read time: 389; Write time: 1961 03:24:12 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 347931136; Write bytes: 336365056; Read time: 26540; Write time: 642 03:24:12 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 31661568; Write bytes: 298975232; Read time: 307; Write time: 1268 03:24:12 INFO - Running post-run listener: _upload_blobber_files 03:24:12 INFO - Blob upload gear active. 03:24:12 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:24:12 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 03:24:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:24:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:24:12 INFO - (blobuploader) - INFO - Open directory for files ... 03:24:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:24:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:24:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:24:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:24:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:24:13 INFO - (blobuploader) - INFO - Done attempting. 03:24:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:24:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:24:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:24:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:24:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:24:14 INFO - (blobuploader) - INFO - Done attempting. 03:24:14 INFO - (blobuploader) - INFO - Iteration through files over. 03:24:14 INFO - Return code: 0 03:24:14 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:24:14 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:24:14 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b6c6e4e43ec54954901af33d4be8b745289f56e372f71a9284cb7df0a9b9a4747b7566f4f566891f962e01cbe28f8df8c669ab2a73fc9ee87346ef5372082dfa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6955cd011b945b973eec201b285e477f75a8c6a190fd291890ce6d86d939caf59cdb6e3b03662eadea9c4371bc3f15ae327a579733cb39d959c8f3705cb91819"} 03:24:14 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:24:14 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:24:14 INFO - Contents: 03:24:14 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b6c6e4e43ec54954901af33d4be8b745289f56e372f71a9284cb7df0a9b9a4747b7566f4f566891f962e01cbe28f8df8c669ab2a73fc9ee87346ef5372082dfa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6955cd011b945b973eec201b285e477f75a8c6a190fd291890ce6d86d939caf59cdb6e3b03662eadea9c4371bc3f15ae327a579733cb39d959c8f3705cb91819"} 03:24:14 INFO - Running post-run listener: copy_logs_to_upload_dir 03:24:14 INFO - Copying logs to upload dir... 03:24:14 INFO - mkdir: /builds/slave/test/build/upload/logs 03:24:14 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.282191 ========= master_lag: 0.08 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-05-19 03:24:14.577968) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-19 03:24:14.585152) ========= 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.QcB2U57zaF/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.aZGfgWJzCB/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b6c6e4e43ec54954901af33d4be8b745289f56e372f71a9284cb7df0a9b9a4747b7566f4f566891f962e01cbe28f8df8c669ab2a73fc9ee87346ef5372082dfa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6955cd011b945b973eec201b285e477f75a8c6a190fd291890ce6d86d939caf59cdb6e3b03662eadea9c4371bc3f15ae327a579733cb39d959c8f3705cb91819"} build_url:https://queue.taskcluster.net/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011219 build_url: 'https://queue.taskcluster.net/v1/task/Fq2yPjhXR4SCUC5zxcq6VA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/b6c6e4e43ec54954901af33d4be8b745289f56e372f71a9284cb7df0a9b9a4747b7566f4f566891f962e01cbe28f8df8c669ab2a73fc9ee87346ef5372082dfa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6955cd011b945b973eec201b285e477f75a8c6a190fd291890ce6d86d939caf59cdb6e3b03662eadea9c4371bc3f15ae327a579733cb39d959c8f3705cb91819"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-19 03:24:14.617641) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 03:24:14.617976) ========= 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.QcB2U57zaF/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.aZGfgWJzCB/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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.005357 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-19 03:24:14.666388) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 03:24:14.666692) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-19 03:24:14.670063) ========= ========= Total master_lag: 0.20 =========