builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0378 starttime: 1464651357.35 results: success (0) buildid: 20160526140250 builduid: 01574422c30242cc92a6c0b32dab29da revision: 2ee4473c729acb2ba7dc723e7affe79ce14bff85 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 16:35:57.355233) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 16:35:57.355825) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 16:35:57.356139) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 16:35:57.599470) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 16:35:57.599788) ========= 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.epUbqUHVQr/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.gUD9Mam6Cu/Listeners TMPDIR=/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/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-30 16:35:57-- 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% 159M=0s 2016-05-30 16:35:57 (159 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.172619 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 16:35:57.795422) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 16:35:57.795845) ========= 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.epUbqUHVQr/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.gUD9Mam6Cu/Listeners TMPDIR=/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/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.020044 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 16:35:57.844288) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-30 16:35:57.844681) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 2ee4473c729acb2ba7dc723e7affe79ce14bff85 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 2ee4473c729acb2ba7dc723e7affe79ce14bff85 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.epUbqUHVQr/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.gUD9Mam6Cu/Listeners TMPDIR=/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/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-30 16:35:57,806 truncating revision to first 12 chars 2016-05-30 16:35:57,806 Setting DEBUG logging. 2016-05-30 16:35:57,806 attempt 1/10 2016-05-30 16:35:57,806 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/2ee4473c729a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-30 16:35:59,874 unpacking tar archive at: mozilla-beta-2ee4473c729a/testing/mozharness/ program finished with exit code 0 elapsedTime=2.345296 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-30 16:36:00.210392) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 16:36:00.210734) ========= script_repo_revision: 2ee4473c729acb2ba7dc723e7affe79ce14bff85 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 16:36:00.211135) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 16:36:00.211501) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 16:36:00.231005) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 29 secs) (at 2016-05-30 16:36:00.231737) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.epUbqUHVQr/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.gUD9Mam6Cu/Listeners TMPDIR=/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/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 16:36:00 INFO - MultiFileLogger online at 20160530 16:36:00 in /builds/slave/test 16:36:00 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 16:36:00 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:36:00 INFO - {'append_to_log': False, 16:36:00 INFO - 'base_work_dir': '/builds/slave/test', 16:36:00 INFO - 'blob_upload_branch': 'Mozilla-Beta', 16:36:00 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:36:00 INFO - 'branch': 'Mozilla-Beta', 16:36:00 INFO - 'buildbot_json_path': 'buildprops.json', 16:36:00 INFO - 'config_files': ('talos/mac_config.py',), 16:36:00 INFO - 'default_actions': ('clobber', 16:36:00 INFO - 'read-buildbot-config', 16:36:00 INFO - 'download-and-extract', 16:36:00 INFO - 'populate-webroot', 16:36:00 INFO - 'create-virtualenv', 16:36:00 INFO - 'install', 16:36:00 INFO - 'run-tests'), 16:36:00 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:36:00 INFO - 'download_minidump_stackwalk': True, 16:36:00 INFO - 'download_symbols': 'ondemand', 16:36:00 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:36:00 INFO - 'tooltool.py': '/tools/tooltool.py', 16:36:00 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:36:00 INFO - '/tools/misc-python/virtualenv.py')}, 16:36:00 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:36:00 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:36:00 INFO - 'installer_path': 'installer.exe', 16:36:00 INFO - 'log_level': 'info', 16:36:00 INFO - 'log_name': 'talos', 16:36:00 INFO - 'log_to_console': True, 16:36:00 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:36:00 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:36:00 INFO - 'opt_config_files': (), 16:36:00 INFO - 'pip_index': False, 16:36:00 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:36:00 INFO - 'cmd': ('bash', 16:36:00 INFO - '-c', 16:36:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:36:00 INFO - 'enabled': True, 16:36:00 INFO - 'halt_on_failure': False, 16:36:00 INFO - 'name': 'check_screen_resolution'},), 16:36:00 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:36:00 INFO - 'cmd': ('bash', 16:36:00 INFO - '-c', 16:36:00 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:36:00 INFO - 'enabled': True, 16:36:00 INFO - 'halt_on_failure': False, 16:36:00 INFO - 'name': 'check_screen_resolution'},), 16:36:00 INFO - 'run_cmd_checks_enabled': True, 16:36:00 INFO - 'sps_profile': False, 16:36:00 INFO - 'sps_profile_interval': 0, 16:36:00 INFO - 'suite': 'dromaeojs', 16:36:00 INFO - 'system_bits': '32', 16:36:00 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:36:00 INFO - 'title': 't-yosemite-r7-0378', 16:36:00 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:36:00 INFO - 'use_talos_json': True, 16:36:00 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:36:00 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:36:00 INFO - 'work_dir': 'build'} 16:36:00 INFO - ##### 16:36:00 INFO - ##### Running clobber step. 16:36:00 INFO - ##### 16:36:00 INFO - Running pre-action listener: _resource_record_pre_action 16:36:00 INFO - Running main action method: clobber 16:36:00 INFO - rmtree: /builds/slave/test/build 16:36:00 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:36:01 INFO - Running post-action listener: _resource_record_post_action 16:36:01 INFO - ##### 16:36:01 INFO - ##### Running read-buildbot-config step. 16:36:01 INFO - ##### 16:36:01 INFO - Running pre-action listener: _resource_record_pre_action 16:36:01 INFO - Running main action method: read_buildbot_config 16:36:01 INFO - Using buildbot properties: 16:36:01 INFO - { 16:36:01 INFO - "project": "", 16:36:01 INFO - "product": "firefox", 16:36:01 INFO - "script_repo_revision": "production", 16:36:01 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 16:36:01 INFO - "repository": "", 16:36:01 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 16:36:01 INFO - "buildid": "20160526140250", 16:36:01 INFO - "pgo_build": "False", 16:36:01 INFO - "basedir": "/builds/slave/test", 16:36:01 INFO - "buildnumber": 15, 16:36:01 INFO - "slavename": "t-yosemite-r7-0378", 16:36:01 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 16:36:01 INFO - "platform": "yosemite_r7", 16:36:01 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 16:36:01 INFO - "branch": "mozilla-beta", 16:36:01 INFO - "repo_path": "releases/mozilla-beta", 16:36:01 INFO - "revision": "2ee4473c729acb2ba7dc723e7affe79ce14bff85", 16:36:01 INFO - "stage_platform": "macosx64", 16:36:01 INFO - "builduid": "01574422c30242cc92a6c0b32dab29da", 16:36:01 INFO - "slavebuilddir": "test" 16:36:01 INFO - } 16:36:01 INFO - Found installer url https://queue.taskcluster.net/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.dmg. 16:36:01 INFO - Running post-action listener: _resource_record_post_action 16:36:01 INFO - ##### 16:36:01 INFO - ##### Running download-and-extract step. 16:36:01 INFO - ##### 16:36:01 INFO - Running pre-action listener: _resource_record_pre_action 16:36:01 INFO - Running main action method: download_and_extract 16:36:01 INFO - mkdir: /builds/slave/test/build/tests 16:36:01 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')]} 16:36:01 INFO - https://queue.taskcluster.net/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 16:36:01 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 16:36:01 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 16:36:01 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 16:36:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json'}, attempt #1 16:36:32 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json: timed out 16:36:32 INFO - retry: attempt #1 caught exception: timed out 16:36:32 INFO - retry: Failed, sleeping 30 seconds before retrying 16:37:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json'}, attempt #2 16:37:19 INFO - Downloaded 1241 bytes. 16:37:19 INFO - Reading from file /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 16:37:19 INFO - Using the following test package requirements: 16:37:19 INFO - {u'common': [u'firefox-47.0.en-US.mac.common.tests.zip'], 16:37:19 INFO - u'cppunittest': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:37:19 INFO - u'firefox-47.0.en-US.mac.cppunittest.tests.zip'], 16:37:19 INFO - u'gtest': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:37:19 INFO - u'firefox-47.0.en-US.mac.gtest.tests.zip'], 16:37:19 INFO - u'jittest': [u'firefox-47.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 16:37:19 INFO - u'mochitest': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:37:19 INFO - u'firefox-47.0.en-US.mac.mochitest.tests.zip'], 16:37:19 INFO - u'mozbase': [u'firefox-47.0.en-US.mac.common.tests.zip'], 16:37:19 INFO - u'reftest': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:37:19 INFO - u'firefox-47.0.en-US.mac.reftest.tests.zip'], 16:37:19 INFO - u'talos': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:37:19 INFO - u'firefox-47.0.en-US.mac.talos.tests.zip'], 16:37:19 INFO - u'web-platform': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:37:19 INFO - u'firefox-47.0.en-US.mac.web-platform.tests.zip'], 16:37:19 INFO - u'webapprt': [u'firefox-47.0.en-US.mac.common.tests.zip'], 16:37:19 INFO - u'xpcshell': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:37:19 INFO - u'firefox-47.0.en-US.mac.xpcshell.tests.zip']} 16:37:19 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip'] for test suite category: common 16:37: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')]} 16:37:19 INFO - https://queue.taskcluster.net/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:37:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 16:37:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 16:37:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip 16:37:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #1 16:37:21 INFO - Downloaded 17816878 bytes. 16:37:21 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:37:21 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:37:24 INFO - Return code: 0 16:37:24 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip', u'firefox-47.0.en-US.mac.talos.tests.zip'] for test suite category: talos 16:37: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')]} 16:37:24 INFO - https://queue.taskcluster.net/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:37:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 16:37:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 16:37:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip 16:37:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #1 16:37:47 INFO - Downloaded 17816878 bytes. 16:37:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:37:47 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:37:51 INFO - Return code: 0 16:37:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:37:51 INFO - https://queue.taskcluster.net/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 16:37:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 16:37:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 16:37:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip 16:37:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip'}, attempt #1 16:37:52 INFO - Downloaded 10845194 bytes. 16:37:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:37:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 16:37:52 INFO - Return code: 0 16:37:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:37:52 INFO - https://queue.taskcluster.net/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.dmg matches https://queue.taskcluster.net 16:37:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.dmg 16:37:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.dmg 16:37:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0.en-US.mac.dmg 16:37:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.dmg'}, attempt #1 16:37:55 INFO - Downloaded 88466316 bytes. 16:37:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.dmg 16:37:55 INFO - mkdir: /builds/slave/test/properties 16:37:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:37:55 INFO - Writing to file /builds/slave/test/properties/build_url 16:37:55 INFO - Contents: 16:37:55 INFO - build_url:https://queue.taskcluster.net/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.dmg 16:37:56 INFO - Running post-action listener: _resource_record_post_action 16:37:56 INFO - Running post-action listener: set_extra_try_arguments 16:37:56 INFO - ##### 16:37:56 INFO - ##### Running populate-webroot step. 16:37:56 INFO - ##### 16:37:56 INFO - Running pre-action listener: _resource_record_pre_action 16:37:56 INFO - Running main action method: populate_webroot 16:37:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:37:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:37:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:37:56 INFO - '--tppagecycles', 16:37:56 INFO - '7'], 16:37:56 INFO - 'tests': ['tsvgm']}}, 16:37:56 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 16:37:56 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 16:37:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:37:56 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 16:37:56 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 16:37:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:37:56 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:37:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:37:56 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:37:56 INFO - 'talos_options': ['--e10s'], 16:37:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:37:56 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:37:56 INFO - 'tests': ['damp', 'tps']}, 16:37:56 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:37:56 INFO - 'talos_options': ['--e10s'], 16:37:56 INFO - 'tests': ['damp', 'tps']}, 16:37:56 INFO - 'g3': {'tests': ['dromaeo_dom']}, 16:37:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:37:56 INFO - 'other': {'tests': ['a11yr', 16:37:56 INFO - 'ts_paint', 16:37:56 INFO - 'tpaint', 16:37:56 INFO - 'sessionrestore', 16:37:56 INFO - 'sessionrestore_no_auto_restore', 16:37:56 INFO - 'tabpaint']}, 16:37:56 INFO - 'other-e10s': {'talos_options': ['--e10s'], 16:37:56 INFO - 'tests': ['a11yr', 16:37:56 INFO - 'ts_paint', 16:37:56 INFO - 'tpaint', 16:37:56 INFO - 'sessionrestore', 16:37:56 INFO - 'sessionrestore_no_auto_restore', 16:37:56 INFO - 'tabpaint']}, 16:37:56 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 16:37:56 INFO - 'tests': ['a11yr', 16:37:56 INFO - 'ts_paint', 16:37:56 INFO - 'tpaint', 16:37:56 INFO - 'sessionrestore', 16:37:56 INFO - 'sessionrestore_no_auto_restore', 16:37:56 INFO - 'tabpaint']}, 16:37:56 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 16:37:56 INFO - 'tests': ['a11yr', 16:37:56 INFO - 'ts_paint', 16:37:56 INFO - 'tpaint', 16:37:56 INFO - 'sessionrestore', 16:37:56 INFO - 'sessionrestore_no_auto_restore', 16:37:56 INFO - 'tabpaint']}, 16:37:56 INFO - 'other_l64': {'tests': ['a11yr', 16:37:56 INFO - 'ts_paint', 16:37:56 INFO - 'tpaint', 16:37:56 INFO - 'sessionrestore', 16:37:56 INFO - 'sessionrestore_no_auto_restore', 16:37:56 INFO - 'tabpaint']}, 16:37:56 INFO - 'other_nol64': {'tests': ['a11yr', 16:37:56 INFO - 'ts_paint', 16:37:56 INFO - 'tpaint', 16:37:56 INFO - 'sessionrestore', 16:37:56 INFO - 'sessionrestore_no_auto_restore', 16:37:56 INFO - 'tabpaint']}, 16:37:56 INFO - 'svgr': {'tests': ['tsvgx', 16:37:56 INFO - 'tsvgr_opacity', 16:37:56 INFO - 'tart', 16:37:56 INFO - 'tscrollx', 16:37:56 INFO - 'cart']}, 16:37:56 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 16:37:56 INFO - 'tests': ['tsvgx', 16:37:56 INFO - 'tsvgr_opacity', 16:37:56 INFO - 'tart', 16:37:56 INFO - 'tscrollx', 16:37:56 INFO - 'cart']}, 16:37:56 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:37:56 INFO - 'tests': ['tp5o']}, 16:37:56 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:37:56 INFO - 'talos_options': ['--e10s'], 16:37:56 INFO - 'tests': ['tp5o']}, 16:37:56 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:37:56 INFO - 'talos_options': ['--xperf_path', 16:37:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:37:56 INFO - 'tests': ['tp5n']}, 16:37:56 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:37:56 INFO - 'talos_options': ['--e10s', 16:37:56 INFO - '--xperf_path', 16:37:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:37:56 INFO - 'tests': ['tp5n']}}, 16:37:56 INFO - 'talos.zip': {'path': '', 16:37:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:37:56 INFO - Running post-action listener: _resource_record_post_action 16:37:56 INFO - ##### 16:37:56 INFO - ##### Running create-virtualenv step. 16:37:56 INFO - ##### 16:37:56 INFO - Running pre-action listener: _resource_record_pre_action 16:37:56 INFO - Running main action method: create_virtualenv 16:37:56 INFO - Creating virtualenv /builds/slave/test/build/venv 16:37:56 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 16:37:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:37:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:37:56 INFO - Using real prefix '/tools/python27' 16:37:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:37:57 INFO - Installing distribute.............................................................................................................................................................................................done. 16:38:00 INFO - Installing pip.................done. 16:38:00 INFO - Return code: 0 16:38:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:38: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')]} 16:38:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:38:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:38: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')]} 16:38:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:38:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:38: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c8f2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8f94c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4dbc912f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c796d30>, '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 0x10c5fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c78db20>, '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.epUbqUHVQr/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.gUD9Mam6Cu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/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 16:38: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', 'pip>=1.5'] in /builds/slave/test/build 16:38: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 pip>=1.5 16:38:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.epUbqUHVQr/Render', 16:38:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:38:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:38:00 INFO - 'HOME': '/Users/cltbld', 16:38:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:38:00 INFO - 'LOGNAME': 'cltbld', 16:38:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:38:00 INFO - 'MOZ_NO_REMOTE': '1', 16:38:00 INFO - 'NO_EM_RESTART': '1', 16:38:00 INFO - 'PAGER': '/bin/cat', 16:38:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:38:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:38:00 INFO - 'PWD': '/builds/slave/test', 16:38:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:38:00 INFO - 'SHELL': '/bin/bash', 16:38:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gUD9Mam6Cu/Listeners', 16:38:00 INFO - 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/', 16:38:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:38:00 INFO - 'USER': 'cltbld', 16:38:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:38:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:38:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:38:00 INFO - 'XPC_FLAGS': '0x0', 16:38:00 INFO - 'XPC_SERVICE_NAME': '0', 16:38:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:38:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:38:00 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 16:38:00 INFO - Cleaning up... 16:38:00 INFO - Return code: 0 16:38:00 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 16:38: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')]} 16:38:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:38:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:38: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')]} 16:38:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:38:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:38: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', '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 0x10c8f2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8f94c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4dbc912f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c796d30>, '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 0x10c5fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c78db20>, '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.epUbqUHVQr/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.gUD9Mam6Cu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/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 16:38: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', 'psutil>=0.7.1'] in /builds/slave/test/build 16:38: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 psutil>=0.7.1 16:38:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.epUbqUHVQr/Render', 16:38:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:38:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:38:00 INFO - 'HOME': '/Users/cltbld', 16:38:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:38:00 INFO - 'LOGNAME': 'cltbld', 16:38:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:38:00 INFO - 'MOZ_NO_REMOTE': '1', 16:38:00 INFO - 'NO_EM_RESTART': '1', 16:38:00 INFO - 'PAGER': '/bin/cat', 16:38:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:38:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:38:00 INFO - 'PWD': '/builds/slave/test', 16:38:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:38:00 INFO - 'SHELL': '/bin/bash', 16:38:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gUD9Mam6Cu/Listeners', 16:38:00 INFO - 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/', 16:38:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:38:00 INFO - 'USER': 'cltbld', 16:38:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:38:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:38:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:38:00 INFO - 'XPC_FLAGS': '0x0', 16:38:00 INFO - 'XPC_SERVICE_NAME': '0', 16:38:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:38:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:38:00 INFO - Downloading/unpacking psutil>=0.7.1 16:38: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 16:38: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 16:38: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 16:38: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 16:38: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 16:38: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 16:38:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:38:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:38:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:38:04 INFO - Installing collected packages: psutil 16:38:04 INFO - Running setup.py install for psutil 16:38:04 INFO - building 'psutil._psutil_osx' extension 16:38:04 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 16:38:04 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 16:38:04 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 16:38:04 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 16:38:05 INFO - building 'psutil._psutil_posix' extension 16:38:05 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 16:38:05 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:38:05 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:38:05 INFO - ^ 16:38:05 INFO - 1 warning generated. 16:38:05 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 16:38:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:38:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:38:05 INFO - Successfully installed psutil 16:38:05 INFO - Cleaning up... 16:38:05 INFO - Return code: 0 16:38:05 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 16:38:05 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')]} 16:38:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:38:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:38:05 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')]} 16:38:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:38:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:38:05 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 0x10c8f2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8f94c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4dbc912f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c796d30>, '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 0x10c5fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c78db20>, '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.epUbqUHVQr/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.gUD9Mam6Cu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/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 16:38:05 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 16:38:05 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 16:38:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.epUbqUHVQr/Render', 16:38:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:38:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:38:05 INFO - 'HOME': '/Users/cltbld', 16:38:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:38:05 INFO - 'LOGNAME': 'cltbld', 16:38:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:38:05 INFO - 'MOZ_NO_REMOTE': '1', 16:38:05 INFO - 'NO_EM_RESTART': '1', 16:38:05 INFO - 'PAGER': '/bin/cat', 16:38:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:38:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:38:05 INFO - 'PWD': '/builds/slave/test', 16:38:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:38:05 INFO - 'SHELL': '/bin/bash', 16:38:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gUD9Mam6Cu/Listeners', 16:38:05 INFO - 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/', 16:38:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:38:05 INFO - 'USER': 'cltbld', 16:38:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:38:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:38:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:38:05 INFO - 'XPC_FLAGS': '0x0', 16:38:05 INFO - 'XPC_SERVICE_NAME': '0', 16:38:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:38:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:38:05 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 16:38:05 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 16:38:05 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 16:38:05 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 16:38:05 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 16:38:05 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 16:38:05 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 16:38:07 INFO - Downloading mozsystemmonitor-0.0.tar.gz 16:38:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:38:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 16:38:08 INFO - Installing collected packages: mozsystemmonitor 16:38:08 INFO - Running setup.py install for mozsystemmonitor 16:38:08 INFO - Successfully installed mozsystemmonitor 16:38:08 INFO - Cleaning up... 16:38:08 INFO - Return code: 0 16:38:08 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:38:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:38:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:38:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:38:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 16:38:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:38:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:38:08 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 0x10c8f2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8f94c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4dbc912f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c796d30>, '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 0x10c5fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c78db20>, '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.epUbqUHVQr/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.gUD9Mam6Cu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/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 16:38:08 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 16:38:08 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 16:38:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.epUbqUHVQr/Render', 16:38:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:38:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:38:08 INFO - 'HOME': '/Users/cltbld', 16:38:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:38:08 INFO - 'LOGNAME': 'cltbld', 16:38:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:38:08 INFO - 'MOZ_NO_REMOTE': '1', 16:38:08 INFO - 'NO_EM_RESTART': '1', 16:38:08 INFO - 'PAGER': '/bin/cat', 16:38:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:38:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:38:08 INFO - 'PWD': '/builds/slave/test', 16:38:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:38:08 INFO - 'SHELL': '/bin/bash', 16:38:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gUD9Mam6Cu/Listeners', 16:38:08 INFO - 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/', 16:38:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:38:08 INFO - 'USER': 'cltbld', 16:38:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:38:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:38:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:38:08 INFO - 'XPC_FLAGS': '0x0', 16:38:08 INFO - 'XPC_SERVICE_NAME': '0', 16:38:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:38:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:38:08 INFO - Downloading/unpacking blobuploader==1.2.4 16:38:08 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 16:38:08 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 16:38:08 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 16:38:08 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 16:38:08 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 16:38:08 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 16:38:10 INFO - Downloading blobuploader-1.2.4.tar.gz 16:38:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:38:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:38:11 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 16:38:11 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 16:38:11 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 16:38:11 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 16:38:11 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 16:38:11 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 16:38:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:38:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:38:11 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 16:38:11 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 16:38:11 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 16:38:11 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 16:38:11 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 16:38:11 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 16:38:12 INFO - Downloading docopt-0.6.1.tar.gz 16:38:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:38:12 INFO - Installing collected packages: blobuploader, requests, docopt 16:38:12 INFO - Running setup.py install for blobuploader 16:38:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:38:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:38:12 INFO - Running setup.py install for requests 16:38:12 INFO - Running setup.py install for docopt 16:38:13 INFO - Successfully installed blobuploader requests docopt 16:38:13 INFO - Cleaning up... 16:38:13 INFO - Return code: 0 16:38:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:38:13 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')]} 16:38:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:38:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:38:13 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')]} 16:38:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:38:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:38:13 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 0x10c8f2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8f94c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4dbc912f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c796d30>, '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 0x10c5fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c78db20>, '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.epUbqUHVQr/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.gUD9Mam6Cu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/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 16:38:13 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 16:38:13 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 16:38:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.epUbqUHVQr/Render', 16:38:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:38:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:38:13 INFO - 'HOME': '/Users/cltbld', 16:38:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:38:13 INFO - 'LOGNAME': 'cltbld', 16:38:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:38:13 INFO - 'MOZ_NO_REMOTE': '1', 16:38:13 INFO - 'NO_EM_RESTART': '1', 16:38:13 INFO - 'PAGER': '/bin/cat', 16:38:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:38:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:38:13 INFO - 'PWD': '/builds/slave/test', 16:38:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:38:13 INFO - 'SHELL': '/bin/bash', 16:38:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gUD9Mam6Cu/Listeners', 16:38:13 INFO - 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/', 16:38:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:38:13 INFO - 'USER': 'cltbld', 16:38:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:38:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:38:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:38:13 INFO - 'XPC_FLAGS': '0x0', 16:38:13 INFO - 'XPC_SERVICE_NAME': '0', 16:38:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:38:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:38:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:38:13 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-ydIxqu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:38:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:38:13 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-317F1_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:38:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:38:13 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-6PfEMp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:38:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:38:13 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-w4DuX7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:38:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:38:13 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-C3qN0G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:38:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:38:14 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-OprtDV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:38:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:38:14 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-uTc2r1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:38:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:38:14 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-OPV4uj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:38:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:38:14 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-sMbz2W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:38:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:38:14 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-xGH3ot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:38:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:38:14 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-zba93S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:38:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:38:14 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-xbpmmJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:38:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:38:14 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-l88Ua_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:38:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:38:14 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-vg7YV6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:38:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:38:14 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-7VCwIR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:38:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:38:15 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-9TSYRi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:38:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:38:15 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-nUQ0WX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:38:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:38:15 INFO - Running setup.py install for manifestparser 16:38:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:38:15 INFO - Running setup.py install for mozcrash 16:38:15 INFO - Running setup.py install for mozdebug 16:38:15 INFO - Running setup.py install for mozdevice 16:38:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:38:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:38:15 INFO - Running setup.py install for mozfile 16:38:16 INFO - Running setup.py install for mozhttpd 16:38:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:38:16 INFO - Running setup.py install for mozinfo 16:38:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:38:16 INFO - Running setup.py install for mozInstall 16:38:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:38:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:38:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:38:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:38:16 INFO - Running setup.py install for mozleak 16:38:16 INFO - Running setup.py install for mozlog 16:38:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:38:16 INFO - Running setup.py install for moznetwork 16:38:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:38:16 INFO - Running setup.py install for mozprocess 16:38:17 INFO - Running setup.py install for mozprofile 16:38:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:38:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:38:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:38:17 INFO - Running setup.py install for mozrunner 16:38:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:38:17 INFO - Running setup.py install for mozscreenshot 16:38:17 INFO - Running setup.py install for moztest 16:38:17 INFO - Running setup.py install for mozversion 16:38:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:38:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:38:17 INFO - Cleaning up... 16:38:17 INFO - Return code: 0 16:38:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:38:17 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')]} 16:38:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:38:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:38:17 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')]} 16:38:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:38:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:38:17 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 0x10c8f2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8f94c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4dbc912f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c796d30>, '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 0x10c5fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c78db20>, '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.epUbqUHVQr/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.gUD9Mam6Cu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/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 16:38:17 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 16:38:17 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 16:38:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.epUbqUHVQr/Render', 16:38:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:38:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:38:17 INFO - 'HOME': '/Users/cltbld', 16:38:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:38:17 INFO - 'LOGNAME': 'cltbld', 16:38:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:38:17 INFO - 'MOZ_NO_REMOTE': '1', 16:38:17 INFO - 'NO_EM_RESTART': '1', 16:38:17 INFO - 'PAGER': '/bin/cat', 16:38:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:38:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:38:17 INFO - 'PWD': '/builds/slave/test', 16:38:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:38:17 INFO - 'SHELL': '/bin/bash', 16:38:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gUD9Mam6Cu/Listeners', 16:38:17 INFO - 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/', 16:38:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:38:17 INFO - 'USER': 'cltbld', 16:38:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:38:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:38:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:38:17 INFO - 'XPC_FLAGS': '0x0', 16:38:17 INFO - 'XPC_SERVICE_NAME': '0', 16:38:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:38:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:38:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:38:18 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-Ae6YV3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:38:18 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)) 16:38:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:38:18 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-S08_Qp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:38:18 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)) 16:38:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:38:18 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-c8PzDg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:38:18 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)) 16:38:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:38:18 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-oDMnl4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:38:18 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)) 16:38:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:38:18 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-BEdnmD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:38:18 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)) 16:38:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:38:18 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-wsZCth-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:38:18 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)) 16:38:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:38:18 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-dAG9k8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:38:18 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)) 16:38:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:38:18 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-lvUeeq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:38:18 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)) 16:38:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:38:18 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-BoD6VN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:38:19 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)) 16:38:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:38:19 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-cTExP8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:38:19 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)) 16:38:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:38:19 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-c8aFPN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:38:19 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)) 16:38:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:38:19 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-y11Iqv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:38:19 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)) 16:38:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:38:19 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-rkPmoL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:38:19 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)) 16:38:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:38:19 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-ZCAhIE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:38:19 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)) 16:38:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:38:19 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-KrlAvE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:38:19 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)) 16:38:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:38:19 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-ZL4cef-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:38:19 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)) 16:38:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:38:19 INFO - Running setup.py (path:/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/pip-08a5Ca-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:38:19 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)) 16:38:19 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)) 16:38:19 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)) 16:38:19 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)) 16:38:19 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)) 16:38:19 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)) 16:38:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:38:19 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 16:38:19 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 16:38:19 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 16:38:19 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 16:38:19 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 16:38:19 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 16:38:22 INFO - Downloading blessings-1.6.tar.gz 16:38:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:38:22 INFO - Installing collected packages: blessings 16:38:22 INFO - Running setup.py install for blessings 16:38:22 INFO - Successfully installed blessings 16:38:22 INFO - Cleaning up... 16:38:22 INFO - Return code: 0 16:38:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:38:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:38:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:38:22 INFO - Reading from file tmpfile_stdout 16:38:22 INFO - Current package versions: 16:38:22 INFO - blessings == 1.6 16:38:22 INFO - blobuploader == 1.2.4 16:38:22 INFO - docopt == 0.6.1 16:38:22 INFO - manifestparser == 1.1 16:38:22 INFO - mozInstall == 1.12 16:38:22 INFO - mozcrash == 0.17 16:38:22 INFO - mozdebug == 0.1 16:38:22 INFO - mozdevice == 0.48 16:38:22 INFO - mozfile == 1.2 16:38:22 INFO - mozhttpd == 0.7 16:38:22 INFO - mozinfo == 0.9 16:38:22 INFO - mozleak == 0.1 16:38:22 INFO - mozlog == 3.1 16:38:22 INFO - moznetwork == 0.27 16:38:22 INFO - mozprocess == 0.22 16:38:22 INFO - mozprofile == 0.28 16:38:22 INFO - mozrunner == 6.11 16:38:22 INFO - mozscreenshot == 0.1 16:38:22 INFO - mozsystemmonitor == 0.0 16:38:22 INFO - moztest == 0.7 16:38:22 INFO - mozversion == 1.4 16:38:22 INFO - psutil == 3.1.1 16:38:22 INFO - requests == 1.2.3 16:38:22 INFO - wsgiref == 0.1.2 16:38:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:38:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:38:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:38:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:38: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')]} 16:38:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:38:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:38:23 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 0x10c8f2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8f94c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4dbc912f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c796d30>, '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 0x10c5fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c78db20>, '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.epUbqUHVQr/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.gUD9Mam6Cu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/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 16:38:23 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 16:38:23 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 16:38:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.epUbqUHVQr/Render', 16:38:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:38:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:38:23 INFO - 'HOME': '/Users/cltbld', 16:38:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:38:23 INFO - 'LOGNAME': 'cltbld', 16:38:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:38:23 INFO - 'MOZ_NO_REMOTE': '1', 16:38:23 INFO - 'NO_EM_RESTART': '1', 16:38:23 INFO - 'PAGER': '/bin/cat', 16:38:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:38:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:38:23 INFO - 'PWD': '/builds/slave/test', 16:38:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:38:23 INFO - 'SHELL': '/bin/bash', 16:38:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gUD9Mam6Cu/Listeners', 16:38:23 INFO - 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/', 16:38:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:38:23 INFO - 'USER': 'cltbld', 16:38:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:38:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:38:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:38:23 INFO - 'XPC_FLAGS': '0x0', 16:38:23 INFO - 'XPC_SERVICE_NAME': '0', 16:38:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:38:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:38:23 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)) 16:38:23 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)) 16:38:23 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)) 16:38:23 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)) 16:38:23 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)) 16:38:23 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)) 16:38:23 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)) 16:38:23 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)) 16:38:23 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)) 16:38:23 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)) 16:38:23 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)) 16:38:23 INFO - Cleaning up... 16:38:23 INFO - Return code: 0 16:38:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:38: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')]} 16:38:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:38:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:38: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')]} 16:38:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:38:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:38:23 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 0x10c8f2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8f94c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4dbc912f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c796d30>, '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 0x10c5fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c78db20>, '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.epUbqUHVQr/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.gUD9Mam6Cu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/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 16:38:23 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 16:38:23 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 16:38:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.epUbqUHVQr/Render', 16:38:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:38:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:38:23 INFO - 'HOME': '/Users/cltbld', 16:38:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:38:23 INFO - 'LOGNAME': 'cltbld', 16:38:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:38:23 INFO - 'MOZ_NO_REMOTE': '1', 16:38:23 INFO - 'NO_EM_RESTART': '1', 16:38:23 INFO - 'PAGER': '/bin/cat', 16:38:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:38:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:38:23 INFO - 'PWD': '/builds/slave/test', 16:38:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:38:23 INFO - 'SHELL': '/bin/bash', 16:38:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gUD9Mam6Cu/Listeners', 16:38:23 INFO - 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/', 16:38:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:38:23 INFO - 'USER': 'cltbld', 16:38:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:38:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:38:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:38:23 INFO - 'XPC_FLAGS': '0x0', 16:38:23 INFO - 'XPC_SERVICE_NAME': '0', 16:38:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:38:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:38:23 INFO - Downloading/unpacking jsonschema 16:38:23 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 16:38:23 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 16:38:23 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 16:38:23 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 16:38:23 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 16:38:23 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 16:38:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:38:26 INFO - Downloading/unpacking functools32 (from jsonschema) 16:38:26 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 16:38:26 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 16:38:26 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 16:38:26 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 16:38:26 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 16:38:26 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 16:38:26 INFO - Downloading functools32-3.2.3-2.tar.gz 16:38:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:38:26 INFO - warning: no files found matching '*.txt' 16:38:26 INFO - no previously-included directories found matching 'build' 16:38:26 INFO - no previously-included directories found matching 'dist' 16:38:26 INFO - no previously-included directories found matching '.git*' 16:38:26 INFO - Installing collected packages: jsonschema, functools32 16:38:26 INFO - Running setup.py install for functools32 16:38:26 INFO - warning: no files found matching '*.txt' 16:38:26 INFO - no previously-included directories found matching 'build' 16:38:26 INFO - no previously-included directories found matching 'dist' 16:38:26 INFO - no previously-included directories found matching '.git*' 16:38:26 INFO - Successfully installed jsonschema functools32 16:38:26 INFO - Cleaning up... 16:38:26 INFO - Return code: 0 16:38:26 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:38:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:38:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:38:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:38:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:38:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:38:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:38:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c8f2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8f94c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4dbc912f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c796d30>, '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 0x10c5fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c78db20>, '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.epUbqUHVQr/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.gUD9Mam6Cu/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/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 16:38:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 16:38:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 16:38:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.epUbqUHVQr/Render', 16:38:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:38:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:38:26 INFO - 'HOME': '/Users/cltbld', 16:38:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:38:26 INFO - 'LOGNAME': 'cltbld', 16:38:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:38:26 INFO - 'MOZ_NO_REMOTE': '1', 16:38:26 INFO - 'NO_EM_RESTART': '1', 16:38:26 INFO - 'PAGER': '/bin/cat', 16:38:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:38:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:38:26 INFO - 'PWD': '/builds/slave/test', 16:38:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:38:26 INFO - 'SHELL': '/bin/bash', 16:38:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gUD9Mam6Cu/Listeners', 16:38:26 INFO - 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/', 16:38:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:38:26 INFO - 'USER': 'cltbld', 16:38:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:38:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:38:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:38:26 INFO - 'XPC_FLAGS': '0x0', 16:38:26 INFO - 'XPC_SERVICE_NAME': '0', 16:38:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:38:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:38:27 INFO - Downloading/unpacking flake8 16:38:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:38:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:38:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:38:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:38:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:38:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:38:29 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:38:29 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:38:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:38:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:38:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:38:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:38:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:38:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:38:30 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:38:30 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:38:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:38:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:38:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:38:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:38:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:38:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:38:30 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:38:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:38:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:38:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:38:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:38:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:38:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:38:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:38:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:38:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:38:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:38:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:38:30 INFO - no previously-included directories found matching 'docs/_build' 16:38:30 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:38:31 INFO - Running setup.py install for pep8 16:38:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:38:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:38:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:38:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:38:31 INFO - no previously-included directories found matching 'docs/_build' 16:38:31 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:38:31 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:38:31 INFO - Cleaning up... 16:38:31 INFO - Return code: 0 16:38:31 INFO - Running post-action listener: _resource_record_post_action 16:38:31 INFO - Running post-action listener: _start_resource_monitoring 16:38:31 INFO - Starting resource monitoring. 16:38:31 INFO - ##### 16:38:31 INFO - ##### Running install step. 16:38:31 INFO - ##### 16:38:31 INFO - Running pre-action listener: _resource_record_pre_action 16:38:31 INFO - Running main action method: install 16:38:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:38:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:38:31 INFO - Reading from file tmpfile_stdout 16:38:31 INFO - Detecting whether we're running mozinstall >=1.0... 16:38:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:38:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:38:31 INFO - Reading from file tmpfile_stdout 16:38:31 INFO - Output received: 16:38:31 INFO - Usage: mozinstall [options] installer 16:38:31 INFO - Options: 16:38:31 INFO - -h, --help show this help message and exit 16:38:31 INFO - -d DEST, --destination=DEST 16:38:31 INFO - Directory to install application into. [default: 16:38:31 INFO - "/builds/slave/test"] 16:38:31 INFO - --app=APP Application being installed. [default: firefox] 16:38:31 INFO - mkdir: /builds/slave/test/build/application 16:38:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:38:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0.en-US.mac.dmg --destination /builds/slave/test/build/application 16:38:54 INFO - Reading from file tmpfile_stdout 16:38:54 INFO - Output received: 16:38:54 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 16:38:54 INFO - Running post-action listener: _resource_record_post_action 16:38:54 INFO - ##### 16:38:54 INFO - ##### Running run-tests step. 16:38:54 INFO - ##### 16:38:54 INFO - Running pre-action listener: _resource_record_pre_action 16:38:54 INFO - Running main action method: run_tests 16:38:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:38:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:38:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:38:54 INFO - 2016-05-30 16:38:54.498 screenresolution[2280:29345] starting screenresolution argv=screenresolution get 16:38:54 INFO - 2016-05-30 16:38:54.501 screenresolution[2280:29345] Display 0: 1600x1200x32@60 16:38:54 INFO - 2016-05-30 16:38:54.507 screenresolution[2281:29347] starting screenresolution argv=screenresolution list 16:38:54 INFO - Available Modes on Display 0 16:38:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:38:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:38:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:38:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:38:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:38:54 INFO - Intel Iris: 16:38:54 INFO - Chipset Model: Intel Iris 16:38:54 INFO - Type: GPU 16:38:54 INFO - Bus: Built-In 16:38:54 INFO - VRAM (Dynamic, Max): 1536 MB 16:38:54 INFO - Vendor: Intel (0x8086) 16:38:54 INFO - Device ID: 0x0a2e 16:38:54 INFO - Revision ID: 0x0009 16:38:54 INFO - Displays: 16:38:54 INFO - Display: 16:38:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:38:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:38:54 INFO - Display Serial Number: 1600x1200 60 16:38:54 INFO - Main Display: Yes 16:38:54 INFO - Mirror: Off 16:38:54 INFO - Online: Yes 16:38:54 INFO - Rotation: Supported 16:38:54 INFO - Return code: 0 16:38:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:38:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:38:54 INFO - Python 2.7.3 16:38:54 INFO - Return code: 0 16:38:54 INFO - grabbing minidump binary from tooltool 16:38:54 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')]} 16:38:54 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 0x10c796d30>, '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 0x10c5fadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c78db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:38:54 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 16:38:54 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 16:38:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:38:54 INFO - Return code: 0 16:38:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:38:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:38:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:38:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:38:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:38:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:38:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:38:56 INFO - Return code: 0 16:38:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0378', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 16:38:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0378 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 16:38:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.epUbqUHVQr/Render', 16:38:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:38:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:38:56 INFO - 'HOME': '/Users/cltbld', 16:38:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:38:56 INFO - 'LOGNAME': 'cltbld', 16:38:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:38:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:38:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:38:56 INFO - 'MOZ_NO_REMOTE': '1', 16:38:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:38:56 INFO - 'NO_EM_RESTART': '1', 16:38:56 INFO - 'PAGER': '/bin/cat', 16:38:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:38:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:38:56 INFO - 'PWD': '/builds/slave/test', 16:38:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:38:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:38:56 INFO - 'SHELL': '/bin/bash', 16:38:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gUD9Mam6Cu/Listeners', 16:38:56 INFO - 'TMPDIR': '/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/', 16:38:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:38:56 INFO - 'USER': 'cltbld', 16:38:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:38:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:38:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:38:56 INFO - 'XPC_FLAGS': '0x0', 16:38:56 INFO - 'XPC_SERVICE_NAME': '0', 16:38:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:38:56 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0378', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 16:38:56 INFO - mozversion application_buildid: 20160526140250 16:38:56 INFO - mozversion application_changeset: 2ee4473c729acb2ba7dc723e7affe79ce14bff85 16:38:56 INFO - mozversion application_display_name: Firefox 16:38:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:38:56 INFO - mozversion application_name: Firefox 16:38:56 INFO - mozversion application_remotingname: firefox 16:38:56 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 16:38:56 INFO - mozversion application_vendor: Mozilla 16:38:56 INFO - mozversion application_version: 47.0 16:38:56 INFO - mozversion platform_buildid: 20160526140250 16:38:56 INFO - mozversion platform_changeset: 2ee4473c729acb2ba7dc723e7affe79ce14bff85 16:38:56 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 16:38:56 INFO - mozversion platform_version: 47.0 16:38:56 INFO - using testdate: 1464651536 16:38:56 INFO - actual date: 1464651536 16:38:56 INFO - starting webserver on 'localhost:54525' 16:38:56 INFO - SUITE-START | Running 2 tests 16:38:56 INFO - TEST-START | dromaeo_css 16:38:56 INFO - operating with platform_type : mac_ 16:38:56 INFO - Initialising browser for dromaeo_css test... 16:38:56 INFO - TEST-INFO | started process 2299 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/tmpR0f8cS/profile http://localhost:54525/getInfo.html) 16:38:58 INFO - PROCESS | 2299 | __metrics Screen width/height:1600/1200 16:38:58 INFO - PROCESS | 2299 | colorDepth:24 16:38:58 INFO - PROCESS | 2299 | Browser inner width/height: 1024/689 16:38:58 INFO - PROCESS | 2299 | __metrics 16:38:58 INFO - PROCESS | 2299 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:38:58 INFO - PROCESS | 2299 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:38:59 INFO - TEST-INFO | 2299: exit 0 16:38:59 INFO - Browser initialized. 16:38:59 INFO - Running cycle 1/1 for dromaeo_css test... 16:38:59 INFO - TEST-INFO | started process 2302 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/tmpR0f8cS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:39:11 INFO - PROCESS | 2302 | RSS: Main: 157159424 16:39:11 INFO - PROCESS | 2302 | 16:40:02 INFO - PROCESS | 2302 | 1464651602390 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] 16:42:12 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:54525/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54525/tests/dromaeo/cssquery-ext.html) 16:42:13 INFO - PROCESS | 2302 | RSS: Main: 297267200 16:42:13 INFO - PROCESS | 2302 | 16:45:15 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:54525/tests/dromaeo/cssquery-ext.html (next: http://localhost:54525/tests/dromaeo/cssquery-jquery.html) 16:45:16 INFO - PROCESS | 2302 | RSS: Main: 297734144 16:45:16 INFO - PROCESS | 2302 | 16:48:18 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:54525/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54525/tests/dromaeo/cssquery-mootools.html) 16:48:19 INFO - PROCESS | 2302 | RSS: Main: 298143744 16:48:19 INFO - PROCESS | 2302 | 16:51:21 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:54525/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54525/tests/dromaeo/cssquery-prototype.html) 16:51:21 INFO - PROCESS | 2302 | RSS: Main: 257204224 16:51:21 INFO - PROCESS | 2302 | 16:54:24 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:54525/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54525/tests/dromaeo/cssquery-yui.html) 16:54:25 INFO - PROCESS | 2302 | RSS: Main: 258080768 16:54:25 INFO - PROCESS | 2302 | 16:57:27 INFO - PROCESS | 2302 | Cycle 1(1): loaded http://localhost:54525/tests/dromaeo/cssquery-yui.html (next: http://localhost:54525/tests/dromaeo/cssquery-dojo.html) 16:57:27 INFO - PROCESS | 2302 | RSS: Main: 304173056 16:57:27 INFO - PROCESS | 2302 | 16:57:27 INFO - PROCESS | 2302 | __start_tp_report 16:57:27 INFO - PROCESS | 2302 | _x_x_mozilla_page_load 16:57:27 INFO - PROCESS | 2302 | _x_x_mozilla_page_load_details 16:57:27 INFO - PROCESS | 2302 | |i|pagename|runs| 16:57:27 INFO - PROCESS | 2302 | |0;dojo.html;3546.414841551144;3585.480105384719;3589.730770192233;3611;3667.046567892349;5834.620749651275;5838.9999999999945;5863.824085277449;5890.88218235635;5914.319853216876;5843.18195452637;5867.002609556375;5869.266341707286;5882.588218824684;5908.054711246202;5192.117340052181;5216.191490319006;5225.294585231002;5284.154535274357;5284.44513326101;4932.728699921503;5397.649152805075;5416.1063424534905;5417.377001644822;5417.64785288957;5064.772085256172;5239.973800131013;5285.550728188108;5298.629095963284;5311.309529761145;5289.127293996486;5332.173513105475;5340.172273297626;5349.251104845324;5386.3536375634785;4313.309870420718;5060.645754797166;5237.109691352479;5257.658252213594;5266.262723218752;5660.943390566124;5710.71446427677;5729.914051289234;5737.88524229513;5749.942500574983;6169.814905552796;6218.782342618032;6230.532710046765;6231.626102433868;6248.625082495065;6151.938480615226;6157.599756015845;6184.8763024739255;6217.502599792006;6223.502119830403;5960.105984102419;5982.072778719349;5984.700764961822;5998.220231369937;6010.502730014266;6190.876182476325;6228.906566401508;6277.717502712298;6298.559100862987;6299.992001279773;6213.96893015532;6245.75016999324;6248.125262463259;6251.3123556408755;6261.24865016201;6212.47193988507;6227.844303892439;6229.719662615194;6232.688365581703;6232.875342493216;6138.631682099089;6144.9246381882995;6152.600080994721;6220.160278362363;6232.657203853832;6185.505159587222;6196.0705894116245;6205.069239614094;6219.782307619212;6228.190335256388;4835.694362522099;5000.924986125211;5003.274525193827;5003.2995380646735;5035.521625445577;4296.37702533131;4464.486584042812;4531.18438681034;4566.246569316026;4589.472210695746;5801.129830525455;5841.153032810219;5851.1223316502865;5852.356240813507;5867.853303667443;6086.878262434815;6173.290071641819;6196.8760624787255;6214.782482613087;6219.440250377488;5797.826065218051;5872.677002764804;5876.882462350729;5878.088896221093;5886.470217680429;5439.809606663827;5459.235707001023;5464.426235245242;5477.616566840283;5484.122540393598;6262.561620686508;6267.811965641038;6315.778947736807;6360.52296077796;6395.904061438989;6208.192934918432;6229.595076320024;6241.157443745036;6241.469475094668;6249.531285153541;6306.211723534559;6336.112944187909;6362;6374.362563743589;6381.776637817654;6143.818944274897;6162.106494558354;6167;6239.844003899939;6242.625442473466;6182.907256391248;6197.349278325711;6200.100985357189;6212.254529456494;6214.689265536615;5048.5203905628905;6200.3799620037635;6206.379362063757;6231.2834024086;6239.719212635351;6139.8601398601395;6204.224471941007;6205.844853878689;6209;6236.906446403398;5891.293044834647;6010.098485227076;6032.734070479717;6040.909386359121;6075.483583895419;6040.033594624839;6145.3407579954055;6191;6207.65857877803;6211.9068213977735;6134.723937422737;6136.251168416979;6142.907856382247;6154;6156.384361563808 16:57:27 INFO - PROCESS | 2302 | |1;ext.html;444.06524266419166;548.4816848078567;604.6583680220675;642.8264368620471;667.046124042619;31847.68152318479;32456.513152302734;32479.350412991727;32539.349213015725;32615.51076733848;33503.83248083756;41879;42160;42313;42381.36427953583;9637.37357071788;9813.509324533781;9824.90175098247;9833.803323933516;9847.753806154831;9629.662961796339;9780.853287200698;9790.804183916318;9800.31397802154;9802.41185528867;9719.222462203043;9779.168770654504;9785.951070244639;9788.951055244714;9789.657361992331;9714.65998690046;9834.31159818813;9839.458829764324;9849.50752462374;9861.704148875546;9778.07108324708;9922.9503852481;9924.652637157666;9936.105750482491;9945.552450139761;41844.18791838947;41899.37150942738;42295.365569516485;42660;42856.57143428557;3236.255661197914;3312.6521715220038;3400.96599034009;3412.744044196695;3427.5715535558056;28520;28800.847966081332;29609.70390296091;30293;30916.381672366428;2773.972260277392;2796.14717511159;2846.729560691731;2885.3940672458807;2910.1124157132067;497672;499211.9999999964;499682;500530;500724.49637751945;47008.764956175;69227;69904;70014.29985700078;70222.2977770226;46319.07361852744;46452.838679033295;46476;46626.53373466222;46684.29973550399;16901.7464738029;17241.224144913507;17306.048167350666;17310.13449327554;17327.22027508765;13759;13867.167969921635;13945.093568917988;13958.860411395959;13983.230922299168;14897.776533351791;14907.478238261825;15132.319045642973;15139.243037848064;15278.541643750705;5405.783698667847;5507.972460137674;6379.904301435482;6409.134766806422;6458.257300410419;1009.9697009089739;1057.9047885690325;1066.2909165405074;1108.3682301088302;1230.501646833034;29521.114366569036;29973.651185696697;30373.24066898301;30404.391912161635;30610.54084188739;1022.8135362978909;1026.3226270661328;1255.215490318551;1287.1826390242077;1292.4184117147322;12039.458224379923;15503.5348939532;15540.456084037005;15825.129617871127;16008.919955400148;545274;1081436;1095373;1097469;1110668;64490.677546611965;64802.67598662071;65324;65371.67314163399;65390.67304663446;1387.569853345466;1411.9929400352933;1526.9921851577906;1549.945751898678;1554.1452201289471;29844.403111937205;31539.21151971219;31543.684563154075;31862.044138675876;32095.839520802245;53433;54047.729761352515;54091.91816163733;54185;54190;414395;415862;417214;417756;417870;31072.844635776677;32839.835800820845;32900;32963.505547417284;34172.82913585416;41969.79015104905;44781.32828007518;44821.32768008417;46398.30402543897;46401.53598463972;37073.88778336654;37150.256994860494;37191.44212836864;37692.62307376891;37980.67067652619;36170.01064941454;36619.450708238866;36841.447378289886;37213.81393093017;37423.06442338855;36139.457908131924;36650.8167459161;36941.815290923376;37429.62570374261;37785;31636;32188.654531638866;32708;33578.83210583931;34555;24391;24432;24766.752332477165;24835.875820621502;24885.875570622757 16:57:27 INFO - PROCESS | 2302 | |2;jquery.html;375.93421151298526;384.4982298100977;384.5884903153626;386.0368380889679;389.67851522493913;19750.506237344056;21109;21469.463263418424;21822.90885455725;21840.890795546;12214.206076605014;12582.245065296089;20749.896250518726;21539.784602153974;21550.676739848914;5259.737013149337;5405.567554595623;5456.8090116845915;5457.454254574531;5462.262594549725;4191.266528357541;4371.060222052258;5858.853528661796;6107.083937409379;6124;5953.83900139473;6093.847653808646;6105.297890742555;6105.664188469633;6105.938940610581;6568.7700930467445;6608.768693095719;6625.006249062631;6625.1387319648375;6636.17047869014;5768.682722450264;5775.191473193756;5830.38780928005;5846.123081537761;5861.824145275648;14013.299335033309;14308.713825723531;14374.496892608708;14395.136291822419;15577.532674019798;2139.5934772393352;2169.0998235732154;2343.8359314847967;2467.642191882167;2617.332580192058;10113.544890479947;10151.898481015169;10200.94899525505;10438.895611043868;10512.421816800073;1667;1759.1028575426496;1770.362669439001;1801.5874196251066;1822.9645799871753;107446;111059.44470277677;113175;113807;114316.99999999917;9609.423434593948;11651.096882544443;11698.415079246004;11709.707257318636;11964.461599228056;14551.490697825526;14565.708685826225;14623.634409139644;14631.26843657834;14646.707065858624;8876.8668469973;9045;9072.54637268134;9145.268378529701;9152.084791520794;9300.302477314228;9303.58133883977;9316.068393160765;9366.859497107549;9449.858252126223;3981.3828856527284;4533.54664533544;4541.250693635513;4543.772811359485;4572.039871626929;1767.2907452044049;1768.6993211154136;1822.9978957791807;1856.2574970011833;1902.8953407562717;940.586142097475;943.5235206220815;976.5166242709904;1002.3033991376069;1032.5353590884133;17467.737983930252;20921.79078209229;21007.8949605251;21187.15251389958;21511.354659360244;995.7908839143716;996.9202463802878;1017.730301470111;1019.90820826126;1039.5374058543875;11326.754057053717;11637.592684256011;11644.6506604802;11657.125715571197;11684;577298;652022;654686;671178;696050;117772;119172.40413798048;119242;119996.99999999825;123943;1039.26212389203;1041.1514615588271;1057.1331508163387;1058.6823952814125;1059.4861492176287;18203.726944096114;18862.905685471484;19075.809241907402;19084.427467176007;19350.32273870408;51010;51941.70145746235;52430;53270.66823329448;53284;71483.28516714767;73613.26386736064;73737.52524949578;73788.52422951403;74558.79853216033;8156.877646835183;8290.585470726319;8417.494950303002;8502.78743031429;8745.73186887885;10150.390976541432;12800.61598152057;13333.599992000256;13655.726885462036;13747.312634368041;11492.770144597229;11625.5349786011;11642.825357619811;11786.233894796811;11865.822012669643;9180.357374983687;11376.94311528437;11539.692061587548;11646.825297620711;11846.58536951237;11368.602098926498;11605.187636865337;11704.297742135499;11809.527618895492;11884.405779711153;7350.154732205865;8129.146439623991;8259.545724984942;8354.206350396702;8374.87437688423;8946.821063578822;11819.290842549475;11920.986716129226;12006.459709312927;12011.939940300243 16:57:27 INFO - PROCESS | 2302 | |3;mootools.html;1424.9928750356246;1503.1056521369794;1659.8838081334304;1909.3412772593458;2067.5037990882183;8243.258106770389;8817.647294108236;8958.41770284931;8988.101189881008;9005.414648047881;8738.563071846414;8937.150970657776;8945.642174313036;8955.731328060152;8963.775905602362;2183.5851188274255;2504.8371855829405;2533.784628306596;2536.226450932465;2539.301692034696;2472.554940110779;2575.0858445251947;2581.3288544978345;2583.715791262969;2599;2545.1728188338866;2565.076572433922;2567.42232997575;2567.512172687193;2567.9984805925706;2532.088448158652;2536.9112081077164;2545.5418024755536;2545.809064320174;2580.4839032193636;2530.4812513434704;2556.590945448719;2564.3717289264064;2567.3838278813137;2577.1881857214917;7785.844283114306;8350;8516.403851730382;9078.86381704275;9116.635334586608;5599.188117722948;5812.941870581282;5826.563007774392;5828.766849326022;5828.912566311508;3678.742488025839;3715.777053376806;3794.7343685941996;3844.5770965193806;3856.710746693981;1045.6288017753664;1087.8912108789136;1087.9347239165597;1124.5389390349917;1127.971800704989;12315.384230788515;12353.75292494154;12356.073294502949;12384.318862462562;12411.379431028503;4468.888277793016;4872.78072486739;4909.165441874889;4956.950430495721;5014.52362025607;10483.108935740394;10485.37087774736;10504.317219380715;10516;10531.210159238088;5786.987277226553;5791.218185544982;5810.21562089121;5848.707564621752;5848.9707551462825;4634.374359461497;4805.13507568641;4976.651634385558;5096.592272618182;5223.973880130575;3971.4439978403047;4015.4579131817413;4017.5580686124504;4064.4919385076864;4077.6941729370415;1145.5532342386475;1337.5719769673806;1341.3829638366265;1354.6816498122932;1356.8100465934776;1427.122319773332;1431.9427222911174;1433.032702925521;1433.0613448191452;1455.5633310006956;8505.362097842686;8521.190486903733;8523.48859068458;8524.57377131141;8527.40308178434;1300.0964329790893;1339.6115126613172;1365.8429280632656;1365.9180449173082;1371.8079468874366;6329.841753956188;6397.4562162216735;6424.3254458282145;6424.903626445607;6428.903566446507;10552.947235263775;10783.892161078289;10821.67534973952;10941.507632156572;10965.50655220517;10578.206634502289;10593.417362045162;10720.73198170052;10796.460176991277;10814.837777433344;8431.789205269795;8617.138286171457;8664.783380415414;8675.609597568124;8678.652853885775;7952.165022672536;8321.6671333146;8358.916410835815;8438.367122465717;8636.956815215884;8511.702090426805;9826.213902887896;10092.34399764013;10164.84752728695;10191.592336306465;10057.19542436618;10271.691849244535;10486.108680761916;10505.31715438494;10520.316179448311;7761;7887.132415434412;8155.51066935962;8436.198561136927;8451.366147539082;8512.829743405222;8543.188397102265;8585.313174946114;8601.967763868126;8611.181937715906;7499.437542184467;7617.314441700273;7670.501417407851;7743.225677432211;7771.766846994599;7463.738769143054;7480.438967077382;7559.508631938907;7578.507397019176;7736.1490236075115;8046.436749427484;8081.030276366873;8089;8093.635786389745;8096.311813495683;7202.53183543068;7338.339549440362;7344.449166312655;7375.852482950419;7379.151397589346;7454.769962956051;7468.663910123949;7507.512011719221;7515.586642734702;7529.209433009455 16:57:27 INFO - PROCESS | 2302 | |4;prototype.html;331.0482363205783;340.74614412262866;342.67788279017714;342.7497926513643;347.21529343683267;5436.782528698857;6621.933780662192;6623.966880165598;6647.468202543803;6694.531382803206;5222.268882356464;5376.892462150755;6468.5148613854035;6553.115329430538;6607.933920660792;4707.152712511746;4781.665283430161;4838.806447742086;4873.731944743037;4983.2275997220395;4238.872833814975;4617.607503362202;4855.975720121412;4879.609631229511;4928.728919909404;4863.14894893394;4916.631252656047;4923.655344125913;4926.236433352836;4945.826896058638;4649.093426781779;4791.496892826255;4875.878103047452;4887.608991280689;4923.507649235084;4218.810153543098;4224.5353011168745;4225.492940847087;4239.512456067561;4246.5541118182455;6261.217347831521;6303.338149494283;6307.905381419282;6314.494840412756;6317.620942743404;5364.968792632189;5812.9709351452975;5828.271466066742;5835.766569337221;5855.882882342372;6236.596765727721;6858;7124.821879453003;7149.106361704787;7155.785326440215;4752.168370535177;4774.880627984294;4776.426828780569;4784.712917224978;4797.904041919142;7422.814429639248;7431;7440.73957411488;7442.255774422569;7483.962580187119;4869.488703686133;4871.14754917888;4873.244647079709;4874.5856602189215;4878;4912.606991440676;4926.261060840902;4932.28481870134;4937.580305674058;4939.876503087452;4987.351644286292;5737.627054241461;5757.740901659436;5771.451712087345;5793.188953546507;5712.257406537206;6708.228553716385;7007.229204787469;7021.368076873106;7031.191412987469;1604.294110591336;1639.5901024743814;1651.2074204381963;1656.1719140429784;1660.9252583633765;993.6621548673486;1014.1531820929606;1045.5504133222635;1050.2123407444417;1070.4326706845384;6610.999999999904;6723.361280678328;6730.226024007204;6770.593764374154;6782.389584937379;6412.9098053331045;7135.322144396274;7397.223291554417;7422.740204092831;7429.442791790637;6752.966235168793;6760.966195169091;6761.492888033319;6763.898541521881;6770.1537307836525;3769.6793583185695;3829.617038296148;3884.4173373993563;3887.3002859485564;3890.922181556353;6500.772472963424;7561.941328214054;7572.734954276574;7700.653470593837;7746.49647772893;6217.694284200278;6571.2771595124495;7163.677634506355;7588.203238659971;7613.42899282556;6222.937770622327;7720.648886444849;7724.3434308083315;7732.188120247293;7754.147043825062;6675.132232809704;7057.470689698191;7332.45006624516;7342.743003994835;7381.8523629526035;7162.928370716435;7169.211386747349;7202.099737532809;7206.2793720627515;7212;6177.6911154443;7604.125525564631;7636;7642.388608911163;7660;4676.976615116902;4686.437627484724;4694.319323698113;4694.723059316298;4696.765161741968;7039.331263529956;7269.491135620456;7281.708731650675;7284.927150728425;7287.744928927674;5810.883782324415;7002.684879180347;7008.649567521502;7035.33164349386;7064.576125432491;6984.266652001466;6997.090378250795;7045.753398631024;7056.15326160864;7056.6471676417;5319.946800531945;5645.322561292671;6861.588304701734;6895.241523432318;6900.689468973807;6844.076049733222;7019.5788252705;7026.156861176692;7026.437884969088;7040.295970403124;6724.293949135466;6833.897491537531;6905.309469053255;6923.376896079377;7016.789496315118 16:57:27 INFO - PROCESS | 2302 | |5;yui.html;673.4410439335352;701.4003027411566;780.3088515975508;791.509264256161;801.9157988411216;15121.395144194234;15303.770443443358;15337.769933451007;15354.232288385578;15356.619723942793;14177;14252.002359834816;14261.714765704679;14271;14277.286135693199;781.3905153979905;796.3629096722627;796.8685166947475;798.2057852436805;960.4429430930079;797.5135167547792;937.1565590968149;965.4689920543708;966.6133546581389;966.6278482784109;1031.1750599520392;1035.2184101003731;1036.9429681367521;1037.8806437259736;1040.173062415378;1008.5209525475414;1025.1900998211404;1026.4816267784795;1027.3168343051898;1029.0738335498074;614.6281499692711;756.3948840927236;772.1892013385923;772.5943879463271;773.9071311442607;15124.773128403083;15304;15524.844751552453;15538.61153471161;15550.455734049254;11381.433021806854;13502.189868607915;13652.726945461036;13852.861471385359;14190.50333238342;10559.208059395576;10584;10708.946455267751;10851.294665846695;10885;3680.7607505512055;3680.815959202056;3732.9440058399146;3741.139537906297;3907.882763517099;85411;86567.56716216379;87371.75769453916;87759;87766;9708.660196893215;9779.364341317934;9798;9850.064243896819;9904.950475247577;21894.78105218959;21927.67108493374;21931.451713706967;21937.780622193575;21946.45133871666;7264.309890560388;7596.316331530078;7688.423368247404;7886.881696774553;7928.484648497829;4314.482262128502;4332.935005974913;4345.391645169679;4541.182587134347;4587.72473651582;3534.8335049433626;3622.166901612644;3641.708663306929;3709.647583479565;3735.701143908481;656.4157899469476;673.572281601177;691.865086308165;694.5763084518439;696.902433659278;592.8280798568453;594.8572342637823;606.1756011823916;613.457090474924;615.8552740106071;8026.237507436901;8209.753707388787;8226.136255693185;8390.622421991025;8442.746717598482;530.6391653675461;575.32686756495;578.8552861784553;586.9727976041927;623.6694551887507;3859.807009649506;3900.9804950975063;3902.785346805953;3917.334053210961;4060.7881076212607;96980.51509742407;97108.51445742726;99819;100030;100441;82370.58814706007;82438;82884;83271;83333;73483;73959;74229.99999999892;74309.25690743132;76104;7606.8098297543;7777;7844.450888437755;7993.12075671664;8178.7955301117945;51297.487025130766;51770;52709;52876.20685689641;53062.204066938255;64378.03432948603;64874.35125648683;65420.67289663521;66439.00341494785;66444.33555664381;9542.952285238529;9626.47054411986;9708.36895601812;10052.095311422006;10278.588856445658;10533.473326333806;10618.256722029384;10919.071878890383;10935.671929842118;10987.429804752306;10195.388276703421;10350.5342259597;10367.533460994424;10472.738181545523;10725.30285531438;10583.153347732317;11488.023518001064;11809.697109063995;11897.881021189678;12108.636740897435;9472.431654100776;10698.83951740709;11710.297382157098;11727.589534366256;11881.643550693492;9609.471479068718;9691.951540242253;9898.851517227104;10126.44304563256;10178.745531361776;9473.242140628596;9511.048895110434;9534.85697714521;9543.735895864713;9992.900070999198 16:57:27 INFO - PROCESS | 2302 | __end_tp_report 16:57:27 INFO - PROCESS | 2302 | __start_cc_report 16:57:27 INFO - PROCESS | 2302 | _x_x_mozilla_cycle_collect,1174 16:57:27 INFO - PROCESS | 2302 | __end_cc_report 16:57:27 INFO - PROCESS | 2302 | __startTimestamp1464652647796__endTimestamp 16:57:27 INFO - PROCESS | 2302 | 16:57:27 INFO - PROCESS | 2302 | ------- Summary: start ------- 16:57:27 INFO - PROCESS | 2302 | Number of tests: 6 16:57:27 INFO - PROCESS | 2302 | 16:57:27 INFO - PROCESS | 2302 | [#0] dojo.html Cycles:165 Average:5798.31 Median:6086.88 stddev:616.19 (10.1%) stddev-sans-first:592.22 16:57:27 INFO - PROCESS | 2302 | Values: 3546.4 3585.5 3589.7 3611.0 3667.0 5834.6 5839.0 5863.8 5890.9 5914.3 5843.2 5867.0 5869.3 5882.6 5908.1 5192.1 5216.2 5225.3 5284.2 5284.4 4932.7 5397.6 5416.1 5417.4 5417.6 5064.8 5240.0 5285.6 5298.6 5311.3 5289.1 5332.2 5340.2 5349.3 5386.4 4313.3 5060.6 5237.1 5257.7 5266.3 5660.9 5710.7 5729.9 5737.9 5749.9 6169.8 6218.8 6230.5 6231.6 6248.6 6151.9 6157.6 6184.9 6217.5 6223.5 5960.1 5982.1 5984.7 5998.2 6010.5 6190.9 6228.9 6277.7 6298.6 6300.0 6214.0 6245.8 6248.1 6251.3 6261.2 6212.5 6227.8 6229.7 6232.7 6232.9 6138.6 6144.9 6152.6 6220.2 6232.7 6185.5 6196.1 6205.1 6219.8 6228.2 4835.7 5000.9 5003.3 5003.3 5035.5 4296.4 4464.5 4531.2 4566.2 4589.5 5801.1 5841.2 5851.1 5852.4 5867.9 6086.9 6173.3 6196.9 6214.8 6219.4 5797.8 5872.7 5876.9 5878.1 5886.5 5439.8 5459.2 5464.4 5477.6 5484.1 6262.6 6267.8 6315.8 6360.5 6395.9 6208.2 6229.6 6241.2 6241.5 6249.5 6306.2 6336.1 6362.0 6374.4 6381.8 6143.8 6162.1 6167.0 6239.8 6242.6 6182.9 6197.3 6200.1 6212.3 6214.7 5048.5 6200.4 6206.4 6231.3 6239.7 6139.9 6204.2 6205.8 6209.0 6236.9 5891.3 6010.1 6032.7 6040.9 6075.5 6040.0 6145.3 6191.0 6207.7 6211.9 6134.7 6136.3 6142.9 6154.0 6156.4 16:57:27 INFO - PROCESS | 2302 | 16:57:27 INFO - PROCESS | 2302 | [#1] ext.html Cycles:180 Average:75353.36 Median:37007.85 stddev:188420.65 (509.1%) stddev-sans-first:188865.27 16:57:27 INFO - PROCESS | 2302 | Values: 444.1 548.5 604.7 642.8 667.0 31847.7 32456.5 32479.4 32539.3 32615.5 33503.8 41879.0 42160.0 42313.0 42381.4 9637.4 9813.5 9824.9 9833.8 9847.8 9629.7 9780.9 9790.8 9800.3 9802.4 9719.2 9779.2 9786.0 9789.0 9789.7 9714.7 9834.3 9839.5 9849.5 9861.7 9778.1 9923.0 9924.7 9936.1 9945.6 41844.2 41899.4 42295.4 42660.0 42856.6 3236.3 3312.7 3401.0 3412.7 3427.6 28520.0 28800.8 29609.7 30293.0 30916.4 2774.0 2796.1 2846.7 2885.4 2910.1 497672.0 499212.0 499682.0 500530.0 500724.5 47008.8 69227.0 69904.0 70014.3 70222.3 46319.1 46452.8 46476.0 46626.5 46684.3 16901.7 17241.2 17306.0 17310.1 17327.2 13759.0 13867.2 13945.1 13958.9 13983.2 14897.8 14907.5 15132.3 15139.2 15278.5 5405.8 5508.0 6379.9 6409.1 6458.3 1010.0 1057.9 1066.3 1108.4 1230.5 29521.1 29973.7 30373.2 30404.4 30610.5 1022.8 1026.3 1255.2 1287.2 1292.4 12039.5 15503.5 15540.5 15825.1 16008.9 545274.0 1081436.0 1095373.0 1097469.0 1110668.0 64490.7 64802.7 65324.0 65371.7 65390.7 1387.6 1412.0 1527.0 1549.9 1554.1 29844.4 31539.2 31543.7 31862.0 32095.8 53433.0 54047.7 54091.9 54185.0 54190.0 414395.0 415862.0 417214.0 417756.0 417870.0 31072.8 32839.8 32900.0 32963.5 34172.8 41969.8 44781.3 44821.3 46398.3 46401.5 37073.9 37150.3 37191.4 37692.6 37980.7 36170.0 36619.5 36841.4 37213.8 37423.1 36139.5 36650.8 36941.8 37429.6 37785.0 31636.0 32188.7 32708.0 33578.8 34555.0 24391.0 24432.0 24766.8 24835.9 24885.9 16:57:27 INFO - PROCESS | 2302 | 16:57:27 INFO - PROCESS | 2302 | [#2] jquery.html Cycles:180 Average:35699.16 Median:11663.37 stddev:107947.89 (925.5%) stddev-sans-first:108218.12 16:57:27 INFO - PROCESS | 2302 | Values: 375.9 384.5 384.6 386.0 389.7 19750.5 21109.0 21469.5 21822.9 21840.9 12214.2 12582.2 20749.9 21539.8 21550.7 5259.7 5405.6 5456.8 5457.5 5462.3 4191.3 4371.1 5858.9 6107.1 6124.0 5953.8 6093.8 6105.3 6105.7 6105.9 6568.8 6608.8 6625.0 6625.1 6636.2 5768.7 5775.2 5830.4 5846.1 5861.8 14013.3 14308.7 14374.5 14395.1 15577.5 2139.6 2169.1 2343.8 2467.6 2617.3 10113.5 10151.9 10200.9 10438.9 10512.4 1667.0 1759.1 1770.4 1801.6 1823.0 107446.0 111059.4 113175.0 113807.0 114317.0 9609.4 11651.1 11698.4 11709.7 11964.5 14551.5 14565.7 14623.6 14631.3 14646.7 8876.9 9045.0 9072.5 9145.3 9152.1 9300.3 9303.6 9316.1 9366.9 9449.9 3981.4 4533.5 4541.3 4543.8 4572.0 1767.3 1768.7 1823.0 1856.3 1902.9 940.6 943.5 976.5 1002.3 1032.5 17467.7 20921.8 21007.9 21187.2 21511.4 995.8 996.9 1017.7 1019.9 1039.5 11326.8 11637.6 11644.7 11657.1 11684.0 577298.0 652022.0 654686.0 671178.0 696050.0 117772.0 119172.4 119242.0 119997.0 123943.0 1039.3 1041.2 1057.1 1058.7 1059.5 18203.7 18862.9 19075.8 19084.4 19350.3 51010.0 51941.7 52430.0 53270.7 53284.0 71483.3 73613.3 73737.5 73788.5 74558.8 8156.9 8290.6 8417.5 8502.8 8745.7 10150.4 12800.6 13333.6 13655.7 13747.3 11492.8 11625.5 11642.8 11786.2 11865.8 9180.4 11376.9 11539.7 11646.8 11846.6 11368.6 11605.2 11704.3 11809.5 11884.4 7350.2 8129.1 8259.5 8354.2 8374.9 8946.8 11819.3 11921.0 12006.5 12011.9 16:57:27 INFO - PROCESS | 2302 | 16:57:27 INFO - PROCESS | 2302 | [#3] mootools.html Cycles:180 Average:6285.82 Median:4838.96 stddev:3267.04 (67.5%) stddev-sans-first:3255.77 16:57:27 INFO - PROCESS | 2302 | Values: 1425.0 1503.1 1659.9 1909.3 2067.5 8243.3 8817.6 8958.4 8988.1 9005.4 8738.6 8937.2 8945.6 8955.7 8963.8 2183.6 2504.8 2533.8 2536.2 2539.3 2472.6 2575.1 2581.3 2583.7 2599.0 2545.2 2565.1 2567.4 2567.5 2568.0 2532.1 2536.9 2545.5 2545.8 2580.5 2530.5 2556.6 2564.4 2567.4 2577.2 7785.8 8350.0 8516.4 9078.9 9116.6 5599.2 5812.9 5826.6 5828.8 5828.9 3678.7 3715.8 3794.7 3844.6 3856.7 1045.6 1087.9 1087.9 1124.5 1128.0 12315.4 12353.8 12356.1 12384.3 12411.4 4468.9 4872.8 4909.2 4957.0 5014.5 10483.1 10485.4 10504.3 10516.0 10531.2 5787.0 5791.2 5810.2 5848.7 5849.0 4634.4 4805.1 4976.7 5096.6 5224.0 3971.4 4015.5 4017.6 4064.5 4077.7 1145.6 1337.6 1341.4 1354.7 1356.8 1427.1 1431.9 1433.0 1433.1 1455.6 8505.4 8521.2 8523.5 8524.6 8527.4 1300.1 1339.6 1365.8 1365.9 1371.8 6329.8 6397.5 6424.3 6424.9 6428.9 10552.9 10783.9 10821.7 10941.5 10965.5 10578.2 10593.4 10720.7 10796.5 10814.8 8431.8 8617.1 8664.8 8675.6 8678.7 7952.2 8321.7 8358.9 8438.4 8637.0 8511.7 9826.2 10092.3 10164.8 10191.6 10057.2 10271.7 10486.1 10505.3 10520.3 7761.0 7887.1 8155.5 8436.2 8451.4 8512.8 8543.2 8585.3 8602.0 8611.2 7499.4 7617.3 7670.5 7743.2 7771.8 7463.7 7480.4 7559.5 7578.5 7736.1 8046.4 8081.0 8089.0 8093.6 8096.3 7202.5 7338.3 7344.4 7375.9 7379.2 7454.8 7468.7 7507.5 7515.6 7529.2 16:57:27 INFO - PROCESS | 2302 | 16:57:27 INFO - PROCESS | 2302 | [#4] prototype.html Cycles:180 Average:5729.34 Median:6440.71 stddev:1803.56 (28.0%) stddev-sans-first:1762.52 16:57:27 INFO - PROCESS | 2302 | Values: 331.0 340.7 342.7 342.7 347.2 5436.8 6621.9 6624.0 6647.5 6694.5 5222.3 5376.9 6468.5 6553.1 6607.9 4707.2 4781.7 4838.8 4873.7 4983.2 4238.9 4617.6 4856.0 4879.6 4928.7 4863.1 4916.6 4923.7 4926.2 4945.8 4649.1 4791.5 4875.9 4887.6 4923.5 4218.8 4224.5 4225.5 4239.5 4246.6 6261.2 6303.3 6307.9 6314.5 6317.6 5365.0 5813.0 5828.3 5835.8 5855.9 6236.6 6858.0 7124.8 7149.1 7155.8 4752.2 4774.9 4776.4 4784.7 4797.9 7422.8 7431.0 7440.7 7442.3 7484.0 4869.5 4871.1 4873.2 4874.6 4878.0 4912.6 4926.3 4932.3 4937.6 4939.9 4987.4 5737.6 5757.7 5771.5 5793.2 5712.3 6708.2 7007.2 7021.4 7031.2 1604.3 1639.6 1651.2 1656.2 1660.9 993.7 1014.2 1045.6 1050.2 1070.4 6611.0 6723.4 6730.2 6770.6 6782.4 6412.9 7135.3 7397.2 7422.7 7429.4 6753.0 6761.0 6761.5 6763.9 6770.2 3769.7 3829.6 3884.4 3887.3 3890.9 6500.8 7561.9 7572.7 7700.7 7746.5 6217.7 6571.3 7163.7 7588.2 7613.4 6222.9 7720.6 7724.3 7732.2 7754.1 6675.1 7057.5 7332.5 7342.7 7381.9 7162.9 7169.2 7202.1 7206.3 7212.0 6177.7 7604.1 7636.0 7642.4 7660.0 4677.0 4686.4 4694.3 4694.7 4696.8 7039.3 7269.5 7281.7 7284.9 7287.7 5810.9 7002.7 7008.6 7035.3 7064.6 6984.3 6997.1 7045.8 7056.2 7056.6 5319.9 5645.3 6861.6 6895.2 6900.7 6844.1 7019.6 7026.2 7026.4 7040.3 6724.3 6833.9 6905.3 6923.4 7016.8 16:57:27 INFO - PROCESS | 2302 | 16:57:27 INFO - PROCESS | 2302 | [#5] yui.html Cycles:180 Average:18935.61 Median:26818.77 stddev:27179.63 (101.3%) stddev-sans-first:27221.29 16:57:27 INFO - PROCESS | 2302 | Values: 673.4 701.4 780.3 791.5 801.9 15121.4 15303.8 15337.8 15354.2 15356.6 14177.0 14252.0 14261.7 14271.0 14277.3 781.4 796.4 796.9 798.2 960.4 797.5 937.2 965.5 966.6 966.6 1031.2 1035.2 1036.9 1037.9 1040.2 1008.5 1025.2 1026.5 1027.3 1029.1 614.6 756.4 772.2 772.6 773.9 15124.8 15304.0 15524.8 15538.6 15550.5 11381.4 13502.2 13652.7 13852.9 14190.5 10559.2 10584.0 10708.9 10851.3 10885.0 3680.8 3680.8 3732.9 3741.1 3907.9 85411.0 86567.6 87371.8 87759.0 87766.0 9708.7 9779.4 9798.0 9850.1 9905.0 21894.8 21927.7 21931.5 21937.8 21946.5 7264.3 7596.3 7688.4 7886.9 7928.5 4314.5 4332.9 4345.4 4541.2 4587.7 3534.8 3622.2 3641.7 3709.6 3735.7 656.4 673.6 691.9 694.6 696.9 592.8 594.9 606.2 613.5 615.9 8026.2 8209.8 8226.1 8390.6 8442.7 530.6 575.3 578.9 587.0 623.7 3859.8 3901.0 3902.8 3917.3 4060.8 96980.5 97108.5 99819.0 100030.0 100441.0 82370.6 82438.0 82884.0 83271.0 83333.0 73483.0 73959.0 74230.0 74309.3 76104.0 7606.8 7777.0 7844.5 7993.1 8178.8 51297.5 51770.0 52709.0 52876.2 53062.2 64378.0 64874.4 65420.7 66439.0 66444.3 9543.0 9626.5 9708.4 10052.1 10278.6 10533.5 10618.3 10919.1 10935.7 10987.4 10195.4 10350.5 10367.5 10472.7 10725.3 10583.2 11488.0 11809.7 11897.9 12108.6 9472.4 10698.8 11710.3 11727.6 11881.6 9609.5 9692.0 9898.9 10126.4 10178.7 9473.2 9511.0 9534.9 9543.7 9992.9 16:57:27 INFO - PROCESS | 2302 | -------- Summary: end -------- 16:57:27 INFO - PROCESS | 2302 | 16:57:28 INFO - TEST-INFO | 2302: exit 0 16:57:28 INFO - TEST-OK | dromaeo_css | took 1112088ms 16:57:28 INFO - TEST-START | kraken 16:57:28 INFO - operating with platform_type : mac_ 16:57:28 INFO - Initialising browser for kraken test... 16:57:28 INFO - TEST-INFO | started process 2305 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/tmp_5Kl4n/profile http://localhost:54525/getInfo.html) 16:57:30 INFO - PROCESS | 2305 | __metrics Screen width/height:1600/1200 16:57:30 INFO - PROCESS | 2305 | colorDepth:24 16:57:30 INFO - PROCESS | 2305 | Browser inner width/height: 1024/689 16:57:30 INFO - PROCESS | 2305 | __metrics 16:57:30 INFO - PROCESS | 2305 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 16:57:30 INFO - PROCESS | 2305 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:57:31 INFO - TEST-INFO | 2305: exit 0 16:57:31 INFO - Browser initialized. 16:57:31 INFO - Running cycle 1/1 for kraken test... 16:57:31 INFO - TEST-INFO | started process 2308 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/tmp_5Kl4n/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:57:42 INFO - PROCESS | 2308 | RSS: Main: 157065216 16:57:42 INFO - PROCESS | 2308 | 16:57:45 INFO - PROCESS | 2308 | Cycle 1(1): loaded http://localhost:54525/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54525/tests/kraken/driver.html?testName=audio-beat-detection) 16:57:46 INFO - PROCESS | 2308 | RSS: Main: 218189824 16:57:46 INFO - PROCESS | 2308 | 16:57:48 INFO - PROCESS | 2308 | Cycle 1(1): loaded http://localhost:54525/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54525/tests/kraken/driver.html?testName=audio-dft) 16:57:49 INFO - PROCESS | 2308 | RSS: Main: 228659200 16:57:49 INFO - PROCESS | 2308 | 16:57:52 INFO - PROCESS | 2308 | Cycle 1(1): loaded http://localhost:54525/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54525/tests/kraken/driver.html?testName=audio-fft) 16:57:53 INFO - PROCESS | 2308 | RSS: Main: 218255360 16:57:53 INFO - PROCESS | 2308 | 16:57:55 INFO - PROCESS | 2308 | Cycle 1(1): loaded http://localhost:54525/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54525/tests/kraken/driver.html?testName=audio-oscillator) 16:57:55 INFO - PROCESS | 2308 | RSS: Main: 220278784 16:57:55 INFO - PROCESS | 2308 | 16:57:57 INFO - PROCESS | 2308 | Cycle 1(1): loaded http://localhost:54525/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54525/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:57:58 INFO - PROCESS | 2308 | RSS: Main: 219996160 16:57:58 INFO - PROCESS | 2308 | 16:58:01 INFO - PROCESS | 2308 | Cycle 1(1): loaded http://localhost:54525/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54525/tests/kraken/driver.html?testName=imaging-darkroom) 16:58:01 INFO - PROCESS | 2308 | RSS: Main: 220180480 16:58:01 INFO - PROCESS | 2308 | 16:58:04 INFO - PROCESS | 2308 | Cycle 1(1): loaded http://localhost:54525/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54525/tests/kraken/driver.html?testName=imaging-desaturate) 16:58:05 INFO - PROCESS | 2308 | RSS: Main: 220286976 16:58:05 INFO - PROCESS | 2308 | 16:58:07 INFO - PROCESS | 2308 | Cycle 1(1): loaded http://localhost:54525/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54525/tests/kraken/driver.html?testName=json-parse-financial) 16:58:08 INFO - PROCESS | 2308 | RSS: Main: 220520448 16:58:08 INFO - PROCESS | 2308 | 16:58:09 INFO - PROCESS | 2308 | Cycle 1(1): loaded http://localhost:54525/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54525/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:58:10 INFO - PROCESS | 2308 | RSS: Main: 255307776 16:58:10 INFO - PROCESS | 2308 | 16:58:12 INFO - PROCESS | 2308 | Cycle 1(1): loaded http://localhost:54525/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54525/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:58:13 INFO - PROCESS | 2308 | RSS: Main: 256393216 16:58:13 INFO - PROCESS | 2308 | 16:58:15 INFO - PROCESS | 2308 | Cycle 1(1): loaded http://localhost:54525/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54525/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:58:15 INFO - PROCESS | 2308 | RSS: Main: 261394432 16:58:15 INFO - PROCESS | 2308 | 16:58:18 INFO - PROCESS | 2308 | Cycle 1(1): loaded http://localhost:54525/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54525/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:58:18 INFO - PROCESS | 2308 | RSS: Main: 263360512 16:58:18 INFO - PROCESS | 2308 | 16:58:21 INFO - PROCESS | 2308 | Cycle 1(1): loaded http://localhost:54525/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54525/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:58:21 INFO - PROCESS | 2308 | RSS: Main: 263540736 16:58:21 INFO - PROCESS | 2308 | 16:58:23 INFO - PROCESS | 2308 | Cycle 1(1): loaded http://localhost:54525/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54525/tests/kraken/driver.html?testName=ai-astar) 16:58:23 INFO - PROCESS | 2308 | RSS: Main: 325570560 16:58:23 INFO - PROCESS | 2308 | 16:58:23 INFO - PROCESS | 2308 | __start_tp_report 16:58:23 INFO - PROCESS | 2308 | _x_x_mozilla_page_load 16:58:23 INFO - PROCESS | 2308 | _x_x_mozilla_page_load_details 16:58:23 INFO - PROCESS | 2308 | |i|pagename|runs| 16:58:23 INFO - PROCESS | 2308 | |0;ai-astar;111;113;112;113;113;113;130;114;111;112 16:58:23 INFO - PROCESS | 2308 | |1;audio-beat-detection;155;120;155;150;115;140;125;146;176;147 16:58:23 INFO - PROCESS | 2308 | |2;audio-dft;185;184;187;195;258;206;184;184;186;184 16:58:23 INFO - PROCESS | 2308 | |3;audio-fft;74;78;74;119;55;57;57;57;117;56 16:58:23 INFO - PROCESS | 2308 | |4;audio-oscillator;90;95;128;79;81;84;117;76;78;117 16:58:23 INFO - PROCESS | 2308 | |5;imaging-gaussian-blur;89;86;89;87;90;86;90;86;89;86 16:58:23 INFO - PROCESS | 2308 | |6;imaging-darkroom;106;146;94;94;94;93;93;94;93;94 16:58:23 INFO - PROCESS | 2308 | |7;imaging-desaturate;86;85;87;85;86;87;86;89;87;87 16:58:23 INFO - PROCESS | 2308 | |8;json-parse-financial;53;57;57;58;58;58;57;56;69;51 16:58:23 INFO - PROCESS | 2308 | |9;json-stringify-tinderbox;62;65;68;66;75;70;70;67;67;67 16:58:23 INFO - PROCESS | 2308 | |10;stanford-crypto-aes;81;80;81;81;80;80;83;80;79;87 16:58:23 INFO - PROCESS | 2308 | |11;stanford-crypto-ccm;125;128;134;126;119;116;116;119;137;131 16:58:23 INFO - PROCESS | 2308 | |12;stanford-crypto-pbkdf2;140;123;134;136;124;121;165;133;136;133 16:58:23 INFO - PROCESS | 2308 | |13;stanford-crypto-sha256-iterative;67;65;65;67;66;66;66;68;64;64 16:58:23 INFO - PROCESS | 2308 | __end_tp_report 16:58:23 INFO - PROCESS | 2308 | __start_cc_report 16:58:23 INFO - PROCESS | 2308 | _x_x_mozilla_cycle_collect,1208 16:58:23 INFO - PROCESS | 2308 | __end_cc_report 16:58:23 INFO - PROCESS | 2308 | __startTimestamp1464652703811__endTimestamp 16:58:23 INFO - PROCESS | 2308 | 16:58:23 INFO - PROCESS | 2308 | ------- Summary: start ------- 16:58:23 INFO - PROCESS | 2308 | Number of tests: 14 16:58:23 INFO - PROCESS | 2308 | 16:58:23 INFO - PROCESS | 2308 | [#0] ai-astar Cycles:10 Average:114.20 Median:113.00 stddev:5.63 (5.0%) stddev-sans-first:5.85 16:58:23 INFO - PROCESS | 2308 | Values: 111.0 113.0 112.0 113.0 113.0 113.0 130.0 114.0 111.0 112.0 16:58:23 INFO - PROCESS | 2308 | 16:58:23 INFO - PROCESS | 2308 | [#1] audio-beat-detection Cycles:10 Average:142.90 Median:148.50 stddev:18.55 (12.5%) stddev-sans-first:19.15 16:58:23 INFO - PROCESS | 2308 | Values: 155.0 120.0 155.0 150.0 115.0 140.0 125.0 146.0 176.0 147.0 16:58:23 INFO - PROCESS | 2308 | 16:58:23 INFO - PROCESS | 2308 | [#2] audio-dft Cycles:10 Average:195.30 Median:186.50 stddev:23.14 (12.4%) stddev-sans-first:24.24 16:58:23 INFO - PROCESS | 2308 | Values: 185.0 184.0 187.0 195.0 258.0 206.0 184.0 184.0 186.0 184.0 16:58:23 INFO - PROCESS | 2308 | 16:58:23 INFO - PROCESS | 2308 | [#3] audio-fft Cycles:10 Average:74.40 Median:57.00 stddev:24.59 (43.1%) stddev-sans-first:26.08 16:58:23 INFO - PROCESS | 2308 | Values: 74.0 78.0 74.0 119.0 55.0 57.0 57.0 57.0 117.0 56.0 16:58:23 INFO - PROCESS | 2308 | 16:58:23 INFO - PROCESS | 2308 | [#4] audio-oscillator Cycles:10 Average:94.50 Median:80.00 stddev:19.16 (23.9%) stddev-sans-first:20.25 16:58:23 INFO - PROCESS | 2308 | Values: 90.0 95.0 128.0 79.0 81.0 84.0 117.0 76.0 78.0 117.0 16:58:23 INFO - PROCESS | 2308 | 16:58:23 INFO - PROCESS | 2308 | [#5] imaging-gaussian-blur Cycles:10 Average:87.80 Median:89.00 stddev:1.75 (2.0%) stddev-sans-first:1.80 16:58:23 INFO - PROCESS | 2308 | Values: 89.0 86.0 89.0 87.0 90.0 86.0 90.0 86.0 89.0 86.0 16:58:23 INFO - PROCESS | 2308 | 16:58:23 INFO - PROCESS | 2308 | [#6] imaging-darkroom Cycles:10 Average:100.10 Median:94.00 stddev:16.60 (17.7%) stddev-sans-first:17.47 16:58:23 INFO - PROCESS | 2308 | Values: 106.0 146.0 94.0 94.0 94.0 93.0 93.0 94.0 93.0 94.0 16:58:23 INFO - PROCESS | 2308 | 16:58:23 INFO - PROCESS | 2308 | [#7] imaging-desaturate Cycles:10 Average:86.50 Median:87.00 stddev:1.18 (1.4%) stddev-sans-first:1.24 16:58:23 INFO - PROCESS | 2308 | Values: 86.0 85.0 87.0 85.0 86.0 87.0 86.0 89.0 87.0 87.0 16:58:23 INFO - PROCESS | 2308 | 16:58:23 INFO - PROCESS | 2308 | [#8] json-parse-financial Cycles:10 Average:57.40 Median:57.50 stddev:4.70 (8.2%) stddev-sans-first:4.70 16:58:23 INFO - PROCESS | 2308 | Values: 53.0 57.0 57.0 58.0 58.0 58.0 57.0 56.0 69.0 51.0 16:58:23 INFO - PROCESS | 2308 | 16:58:23 INFO - PROCESS | 2308 | [#9] json-stringify-tinderbox Cycles:10 Average:67.70 Median:67.50 stddev:3.47 (5.1%) stddev-sans-first:3.00 16:58:23 INFO - PROCESS | 2308 | Values: 62.0 65.0 68.0 66.0 75.0 70.0 70.0 67.0 67.0 67.0 16:58:23 INFO - PROCESS | 2308 | 16:58:23 INFO - PROCESS | 2308 | [#10] stanford-crypto-aes Cycles:10 Average:81.20 Median:81.00 stddev:2.30 (2.8%) stddev-sans-first:2.44 16:58:23 INFO - PROCESS | 2308 | Values: 81.0 80.0 81.0 81.0 80.0 80.0 83.0 80.0 79.0 87.0 16:58:23 INFO - PROCESS | 2308 | 16:58:23 INFO - PROCESS | 2308 | [#11] stanford-crypto-ccm Cycles:10 Average:125.10 Median:127.00 stddev:7.49 (5.9%) stddev-sans-first:7.94 16:58:23 INFO - PROCESS | 2308 | Values: 125.0 128.0 134.0 126.0 119.0 116.0 116.0 119.0 137.0 131.0 16:58:23 INFO - PROCESS | 2308 | 16:58:23 INFO - PROCESS | 2308 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:134.50 Median:135.00 stddev:12.45 (9.2%) stddev-sans-first:13.04 16:58:23 INFO - PROCESS | 2308 | Values: 140.0 123.0 134.0 136.0 124.0 121.0 165.0 133.0 136.0 133.0 16:58:23 INFO - PROCESS | 2308 | 16:58:23 INFO - PROCESS | 2308 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.80 Median:66.00 stddev:1.32 (2.0%) stddev-sans-first:1.32 16:58:23 INFO - PROCESS | 2308 | Values: 67.0 65.0 65.0 67.0 66.0 66.0 66.0 68.0 64.0 64.0 16:58:23 INFO - PROCESS | 2308 | -------- Summary: end -------- 16:58:23 INFO - PROCESS | 2308 | 16:58:24 INFO - TEST-INFO | 2308: exit 0 16:58:24 INFO - TEST-OK | kraken | took 56189ms 16:58:24 INFO - SUITE-END | took 1168s 16:58:24 INFO - Completed test suite (00:19:28) 16:58:24 INFO - javascript benchmark 16:58:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3546.414841551144, 3585.480105384719, 3589.730770192233, 3611.0, 3667.046567892349, 5834.620749651275, 5838.9999999999945, 5863.824085277449, 5890.88218235635, 5914.319853216876, 5843.18195452637, 5867.002609556375, 5869.266341707286, 5882.588218824684, 5908.054711246202, 5192.117340052181, 5216.191490319006, 5225.294585231002, 5284.154535274357, 5284.44513326101, 4932.728699921503, 5397.649152805075, 5416.1063424534905, 5417.377001644822, 5417.64785288957, 5064.772085256172, 5239.973800131013, 5285.550728188108, 5298.629095963284, 5311.309529761145, 5289.127293996486, 5332.173513105475, 5340.172273297626, 5349.251104845324, 5386.3536375634785, 4313.309870420718, 5060.645754797166, 5237.109691352479, 5257.658252213594, 5266.262723218752, 5660.943390566124, 5710.71446427677, 5729.914051289234, 5737.88524229513, 5749.942500574983, 6169.814905552796, 6218.782342618032, 6230.532710046765, 6231.626102433868, 6248.625082495065, 6151.938480615226, 6157.599756015845, 6184.8763024739255, 6217.502599792006, 6223.502119830403, 5960.105984102419, 5982.072778719349, 5984.700764961822, 5998.220231369937, 6010.502730014266, 6190.876182476325, 6228.906566401508, 6277.717502712298, 6298.559100862987, 6299.992001279773, 6213.96893015532, 6245.75016999324, 6248.125262463259, 6251.3123556408755, 6261.24865016201, 6212.47193988507, 6227.844303892439, 6229.719662615194, 6232.688365581703, 6232.875342493216, 6138.631682099089, 6144.9246381882995, 6152.600080994721, 6220.160278362363, 6232.657203853832, 6185.505159587222, 6196.0705894116245, 6205.069239614094, 6219.782307619212, 6228.190335256388, 4835.694362522099, 5000.924986125211, 5003.274525193827, 5003.2995380646735, 5035.521625445577, 4296.37702533131, 4464.486584042812, 4531.18438681034, 4566.246569316026, 4589.472210695746, 5801.129830525455, 5841.153032810219, 5851.1223316502865, 5852.356240813507, 5867.853303667443, 6086.878262434815, 6173.290071641819, 6196.8760624787255, 6214.782482613087, 6219.440250377488, 5797.826065218051, 5872.677002764804, 5876.882462350729, 5878.088896221093, 5886.470217680429, 5439.809606663827, 5459.235707001023, 5464.426235245242, 5477.616566840283, 5484.122540393598, 6262.561620686508, 6267.811965641038, 6315.778947736807, 6360.52296077796, 6395.904061438989, 6208.192934918432, 6229.595076320024, 6241.157443745036, 6241.469475094668, 6249.531285153541, 6306.211723534559, 6336.112944187909, 6362.0, 6374.362563743589, 6381.776637817654, 6143.818944274897, 6162.106494558354, 6167.0, 6239.844003899939, 6242.625442473466, 6182.907256391248, 6197.349278325711, 6200.100985357189, 6212.254529456494, 6214.689265536615, 5048.5203905628905, 6200.3799620037635, 6206.379362063757, 6231.2834024086, 6239.719212635351, 6139.8601398601395, 6204.224471941007, 6205.844853878689, 6209.0, 6236.906446403398, 5891.293044834647, 6010.098485227076, 6032.734070479717, 6040.909386359121, 6075.483583895419, 6040.033594624839, 6145.3407579954055, 6191.0, 6207.65857877803, 6211.9068213977735, 6134.723937422737, 6136.251168416979, 6142.907856382247, 6154.0, 6156.384361563808], "unit": "score", "name": "dojo.html", "value": 5761.566090848129}, {"lowerIsBetter": false, "replicates": [444.06524266419166, 548.4816848078567, 604.6583680220675, 642.8264368620471, 667.046124042619, 31847.68152318479, 32456.513152302734, 32479.350412991727, 32539.349213015725, 32615.51076733848, 33503.83248083756, 41879.0, 42160.0, 42313.0, 42381.36427953583, 9637.37357071788, 9813.509324533781, 9824.90175098247, 9833.803323933516, 9847.753806154831, 9629.662961796339, 9780.853287200698, 9790.804183916318, 9800.31397802154, 9802.41185528867, 9719.222462203043, 9779.168770654504, 9785.951070244639, 9788.951055244714, 9789.657361992331, 9714.65998690046, 9834.31159818813, 9839.458829764324, 9849.50752462374, 9861.704148875546, 9778.07108324708, 9922.9503852481, 9924.652637157666, 9936.105750482491, 9945.552450139761, 41844.18791838947, 41899.37150942738, 42295.365569516485, 42660.0, 42856.57143428557, 3236.255661197914, 3312.6521715220038, 3400.96599034009, 3412.744044196695, 3427.5715535558056, 28520.0, 28800.847966081332, 29609.70390296091, 30293.0, 30916.381672366428, 2773.972260277392, 2796.14717511159, 2846.729560691731, 2885.3940672458807, 2910.1124157132067, 497672.0, 499211.9999999964, 499682.0, 500530.0, 500724.49637751945, 47008.764956175, 69227.0, 69904.0, 70014.29985700078, 70222.2977770226, 46319.07361852744, 46452.838679033295, 46476.0, 46626.53373466222, 46684.29973550399, 16901.7464738029, 17241.224144913507, 17306.048167350666, 17310.13449327554, 17327.22027508765, 13759.0, 13867.167969921635, 13945.093568917988, 13958.860411395959, 13983.230922299168, 14897.776533351791, 14907.478238261825, 15132.319045642973, 15139.243037848064, 15278.541643750705, 5405.783698667847, 5507.972460137674, 6379.904301435482, 6409.134766806422, 6458.257300410419, 1009.9697009089739, 1057.9047885690325, 1066.2909165405074, 1108.3682301088302, 1230.501646833034, 29521.114366569036, 29973.651185696697, 30373.24066898301, 30404.391912161635, 30610.54084188739, 1022.8135362978909, 1026.3226270661328, 1255.215490318551, 1287.1826390242077, 1292.4184117147322, 12039.458224379923, 15503.5348939532, 15540.456084037005, 15825.129617871127, 16008.919955400148, 545274.0, 1081436.0, 1095373.0, 1097469.0, 1110668.0, 64490.677546611965, 64802.67598662071, 65324.0, 65371.67314163399, 65390.67304663446, 1387.569853345466, 1411.9929400352933, 1526.9921851577906, 1549.945751898678, 1554.1452201289471, 29844.403111937205, 31539.21151971219, 31543.684563154075, 31862.044138675876, 32095.839520802245, 53433.0, 54047.729761352515, 54091.91816163733, 54185.0, 54190.0, 414395.0, 415862.0, 417214.0, 417756.0, 417870.0, 31072.844635776677, 32839.835800820845, 32900.0, 32963.505547417284, 34172.82913585416, 41969.79015104905, 44781.32828007518, 44821.32768008417, 46398.30402543897, 46401.53598463972, 37073.88778336654, 37150.256994860494, 37191.44212836864, 37692.62307376891, 37980.67067652619, 36170.01064941454, 36619.450708238866, 36841.447378289886, 37213.81393093017, 37423.06442338855, 36139.457908131924, 36650.8167459161, 36941.815290923376, 37429.62570374261, 37785.0, 31636.0, 32188.654531638866, 32708.0, 33578.83210583931, 34555.0, 24391.0, 24432.0, 24766.752332477165, 24835.875820621502, 24885.875570622757], "unit": "score", "name": "ext.html", "value": 19973.893994081085}, {"lowerIsBetter": false, "replicates": [375.93421151298526, 384.4982298100977, 384.5884903153626, 386.0368380889679, 389.67851522493913, 19750.506237344056, 21109.0, 21469.463263418424, 21822.90885455725, 21840.890795546, 12214.206076605014, 12582.245065296089, 20749.896250518726, 21539.784602153974, 21550.676739848914, 5259.737013149337, 5405.567554595623, 5456.8090116845915, 5457.454254574531, 5462.262594549725, 4191.266528357541, 4371.060222052258, 5858.853528661796, 6107.083937409379, 6124.0, 5953.83900139473, 6093.847653808646, 6105.297890742555, 6105.664188469633, 6105.938940610581, 6568.7700930467445, 6608.768693095719, 6625.006249062631, 6625.1387319648375, 6636.17047869014, 5768.682722450264, 5775.191473193756, 5830.38780928005, 5846.123081537761, 5861.824145275648, 14013.299335033309, 14308.713825723531, 14374.496892608708, 14395.136291822419, 15577.532674019798, 2139.5934772393352, 2169.0998235732154, 2343.8359314847967, 2467.642191882167, 2617.332580192058, 10113.544890479947, 10151.898481015169, 10200.94899525505, 10438.895611043868, 10512.421816800073, 1667.0, 1759.1028575426496, 1770.362669439001, 1801.5874196251066, 1822.9645799871753, 107446.0, 111059.44470277677, 113175.0, 113807.0, 114316.99999999917, 9609.423434593948, 11651.096882544443, 11698.415079246004, 11709.707257318636, 11964.461599228056, 14551.490697825526, 14565.708685826225, 14623.634409139644, 14631.26843657834, 14646.707065858624, 8876.8668469973, 9045.0, 9072.54637268134, 9145.268378529701, 9152.084791520794, 9300.302477314228, 9303.58133883977, 9316.068393160765, 9366.859497107549, 9449.858252126223, 3981.3828856527284, 4533.54664533544, 4541.250693635513, 4543.772811359485, 4572.039871626929, 1767.2907452044049, 1768.6993211154136, 1822.9978957791807, 1856.2574970011833, 1902.8953407562717, 940.586142097475, 943.5235206220815, 976.5166242709904, 1002.3033991376069, 1032.5353590884133, 17467.737983930252, 20921.79078209229, 21007.8949605251, 21187.15251389958, 21511.354659360244, 995.7908839143716, 996.9202463802878, 1017.730301470111, 1019.90820826126, 1039.5374058543875, 11326.754057053717, 11637.592684256011, 11644.6506604802, 11657.125715571197, 11684.0, 577298.0, 652022.0, 654686.0, 671178.0, 696050.0, 117772.0, 119172.40413798048, 119242.0, 119996.99999999825, 123943.0, 1039.26212389203, 1041.1514615588271, 1057.1331508163387, 1058.6823952814125, 1059.4861492176287, 18203.726944096114, 18862.905685471484, 19075.809241907402, 19084.427467176007, 19350.32273870408, 51010.0, 51941.70145746235, 52430.0, 53270.66823329448, 53284.0, 71483.28516714767, 73613.26386736064, 73737.52524949578, 73788.52422951403, 74558.79853216033, 8156.877646835183, 8290.585470726319, 8417.494950303002, 8502.78743031429, 8745.73186887885, 10150.390976541432, 12800.61598152057, 13333.599992000256, 13655.726885462036, 13747.312634368041, 11492.770144597229, 11625.5349786011, 11642.825357619811, 11786.233894796811, 11865.822012669643, 9180.357374983687, 11376.94311528437, 11539.692061587548, 11646.825297620711, 11846.58536951237, 11368.602098926498, 11605.187636865337, 11704.297742135499, 11809.527618895492, 11884.405779711153, 7350.154732205865, 8129.146439623991, 8259.545724984942, 8354.206350396702, 8374.87437688423, 8946.821063578822, 11819.290842549475, 11920.986716129226, 12006.459709312927, 12011.939940300243], "unit": "score", "name": "jquery.html", "value": 9504.951109686303}, {"lowerIsBetter": false, "replicates": [1424.9928750356246, 1503.1056521369794, 1659.8838081334304, 1909.3412772593458, 2067.5037990882183, 8243.258106770389, 8817.647294108236, 8958.41770284931, 8988.101189881008, 9005.414648047881, 8738.563071846414, 8937.150970657776, 8945.642174313036, 8955.731328060152, 8963.775905602362, 2183.5851188274255, 2504.8371855829405, 2533.784628306596, 2536.226450932465, 2539.301692034696, 2472.554940110779, 2575.0858445251947, 2581.3288544978345, 2583.715791262969, 2599.0, 2545.1728188338866, 2565.076572433922, 2567.42232997575, 2567.512172687193, 2567.9984805925706, 2532.088448158652, 2536.9112081077164, 2545.5418024755536, 2545.809064320174, 2580.4839032193636, 2530.4812513434704, 2556.590945448719, 2564.3717289264064, 2567.3838278813137, 2577.1881857214917, 7785.844283114306, 8350.0, 8516.403851730382, 9078.86381704275, 9116.635334586608, 5599.188117722948, 5812.941870581282, 5826.563007774392, 5828.766849326022, 5828.912566311508, 3678.742488025839, 3715.777053376806, 3794.7343685941996, 3844.5770965193806, 3856.710746693981, 1045.6288017753664, 1087.8912108789136, 1087.9347239165597, 1124.5389390349917, 1127.971800704989, 12315.384230788515, 12353.75292494154, 12356.073294502949, 12384.318862462562, 12411.379431028503, 4468.888277793016, 4872.78072486739, 4909.165441874889, 4956.950430495721, 5014.52362025607, 10483.108935740394, 10485.37087774736, 10504.317219380715, 10516.0, 10531.210159238088, 5786.987277226553, 5791.218185544982, 5810.21562089121, 5848.707564621752, 5848.9707551462825, 4634.374359461497, 4805.13507568641, 4976.651634385558, 5096.592272618182, 5223.973880130575, 3971.4439978403047, 4015.4579131817413, 4017.5580686124504, 4064.4919385076864, 4077.6941729370415, 1145.5532342386475, 1337.5719769673806, 1341.3829638366265, 1354.6816498122932, 1356.8100465934776, 1427.122319773332, 1431.9427222911174, 1433.032702925521, 1433.0613448191452, 1455.5633310006956, 8505.362097842686, 8521.190486903733, 8523.48859068458, 8524.57377131141, 8527.40308178434, 1300.0964329790893, 1339.6115126613172, 1365.8429280632656, 1365.9180449173082, 1371.8079468874366, 6329.841753956188, 6397.4562162216735, 6424.3254458282145, 6424.903626445607, 6428.903566446507, 10552.947235263775, 10783.892161078289, 10821.67534973952, 10941.507632156572, 10965.50655220517, 10578.206634502289, 10593.417362045162, 10720.73198170052, 10796.460176991277, 10814.837777433344, 8431.789205269795, 8617.138286171457, 8664.783380415414, 8675.609597568124, 8678.652853885775, 7952.165022672536, 8321.6671333146, 8358.916410835815, 8438.367122465717, 8636.956815215884, 8511.702090426805, 9826.213902887896, 10092.34399764013, 10164.84752728695, 10191.592336306465, 10057.19542436618, 10271.691849244535, 10486.108680761916, 10505.31715438494, 10520.316179448311, 7761.0, 7887.132415434412, 8155.51066935962, 8436.198561136927, 8451.366147539082, 8512.829743405222, 8543.188397102265, 8585.313174946114, 8601.967763868126, 8611.181937715906, 7499.437542184467, 7617.314441700273, 7670.501417407851, 7743.225677432211, 7771.766846994599, 7463.738769143054, 7480.438967077382, 7559.508631938907, 7578.507397019176, 7736.1490236075115, 8046.436749427484, 8081.030276366873, 8089.0, 8093.635786389745, 8096.311813495683, 7202.53183543068, 7338.339549440362, 7344.449166312655, 7375.852482950419, 7379.151397589346, 7454.769962956051, 7468.663910123949, 7507.512011719221, 7515.586642734702, 7529.209433009455], "unit": "score", "name": "mootools.html", "value": 5156.553430381439}, {"lowerIsBetter": false, "replicates": [331.0482363205783, 340.74614412262866, 342.67788279017714, 342.7497926513643, 347.21529343683267, 5436.782528698857, 6621.933780662192, 6623.966880165598, 6647.468202543803, 6694.531382803206, 5222.268882356464, 5376.892462150755, 6468.5148613854035, 6553.115329430538, 6607.933920660792, 4707.152712511746, 4781.665283430161, 4838.806447742086, 4873.731944743037, 4983.2275997220395, 4238.872833814975, 4617.607503362202, 4855.975720121412, 4879.609631229511, 4928.728919909404, 4863.14894893394, 4916.631252656047, 4923.655344125913, 4926.236433352836, 4945.826896058638, 4649.093426781779, 4791.496892826255, 4875.878103047452, 4887.608991280689, 4923.507649235084, 4218.810153543098, 4224.5353011168745, 4225.492940847087, 4239.512456067561, 4246.5541118182455, 6261.217347831521, 6303.338149494283, 6307.905381419282, 6314.494840412756, 6317.620942743404, 5364.968792632189, 5812.9709351452975, 5828.271466066742, 5835.766569337221, 5855.882882342372, 6236.596765727721, 6858.0, 7124.821879453003, 7149.106361704787, 7155.785326440215, 4752.168370535177, 4774.880627984294, 4776.426828780569, 4784.712917224978, 4797.904041919142, 7422.814429639248, 7431.0, 7440.73957411488, 7442.255774422569, 7483.962580187119, 4869.488703686133, 4871.14754917888, 4873.244647079709, 4874.5856602189215, 4878.0, 4912.606991440676, 4926.261060840902, 4932.28481870134, 4937.580305674058, 4939.876503087452, 4987.351644286292, 5737.627054241461, 5757.740901659436, 5771.451712087345, 5793.188953546507, 5712.257406537206, 6708.228553716385, 7007.229204787469, 7021.368076873106, 7031.191412987469, 1604.294110591336, 1639.5901024743814, 1651.2074204381963, 1656.1719140429784, 1660.9252583633765, 993.6621548673486, 1014.1531820929606, 1045.5504133222635, 1050.2123407444417, 1070.4326706845384, 6610.999999999904, 6723.361280678328, 6730.226024007204, 6770.593764374154, 6782.389584937379, 6412.9098053331045, 7135.322144396274, 7397.223291554417, 7422.740204092831, 7429.442791790637, 6752.966235168793, 6760.966195169091, 6761.492888033319, 6763.898541521881, 6770.1537307836525, 3769.6793583185695, 3829.617038296148, 3884.4173373993563, 3887.3002859485564, 3890.922181556353, 6500.772472963424, 7561.941328214054, 7572.734954276574, 7700.653470593837, 7746.49647772893, 6217.694284200278, 6571.2771595124495, 7163.677634506355, 7588.203238659971, 7613.42899282556, 6222.937770622327, 7720.648886444849, 7724.3434308083315, 7732.188120247293, 7754.147043825062, 6675.132232809704, 7057.470689698191, 7332.45006624516, 7342.743003994835, 7381.8523629526035, 7162.928370716435, 7169.211386747349, 7202.099737532809, 7206.2793720627515, 7212.0, 6177.6911154443, 7604.125525564631, 7636.0, 7642.388608911163, 7660.0, 4676.976615116902, 4686.437627484724, 4694.319323698113, 4694.723059316298, 4696.765161741968, 7039.331263529956, 7269.491135620456, 7281.708731650675, 7284.927150728425, 7287.744928927674, 5810.883782324415, 7002.684879180347, 7008.649567521502, 7035.33164349386, 7064.576125432491, 6984.266652001466, 6997.090378250795, 7045.753398631024, 7056.15326160864, 7056.6471676417, 5319.946800531945, 5645.322561292671, 6861.588304701734, 6895.241523432318, 6900.689468973807, 6844.076049733222, 7019.5788252705, 7026.156861176692, 7026.437884969088, 7040.295970403124, 6724.293949135466, 6833.897491537531, 6905.309469053255, 6923.376896079377, 7016.789496315118], "unit": "score", "name": "prototype.html", "value": 5132.11085001389}, {"lowerIsBetter": false, "replicates": [673.4410439335352, 701.4003027411566, 780.3088515975508, 791.509264256161, 801.9157988411216, 15121.395144194234, 15303.770443443358, 15337.769933451007, 15354.232288385578, 15356.619723942793, 14177.0, 14252.002359834816, 14261.714765704679, 14271.0, 14277.286135693199, 781.3905153979905, 796.3629096722627, 796.8685166947475, 798.2057852436805, 960.4429430930079, 797.5135167547792, 937.1565590968149, 965.4689920543708, 966.6133546581389, 966.6278482784109, 1031.1750599520392, 1035.2184101003731, 1036.9429681367521, 1037.8806437259736, 1040.173062415378, 1008.5209525475414, 1025.1900998211404, 1026.4816267784795, 1027.3168343051898, 1029.0738335498074, 614.6281499692711, 756.3948840927236, 772.1892013385923, 772.5943879463271, 773.9071311442607, 15124.773128403083, 15304.0, 15524.844751552453, 15538.61153471161, 15550.455734049254, 11381.433021806854, 13502.189868607915, 13652.726945461036, 13852.861471385359, 14190.50333238342, 10559.208059395576, 10584.0, 10708.946455267751, 10851.294665846695, 10885.0, 3680.7607505512055, 3680.815959202056, 3732.9440058399146, 3741.139537906297, 3907.882763517099, 85411.0, 86567.56716216379, 87371.75769453916, 87759.0, 87766.0, 9708.660196893215, 9779.364341317934, 9798.0, 9850.064243896819, 9904.950475247577, 21894.78105218959, 21927.67108493374, 21931.451713706967, 21937.780622193575, 21946.45133871666, 7264.309890560388, 7596.316331530078, 7688.423368247404, 7886.881696774553, 7928.484648497829, 4314.482262128502, 4332.935005974913, 4345.391645169679, 4541.182587134347, 4587.72473651582, 3534.8335049433626, 3622.166901612644, 3641.708663306929, 3709.647583479565, 3735.701143908481, 656.4157899469476, 673.572281601177, 691.865086308165, 694.5763084518439, 696.902433659278, 592.8280798568453, 594.8572342637823, 606.1756011823916, 613.457090474924, 615.8552740106071, 8026.237507436901, 8209.753707388787, 8226.136255693185, 8390.622421991025, 8442.746717598482, 530.6391653675461, 575.32686756495, 578.8552861784553, 586.9727976041927, 623.6694551887507, 3859.807009649506, 3900.9804950975063, 3902.785346805953, 3917.334053210961, 4060.7881076212607, 96980.51509742407, 97108.51445742726, 99819.0, 100030.0, 100441.0, 82370.58814706007, 82438.0, 82884.0, 83271.0, 83333.0, 73483.0, 73959.0, 74229.99999999892, 74309.25690743132, 76104.0, 7606.8098297543, 7777.0, 7844.450888437755, 7993.12075671664, 8178.7955301117945, 51297.487025130766, 51770.0, 52709.0, 52876.20685689641, 53062.204066938255, 64378.03432948603, 64874.35125648683, 65420.67289663521, 66439.00341494785, 66444.33555664381, 9542.952285238529, 9626.47054411986, 9708.36895601812, 10052.095311422006, 10278.588856445658, 10533.473326333806, 10618.256722029384, 10919.071878890383, 10935.671929842118, 10987.429804752306, 10195.388276703421, 10350.5342259597, 10367.533460994424, 10472.738181545523, 10725.30285531438, 10583.153347732317, 11488.023518001064, 11809.697109063995, 11897.881021189678, 12108.636740897435, 9472.431654100776, 10698.83951740709, 11710.297382157098, 11727.589534366256, 11881.643550693492, 9609.471479068718, 9691.951540242253, 9898.851517227104, 10126.44304563256, 10178.745531361776, 9473.242140628596, 9511.048895110434, 9534.85697714521, 9543.735895864713, 9992.900070999198], "unit": "score", "name": "yui.html", "value": 7040.701382515635}], "name": "dromaeo_css", "value": 7671.431038965605}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 113.0, 112.0, 113.0, 113.0, 113.0, 130.0, 114.0, 111.0, 112.0], "unit": "score", "name": "ai-astar", "value": 114.2}, {"lowerIsBetter": true, "replicates": [155.0, 120.0, 155.0, 150.0, 115.0, 140.0, 125.0, 146.0, 176.0, 147.0], "unit": "score", "name": "audio-beat-detection", "value": 142.9}, {"lowerIsBetter": true, "replicates": [185.0, 184.0, 187.0, 195.0, 258.0, 206.0, 184.0, 184.0, 186.0, 184.0], "unit": "score", "name": "audio-dft", "value": 195.3}, {"lowerIsBetter": true, "replicates": [74.0, 78.0, 74.0, 119.0, 55.0, 57.0, 57.0, 57.0, 117.0, 56.0], "unit": "score", "name": "audio-fft", "value": 74.4}, {"lowerIsBetter": true, "replicates": [90.0, 95.0, 128.0, 79.0, 81.0, 84.0, 117.0, 76.0, 78.0, 117.0], "unit": "score", "name": "audio-oscillator", "value": 94.5}, {"lowerIsBetter": true, "replicates": [89.0, 86.0, 89.0, 87.0, 90.0, 86.0, 90.0, 86.0, 89.0, 86.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 87.8}, {"lowerIsBetter": true, "replicates": [106.0, 146.0, 94.0, 94.0, 94.0, 93.0, 93.0, 94.0, 93.0, 94.0], "unit": "score", "name": "imaging-darkroom", "value": 100.1}, {"lowerIsBetter": true, "replicates": [86.0, 85.0, 87.0, 85.0, 86.0, 87.0, 86.0, 89.0, 87.0, 87.0], "unit": "score", "name": "imaging-desaturate", "value": 86.5}, {"lowerIsBetter": true, "replicates": [53.0, 57.0, 57.0, 58.0, 58.0, 58.0, 57.0, 56.0, 69.0, 51.0], "unit": "score", "name": "json-parse-financial", "value": 57.4}, {"lowerIsBetter": true, "replicates": [62.0, 65.0, 68.0, 66.0, 75.0, 70.0, 70.0, 67.0, 67.0, 67.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 67.7}, {"lowerIsBetter": true, "replicates": [81.0, 80.0, 81.0, 81.0, 80.0, 80.0, 83.0, 80.0, 79.0, 87.0], "unit": "score", "name": "stanford-crypto-aes", "value": 81.2}, {"lowerIsBetter": true, "replicates": [125.0, 128.0, 134.0, 126.0, 119.0, 116.0, 116.0, 119.0, 137.0, 131.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 125.1}, {"lowerIsBetter": true, "replicates": [140.0, 123.0, 134.0, 136.0, 124.0, 121.0, 165.0, 133.0, 136.0, 133.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 134.5}, {"lowerIsBetter": true, "replicates": [67.0, 65.0, 65.0, 67.0, 66.0, 66.0, 66.0, 68.0, 64.0, 64.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 65.8}], "name": "kraken", "value": 1427.3999999999999}]} 16:58:25 INFO - Return code: 0 16:58:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:58:25 INFO - # TBPL SUCCESS # 16:58:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:58:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:58:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:58:25 INFO - 2016-05-30 16:58:25.089 screenresolution[2312:31477] starting screenresolution argv=screenresolution get 16:58:25 INFO - 2016-05-30 16:58:25.092 screenresolution[2312:31477] Display 0: 1600x1200x32@60 16:58:25 INFO - 2016-05-30 16:58:25.098 screenresolution[2313:31480] starting screenresolution argv=screenresolution list 16:58:25 INFO - Available Modes on Display 0 16:58:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:58:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:58:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:58:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:58:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:58:25 INFO - Intel Iris: 16:58:25 INFO - Chipset Model: Intel Iris 16:58:25 INFO - Type: GPU 16:58:25 INFO - Bus: Built-In 16:58:25 INFO - VRAM (Dynamic, Max): 1536 MB 16:58:25 INFO - Vendor: Intel (0x8086) 16:58:25 INFO - Device ID: 0x0a2e 16:58:25 INFO - Revision ID: 0x0009 16:58:25 INFO - Displays: 16:58:25 INFO - Display: 16:58:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:58:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:58:25 INFO - Display Serial Number: 1600x1200 60 16:58:25 INFO - Main Display: Yes 16:58:25 INFO - Mirror: Off 16:58:25 INFO - Online: Yes 16:58:25 INFO - Rotation: Supported 16:58:25 INFO - Return code: 0 16:58:25 INFO - Running post-action listener: _resource_record_post_action 16:58:25 INFO - Running post-run listener: _resource_record_post_run 16:58:26 INFO - Total resource usage - Wall time: 1193s; CPU: 53.0%; Read bytes: 520192; Write bytes: 509392896; Read time: 5; Write time: 1540 16:58:26 INFO - install - Wall time: 23s; CPU: 55.0%; Read bytes: 205980160; Write bytes: 208279040; Read time: 19029; Write time: 431 16:58:26 INFO - run-tests - Wall time: 1171s; CPU: 53.0%; Read bytes: 520192; Write bytes: 299954688; Read time: 5; Write time: 1104 16:58:26 INFO - Running post-run listener: _upload_blobber_files 16:58:26 INFO - Blob upload gear active. 16:58:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:58:26 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 16:58:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:58:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:58:26 INFO - (blobuploader) - INFO - Open directory for files ... 16:58:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 16:58:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:58:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:58:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 16:58:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:58:27 INFO - (blobuploader) - INFO - Done attempting. 16:58:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 16:58:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:58:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:58:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 16:58:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:58:28 INFO - (blobuploader) - INFO - Done attempting. 16:58:28 INFO - (blobuploader) - INFO - Iteration through files over. 16:58:28 INFO - Return code: 0 16:58:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:58:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:58:28 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/317c0a522a899a4f2b285dfb31861ace4b34827cd99d224c8e46afbc5fbc8fb15c1ac7cfd806a60cfa8f52b76041f829fa2719bf567c73c60122d76659639d02", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dfb0d4590205fb9694ff2d38d5448d4d58da80cba66c281a1f4c742363f65d50e22b098b880aebae0f040fbf962014ce6449f62152ef3fa82789b59ed779a3dc"} 16:58:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:58:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:58:28 INFO - Contents: 16:58:28 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/317c0a522a899a4f2b285dfb31861ace4b34827cd99d224c8e46afbc5fbc8fb15c1ac7cfd806a60cfa8f52b76041f829fa2719bf567c73c60122d76659639d02", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dfb0d4590205fb9694ff2d38d5448d4d58da80cba66c281a1f4c742363f65d50e22b098b880aebae0f040fbf962014ce6449f62152ef3fa82789b59ed779a3dc"} 16:58:28 INFO - Running post-run listener: copy_logs_to_upload_dir 16:58:28 INFO - Copying logs to upload dir... 16:58:28 INFO - mkdir: /builds/slave/test/build/upload/logs 16:58:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1348.807252 ========= master_lag: 0.56 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 29 secs) (at 2016-05-30 16:58:29.601182) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 16:58:29.604872) ========= 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.epUbqUHVQr/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.gUD9Mam6Cu/Listeners TMPDIR=/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/317c0a522a899a4f2b285dfb31861ace4b34827cd99d224c8e46afbc5fbc8fb15c1ac7cfd806a60cfa8f52b76041f829fa2719bf567c73c60122d76659639d02", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dfb0d4590205fb9694ff2d38d5448d4d58da80cba66c281a1f4c742363f65d50e22b098b880aebae0f040fbf962014ce6449f62152ef3fa82789b59ed779a3dc"} build_url:https://queue.taskcluster.net/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011290 build_url: 'https://queue.taskcluster.net/v1/task/e8rZz6AOSjuCacoZyaE_Lw/artifacts/public/build/firefox-47.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/317c0a522a899a4f2b285dfb31861ace4b34827cd99d224c8e46afbc5fbc8fb15c1ac7cfd806a60cfa8f52b76041f829fa2719bf567c73c60122d76659639d02", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/dfb0d4590205fb9694ff2d38d5448d4d58da80cba66c281a1f4c742363f65d50e22b098b880aebae0f040fbf962014ce6449f62152ef3fa82789b59ed779a3dc"}' ========= master_lag: 0.05 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 16:58:29.667074) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 16:58:29.667386) ========= 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.epUbqUHVQr/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.gUD9Mam6Cu/Listeners TMPDIR=/var/folders/vs/y0ftz2f17fq_g0l29r50mgb800000w/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.005297 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 16:58:29.719245) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 16:58:29.719980) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 16:58:29.720730) ========= ========= Total master_lag: 0.73 =========