builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0125 starttime: 1463044960.78 results: success (0) buildid: 20160512002714 builduid: e442f1c302aa45e4997cb3cc58185045 revision: 5d7bbfed978a2e0aceff2bbdd8c8d72f5137081d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 02:22:40.785218) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-12 02:22:40.785615) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 02:22:40.785888) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-12 02:22:40.807031) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 02:22:40.807360) ========= 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.U72f5jhXVm/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.YGXSZLM6VV/Listeners TMPDIR=/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-12 02:22:40-- 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% 7.89M=0.001s 2016-05-12 02:22:40 (7.89 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.073728 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 02:22:40.902728) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 02:22:40.907165) ========= 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.U72f5jhXVm/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.YGXSZLM6VV/Listeners TMPDIR=/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.020103 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 02:22:40.955703) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 02:22:40.958068) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 5d7bbfed978a2e0aceff2bbdd8c8d72f5137081d --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 5d7bbfed978a2e0aceff2bbdd8c8d72f5137081d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.U72f5jhXVm/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.YGXSZLM6VV/Listeners TMPDIR=/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-12 02:22:41,029 truncating revision to first 12 chars 2016-05-12 02:22:41,029 Setting DEBUG logging. 2016-05-12 02:22:41,029 attempt 1/10 2016-05-12 02:22:41,029 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/5d7bbfed978a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-12 02:22:41,313 unpacking tar archive at: mozilla-beta-5d7bbfed978a/testing/mozharness/ program finished with exit code 0 elapsedTime=0.535741 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 02:22:41.511728) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 02:22:41.512042) ========= script_repo_revision: 5d7bbfed978a2e0aceff2bbdd8c8d72f5137081d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-12 02:22:41.512414) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 02:22:41.512675) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-12 02:22:41.535004) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-05-12 02:22:41.535334) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.U72f5jhXVm/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.YGXSZLM6VV/Listeners TMPDIR=/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 02:22:41 INFO - MultiFileLogger online at 20160512 02:22:41 in /builds/slave/test 02:22:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 02:22:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:22:41 INFO - {'append_to_log': False, 02:22:41 INFO - 'base_work_dir': '/builds/slave/test', 02:22:41 INFO - 'blob_upload_branch': 'Mozilla-Beta', 02:22:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:22:41 INFO - 'branch': 'Mozilla-Beta', 02:22:41 INFO - 'buildbot_json_path': 'buildprops.json', 02:22:41 INFO - 'config_files': ('talos/mac_config.py',), 02:22:41 INFO - 'default_actions': ('clobber', 02:22:41 INFO - 'read-buildbot-config', 02:22:41 INFO - 'download-and-extract', 02:22:41 INFO - 'populate-webroot', 02:22:41 INFO - 'create-virtualenv', 02:22:41 INFO - 'install', 02:22:41 INFO - 'run-tests'), 02:22:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:22:41 INFO - 'download_minidump_stackwalk': True, 02:22:41 INFO - 'download_symbols': 'ondemand', 02:22:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:22:41 INFO - 'tooltool.py': '/tools/tooltool.py', 02:22:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:22:41 INFO - '/tools/misc-python/virtualenv.py')}, 02:22:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:22:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:22:41 INFO - 'installer_path': 'installer.exe', 02:22:41 INFO - 'log_level': 'info', 02:22:41 INFO - 'log_name': 'talos', 02:22:41 INFO - 'log_to_console': True, 02:22:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:22:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:22:41 INFO - 'opt_config_files': (), 02:22:41 INFO - 'pip_index': False, 02:22:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:22:41 INFO - 'cmd': ('bash', 02:22:41 INFO - '-c', 02:22:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:22:41 INFO - 'enabled': True, 02:22:41 INFO - 'halt_on_failure': False, 02:22:41 INFO - 'name': 'check_screen_resolution'},), 02:22:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:22:41 INFO - 'cmd': ('bash', 02:22:41 INFO - '-c', 02:22:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:22:41 INFO - 'enabled': True, 02:22:41 INFO - 'halt_on_failure': False, 02:22:41 INFO - 'name': 'check_screen_resolution'},), 02:22:41 INFO - 'run_cmd_checks_enabled': True, 02:22:41 INFO - 'sps_profile': False, 02:22:41 INFO - 'sps_profile_interval': 0, 02:22:41 INFO - 'suite': 'dromaeojs-e10s', 02:22:41 INFO - 'system_bits': '32', 02:22:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:22:41 INFO - 'title': 't-yosemite-r7-0125', 02:22:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:22:41 INFO - 'use_talos_json': True, 02:22:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:22:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:22:41 INFO - 'work_dir': 'build'} 02:22:41 INFO - ##### 02:22:41 INFO - ##### Running clobber step. 02:22:41 INFO - ##### 02:22:41 INFO - Running pre-action listener: _resource_record_pre_action 02:22:41 INFO - Running main action method: clobber 02:22:41 INFO - rmtree: /builds/slave/test/build 02:22:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:22:41 INFO - Running post-action listener: _resource_record_post_action 02:22:41 INFO - ##### 02:22:41 INFO - ##### Running read-buildbot-config step. 02:22:41 INFO - ##### 02:22:41 INFO - Running pre-action listener: _resource_record_pre_action 02:22:41 INFO - Running main action method: read_buildbot_config 02:22:41 INFO - Using buildbot properties: 02:22:41 INFO - { 02:22:41 INFO - "project": "", 02:22:41 INFO - "product": "firefox", 02:22:41 INFO - "script_repo_revision": "production", 02:22:41 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 02:22:41 INFO - "repository": "", 02:22:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 02:22:41 INFO - "buildid": "20160512002714", 02:22:41 INFO - "pgo_build": "False", 02:22:41 INFO - "basedir": "/builds/slave/test", 02:22:41 INFO - "buildnumber": 21, 02:22:41 INFO - "slavename": "t-yosemite-r7-0125", 02:22:41 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 02:22:41 INFO - "platform": "yosemite_r7", 02:22:41 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 02:22:41 INFO - "branch": "mozilla-beta", 02:22:41 INFO - "repo_path": "releases/mozilla-beta", 02:22:41 INFO - "revision": "5d7bbfed978a2e0aceff2bbdd8c8d72f5137081d", 02:22:41 INFO - "stage_platform": "macosx64", 02:22:41 INFO - "builduid": "e442f1c302aa45e4997cb3cc58185045", 02:22:41 INFO - "slavebuilddir": "test" 02:22:41 INFO - } 02:22:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/KxyjPdUHTBGHEsDmVYVgrQ/artifacts/public/build/firefox-47.0.en-US.mac.dmg. 02:22:41 INFO - Running post-action listener: _resource_record_post_action 02:22:41 INFO - ##### 02:22:41 INFO - ##### Running download-and-extract step. 02:22:41 INFO - ##### 02:22:41 INFO - Running pre-action listener: _resource_record_pre_action 02:22:41 INFO - Running main action method: download_and_extract 02:22:41 INFO - mkdir: /builds/slave/test/build/tests 02:22:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 02:22:41 INFO - https://queue.taskcluster.net/v1/task/KxyjPdUHTBGHEsDmVYVgrQ/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 02:22:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KxyjPdUHTBGHEsDmVYVgrQ/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 02:22:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KxyjPdUHTBGHEsDmVYVgrQ/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 02:22:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KxyjPdUHTBGHEsDmVYVgrQ/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 02:22:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KxyjPdUHTBGHEsDmVYVgrQ/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 02:22:45 INFO - Downloaded 1241 bytes. 02:22:45 INFO - Reading from file /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 02:22:45 INFO - Using the following test package requirements: 02:22:45 INFO - {u'common': [u'firefox-47.0.en-US.mac.common.tests.zip'], 02:22:45 INFO - u'cppunittest': [u'firefox-47.0.en-US.mac.common.tests.zip', 02:22:45 INFO - u'firefox-47.0.en-US.mac.cppunittest.tests.zip'], 02:22:45 INFO - u'gtest': [u'firefox-47.0.en-US.mac.common.tests.zip', 02:22:45 INFO - u'firefox-47.0.en-US.mac.gtest.tests.zip'], 02:22:45 INFO - u'jittest': [u'firefox-47.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 02:22:45 INFO - u'mochitest': [u'firefox-47.0.en-US.mac.common.tests.zip', 02:22:45 INFO - u'firefox-47.0.en-US.mac.mochitest.tests.zip'], 02:22:45 INFO - u'mozbase': [u'firefox-47.0.en-US.mac.common.tests.zip'], 02:22:45 INFO - u'reftest': [u'firefox-47.0.en-US.mac.common.tests.zip', 02:22:45 INFO - u'firefox-47.0.en-US.mac.reftest.tests.zip'], 02:22:45 INFO - u'talos': [u'firefox-47.0.en-US.mac.common.tests.zip', 02:22:45 INFO - u'firefox-47.0.en-US.mac.talos.tests.zip'], 02:22:45 INFO - u'web-platform': [u'firefox-47.0.en-US.mac.common.tests.zip', 02:22:45 INFO - u'firefox-47.0.en-US.mac.web-platform.tests.zip'], 02:22:45 INFO - u'webapprt': [u'firefox-47.0.en-US.mac.common.tests.zip'], 02:22:45 INFO - u'xpcshell': [u'firefox-47.0.en-US.mac.common.tests.zip', 02:22:45 INFO - u'firefox-47.0.en-US.mac.xpcshell.tests.zip']} 02:22:45 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip'] for test suite category: common 02:22:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:22:45 INFO - https://queue.taskcluster.net/v1/task/KxyjPdUHTBGHEsDmVYVgrQ/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:22:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KxyjPdUHTBGHEsDmVYVgrQ/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 02:22:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KxyjPdUHTBGHEsDmVYVgrQ/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 02:22:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KxyjPdUHTBGHEsDmVYVgrQ/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 02:22:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KxyjPdUHTBGHEsDmVYVgrQ/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 02:22:47 INFO - Downloaded 17747480 bytes. 02:22: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'] 02:22: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 02:22:50 INFO - Return code: 0 02:22:50 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 02:22:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:22:50 INFO - https://queue.taskcluster.net/v1/task/KxyjPdUHTBGHEsDmVYVgrQ/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:22:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KxyjPdUHTBGHEsDmVYVgrQ/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 02:22:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KxyjPdUHTBGHEsDmVYVgrQ/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 02:22:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KxyjPdUHTBGHEsDmVYVgrQ/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 02:22:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KxyjPdUHTBGHEsDmVYVgrQ/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 02:22:51 INFO - Downloaded 17747480 bytes. 02:22:51 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'] 02:22:51 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 02:22:54 INFO - Return code: 0 02:22: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')]} 02:22:54 INFO - https://queue.taskcluster.net/v1/task/KxyjPdUHTBGHEsDmVYVgrQ/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 02:22:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KxyjPdUHTBGHEsDmVYVgrQ/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 02:22:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KxyjPdUHTBGHEsDmVYVgrQ/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 02:22:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KxyjPdUHTBGHEsDmVYVgrQ/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 02:22:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KxyjPdUHTBGHEsDmVYVgrQ/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 02:22:56 INFO - Downloaded 10845194 bytes. 02:22:56 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'] 02:22:56 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 02:22:56 INFO - Return code: 0 02:22:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:22:56 INFO - https://queue.taskcluster.net/v1/task/KxyjPdUHTBGHEsDmVYVgrQ/artifacts/public/build/firefox-47.0.en-US.mac.dmg matches https://queue.taskcluster.net 02:22:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KxyjPdUHTBGHEsDmVYVgrQ/artifacts/public/build/firefox-47.0.en-US.mac.dmg 02:22:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KxyjPdUHTBGHEsDmVYVgrQ/artifacts/public/build/firefox-47.0.en-US.mac.dmg 02:22:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KxyjPdUHTBGHEsDmVYVgrQ/artifacts/public/build/firefox-47.0.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0.en-US.mac.dmg 02:22:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KxyjPdUHTBGHEsDmVYVgrQ/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 02:23:01 INFO - Downloaded 88376113 bytes. 02:23:01 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KxyjPdUHTBGHEsDmVYVgrQ/artifacts/public/build/firefox-47.0.en-US.mac.dmg 02:23:01 INFO - mkdir: /builds/slave/test/properties 02:23:01 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:23:01 INFO - Writing to file /builds/slave/test/properties/build_url 02:23:01 INFO - Contents: 02:23:01 INFO - build_url:https://queue.taskcluster.net/v1/task/KxyjPdUHTBGHEsDmVYVgrQ/artifacts/public/build/firefox-47.0.en-US.mac.dmg 02:23:02 INFO - Running post-action listener: _resource_record_post_action 02:23:02 INFO - Running post-action listener: set_extra_try_arguments 02:23:02 INFO - ##### 02:23:02 INFO - ##### Running populate-webroot step. 02:23:02 INFO - ##### 02:23:02 INFO - Running pre-action listener: _resource_record_pre_action 02:23:02 INFO - Running main action method: populate_webroot 02:23:02 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:23:02 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:23:02 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:23:02 INFO - '--tppagecycles', 02:23:02 INFO - '7'], 02:23:02 INFO - 'tests': ['tsvgm']}}, 02:23:02 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 02:23:02 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 02:23:02 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:23:02 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 02:23:02 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 02:23:02 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:23:02 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:23:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:23:02 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:23:02 INFO - 'talos_options': ['--e10s'], 02:23:02 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:23:02 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:23:02 INFO - 'tests': ['damp', 'tps']}, 02:23:02 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:23:02 INFO - 'talos_options': ['--e10s'], 02:23:02 INFO - 'tests': ['damp', 'tps']}, 02:23:02 INFO - 'g3': {'tests': ['dromaeo_dom']}, 02:23:02 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:23:02 INFO - 'other': {'tests': ['a11yr', 02:23:02 INFO - 'ts_paint', 02:23:02 INFO - 'tpaint', 02:23:02 INFO - 'sessionrestore', 02:23:02 INFO - 'sessionrestore_no_auto_restore', 02:23:02 INFO - 'tabpaint']}, 02:23:02 INFO - 'other-e10s': {'talos_options': ['--e10s'], 02:23:02 INFO - 'tests': ['a11yr', 02:23:02 INFO - 'ts_paint', 02:23:02 INFO - 'tpaint', 02:23:02 INFO - 'sessionrestore', 02:23:02 INFO - 'sessionrestore_no_auto_restore', 02:23:02 INFO - 'tabpaint']}, 02:23:02 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 02:23:02 INFO - 'tests': ['a11yr', 02:23:02 INFO - 'ts_paint', 02:23:02 INFO - 'tpaint', 02:23:02 INFO - 'sessionrestore', 02:23:02 INFO - 'sessionrestore_no_auto_restore', 02:23:02 INFO - 'tabpaint']}, 02:23:02 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 02:23:02 INFO - 'tests': ['a11yr', 02:23:02 INFO - 'ts_paint', 02:23:02 INFO - 'tpaint', 02:23:02 INFO - 'sessionrestore', 02:23:02 INFO - 'sessionrestore_no_auto_restore', 02:23:02 INFO - 'tabpaint']}, 02:23:02 INFO - 'other_l64': {'tests': ['a11yr', 02:23:02 INFO - 'ts_paint', 02:23:02 INFO - 'tpaint', 02:23:02 INFO - 'sessionrestore', 02:23:02 INFO - 'sessionrestore_no_auto_restore', 02:23:02 INFO - 'tabpaint']}, 02:23:02 INFO - 'other_nol64': {'tests': ['a11yr', 02:23:02 INFO - 'ts_paint', 02:23:02 INFO - 'tpaint', 02:23:02 INFO - 'sessionrestore', 02:23:02 INFO - 'sessionrestore_no_auto_restore', 02:23:02 INFO - 'tabpaint']}, 02:23:02 INFO - 'svgr': {'tests': ['tsvgx', 02:23:02 INFO - 'tsvgr_opacity', 02:23:02 INFO - 'tart', 02:23:02 INFO - 'tscrollx', 02:23:02 INFO - 'cart']}, 02:23:02 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 02:23:02 INFO - 'tests': ['tsvgx', 02:23:02 INFO - 'tsvgr_opacity', 02:23:02 INFO - 'tart', 02:23:02 INFO - 'tscrollx', 02:23:02 INFO - 'cart']}, 02:23:02 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:23:02 INFO - 'tests': ['tp5o']}, 02:23:02 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:23:02 INFO - 'talos_options': ['--e10s'], 02:23:02 INFO - 'tests': ['tp5o']}, 02:23:02 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:23:02 INFO - 'talos_options': ['--xperf_path', 02:23:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:23:02 INFO - 'tests': ['tp5n']}, 02:23:02 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:23:02 INFO - 'talos_options': ['--e10s', 02:23:02 INFO - '--xperf_path', 02:23:02 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:23:02 INFO - 'tests': ['tp5n']}}, 02:23:02 INFO - 'talos.zip': {'path': '', 02:23:02 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:23:02 INFO - Running post-action listener: _resource_record_post_action 02:23:02 INFO - ##### 02:23:02 INFO - ##### Running create-virtualenv step. 02:23:02 INFO - ##### 02:23:02 INFO - Running pre-action listener: _resource_record_pre_action 02:23:02 INFO - Running main action method: create_virtualenv 02:23:02 INFO - Creating virtualenv /builds/slave/test/build/venv 02:23:02 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 02:23:02 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:23:02 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:23:02 INFO - Using real prefix '/tools/python27' 02:23:02 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:23:03 INFO - Installing distribute.............................................................................................................................................................................................done. 02:23:06 INFO - Installing pip.................done. 02:23:06 INFO - Return code: 0 02:23:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:23:06 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')]} 02:23:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:23:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:06 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')]} 02:23:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:23:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:06 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 0x100b47a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b4e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb85057d1d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009ebd30>, '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 0x10084fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009e2b20>, '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.U72f5jhXVm/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.YGXSZLM6VV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:23:06 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 02:23:06 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 02:23:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U72f5jhXVm/Render', 02:23:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:23:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:23:06 INFO - 'HOME': '/Users/cltbld', 02:23:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:23:06 INFO - 'LOGNAME': 'cltbld', 02:23:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:23:06 INFO - 'MOZ_NO_REMOTE': '1', 02:23:06 INFO - 'NO_EM_RESTART': '1', 02:23:06 INFO - 'PAGER': '/bin/cat', 02:23:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:23:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:23:06 INFO - 'PWD': '/builds/slave/test', 02:23:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:23:06 INFO - 'SHELL': '/bin/bash', 02:23:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YGXSZLM6VV/Listeners', 02:23:06 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 02:23:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:23:06 INFO - 'USER': 'cltbld', 02:23:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:23:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:23:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:23:06 INFO - 'XPC_FLAGS': '0x0', 02:23:06 INFO - 'XPC_SERVICE_NAME': '0', 02:23:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:23:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:23:06 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 02:23:06 INFO - Cleaning up... 02:23:06 INFO - Return code: 0 02:23:06 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 02:23:06 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')]} 02:23:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:23:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:06 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')]} 02:23:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:23:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:06 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 0x100b47a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b4e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb85057d1d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009ebd30>, '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 0x10084fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009e2b20>, '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.U72f5jhXVm/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.YGXSZLM6VV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:23:06 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 02:23:06 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 02:23:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U72f5jhXVm/Render', 02:23:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:23:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:23:06 INFO - 'HOME': '/Users/cltbld', 02:23:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:23:06 INFO - 'LOGNAME': 'cltbld', 02:23:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:23:06 INFO - 'MOZ_NO_REMOTE': '1', 02:23:06 INFO - 'NO_EM_RESTART': '1', 02:23:06 INFO - 'PAGER': '/bin/cat', 02:23:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:23:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:23:06 INFO - 'PWD': '/builds/slave/test', 02:23:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:23:06 INFO - 'SHELL': '/bin/bash', 02:23:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YGXSZLM6VV/Listeners', 02:23:06 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 02:23:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:23:06 INFO - 'USER': 'cltbld', 02:23:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:23:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:23:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:23:06 INFO - 'XPC_FLAGS': '0x0', 02:23:06 INFO - 'XPC_SERVICE_NAME': '0', 02:23:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:23:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:23:06 INFO - Downloading/unpacking psutil>=0.7.1 02:23:06 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 02:23:06 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 02:23:06 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 02:23:06 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 02:23:06 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 02:23:06 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 02:23:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:23:09 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:23:09 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:23:09 INFO - Installing collected packages: psutil 02:23:09 INFO - Running setup.py install for psutil 02:23:09 INFO - building 'psutil._psutil_osx' extension 02:23:09 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 02:23:09 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 02:23:09 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 02:23:09 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 02:23:10 INFO - building 'psutil._psutil_posix' extension 02:23:10 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 02:23:10 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:23:10 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:23:10 INFO - ^ 02:23:10 INFO - 1 warning generated. 02:23:10 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 02:23:10 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:23:10 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:23:10 INFO - Successfully installed psutil 02:23:10 INFO - Cleaning up... 02:23:10 INFO - Return code: 0 02:23:10 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 02:23:10 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')]} 02:23:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:23:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:10 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')]} 02:23:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:23:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:10 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 0x100b47a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b4e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb85057d1d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009ebd30>, '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 0x10084fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009e2b20>, '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.U72f5jhXVm/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.YGXSZLM6VV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:23:10 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 02:23:10 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 02:23:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U72f5jhXVm/Render', 02:23:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:23:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:23:10 INFO - 'HOME': '/Users/cltbld', 02:23:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:23:10 INFO - 'LOGNAME': 'cltbld', 02:23:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:23:10 INFO - 'MOZ_NO_REMOTE': '1', 02:23:10 INFO - 'NO_EM_RESTART': '1', 02:23:10 INFO - 'PAGER': '/bin/cat', 02:23:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:23:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:23:10 INFO - 'PWD': '/builds/slave/test', 02:23:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:23:10 INFO - 'SHELL': '/bin/bash', 02:23:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YGXSZLM6VV/Listeners', 02:23:10 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 02:23:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:23:10 INFO - 'USER': 'cltbld', 02:23:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:23:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:23:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:23:10 INFO - 'XPC_FLAGS': '0x0', 02:23:10 INFO - 'XPC_SERVICE_NAME': '0', 02:23:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:23:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:23:10 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:23:10 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 02:23:10 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 02:23:10 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 02:23:10 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 02:23:10 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 02:23:10 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 02:23:12 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:23:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:23:13 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 02:23:13 INFO - Installing collected packages: mozsystemmonitor 02:23:13 INFO - Running setup.py install for mozsystemmonitor 02:23:13 INFO - Successfully installed mozsystemmonitor 02:23:13 INFO - Cleaning up... 02:23:13 INFO - Return code: 0 02:23:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:23: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')]} 02:23:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:23:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23: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')]} 02:23:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:23:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:13 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 0x100b47a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b4e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb85057d1d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009ebd30>, '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 0x10084fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009e2b20>, '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.U72f5jhXVm/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.YGXSZLM6VV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:23:13 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 02:23:13 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 02:23:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U72f5jhXVm/Render', 02:23:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:23:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:23:13 INFO - 'HOME': '/Users/cltbld', 02:23:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:23:13 INFO - 'LOGNAME': 'cltbld', 02:23:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:23:13 INFO - 'MOZ_NO_REMOTE': '1', 02:23:13 INFO - 'NO_EM_RESTART': '1', 02:23:13 INFO - 'PAGER': '/bin/cat', 02:23:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:23:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:23:13 INFO - 'PWD': '/builds/slave/test', 02:23:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:23:13 INFO - 'SHELL': '/bin/bash', 02:23:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YGXSZLM6VV/Listeners', 02:23:13 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 02:23:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:23:13 INFO - 'USER': 'cltbld', 02:23:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:23:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:23:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:23:13 INFO - 'XPC_FLAGS': '0x0', 02:23:13 INFO - 'XPC_SERVICE_NAME': '0', 02:23:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:23:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:23:13 INFO - Downloading/unpacking blobuploader==1.2.4 02:23:13 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 02:23:13 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 02:23:13 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 02:23:13 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 02:23:13 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 02:23:13 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 02:23:15 INFO - Downloading blobuploader-1.2.4.tar.gz 02:23:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:23:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:23:16 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 02:23:16 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 02:23:16 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 02:23:16 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 02:23:16 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 02:23:16 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 02:23:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:23:16 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:23:16 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 02:23:16 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 02:23:16 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 02:23:16 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 02:23:16 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 02:23:16 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 02:23:17 INFO - Downloading docopt-0.6.1.tar.gz 02:23:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:23:17 INFO - Installing collected packages: blobuploader, requests, docopt 02:23:17 INFO - Running setup.py install for blobuploader 02:23:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:23:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:23:17 INFO - Running setup.py install for requests 02:23:17 INFO - Running setup.py install for docopt 02:23:18 INFO - Successfully installed blobuploader requests docopt 02:23:18 INFO - Cleaning up... 02:23:18 INFO - Return code: 0 02:23:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:23:18 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')]} 02:23:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:23:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:18 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')]} 02:23:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:23:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:18 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 0x100b47a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b4e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb85057d1d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009ebd30>, '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 0x10084fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009e2b20>, '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.U72f5jhXVm/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.YGXSZLM6VV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:23:18 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 02:23:18 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 02:23:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U72f5jhXVm/Render', 02:23:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:23:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:23:18 INFO - 'HOME': '/Users/cltbld', 02:23:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:23:18 INFO - 'LOGNAME': 'cltbld', 02:23:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:23:18 INFO - 'MOZ_NO_REMOTE': '1', 02:23:18 INFO - 'NO_EM_RESTART': '1', 02:23:18 INFO - 'PAGER': '/bin/cat', 02:23:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:23:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:23:18 INFO - 'PWD': '/builds/slave/test', 02:23:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:23:18 INFO - 'SHELL': '/bin/bash', 02:23:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YGXSZLM6VV/Listeners', 02:23:18 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 02:23:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:23:18 INFO - 'USER': 'cltbld', 02:23:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:23:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:23:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:23:18 INFO - 'XPC_FLAGS': '0x0', 02:23:18 INFO - 'XPC_SERVICE_NAME': '0', 02:23:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:23:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:23:18 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-ke80jj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:23:18 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-SodWvz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:23:18 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-r8akFw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:23:18 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-uwNNtR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:23:18 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-KtrSdK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:23:18 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-s4cImw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:23:19 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-vpg5hb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:23:19 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-CVXpvv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:23:19 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-k2rQUi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:23:19 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-AevrPA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:23:19 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-QaKUe5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:23:19 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-N7PQJf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:23:19 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-ACgjjl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:23:19 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-Co3qTd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:23:19 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-LaP28F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:23:19 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-_8QcwE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:23:20 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-HPBEIi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:23:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:23:20 INFO - Running setup.py install for manifestparser 02:23:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:23:20 INFO - Running setup.py install for mozcrash 02:23:20 INFO - Running setup.py install for mozdebug 02:23:20 INFO - Running setup.py install for mozdevice 02:23:20 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:23:20 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:23:20 INFO - Running setup.py install for mozfile 02:23:20 INFO - Running setup.py install for mozhttpd 02:23:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:23:21 INFO - Running setup.py install for mozinfo 02:23:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:23:21 INFO - Running setup.py install for mozInstall 02:23:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:23:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:23:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:23:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:23:21 INFO - Running setup.py install for mozleak 02:23:21 INFO - Running setup.py install for mozlog 02:23:21 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:23:21 INFO - Running setup.py install for moznetwork 02:23:21 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:23:21 INFO - Running setup.py install for mozprocess 02:23:21 INFO - Running setup.py install for mozprofile 02:23:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:23:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:23:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:23:22 INFO - Running setup.py install for mozrunner 02:23:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:23:22 INFO - Running setup.py install for mozscreenshot 02:23:22 INFO - Running setup.py install for moztest 02:23:22 INFO - Running setup.py install for mozversion 02:23:22 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:23:22 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:23:22 INFO - Cleaning up... 02:23:22 INFO - Return code: 0 02:23:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:23: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')]} 02:23:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:23:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23: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')]} 02:23:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:23:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:22 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 0x100b47a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b4e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb85057d1d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009ebd30>, '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 0x10084fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009e2b20>, '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.U72f5jhXVm/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.YGXSZLM6VV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:23:22 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 02:23:22 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 02:23:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U72f5jhXVm/Render', 02:23:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:23:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:23:22 INFO - 'HOME': '/Users/cltbld', 02:23:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:23:22 INFO - 'LOGNAME': 'cltbld', 02:23:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:23:22 INFO - 'MOZ_NO_REMOTE': '1', 02:23:22 INFO - 'NO_EM_RESTART': '1', 02:23:22 INFO - 'PAGER': '/bin/cat', 02:23:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:23:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:23:22 INFO - 'PWD': '/builds/slave/test', 02:23:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:23:22 INFO - 'SHELL': '/bin/bash', 02:23:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YGXSZLM6VV/Listeners', 02:23:22 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 02:23:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:23:22 INFO - 'USER': 'cltbld', 02:23:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:23:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:23:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:23:22 INFO - 'XPC_FLAGS': '0x0', 02:23:22 INFO - 'XPC_SERVICE_NAME': '0', 02:23:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:23:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:23:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:23:22 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-x2fU3G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:23:23 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)) 02:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:23:23 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-hNsxdf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:23:23 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)) 02:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:23:23 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-5qBLtf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:23:23 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)) 02:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:23:23 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-qyJBAn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:23:23 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)) 02:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:23:23 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-rqVE0c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:23:23 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)) 02:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:23:23 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-MoE6h4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:23:23 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)) 02:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:23:23 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-cfY0eD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:23:23 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)) 02:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:23:23 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-k7Z997-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:23:23 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)) 02:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:23:23 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-PY4b4Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:23:23 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)) 02:23:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:23:23 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-dPOctb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:23:24 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)) 02:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:23:24 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-_m_imv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:23:24 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)) 02:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:23:24 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-0THy_g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:23:24 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)) 02:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:23:24 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-efTljx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:23:24 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)) 02:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:23:24 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-7Mn5Ux-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:23:24 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)) 02:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:23:24 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-hXA1Or-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:23:24 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)) 02:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:23:24 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-CoTZuJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:23:24 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)) 02:23:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:23:24 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-t7VQ03-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:23:24 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)) 02:23:24 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)) 02:23:24 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)) 02:23:24 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)) 02:23:24 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)) 02:23:24 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)) 02:23:24 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:23:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:23:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:23:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:23:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:23:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:23:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:23:27 INFO - Downloading blessings-1.6.tar.gz 02:23:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:23:27 INFO - Installing collected packages: blessings 02:23:27 INFO - Running setup.py install for blessings 02:23:27 INFO - Successfully installed blessings 02:23:27 INFO - Cleaning up... 02:23:27 INFO - Return code: 0 02:23:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:23:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:23:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:23:27 INFO - Reading from file tmpfile_stdout 02:23:27 INFO - Current package versions: 02:23:27 INFO - blessings == 1.6 02:23:27 INFO - blobuploader == 1.2.4 02:23:27 INFO - docopt == 0.6.1 02:23:27 INFO - manifestparser == 1.1 02:23:27 INFO - mozInstall == 1.12 02:23:27 INFO - mozcrash == 0.17 02:23:27 INFO - mozdebug == 0.1 02:23:27 INFO - mozdevice == 0.48 02:23:27 INFO - mozfile == 1.2 02:23:27 INFO - mozhttpd == 0.7 02:23:27 INFO - mozinfo == 0.9 02:23:27 INFO - mozleak == 0.1 02:23:27 INFO - mozlog == 3.1 02:23:27 INFO - moznetwork == 0.27 02:23:27 INFO - mozprocess == 0.22 02:23:27 INFO - mozprofile == 0.28 02:23:27 INFO - mozrunner == 6.11 02:23:27 INFO - mozscreenshot == 0.1 02:23:27 INFO - mozsystemmonitor == 0.0 02:23:27 INFO - moztest == 0.7 02:23:27 INFO - mozversion == 1.4 02:23:27 INFO - psutil == 3.1.1 02:23:27 INFO - requests == 1.2.3 02:23:27 INFO - wsgiref == 0.1.2 02:23:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:23:27 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')]} 02:23:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:23:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:27 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')]} 02:23:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:23:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:27 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 0x100b47a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b4e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb85057d1d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009ebd30>, '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 0x10084fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009e2b20>, '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.U72f5jhXVm/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.YGXSZLM6VV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:23:27 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 02:23:27 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 02:23:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U72f5jhXVm/Render', 02:23:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:23:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:23:27 INFO - 'HOME': '/Users/cltbld', 02:23:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:23:27 INFO - 'LOGNAME': 'cltbld', 02:23:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:23:27 INFO - 'MOZ_NO_REMOTE': '1', 02:23:27 INFO - 'NO_EM_RESTART': '1', 02:23:27 INFO - 'PAGER': '/bin/cat', 02:23:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:23:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:23:27 INFO - 'PWD': '/builds/slave/test', 02:23:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:23:27 INFO - 'SHELL': '/bin/bash', 02:23:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YGXSZLM6VV/Listeners', 02:23:27 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 02:23:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:23:27 INFO - 'USER': 'cltbld', 02:23:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:23:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:23:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:23:27 INFO - 'XPC_FLAGS': '0x0', 02:23:27 INFO - 'XPC_SERVICE_NAME': '0', 02:23:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:23:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:23:28 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)) 02:23:28 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)) 02:23:28 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)) 02:23:28 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)) 02:23:28 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)) 02:23:28 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)) 02:23:28 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)) 02:23:28 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)) 02:23:28 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)) 02:23:28 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)) 02:23:28 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)) 02:23:28 INFO - Cleaning up... 02:23:28 INFO - Return code: 0 02:23:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:23:28 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')]} 02:23:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:23:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:28 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')]} 02:23:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:23:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:28 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 0x100b47a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b4e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb85057d1d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009ebd30>, '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 0x10084fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009e2b20>, '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.U72f5jhXVm/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.YGXSZLM6VV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:23:28 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 02:23:28 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 02:23:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U72f5jhXVm/Render', 02:23:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:23:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:23:28 INFO - 'HOME': '/Users/cltbld', 02:23:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:23:28 INFO - 'LOGNAME': 'cltbld', 02:23:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:23:28 INFO - 'MOZ_NO_REMOTE': '1', 02:23:28 INFO - 'NO_EM_RESTART': '1', 02:23:28 INFO - 'PAGER': '/bin/cat', 02:23:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:23:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:23:28 INFO - 'PWD': '/builds/slave/test', 02:23:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:23:28 INFO - 'SHELL': '/bin/bash', 02:23:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YGXSZLM6VV/Listeners', 02:23:28 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 02:23:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:23:28 INFO - 'USER': 'cltbld', 02:23:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:23:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:23:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:23:28 INFO - 'XPC_FLAGS': '0x0', 02:23:28 INFO - 'XPC_SERVICE_NAME': '0', 02:23:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:23:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:23:28 INFO - Downloading/unpacking jsonschema 02:23:28 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 02:23:28 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 02:23:28 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 02:23:28 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 02:23:28 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 02:23:28 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 02:23:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:23:30 INFO - Downloading/unpacking functools32 (from jsonschema) 02:23: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 02:23: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 02:23: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 02:23: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 02:23: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 02:23: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 02:23:31 INFO - Downloading functools32-3.2.3-2.tar.gz 02:23:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:23:31 INFO - warning: no files found matching '*.txt' 02:23:31 INFO - no previously-included directories found matching 'build' 02:23:31 INFO - no previously-included directories found matching 'dist' 02:23:31 INFO - no previously-included directories found matching '.git*' 02:23:31 INFO - Installing collected packages: jsonschema, functools32 02:23:31 INFO - Running setup.py install for functools32 02:23:31 INFO - warning: no files found matching '*.txt' 02:23:31 INFO - no previously-included directories found matching 'build' 02:23:31 INFO - no previously-included directories found matching 'dist' 02:23:31 INFO - no previously-included directories found matching '.git*' 02:23:31 INFO - Successfully installed jsonschema functools32 02:23:31 INFO - Cleaning up... 02:23:31 INFO - Return code: 0 02:23:31 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:23:31 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')]} 02:23:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:23:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:31 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')]} 02:23:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:23:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:23:31 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 0x100b47a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100b4e4c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb85057d1d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1009ebd30>, '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 0x10084fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009e2b20>, '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.U72f5jhXVm/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.YGXSZLM6VV/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:23:31 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 02:23:31 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 02:23:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U72f5jhXVm/Render', 02:23:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:23:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:23:31 INFO - 'HOME': '/Users/cltbld', 02:23:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:23:31 INFO - 'LOGNAME': 'cltbld', 02:23:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:23:31 INFO - 'MOZ_NO_REMOTE': '1', 02:23:31 INFO - 'NO_EM_RESTART': '1', 02:23:31 INFO - 'PAGER': '/bin/cat', 02:23:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:23:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:23:31 INFO - 'PWD': '/builds/slave/test', 02:23:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:23:31 INFO - 'SHELL': '/bin/bash', 02:23:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YGXSZLM6VV/Listeners', 02:23:31 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 02:23:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:23:31 INFO - 'USER': 'cltbld', 02:23:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:23:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:23:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:23:31 INFO - 'XPC_FLAGS': '0x0', 02:23:31 INFO - 'XPC_SERVICE_NAME': '0', 02:23:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:23:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:23:31 INFO - Downloading/unpacking flake8 02:23:31 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 02:23:31 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 02:23:31 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 02:23:31 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 02:23:31 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 02:23:31 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 02:23:34 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:23:34 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:23:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:23:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:23:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:23:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:23:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:23:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:23:34 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:23:34 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:23:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:23:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:23:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:23:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:23:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:23:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:23:35 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:23:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:23:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:23:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:23:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:23:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:23:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:23:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:23:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:23:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:23:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:23:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:23:35 INFO - no previously-included directories found matching 'docs/_build' 02:23:35 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:23:35 INFO - Running setup.py install for pep8 02:23:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:23:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:23:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:23:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:23:35 INFO - no previously-included directories found matching 'docs/_build' 02:23:35 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:23:35 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:23:35 INFO - Cleaning up... 02:23:35 INFO - Return code: 0 02:23:35 INFO - Running post-action listener: _resource_record_post_action 02:23:35 INFO - Running post-action listener: _start_resource_monitoring 02:23:35 INFO - Starting resource monitoring. 02:23:35 INFO - ##### 02:23:35 INFO - ##### Running install step. 02:23:35 INFO - ##### 02:23:35 INFO - Running pre-action listener: _resource_record_pre_action 02:23:35 INFO - Running main action method: install 02:23:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:23:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:23:36 INFO - Reading from file tmpfile_stdout 02:23:36 INFO - Detecting whether we're running mozinstall >=1.0... 02:23:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:23:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:23:36 INFO - Reading from file tmpfile_stdout 02:23:36 INFO - Output received: 02:23:36 INFO - Usage: mozinstall [options] installer 02:23:36 INFO - Options: 02:23:36 INFO - -h, --help show this help message and exit 02:23:36 INFO - -d DEST, --destination=DEST 02:23:36 INFO - Directory to install application into. [default: 02:23:36 INFO - "/builds/slave/test"] 02:23:36 INFO - --app=APP Application being installed. [default: firefox] 02:23:36 INFO - mkdir: /builds/slave/test/build/application 02:23:36 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'] 02:23:36 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 02:23:58 INFO - Reading from file tmpfile_stdout 02:23:58 INFO - Output received: 02:23:58 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 02:23:58 INFO - Running post-action listener: _resource_record_post_action 02:23:58 INFO - ##### 02:23:58 INFO - ##### Running run-tests step. 02:23:58 INFO - ##### 02:23:58 INFO - Running pre-action listener: _resource_record_pre_action 02:23:58 INFO - Running main action method: run_tests 02:23:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:23:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:23:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:23:58 INFO - 2016-05-12 02:23:58.782 screenresolution[2065:12526] starting screenresolution argv=screenresolution get 02:23:58 INFO - 2016-05-12 02:23:58.784 screenresolution[2065:12526] Display 0: 1600x1200x32@60 02:23:58 INFO - 2016-05-12 02:23:58.790 screenresolution[2066:12528] starting screenresolution argv=screenresolution list 02:23:58 INFO - Available Modes on Display 0 02:23:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:23:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:23:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:23:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:23:59 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:23:59 INFO - Intel Iris: 02:23:59 INFO - Chipset Model: Intel Iris 02:23:59 INFO - Type: GPU 02:23:59 INFO - Bus: Built-In 02:23:59 INFO - VRAM (Dynamic, Max): 1536 MB 02:23:59 INFO - Vendor: Intel (0x8086) 02:23:59 INFO - Device ID: 0x0a2e 02:23:59 INFO - Revision ID: 0x0009 02:23:59 INFO - Displays: 02:23:59 INFO - Display: 02:23:59 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:23:59 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:23:59 INFO - Display Serial Number: 1600x1200 60 02:23:59 INFO - Main Display: Yes 02:23:59 INFO - Mirror: Off 02:23:59 INFO - Online: Yes 02:23:59 INFO - Rotation: Supported 02:23:59 INFO - Return code: 0 02:23:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:23:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:23:59 INFO - Python 2.7.3 02:23:59 INFO - Return code: 0 02:23:59 INFO - grabbing minidump binary from tooltool 02:23:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://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')]} 02:23:59 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 0x1009ebd30>, '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 0x10084fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1009e2b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:23:59 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 02:23:59 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 02:23:59 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:23:59 INFO - Return code: 0 02:23:59 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:23:59 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:23:59 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:23:59 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:23:59 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:23:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:23:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:24:00 INFO - Return code: 0 02:24:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KxyjPdUHTBGHEsDmVYVgrQ/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0125', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 02:24:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KxyjPdUHTBGHEsDmVYVgrQ/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0125 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 02:24:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.U72f5jhXVm/Render', 02:24:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:24:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:24:00 INFO - 'HOME': '/Users/cltbld', 02:24:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:24:00 INFO - 'LOGNAME': 'cltbld', 02:24:00 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:24:00 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:24:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:24:00 INFO - 'MOZ_NO_REMOTE': '1', 02:24:00 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:24:00 INFO - 'NO_EM_RESTART': '1', 02:24:00 INFO - 'PAGER': '/bin/cat', 02:24:00 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:24:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:24:00 INFO - 'PWD': '/builds/slave/test', 02:24:00 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:24:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:24:00 INFO - 'SHELL': '/bin/bash', 02:24:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YGXSZLM6VV/Listeners', 02:24:00 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 02:24:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:24:00 INFO - 'USER': 'cltbld', 02:24:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:24:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:24:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:24:00 INFO - 'XPC_FLAGS': '0x0', 02:24:00 INFO - 'XPC_SERVICE_NAME': '0', 02:24:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:24:00 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KxyjPdUHTBGHEsDmVYVgrQ/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0125', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 02:24:00 INFO - mozversion application_buildid: 20160512002714 02:24:00 INFO - mozversion application_changeset: 5d7bbfed978a2e0aceff2bbdd8c8d72f5137081d 02:24:00 INFO - mozversion application_display_name: Firefox 02:24:00 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:24:00 INFO - mozversion application_name: Firefox 02:24:00 INFO - mozversion application_remotingname: firefox 02:24:00 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 02:24:00 INFO - mozversion application_vendor: Mozilla 02:24:00 INFO - mozversion application_version: 47.0 02:24:00 INFO - mozversion platform_buildid: 20160512002714 02:24:00 INFO - mozversion platform_changeset: 5d7bbfed978a2e0aceff2bbdd8c8d72f5137081d 02:24:00 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 02:24:00 INFO - mozversion platform_version: 47.0 02:24:00 INFO - using testdate: 1463045040 02:24:00 INFO - actual date: 1463045040 02:24:00 INFO - starting webserver on 'localhost:49359' 02:24:00 INFO - SUITE-START | Running 2 tests 02:24:00 INFO - TEST-START | dromaeo_css 02:24:00 INFO - operating with platform_type : mac_ 02:24:00 INFO - Initialising browser for dromaeo_css test... 02:24:00 INFO - TEST-INFO | started process 2084 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/tmpjp18bH/profile http://localhost:49359/getInfo.html) 02:24:03 INFO - PROCESS | 2084 | __metrics Screen width/height:1600/1200 02:24:03 INFO - PROCESS | 2084 | colorDepth:24 02:24:03 INFO - PROCESS | 2084 | Browser inner width/height: 1024/689 02:24:03 INFO - PROCESS | 2084 | __metrics 02:24:03 ERROR - PROCESS | 2084 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2849: TypeError: this.tabs is undefined 02:24:03 INFO - TEST-INFO | 2084: exit 0 02:24:03 INFO - Browser initialized. 02:24:03 INFO - Running cycle 1/1 for dromaeo_css test... 02:24:03 INFO - TEST-INFO | started process 2089 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/tmpjp18bH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:24:15 INFO - PROCESS | 2089 | RSS: Main: 167919616 02:24:15 INFO - PROCESS | 2089 | 02:25:05 INFO - PROCESS | 2089 | 1463045105372 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] 02:27:15 INFO - PROCESS | 2089 | Cycle 1(1): loaded http://localhost:49359/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49359/tests/dromaeo/cssquery-ext.html) 02:27:16 INFO - PROCESS | 2089 | RSS: Main: 179597312 02:27:16 INFO - PROCESS | 2089 | 02:30:18 INFO - PROCESS | 2089 | Cycle 1(1): loaded http://localhost:49359/tests/dromaeo/cssquery-ext.html (next: http://localhost:49359/tests/dromaeo/cssquery-jquery.html) 02:30:19 INFO - PROCESS | 2089 | RSS: Main: 189947904 02:30:19 INFO - PROCESS | 2089 | 02:33:21 INFO - PROCESS | 2089 | Cycle 1(1): loaded http://localhost:49359/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49359/tests/dromaeo/cssquery-mootools.html) 02:33:21 INFO - PROCESS | 2089 | RSS: Main: 191614976 02:33:21 INFO - PROCESS | 2089 | 02:36:23 INFO - PROCESS | 2089 | Cycle 1(1): loaded http://localhost:49359/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49359/tests/dromaeo/cssquery-prototype.html) 02:36:24 INFO - PROCESS | 2089 | RSS: Main: 195309568 02:36:24 INFO - PROCESS | 2089 | 02:39:26 INFO - PROCESS | 2089 | Cycle 1(1): loaded http://localhost:49359/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49359/tests/dromaeo/cssquery-yui.html) 02:39:27 INFO - PROCESS | 2089 | RSS: Main: 194342912 02:39:27 INFO - PROCESS | 2089 | 02:42:29 INFO - PROCESS | 2089 | Cycle 1(1): loaded http://localhost:49359/tests/dromaeo/cssquery-yui.html (next: http://localhost:49359/tests/dromaeo/cssquery-dojo.html) 02:42:29 INFO - PROCESS | 2089 | RSS: Main: 199839744 02:42:29 INFO - PROCESS | 2089 | 02:42:29 INFO - PROCESS | 2089 | __start_tp_report 02:42:29 INFO - PROCESS | 2089 | _x_x_mozilla_page_load 02:42:29 INFO - PROCESS | 2089 | _x_x_mozilla_page_load_details 02:42:29 INFO - PROCESS | 2089 | |i|pagename|runs| 02:42:29 INFO - PROCESS | 2089 | |0;dojo.html;3078.769092318076;3640.7087433005377;3674.1549443627982;3698.0569954661564;3700.537432820896;5888.175655408236;5925.585209035369;5949.345571987078;5955.702214889255;5960.7317670704815;5978.312494063184;5994.31065427476;6015.248093988241;6016.669083200422;6044.486218671418;5277.788888444457;5316.12283973144;5326.3075800145925;5384.703841288728;5495.917561236584;4423.911521769563;5246.317978662769;5369.24830523725;5370.597205209606;5373.650712703681;5323.8136665216725;5394.676319420828;5402.35171779387;5417.539509141728;5466.453354664522;5382.327209098863;5391.32608423947;5394.352677678665;5397.325334333208;5416.4312747161375;4768.666193366465;5181.96360727856;5299.364076310867;5299.761510732026;5409.972950135263;4206.095689426788;5334.893302133935;5562.47156520134;5777;5842.444967728059;6200.193974783249;6244.87510249797;6290.622562646256;6301.52738544611;6308.653024083674;6207.503399728011;6268.905966410507;6274.717637706269;6275.717592708339;6289.465395441393;5947.851303717442;5949.940500595025;5951.5238780897425;5969.31352894414;5988;6275.843103922439;6283.497320214372;6325.493960483151;6359.586626869239;6368.649724265118;6238.844028899223;6284.528660350492;6303.558750887394;6308.558400911892;6315.147455093595;6306.369363063657;6307.684615769193;6319.652419116993;6320.494360451153;6323.873522529524;6097.7865774697675;6193.194884664965;6258.061290806324;6263.68681565929;6281.528885333618;6269.0909818076125;6283.214598175228;6295.6537390443045;6302.432781049728;6309.463695585835;4287.592678695519;4620.907581848344;5019.698818070928;5042.596592272609;5071.822486213038;3839.251345987506;4418.270985287456;4439.778011099432;4518.728876267434;4570.040291538814;5269.657472264291;5745.3105627324985;5758.337791154071;5789.623674461147;5870.941290587125;5234.633575649744;5435.23906653061;6273.811785646438;6303;6314.463270621956;5750.913736293959;5833.212516310328;5870.295564532198;5916.497097746654;6009.459148676553;4927.28554359623;5502;5506.779728810883;5517.944820551743;5524.005678977823;6444;6461.127747754087;6473.514486413538;6476.902846457213;6492.156019717407;6184.319724830175;6225.968870155621;6266.37336266388;6313.2424109107205;6320.873582528416;5490.396056433709;6397.232332119989;6421.229452465734;6440.9033864493;6560.245571759308;6165.352637972948;6258.0925765762795;6281.246250449975;6304.306526282014;6304.432601065926;6212.813615591359;6263.874722505433;6275.52933529978;6280.7487700491465;6294.905576416266;6082;6230.345813689497;6250.249970003629;6257.342978987323;6265.248170219603;6124.081387791867;6180.381961803963;6230.221222347207;6237.438630523275;6252.718627661857;5667.20659107717;6101.206843110367;6204.317525072329;6265.530085243534;6272.466840318624;6271.122042914087;6287.434130928238;6296.464800492009;6299.30707622152;6301.6219026858535;6034.426729460694;6145.217886812774;6187.226596675416;6247.250329960434;6282.937170628235 02:42:29 INFO - PROCESS | 2089 | |1;ext.html;521.4264309259814;622.6637615687529;627.137685682187;666.2637785247301;682.4028974647184;39774;40587.391189132184;40697.79651101748;40716.185676286455;40816.99999999996;42549.361759573556;42626.786866065704;42755;42802;42811;9961.103500684936;10203.744906377362;10220.846687299696;10235.232357573175;10263.178945684327;8433.620487078066;10049.547770350351;10082.546285417175;10177.949110254438;10211;10138.797224055514;10166.64416745414;10209.438480883548;10218.897811021907;10248.487575621226;10112.241581881352;10140.645077422254;10177.54201060954;10202.234832387601;10208.234382421348;9841.852372214422;9993.200543956465;10009.249306302056;10029.297949143564;10032.097111260022;35134.297314053576;41358.79320603378;41537;41661.79169104165;41868.371974420406;3197.2806118623357;3389.5593572835373;3414.453687410027;3446.8104254266004;3562.786232826039;27816.748246328963;27863.442731145467;28951.276218094506;29084.54577271128;29323.266918327;2827.7172282771558;2837.5885496602878;2845.3597940463437;2885.2209903325984;2894.7539459145996;436830;444169;444785;445069;450538.7473062614;48569;49643.75890602764;70941;71448.64275678588;73036.63481682558;46746.76626616913;46904.765476172404;47212;47275.29087063697;47588.28617570739;17858.732119018227;18121.456356309332;18126.637467250846;18311.633767324845;18324.725129123075;14572.562823115111;14634.195119268543;14654.120752754869;14742.484013059491;14768.18774967387;15603.141827199614;15769;15820.76268855968;15839.208039597974;15952.760708589149;6771.119754431892;6848.315168483112;6870.3473170048765;6895.413889819321;6903.965480172567;963.8506031565119;1064.435849000031;1122.3378206857992;1132.9546818127346;1159.5419809175328;30901.536476952864;30945.0716478506;30987.535186972214;31357.37285254282;31537.21156971094;1154.9076073914066;1161.3670549550516;1180.2151569206553;1188.8751681073363;1198.1732604502865;13681.863181368059;15297.005694629814;15987.880848340505;16006.519804405654;16013.879028467896;515836;518783;524294;525003;527322.3633881806;63364.68317658382;64432.03351949724;64608;65191.674041629485;65229;1559.797226360566;1584.1208129488246;1591.3316407109041;1599.704054749875;1609.6136927137402;30133.547996780504;30572.694273056986;30640.387192255585;30943.226419338796;30962.845185773927;46827;55795;55813;56023.15965260606;56098;483589;484366;485428.1457185383;486903;488646;35482.46776298306;36105.63894361128;47469.762651186524;48248.75875620599;49583;45090.323645146;46103.3084503726;46126.53873461222;46347.30479042749;46891;34070;35499.82250088836;40107.59892401155;40238.59761402466;40567;35626.46560301546;36510;38957.41563876601;39535.406968896066;40112.39831402473;34835;40541.39187912243;40847.79576102101;41679.58320416757;42002.57997419986;32996.67003330032;34950.30099397947;35209.94370168899;35493;35594.822025889705;22057.77942220557;26517.33706657349;26758.920470972982;26798.732012679622;27275.590866136627 02:42:29 INFO - PROCESS | 2089 | |2;jquery.html;293.6094993658435;368.9797061161637;375.9154190307181;377.5280898876405;378.8939097052826;18260;20967.266145684884;21046.57906841862;22766.31701048967;22806.54386912263;12292.815607765891;12681.17572357796;12740.299283539425;12819.807702884464;12897.484100635964;5223.37319521656;5477.835664930059;6295.244570651528;6297.905531417033;6318.74725010999;6193.597416167957;6216.378362163793;6234.563580549363;6266.965950618142;6278.0269058296035;5580.107182850745;5585.1063829787245;5641.041023026095;5664.150377443396;5835.153902684129;5559.833205003876;5598.2442370280305;5601.467860553241;5698.943010569883;5714.485696287334;5370.624056316059;5379.81170659029;5386.676799392049;5465.316835395575;5471.206675032138;14017.719645607132;14235.644108897257;14309.499167529086;22518.43703907399;26550.20349389502;2055.0444043519833;2232.2745107839855;2233.8547994380315;2340.32130682102;2850.957235641467;8339.332853371716;9893.505324733735;10212.591496340137;10234.181265498743;10339.483025848678;1413.258039529245;1448.3989144505017;1747.2050217151166;1811.2845426056765;1820.9726854097198;65246.04261872151;97406;105289.47355263251;109124.4543777284;109979;9720;11584.884151158381;11745.882541174478;11786.469608867621;11896.524139034515;14415.495457658932;14552.126872387691;14555.92722036383;14560.63598410027;14615.78076328856;8043.758687239392;8968.282537396992;9159.313051521163;9372.297077719199;9412.15290623847;7647.617619119022;9499;9518.381305215138;9560.808783824285;9610.135087842127;4595.1269258841385;4621.815127394867;4694.6009589185305;4708.317293992352;4740.288956656529;1941.2429152630486;2006.7491563554556;2013.8791672499697;2038.9694154587694;2039.0620314655127;893.3657103456488;929.4237572704831;970.509892504295;1006.2039259921152;1040.5629635552934;20527.692084618742;21336.14655413766;21534.246301379375;21584;21690.023948922335;867.7136544940155;1001.2790790630734;1022.3048327137469;1055.350959160111;1056.3609016545033;11144.665660030212;11525.596604118984;11566.19036667442;11571.594994175164;11603.59387421453;663470.6427596094;674049;685372;688852;690839;89360;121088.39455802664;122422.38788806;124307.75692243142;127921;898.8157427727431;1026.2662196529518;1057.8307470804634;1062.123747907979;1072.453048945028;16767.91616041912;18238.270469181083;18833.869967801427;19238.519037023627;19508.707369389187;46209.76895115503;47705.761471192425;48091.75954120207;48389.51610483991;49576.50423495719;60492.6975365138;61901.07148392688;74106.62946685232;74261;74348;8291.50752864466;8329.37529685288;8528.104549022264;8542.957285213533;8891.066438023914;11544.422778860855;11875.465604047666;13683.86316136826;13689.178649281075;13988.160710357412;10145.03622155894;11598.07215422781;11998.940005299917;12061.457234424648;12152.817707734213;11511;11727.824082638937;11830.940845295718;11997.40012999364;12141.967932725816;11705.531778728755;12020.75958480843;12163.57427490069;12235.449404776627;12280.75438491243;8371.33029357662;8389.11914248995;8505.702300419456;8547.8717819234;8607.268382187583;8766.21104100634;11458.541658333574;12073.154879158727;12228.510859565518;12308.81536776967 02:42:29 INFO - PROCESS | 2089 | |3;mootools.html;1312.8424589049316;1668.3993762245593;1735.7743493345872;1847.4827048426446;2130.9467263318406;8413.78965525862;8727.432716873398;8838.690645827397;8840.734777956664;9111.316651251149;9131.634734610607;9161.450312981226;9183.632654693803;9231.446113233194;9234.307426942989;2602.8698565071763;2606.6871975362983;2615.659964204651;2616.4897844920247;2622.6459427977265;2235.441139715071;2537.9619305710335;2614.398688301692;2619.7118316985116;2624.002878906013;2328.8239439083322;2592.7018392884775;2600.4929038837436;2608.32183632256;2650.9469810603778;2509.2472258322523;2583.9483210335784;2615.9084432044883;2616.6859976802716;2626.461575377062;2093.9790602093935;2479.752024797524;2507.4608959073885;2514.660520829683;2524.9621255680995;7903.565303908283;9238.214751746109;9291.070892910722;9325.440473571607;9334.579943902541;4786.635808794512;5806.59353845231;5916.467517923407;6030.216071910623;6034.818955431344;3300.785448945811;3479.5128681984543;3722.9813850930573;3764.9435258471144;3766.0584853786554;1006.2503434940943;1107.900288973996;1109.5339957217989;1110.7278716714457;1112.1825458288151;12598.81101783483;12603.306818125;12646.936765316206;12652.873471265262;12670.683232919158;4482.8206871725415;5094.108531007059;5094.490550944876;5156.871078223075;5220.5121540360815;10805.891941080488;10835.67492975212;10847.186461015455;10860.67417977462;10874.238803283693;5776.364599894008;5879.206307148565;5891.823245302648;5946.0189068803165;5993.0710739835395;4061.3501839705505;5125.1799712045895;5158.690478571298;5158.871028224325;5359.624826262202;4099.508059032935;4163.375493675972;4171.144915292358;4179.4357761701785;4187.413762073312;1159.8054004375506;1356.1388518290955;1375.2642336350204;1396.0367346530861;1396.5740449163102;1390.2214759734582;1408.2395506426415;1429.1353730992807;1435.6985033142948;1466.5307101727558;7297.525660832029;8601.698940537177;8605.397622166389;8636.222739953435;8644.265237454762;541.2800974703634;554.3458718711959;556.8524341049625;557.877267001259;560.0702833296708;6308.905366419507;6312.621242725451;6426.260979987268;6473.449756770633;6520.869582608321;9842.557084931195;11421.714457138638;11512.021478174449;11569.305841649528;11823.940880295544;10686.198535109897;11019.173561982883;11406.714832129263;11460.656180314603;11614.128940329509;8598.226159645537;8601.053884072748;8681.782955426164;8691.435056721293;8834.028256891737;8365.790855228668;8426.831463370576;8521.957390213009;8551.700690476053;8597.52713600758;9876.012398760067;9974.900250997398;10006.499675016366;10028.34815737004;10106.797864042825;10107;10394.168466522811;10465;10613.840792387966;10707.250492465453;7263.4915555910575;7746.767596972099;7838.686452542062;7887.211278872067;8015.959920200362;8589.828203436022;8653.653853845775;8673.436226645248;8689.348298877483;8721.65113395457;7515.323620874147;7675.654595543326;7717.884231736416;7724.6910123594425;7804.609769511615;7478.1774004858335;7520.210377910241;7556.055493063367;7602.6198690066385;7702.345300649507;8118.188181181835;8149.959250203949;8195.672173113247;8204.056533498724;8209.589520524069;6977.058097157022;7169.677364518505;7231.240719724353;7235.927640723736;7345.081864766904;7476.439267054884;7480.551166930001;7495.925040749523;7497.662605182671;7536.773896783106 02:42:29 INFO - PROCESS | 2089 | |4;prototype.html;302.8627503723518;318.83261287823905;335.6391878730365;337.20251604954285;346.08633208330014;6803.863922721549;6838.829029274264;6885.414739747128;6939.23668396476;6944.652767361631;5424.674519528821;6763.458923286137;6804.727810887571;6807.421369183626;6822.351876571718;4350.934735978963;4796.520347965193;4856.465788763233;4932.6793758405765;4986.476419975905;4900.926486102711;4932.704037757746;4998.200287953928;5006.799728010875;5056.620753443488;4938.950610493903;4955.851324460254;4963.826266080688;4969.453360130383;4969.503049695019;4950.55445009949;4989.875253118665;4990.65065445419;4995.300657907896;5002.549770520671;3524.7180225582147;3627.6553727395853;4212.262854000538;4302.935455968163;4304.827806887721;5704.515116215127;6209.192804935329;6248.343923887972;6415.390537898892;6541.47668186544;5081.441041485434;5656.1798539211995;5870.412958704095;5935.9406405935815;5959.910601340984;7181.6409179540815;7216.3505284524645;7233.421326293884;7234.782956511313;7254.129504459446;4821.975890120562;4827.179379505457;4833.613310935152;4845.515448455127;4864.294677271811;5781.566382521286;7622.428317876182;7652.349550288232;7652.387808975269;7669.38644908406;4166;4769.30845027476;4898.24077268024;4908.803647854117;4927.704337739747;4955.578775804025;5006.148954677714;5014.122528557488;5041.117804384218;5051.1413059779925;4353.955050787788;4975.979924115548;5930.822075337746;5931.169636250928;5932.911006334908;5862.560307976919;5967.6419414835245;7221.711131554784;7222.96388518054;7234.276572342724;1704.4971733338696;1757.5606098475382;1760.1903124562589;1761.198654612174;1761.5331937036694;1083.91870609703;1123.634818683931;1136.420425582942;1139.515705825021;1148.8506494155874;6707.1616047994;6711.295313992058;6747;6755.594664320157;6763.898541521881;7578.696852125853;7581.886271705929;7585.772426827204;7603.581803000888;7620.390368770485;5642.379338272786;5877.177195192677;6463.159789227464;6692.765753198614;6933.1333583302085;4034.1528279061727;4045.959540404617;4060.6548443382053;4074.6536544393466;4082.2039702257775;8078.798030049178;8088.797780055428;8104.149064348277;8123.6750529978135;8127.146649601825;7704.229577042251;7926.048874135141;7944.364450843918;7944.960275198702;7965.203479652105;6570.605763654196;8079.878801817978;8085.353171746364;8099.595020248964;8151.30713889326;6532.281449040697;7721.227877212234;7721.7683469496005;7725.266099720518;7753.302202801549;7543.660535275816;7560.810979725551;7565.016547848964;7582.810429739301;7601.885971710318;6398.424141827258;6537.346265373425;7781.805454863447;8073.87889181651;8100.068492123246;4794.664373493822;4822.445418776745;4836.588889944253;4844.346013288302;4849;6477.578957367756;7137.964310178416;7706.152323244327;7712.884306735291;7751.418643601865;5268.947310526845;6289.654069026247;7123.536970096927;7125;7454.58999754997;6668.099806526056;7161.713531458683;7361.337479626645;7376.889346659697;7386.778396648109;7184.712611495482;7225.421966242793;7230.6384680766805;7241.058662373857;7249.456290778318;7401.259874012771;7420.925790742023;7426.777196684108;7438.218987006503;7461.813454663677;7261.891071634035;7264.745733899288;7273.0908636420445;7276.090488688914;7284.854302914018 02:42:29 INFO - PROCESS | 2089 | |5;yui.html;711.8327193109615;718.8598223346446;734.8750712378895;794.833085052139;808.9110197878234;15547;15878.285477153528;15904.84095159048;15933.283002264898;15958.76061859073;14764.926175369135;14824.925875370607;14841.851581484181;14882.25588720565;14947.327370268364;805.9798505037392;806.7055524733489;989.8267803134459;993.9602415903391;1022.9897701022969;971.2958105373618;1000.0949141027381;1004.1213937804421;1008.6973907827659;1018.0735530667057;1065.7761870007268;1074.8334008228737;1078.687180717591;1079.8596182496265;1081.0540776820283;1057.8043062033548;1063.1760385701066;1073.441810258665;1075.5805235957982;1076.8115579773507;791.5409062743653;803.6102490292203;804.0431886055634;805.1867613710136;808.1756608259601;15576.61058473536;15793.763093553605;15877;15922;15930.362785488567;13429.932850335783;13458.730825383536;13503.392347344394;13508.257045862474;13573.59279221625;8567.82529372766;10357.689269321858;10381.79236415275;10526.684199473952;10712.51793669279;3569.9111770909863;3589.4795254688183;3596.316699827051;3682.779033258013;3706.314331848617;82892;84339;85318.57340713318;85738.99999999937;86119.56940215321;9734.123928846439;9753.65862194834;9987.151092157103;10005;10223.948880255552;23066;23180;23201.3039608812;23284.41788955256;23321.650175247385;7692.7692169235015;7788.260115289152;7788.299053085249;7894.960525197337;7939.28546430824;4189.350650649093;4235.915281694349;4249.298865687127;4298.742075475481;4309.116631090619;3617.764845285048;3664.8350824212976;3666.1201311685536;3667.5965643779164;3670.50448189491;702.4485778663826;703.9084918960605;709.2942522190453;716.3123401534481;737.498501019312;528.6880740363205;538.63103773915;585.3297973819953;590.4685782795518;634.2420807135521;6620.867582648417;8211;8265.8346833063;8283.544405057779;8547.871781923277;610.5207412181505;614.98462538436;627.1188979483742;628.5788521690479;628.8396458902951;3936.4882565266903;3975.968543542029;4043.454133691973;4055.47278853753;4057.736247143985;94373.5281323589;95146.5242673796;95810;99120;101484;76982;77675;80152;82295.17704822875;82314;66972.33027669661;67815.66092169507;68433;68733.65633171803;69344;8726.956365218388;8804.427712198687;8917;8945.418547794372;9010.32422568323;47709.284360733924;50092.499075010244;50298;52197.478025219265;52695;66816;68386.65806670935;69586.9561956561;69834.65082674554;70463;11235;11281.21031527785;11310.94344528272;11490.82763758561;11611.187216895074;11878.46546905374;11899.405029748652;12036.097292703258;12108.697282567653;12455.314957677414;9614.519274036411;11742.236754610924;12088.093392995384;12242.387880605756;12330.938345308216;11733.706657333634;11797.88202117968;11904.166708330335;12009.519619215134;12244.877551224374;11462.94268528652;11463.885361146615;11509.366984815915;11665.300081995107;11729.061675065803;9860;10094.091531762177;10192.898071019195;10218.284720069523;10471.267011309135;8523.531205783493;9598.040195980346;10087.445190514592;10118.89881101209;10137.847932281169 02:42:29 INFO - PROCESS | 2089 | __end_tp_report 02:42:29 INFO - PROCESS | 2089 | __start_cc_report 02:42:29 INFO - PROCESS | 2089 | _x_x_mozilla_cycle_collect,352 02:42:29 INFO - PROCESS | 2089 | __end_cc_report 02:42:29 INFO - PROCESS | 2089 | __startTimestamp1463046149403__endTimestamp 02:42:29 INFO - PROCESS | 2089 | 02:42:29 INFO - PROCESS | 2089 | ------- Summary: start ------- 02:42:29 INFO - PROCESS | 2089 | Number of tests: 6 02:42:29 INFO - PROCESS | 2089 | 02:42:29 INFO - PROCESS | 2089 | [#0] dojo.html Cycles:165 Average:5813.36 Median:6101.21 stddev:675.52 (11.1%) stddev-sans-first:642.63 02:42:29 INFO - PROCESS | 2089 | Values: 3078.8 3640.7 3674.2 3698.1 3700.5 5888.2 5925.6 5949.3 5955.7 5960.7 5978.3 5994.3 6015.2 6016.7 6044.5 5277.8 5316.1 5326.3 5384.7 5495.9 4423.9 5246.3 5369.2 5370.6 5373.7 5323.8 5394.7 5402.4 5417.5 5466.5 5382.3 5391.3 5394.4 5397.3 5416.4 4768.7 5182.0 5299.4 5299.8 5410.0 4206.1 5334.9 5562.5 5777.0 5842.4 6200.2 6244.9 6290.6 6301.5 6308.7 6207.5 6268.9 6274.7 6275.7 6289.5 5947.9 5949.9 5951.5 5969.3 5988.0 6275.8 6283.5 6325.5 6359.6 6368.6 6238.8 6284.5 6303.6 6308.6 6315.1 6306.4 6307.7 6319.7 6320.5 6323.9 6097.8 6193.2 6258.1 6263.7 6281.5 6269.1 6283.2 6295.7 6302.4 6309.5 4287.6 4620.9 5019.7 5042.6 5071.8 3839.3 4418.3 4439.8 4518.7 4570.0 5269.7 5745.3 5758.3 5789.6 5870.9 5234.6 5435.2 6273.8 6303.0 6314.5 5750.9 5833.2 5870.3 5916.5 6009.5 4927.3 5502.0 5506.8 5517.9 5524.0 6444.0 6461.1 6473.5 6476.9 6492.2 6184.3 6226.0 6266.4 6313.2 6320.9 5490.4 6397.2 6421.2 6440.9 6560.2 6165.4 6258.1 6281.2 6304.3 6304.4 6212.8 6263.9 6275.5 6280.7 6294.9 6082.0 6230.3 6250.2 6257.3 6265.2 6124.1 6180.4 6230.2 6237.4 6252.7 5667.2 6101.2 6204.3 6265.5 6272.5 6271.1 6287.4 6296.5 6299.3 6301.6 6034.4 6145.2 6187.2 6247.3 6282.9 02:42:29 INFO - PROCESS | 2089 | 02:42:29 INFO - PROCESS | 2089 | [#1] ext.html Cycles:180 Average:63699.42 Median:35487.73 stddev:128742.22 (362.8%) stddev-sans-first:129015.99 02:42:29 INFO - PROCESS | 2089 | Values: 521.4 622.7 627.1 666.3 682.4 39774.0 40587.4 40697.8 40716.2 40817.0 42549.4 42626.8 42755.0 42802.0 42811.0 9961.1 10203.7 10220.8 10235.2 10263.2 8433.6 10049.5 10082.5 10177.9 10211.0 10138.8 10166.6 10209.4 10218.9 10248.5 10112.2 10140.6 10177.5 10202.2 10208.2 9841.9 9993.2 10009.2 10029.3 10032.1 35134.3 41358.8 41537.0 41661.8 41868.4 3197.3 3389.6 3414.5 3446.8 3562.8 27816.7 27863.4 28951.3 29084.5 29323.3 2827.7 2837.6 2845.4 2885.2 2894.8 436830.0 444169.0 444785.0 445069.0 450538.7 48569.0 49643.8 70941.0 71448.6 73036.6 46746.8 46904.8 47212.0 47275.3 47588.3 17858.7 18121.5 18126.6 18311.6 18324.7 14572.6 14634.2 14654.1 14742.5 14768.2 15603.1 15769.0 15820.8 15839.2 15952.8 6771.1 6848.3 6870.3 6895.4 6904.0 963.9 1064.4 1122.3 1133.0 1159.5 30901.5 30945.1 30987.5 31357.4 31537.2 1154.9 1161.4 1180.2 1188.9 1198.2 13681.9 15297.0 15987.9 16006.5 16013.9 515836.0 518783.0 524294.0 525003.0 527322.4 63364.7 64432.0 64608.0 65191.7 65229.0 1559.8 1584.1 1591.3 1599.7 1609.6 30133.5 30572.7 30640.4 30943.2 30962.8 46827.0 55795.0 55813.0 56023.2 56098.0 483589.0 484366.0 485428.1 486903.0 488646.0 35482.5 36105.6 47469.8 48248.8 49583.0 45090.3 46103.3 46126.5 46347.3 46891.0 34070.0 35499.8 40107.6 40238.6 40567.0 35626.5 36510.0 38957.4 39535.4 40112.4 34835.0 40541.4 40847.8 41679.6 42002.6 32996.7 34950.3 35209.9 35493.0 35594.8 22057.8 26517.3 26758.9 26798.7 27275.6 02:42:29 INFO - PROCESS | 2089 | 02:42:29 INFO - PROCESS | 2089 | [#2] jquery.html Cycles:180 Average:35807.17 Median:2233.06 stddev:112289.29 (5028.5%) stddev-sans-first:112572.62 02:42:29 INFO - PROCESS | 2089 | Values: 293.6 369.0 375.9 377.5 378.9 18260.0 20967.3 21046.6 22766.3 22806.5 12292.8 12681.2 12740.3 12819.8 12897.5 5223.4 5477.8 6295.2 6297.9 6318.7 6193.6 6216.4 6234.6 6267.0 6278.0 5580.1 5585.1 5641.0 5664.2 5835.2 5559.8 5598.2 5601.5 5698.9 5714.5 5370.6 5379.8 5386.7 5465.3 5471.2 14017.7 14235.6 14309.5 22518.4 26550.2 2055.0 2232.3 2233.9 2340.3 2851.0 8339.3 9893.5 10212.6 10234.2 10339.5 1413.3 1448.4 1747.2 1811.3 1821.0 65246.0 97406.0 105289.5 109124.5 109979.0 9720.0 11584.9 11745.9 11786.5 11896.5 14415.5 14552.1 14555.9 14560.6 14615.8 8043.8 8968.3 9159.3 9372.3 9412.2 7647.6 9499.0 9518.4 9560.8 9610.1 4595.1 4621.8 4694.6 4708.3 4740.3 1941.2 2006.7 2013.9 2039.0 2039.1 893.4 929.4 970.5 1006.2 1040.6 20527.7 21336.1 21534.2 21584.0 21690.0 867.7 1001.3 1022.3 1055.4 1056.4 11144.7 11525.6 11566.2 11571.6 11603.6 663470.6 674049.0 685372.0 688852.0 690839.0 89360.0 121088.4 122422.4 124307.8 127921.0 898.8 1026.3 1057.8 1062.1 1072.5 16767.9 18238.3 18833.9 19238.5 19508.7 46209.8 47705.8 48091.8 48389.5 49576.5 60492.7 61901.1 74106.6 74261.0 74348.0 8291.5 8329.4 8528.1 8543.0 8891.1 11544.4 11875.5 13683.9 13689.2 13988.2 10145.0 11598.1 11998.9 12061.5 12152.8 11511.0 11727.8 11830.9 11997.4 12142.0 11705.5 12020.8 12163.6 12235.4 12280.8 8371.3 8389.1 8505.7 8547.9 8607.3 8766.2 11458.5 12073.2 12228.5 12308.8 02:42:29 INFO - PROCESS | 2089 | 02:42:29 INFO - PROCESS | 2089 | [#3] mootools.html Cycles:180 Average:6336.02 Median:5189.69 stddev:3392.11 (65.4%) stddev-sans-first:3380.61 02:42:29 INFO - PROCESS | 2089 | Values: 1312.8 1668.4 1735.8 1847.5 2130.9 8413.8 8727.4 8838.7 8840.7 9111.3 9131.6 9161.5 9183.6 9231.4 9234.3 2602.9 2606.7 2615.7 2616.5 2622.6 2235.4 2538.0 2614.4 2619.7 2624.0 2328.8 2592.7 2600.5 2608.3 2650.9 2509.2 2583.9 2615.9 2616.7 2626.5 2094.0 2479.8 2507.5 2514.7 2525.0 7903.6 9238.2 9291.1 9325.4 9334.6 4786.6 5806.6 5916.5 6030.2 6034.8 3300.8 3479.5 3723.0 3764.9 3766.1 1006.3 1107.9 1109.5 1110.7 1112.2 12598.8 12603.3 12646.9 12652.9 12670.7 4482.8 5094.1 5094.5 5156.9 5220.5 10805.9 10835.7 10847.2 10860.7 10874.2 5776.4 5879.2 5891.8 5946.0 5993.1 4061.4 5125.2 5158.7 5158.9 5359.6 4099.5 4163.4 4171.1 4179.4 4187.4 1159.8 1356.1 1375.3 1396.0 1396.6 1390.2 1408.2 1429.1 1435.7 1466.5 7297.5 8601.7 8605.4 8636.2 8644.3 541.3 554.3 556.9 557.9 560.1 6308.9 6312.6 6426.3 6473.4 6520.9 9842.6 11421.7 11512.0 11569.3 11823.9 10686.2 11019.2 11406.7 11460.7 11614.1 8598.2 8601.1 8681.8 8691.4 8834.0 8365.8 8426.8 8522.0 8551.7 8597.5 9876.0 9974.9 10006.5 10028.3 10106.8 10107.0 10394.2 10465.0 10613.8 10707.3 7263.5 7746.8 7838.7 7887.2 8016.0 8589.8 8653.7 8673.4 8689.3 8721.7 7515.3 7675.7 7717.9 7724.7 7804.6 7478.2 7520.2 7556.1 7602.6 7702.3 8118.2 8150.0 8195.7 8204.1 8209.6 6977.1 7169.7 7231.2 7235.9 7345.1 7476.4 7480.6 7495.9 7497.7 7536.8 02:42:29 INFO - PROCESS | 2089 | 02:42:29 INFO - PROCESS | 2089 | [#4] prototype.html Cycles:180 Average:5898.90 Median:6534.81 stddev:1912.11 (29.3%) stddev-sans-first:1870.77 02:42:29 INFO - PROCESS | 2089 | Values: 302.9 318.8 335.6 337.2 346.1 6803.9 6838.8 6885.4 6939.2 6944.7 5424.7 6763.5 6804.7 6807.4 6822.4 4350.9 4796.5 4856.5 4932.7 4986.5 4900.9 4932.7 4998.2 5006.8 5056.6 4939.0 4955.9 4963.8 4969.5 4969.5 4950.6 4989.9 4990.7 4995.3 5002.5 3524.7 3627.7 4212.3 4302.9 4304.8 5704.5 6209.2 6248.3 6415.4 6541.5 5081.4 5656.2 5870.4 5935.9 5959.9 7181.6 7216.4 7233.4 7234.8 7254.1 4822.0 4827.2 4833.6 4845.5 4864.3 5781.6 7622.4 7652.3 7652.4 7669.4 4166.0 4769.3 4898.2 4908.8 4927.7 4955.6 5006.1 5014.1 5041.1 5051.1 4354.0 4976.0 5930.8 5931.2 5932.9 5862.6 5967.6 7221.7 7223.0 7234.3 1704.5 1757.6 1760.2 1761.2 1761.5 1083.9 1123.6 1136.4 1139.5 1148.9 6707.2 6711.3 6747.0 6755.6 6763.9 7578.7 7581.9 7585.8 7603.6 7620.4 5642.4 5877.2 6463.2 6692.8 6933.1 4034.2 4046.0 4060.7 4074.7 4082.2 8078.8 8088.8 8104.1 8123.7 8127.1 7704.2 7926.0 7944.4 7945.0 7965.2 6570.6 8079.9 8085.4 8099.6 8151.3 6532.3 7721.2 7721.8 7725.3 7753.3 7543.7 7560.8 7565.0 7582.8 7601.9 6398.4 6537.3 7781.8 8073.9 8100.1 4794.7 4822.4 4836.6 4844.3 4849.0 6477.6 7138.0 7706.2 7712.9 7751.4 5268.9 6289.7 7123.5 7125.0 7454.6 6668.1 7161.7 7361.3 7376.9 7386.8 7184.7 7225.4 7230.6 7241.1 7249.5 7401.3 7420.9 7426.8 7438.2 7461.8 7261.9 7264.7 7273.1 7276.1 7284.9 02:42:29 INFO - PROCESS | 2089 | 02:42:29 INFO - PROCESS | 2089 | [#5] yui.html Cycles:180 Average:18869.75 Median:4049.46 stddev:26434.61 (652.8%) stddev-sans-first:26473.60 02:42:29 INFO - PROCESS | 2089 | Values: 711.8 718.9 734.9 794.8 808.9 15547.0 15878.3 15904.8 15933.3 15958.8 14764.9 14824.9 14841.9 14882.3 14947.3 806.0 806.7 989.8 994.0 1023.0 971.3 1000.1 1004.1 1008.7 1018.1 1065.8 1074.8 1078.7 1079.9 1081.1 1057.8 1063.2 1073.4 1075.6 1076.8 791.5 803.6 804.0 805.2 808.2 15576.6 15793.8 15877.0 15922.0 15930.4 13429.9 13458.7 13503.4 13508.3 13573.6 8567.8 10357.7 10381.8 10526.7 10712.5 3569.9 3589.5 3596.3 3682.8 3706.3 82892.0 84339.0 85318.6 85739.0 86119.6 9734.1 9753.7 9987.2 10005.0 10223.9 23066.0 23180.0 23201.3 23284.4 23321.7 7692.8 7788.3 7788.3 7895.0 7939.3 4189.4 4235.9 4249.3 4298.7 4309.1 3617.8 3664.8 3666.1 3667.6 3670.5 702.4 703.9 709.3 716.3 737.5 528.7 538.6 585.3 590.5 634.2 6620.9 8211.0 8265.8 8283.5 8547.9 610.5 615.0 627.1 628.6 628.8 3936.5 3976.0 4043.5 4055.5 4057.7 94373.5 95146.5 95810.0 99120.0 101484.0 76982.0 77675.0 80152.0 82295.2 82314.0 66972.3 67815.7 68433.0 68733.7 69344.0 8727.0 8804.4 8917.0 8945.4 9010.3 47709.3 50092.5 50298.0 52197.5 52695.0 66816.0 68386.7 69587.0 69834.7 70463.0 11235.0 11281.2 11310.9 11490.8 11611.2 11878.5 11899.4 12036.1 12108.7 12455.3 9614.5 11742.2 12088.1 12242.4 12330.9 11733.7 11797.9 11904.2 12009.5 12244.9 11462.9 11463.9 11509.4 11665.3 11729.1 9860.0 10094.1 10192.9 10218.3 10471.3 8523.5 9598.0 10087.4 10118.9 10137.8 02:42:29 INFO - PROCESS | 2089 | -------- Summary: end -------- 02:42:29 INFO - PROCESS | 2089 | 02:42:29 INFO - TEST-INFO | 2089: exit 0 02:42:29 INFO - TEST-OK | dromaeo_css | took 1109457ms 02:42:29 INFO - TEST-START | kraken 02:42:29 INFO - operating with platform_type : mac_ 02:42:29 INFO - Initialising browser for kraken test... 02:42:29 INFO - TEST-INFO | started process 2095 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/tmpcPcLWd/profile http://localhost:49359/getInfo.html) 02:42:32 INFO - PROCESS | 2095 | __metrics Screen width/height:1600/1200 02:42:32 INFO - PROCESS | 2095 | colorDepth:24 02:42:32 INFO - PROCESS | 2095 | Browser inner width/height: 1024/689 02:42:32 INFO - PROCESS | 2095 | __metrics 02:42:32 ERROR - PROCESS | 2095 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2849: TypeError: this.tabs is undefined 02:42:33 INFO - TEST-INFO | 2095: exit 0 02:42:33 INFO - Browser initialized. 02:42:33 INFO - Running cycle 1/1 for kraken test... 02:42:33 INFO - TEST-INFO | started process 2099 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/tmpcPcLWd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:42:45 INFO - PROCESS | 2099 | RSS: Main: 167858176 02:42:45 INFO - PROCESS | 2099 | 02:42:47 INFO - PROCESS | 2099 | Cycle 1(1): loaded http://localhost:49359/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49359/tests/kraken/driver.html?testName=audio-beat-detection) 02:42:48 INFO - PROCESS | 2099 | RSS: Main: 180101120 02:42:48 INFO - PROCESS | 2099 | 02:42:51 INFO - PROCESS | 2099 | Cycle 1(1): loaded http://localhost:49359/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49359/tests/kraken/driver.html?testName=audio-dft) 02:42:51 INFO - PROCESS | 2099 | RSS: Main: 188784640 02:42:51 INFO - PROCESS | 2099 | 02:42:55 INFO - PROCESS | 2099 | Cycle 1(1): loaded http://localhost:49359/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49359/tests/kraken/driver.html?testName=audio-fft) 02:42:55 INFO - PROCESS | 2099 | RSS: Main: 192032768 02:42:55 INFO - PROCESS | 2099 | 02:42:57 INFO - PROCESS | 2099 | Cycle 1(1): loaded http://localhost:49359/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49359/tests/kraken/driver.html?testName=audio-oscillator) 02:42:58 INFO - PROCESS | 2099 | RSS: Main: 192958464 02:42:58 INFO - PROCESS | 2099 | 02:43:00 INFO - PROCESS | 2099 | Cycle 1(1): loaded http://localhost:49359/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49359/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:43:00 INFO - PROCESS | 2099 | RSS: Main: 193220608 02:43:00 INFO - PROCESS | 2099 | 02:43:03 INFO - PROCESS | 2099 | Cycle 1(1): loaded http://localhost:49359/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49359/tests/kraken/driver.html?testName=imaging-darkroom) 02:43:04 INFO - PROCESS | 2099 | RSS: Main: 194281472 02:43:04 INFO - PROCESS | 2099 | 02:43:07 INFO - PROCESS | 2099 | Cycle 1(1): loaded http://localhost:49359/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49359/tests/kraken/driver.html?testName=imaging-desaturate) 02:43:07 INFO - PROCESS | 2099 | RSS: Main: 194224128 02:43:07 INFO - PROCESS | 2099 | 02:43:10 INFO - PROCESS | 2099 | Cycle 1(1): loaded http://localhost:49359/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49359/tests/kraken/driver.html?testName=json-parse-financial) 02:43:11 INFO - PROCESS | 2099 | RSS: Main: 194379776 02:43:11 INFO - PROCESS | 2099 | 02:43:12 INFO - PROCESS | 2099 | Cycle 1(1): loaded http://localhost:49359/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49359/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:43:13 INFO - PROCESS | 2099 | RSS: Main: 194060288 02:43:13 INFO - PROCESS | 2099 | 02:43:15 INFO - PROCESS | 2099 | Cycle 1(1): loaded http://localhost:49359/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49359/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:43:16 INFO - PROCESS | 2099 | RSS: Main: 194080768 02:43:16 INFO - PROCESS | 2099 | 02:43:18 INFO - PROCESS | 2099 | Cycle 1(1): loaded http://localhost:49359/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49359/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:43:18 INFO - PROCESS | 2099 | RSS: Main: 194502656 02:43:18 INFO - PROCESS | 2099 | 02:43:21 INFO - PROCESS | 2099 | Cycle 1(1): loaded http://localhost:49359/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49359/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:43:21 INFO - PROCESS | 2099 | RSS: Main: 194523136 02:43:21 INFO - PROCESS | 2099 | 02:43:24 INFO - PROCESS | 2099 | Cycle 1(1): loaded http://localhost:49359/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49359/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:43:25 INFO - PROCESS | 2099 | RSS: Main: 194531328 02:43:25 INFO - PROCESS | 2099 | 02:43:26 INFO - PROCESS | 2099 | Cycle 1(1): loaded http://localhost:49359/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49359/tests/kraken/driver.html?testName=ai-astar) 02:43:26 INFO - PROCESS | 2099 | RSS: Main: 196292608 02:43:26 INFO - PROCESS | 2099 | 02:43:26 INFO - PROCESS | 2099 | __start_tp_report 02:43:26 INFO - PROCESS | 2099 | _x_x_mozilla_page_load 02:43:26 INFO - PROCESS | 2099 | _x_x_mozilla_page_load_details 02:43:26 INFO - PROCESS | 2099 | |i|pagename|runs| 02:43:26 INFO - PROCESS | 2099 | |0;ai-astar;116;114;115;121;166;182;114;113;115;117 02:43:26 INFO - PROCESS | 2099 | |1;audio-beat-detection;149;120;153;148;118;126;169;147;187;121 02:43:26 INFO - PROCESS | 2099 | |2;audio-dft;192;195;217;178;183;273;234;275;240;197 02:43:26 INFO - PROCESS | 2099 | |3;audio-fft;71;71;72;115;57;58;58;58;139;56 02:43:26 INFO - PROCESS | 2099 | |4;audio-oscillator;80;88;125;84;74;80;123;79;80;121 02:43:26 INFO - PROCESS | 2099 | |5;imaging-gaussian-blur;89;87;89;87;89;86;105;110;87;86 02:43:26 INFO - PROCESS | 2099 | |6;imaging-darkroom;94;95;96;95;105;121;96;118;100;148 02:43:26 INFO - PROCESS | 2099 | |7;imaging-desaturate;90;86;94;88;126;140;85;97;133;136 02:43:26 INFO - PROCESS | 2099 | |8;json-parse-financial;51;54;79;70;61;50;48;57;47;44 02:43:26 INFO - PROCESS | 2099 | |9;json-stringify-tinderbox;61;61;88;90;71;67;75;94;97;74 02:43:26 INFO - PROCESS | 2099 | |10;stanford-crypto-aes;85;80;82;83;85;77;81;72;86;111 02:43:26 INFO - PROCESS | 2099 | |11;stanford-crypto-ccm;130;132;132;131;144;131;129;136;141;140 02:43:26 INFO - PROCESS | 2099 | |12;stanford-crypto-pbkdf2;137;134;135;135;121;162;171;143;138;124 02:43:26 INFO - PROCESS | 2099 | |13;stanford-crypto-sha256-iterative;70;67;68;64;67;62;64;68;83;81 02:43:26 INFO - PROCESS | 2099 | __end_tp_report 02:43:26 INFO - PROCESS | 2099 | __start_cc_report 02:43:26 INFO - PROCESS | 2099 | _x_x_mozilla_cycle_collect,901 02:43:26 INFO - PROCESS | 2099 | __end_cc_report 02:43:26 INFO - PROCESS | 2099 | __startTimestamp1463046206972__endTimestamp 02:43:26 INFO - PROCESS | 2099 | 02:43:26 INFO - PROCESS | 2099 | ------- Summary: start ------- 02:43:26 INFO - PROCESS | 2099 | Number of tests: 14 02:43:26 INFO - PROCESS | 2099 | 02:43:26 INFO - PROCESS | 2099 | [#0] ai-astar Cycles:10 Average:127.30 Median:116.50 stddev:25.00 (21.5%) stddev-sans-first:26.18 02:43:26 INFO - PROCESS | 2099 | Values: 116.0 114.0 115.0 121.0 166.0 182.0 114.0 113.0 115.0 117.0 02:43:26 INFO - PROCESS | 2099 | 02:43:26 INFO - PROCESS | 2099 | [#1] audio-beat-detection Cycles:10 Average:143.80 Median:148.50 stddev:22.88 (15.4%) stddev-sans-first:24.19 02:43:26 INFO - PROCESS | 2099 | Values: 149.0 120.0 153.0 148.0 118.0 126.0 169.0 147.0 187.0 121.0 02:43:26 INFO - PROCESS | 2099 | 02:43:26 INFO - PROCESS | 2099 | [#2] audio-dft Cycles:10 Average:218.40 Median:225.50 stddev:35.72 (15.8%) stddev-sans-first:36.59 02:43:26 INFO - PROCESS | 2099 | Values: 192.0 195.0 217.0 178.0 183.0 273.0 234.0 275.0 240.0 197.0 02:43:26 INFO - PROCESS | 2099 | 02:43:26 INFO - PROCESS | 2099 | [#3] audio-fft Cycles:10 Average:75.50 Median:58.00 stddev:28.45 (49.1%) stddev-sans-first:30.13 02:43:26 INFO - PROCESS | 2099 | Values: 71.0 71.0 72.0 115.0 57.0 58.0 58.0 58.0 139.0 56.0 02:43:26 INFO - PROCESS | 2099 | 02:43:26 INFO - PROCESS | 2099 | [#4] audio-oscillator Cycles:10 Average:93.40 Median:80.00 stddev:20.75 (25.9%) stddev-sans-first:21.44 02:43:26 INFO - PROCESS | 2099 | Values: 80.0 88.0 125.0 84.0 74.0 80.0 123.0 79.0 80.0 121.0 02:43:26 INFO - PROCESS | 2099 | 02:43:26 INFO - PROCESS | 2099 | [#5] imaging-gaussian-blur Cycles:10 Average:91.50 Median:87.00 stddev:8.59 (9.9%) stddev-sans-first:9.07 02:43:26 INFO - PROCESS | 2099 | Values: 89.0 87.0 89.0 87.0 89.0 86.0 105.0 110.0 87.0 86.0 02:43:26 INFO - PROCESS | 2099 | 02:43:26 INFO - PROCESS | 2099 | [#6] imaging-darkroom Cycles:10 Average:106.80 Median:94.50 stddev:17.48 (18.5%) stddev-sans-first:17.91 02:43:26 INFO - PROCESS | 2099 | Values: 94.0 95.0 96.0 95.0 105.0 121.0 96.0 118.0 100.0 148.0 02:43:26 INFO - PROCESS | 2099 | 02:43:26 INFO - PROCESS | 2099 | [#7] imaging-desaturate Cycles:10 Average:107.50 Median:87.00 stddev:23.11 (26.6%) stddev-sans-first:23.63 02:43:26 INFO - PROCESS | 2099 | Values: 90.0 86.0 94.0 88.0 126.0 140.0 85.0 97.0 133.0 136.0 02:43:26 INFO - PROCESS | 2099 | 02:43:26 INFO - PROCESS | 2099 | [#8] json-parse-financial Cycles:10 Average:56.10 Median:55.50 stddev:11.08 (20.0%) stddev-sans-first:11.60 02:43:26 INFO - PROCESS | 2099 | Values: 51.0 54.0 79.0 70.0 61.0 50.0 48.0 57.0 47.0 44.0 02:43:26 INFO - PROCESS | 2099 | 02:43:26 INFO - PROCESS | 2099 | [#9] json-stringify-tinderbox Cycles:10 Average:77.80 Median:81.50 stddev:13.47 (16.5%) stddev-sans-first:12.85 02:43:26 INFO - PROCESS | 2099 | Values: 61.0 61.0 88.0 90.0 71.0 67.0 75.0 94.0 97.0 74.0 02:43:26 INFO - PROCESS | 2099 | 02:43:26 INFO - PROCESS | 2099 | [#10] stanford-crypto-aes Cycles:10 Average:84.20 Median:82.50 stddev:10.32 (12.5%) stddev-sans-first:10.94 02:43:26 INFO - PROCESS | 2099 | Values: 85.0 80.0 82.0 83.0 85.0 77.0 81.0 72.0 86.0 111.0 02:43:26 INFO - PROCESS | 2099 | 02:43:26 INFO - PROCESS | 2099 | [#11] stanford-crypto-ccm Cycles:10 Average:134.60 Median:134.00 stddev:5.30 (4.0%) stddev-sans-first:5.35 02:43:26 INFO - PROCESS | 2099 | Values: 130.0 132.0 132.0 131.0 144.0 131.0 129.0 136.0 141.0 140.0 02:43:26 INFO - PROCESS | 2099 | 02:43:26 INFO - PROCESS | 2099 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:140.00 Median:137.50 stddev:15.53 (11.3%) stddev-sans-first:16.43 02:43:26 INFO - PROCESS | 2099 | Values: 137.0 134.0 135.0 135.0 121.0 162.0 171.0 143.0 138.0 124.0 02:43:26 INFO - PROCESS | 2099 | 02:43:26 INFO - PROCESS | 2099 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.40 Median:68.00 stddev:7.06 (10.4%) stddev-sans-first:7.48 02:43:26 INFO - PROCESS | 2099 | Values: 70.0 67.0 68.0 64.0 67.0 62.0 64.0 68.0 83.0 81.0 02:43:26 INFO - PROCESS | 2099 | -------- Summary: end -------- 02:43:26 INFO - PROCESS | 2099 | 02:43:27 INFO - TEST-INFO | 2099: exit 0 02:43:27 INFO - TEST-OK | kraken | took 57687ms 02:43:27 INFO - SUITE-END | took 1167s 02:43:28 INFO - Completed test suite (00:19:27) 02:43:28 INFO - javascript benchmark 02:43:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3078.769092318076, 3640.7087433005377, 3674.1549443627982, 3698.0569954661564, 3700.537432820896, 5888.175655408236, 5925.585209035369, 5949.345571987078, 5955.702214889255, 5960.7317670704815, 5978.312494063184, 5994.31065427476, 6015.248093988241, 6016.669083200422, 6044.486218671418, 5277.788888444457, 5316.12283973144, 5326.3075800145925, 5384.703841288728, 5495.917561236584, 4423.911521769563, 5246.317978662769, 5369.24830523725, 5370.597205209606, 5373.650712703681, 5323.8136665216725, 5394.676319420828, 5402.35171779387, 5417.539509141728, 5466.453354664522, 5382.327209098863, 5391.32608423947, 5394.352677678665, 5397.325334333208, 5416.4312747161375, 4768.666193366465, 5181.96360727856, 5299.364076310867, 5299.761510732026, 5409.972950135263, 4206.095689426788, 5334.893302133935, 5562.47156520134, 5777.0, 5842.444967728059, 6200.193974783249, 6244.87510249797, 6290.622562646256, 6301.52738544611, 6308.653024083674, 6207.503399728011, 6268.905966410507, 6274.717637706269, 6275.717592708339, 6289.465395441393, 5947.851303717442, 5949.940500595025, 5951.5238780897425, 5969.31352894414, 5988.0, 6275.843103922439, 6283.497320214372, 6325.493960483151, 6359.586626869239, 6368.649724265118, 6238.844028899223, 6284.528660350492, 6303.558750887394, 6308.558400911892, 6315.147455093595, 6306.369363063657, 6307.684615769193, 6319.652419116993, 6320.494360451153, 6323.873522529524, 6097.7865774697675, 6193.194884664965, 6258.061290806324, 6263.68681565929, 6281.528885333618, 6269.0909818076125, 6283.214598175228, 6295.6537390443045, 6302.432781049728, 6309.463695585835, 4287.592678695519, 4620.907581848344, 5019.698818070928, 5042.596592272609, 5071.822486213038, 3839.251345987506, 4418.270985287456, 4439.778011099432, 4518.728876267434, 4570.040291538814, 5269.657472264291, 5745.3105627324985, 5758.337791154071, 5789.623674461147, 5870.941290587125, 5234.633575649744, 5435.23906653061, 6273.811785646438, 6303.0, 6314.463270621956, 5750.913736293959, 5833.212516310328, 5870.295564532198, 5916.497097746654, 6009.459148676553, 4927.28554359623, 5502.0, 5506.779728810883, 5517.944820551743, 5524.005678977823, 6444.0, 6461.127747754087, 6473.514486413538, 6476.902846457213, 6492.156019717407, 6184.319724830175, 6225.968870155621, 6266.37336266388, 6313.2424109107205, 6320.873582528416, 5490.396056433709, 6397.232332119989, 6421.229452465734, 6440.9033864493, 6560.245571759308, 6165.352637972948, 6258.0925765762795, 6281.246250449975, 6304.306526282014, 6304.432601065926, 6212.813615591359, 6263.874722505433, 6275.52933529978, 6280.7487700491465, 6294.905576416266, 6082.0, 6230.345813689497, 6250.249970003629, 6257.342978987323, 6265.248170219603, 6124.081387791867, 6180.381961803963, 6230.221222347207, 6237.438630523275, 6252.718627661857, 5667.20659107717, 6101.206843110367, 6204.317525072329, 6265.530085243534, 6272.466840318624, 6271.122042914087, 6287.434130928238, 6296.464800492009, 6299.30707622152, 6301.6219026858535, 6034.426729460694, 6145.217886812774, 6187.226596675416, 6247.250329960434, 6282.937170628235], "unit": "score", "name": "dojo.html", "value": 5771.812949867284}, {"lowerIsBetter": false, "replicates": [521.4264309259814, 622.6637615687529, 627.137685682187, 666.2637785247301, 682.4028974647184, 39774.0, 40587.391189132184, 40697.79651101748, 40716.185676286455, 40816.99999999996, 42549.361759573556, 42626.786866065704, 42755.0, 42802.0, 42811.0, 9961.103500684936, 10203.744906377362, 10220.846687299696, 10235.232357573175, 10263.178945684327, 8433.620487078066, 10049.547770350351, 10082.546285417175, 10177.949110254438, 10211.0, 10138.797224055514, 10166.64416745414, 10209.438480883548, 10218.897811021907, 10248.487575621226, 10112.241581881352, 10140.645077422254, 10177.54201060954, 10202.234832387601, 10208.234382421348, 9841.852372214422, 9993.200543956465, 10009.249306302056, 10029.297949143564, 10032.097111260022, 35134.297314053576, 41358.79320603378, 41537.0, 41661.79169104165, 41868.371974420406, 3197.2806118623357, 3389.5593572835373, 3414.453687410027, 3446.8104254266004, 3562.786232826039, 27816.748246328963, 27863.442731145467, 28951.276218094506, 29084.54577271128, 29323.266918327, 2827.7172282771558, 2837.5885496602878, 2845.3597940463437, 2885.2209903325984, 2894.7539459145996, 436830.0, 444169.0, 444785.0, 445069.0, 450538.7473062614, 48569.0, 49643.75890602764, 70941.0, 71448.64275678588, 73036.63481682558, 46746.76626616913, 46904.765476172404, 47212.0, 47275.29087063697, 47588.28617570739, 17858.732119018227, 18121.456356309332, 18126.637467250846, 18311.633767324845, 18324.725129123075, 14572.562823115111, 14634.195119268543, 14654.120752754869, 14742.484013059491, 14768.18774967387, 15603.141827199614, 15769.0, 15820.76268855968, 15839.208039597974, 15952.760708589149, 6771.119754431892, 6848.315168483112, 6870.3473170048765, 6895.413889819321, 6903.965480172567, 963.8506031565119, 1064.435849000031, 1122.3378206857992, 1132.9546818127346, 1159.5419809175328, 30901.536476952864, 30945.0716478506, 30987.535186972214, 31357.37285254282, 31537.21156971094, 1154.9076073914066, 1161.3670549550516, 1180.2151569206553, 1188.8751681073363, 1198.1732604502865, 13681.863181368059, 15297.005694629814, 15987.880848340505, 16006.519804405654, 16013.879028467896, 515836.0, 518783.0, 524294.0, 525003.0, 527322.3633881806, 63364.68317658382, 64432.03351949724, 64608.0, 65191.674041629485, 65229.0, 1559.797226360566, 1584.1208129488246, 1591.3316407109041, 1599.704054749875, 1609.6136927137402, 30133.547996780504, 30572.694273056986, 30640.387192255585, 30943.226419338796, 30962.845185773927, 46827.0, 55795.0, 55813.0, 56023.15965260606, 56098.0, 483589.0, 484366.0, 485428.1457185383, 486903.0, 488646.0, 35482.46776298306, 36105.63894361128, 47469.762651186524, 48248.75875620599, 49583.0, 45090.323645146, 46103.3084503726, 46126.53873461222, 46347.30479042749, 46891.0, 34070.0, 35499.82250088836, 40107.59892401155, 40238.59761402466, 40567.0, 35626.46560301546, 36510.0, 38957.41563876601, 39535.406968896066, 40112.39831402473, 34835.0, 40541.39187912243, 40847.79576102101, 41679.58320416757, 42002.57997419986, 32996.67003330032, 34950.30099397947, 35209.94370168899, 35493.0, 35594.822025889705, 22057.77942220557, 26517.33706657349, 26758.920470972982, 26798.732012679622, 27275.590866136627], "unit": "score", "name": "ext.html", "value": 20366.476331097983}, {"lowerIsBetter": false, "replicates": [293.6094993658435, 368.9797061161637, 375.9154190307181, 377.5280898876405, 378.8939097052826, 18260.0, 20967.266145684884, 21046.57906841862, 22766.31701048967, 22806.54386912263, 12292.815607765891, 12681.17572357796, 12740.299283539425, 12819.807702884464, 12897.484100635964, 5223.37319521656, 5477.835664930059, 6295.244570651528, 6297.905531417033, 6318.74725010999, 6193.597416167957, 6216.378362163793, 6234.563580549363, 6266.965950618142, 6278.0269058296035, 5580.107182850745, 5585.1063829787245, 5641.041023026095, 5664.150377443396, 5835.153902684129, 5559.833205003876, 5598.2442370280305, 5601.467860553241, 5698.943010569883, 5714.485696287334, 5370.624056316059, 5379.81170659029, 5386.676799392049, 5465.316835395575, 5471.206675032138, 14017.719645607132, 14235.644108897257, 14309.499167529086, 22518.43703907399, 26550.20349389502, 2055.0444043519833, 2232.2745107839855, 2233.8547994380315, 2340.32130682102, 2850.957235641467, 8339.332853371716, 9893.505324733735, 10212.591496340137, 10234.181265498743, 10339.483025848678, 1413.258039529245, 1448.3989144505017, 1747.2050217151166, 1811.2845426056765, 1820.9726854097198, 65246.04261872151, 97406.0, 105289.47355263251, 109124.4543777284, 109979.0, 9720.0, 11584.884151158381, 11745.882541174478, 11786.469608867621, 11896.524139034515, 14415.495457658932, 14552.126872387691, 14555.92722036383, 14560.63598410027, 14615.78076328856, 8043.758687239392, 8968.282537396992, 9159.313051521163, 9372.297077719199, 9412.15290623847, 7647.617619119022, 9499.0, 9518.381305215138, 9560.808783824285, 9610.135087842127, 4595.1269258841385, 4621.815127394867, 4694.6009589185305, 4708.317293992352, 4740.288956656529, 1941.2429152630486, 2006.7491563554556, 2013.8791672499697, 2038.9694154587694, 2039.0620314655127, 893.3657103456488, 929.4237572704831, 970.509892504295, 1006.2039259921152, 1040.5629635552934, 20527.692084618742, 21336.14655413766, 21534.246301379375, 21584.0, 21690.023948922335, 867.7136544940155, 1001.2790790630734, 1022.3048327137469, 1055.350959160111, 1056.3609016545033, 11144.665660030212, 11525.596604118984, 11566.19036667442, 11571.594994175164, 11603.59387421453, 663470.6427596094, 674049.0, 685372.0, 688852.0, 690839.0, 89360.0, 121088.39455802664, 122422.38788806, 124307.75692243142, 127921.0, 898.8157427727431, 1026.2662196529518, 1057.8307470804634, 1062.123747907979, 1072.453048945028, 16767.91616041912, 18238.270469181083, 18833.869967801427, 19238.519037023627, 19508.707369389187, 46209.76895115503, 47705.761471192425, 48091.75954120207, 48389.51610483991, 49576.50423495719, 60492.6975365138, 61901.07148392688, 74106.62946685232, 74261.0, 74348.0, 8291.50752864466, 8329.37529685288, 8528.104549022264, 8542.957285213533, 8891.066438023914, 11544.422778860855, 11875.465604047666, 13683.86316136826, 13689.178649281075, 13988.160710357412, 10145.03622155894, 11598.07215422781, 11998.940005299917, 12061.457234424648, 12152.817707734213, 11511.0, 11727.824082638937, 11830.940845295718, 11997.40012999364, 12141.967932725816, 11705.531778728755, 12020.75958480843, 12163.57427490069, 12235.449404776627, 12280.75438491243, 8371.33029357662, 8389.11914248995, 8505.702300419456, 8547.8717819234, 8607.268382187583, 8766.21104100634, 11458.541658333574, 12073.154879158727, 12228.510859565518, 12308.81536776967], "unit": "score", "name": "jquery.html", "value": 9409.297748457666}, {"lowerIsBetter": false, "replicates": [1312.8424589049316, 1668.3993762245593, 1735.7743493345872, 1847.4827048426446, 2130.9467263318406, 8413.78965525862, 8727.432716873398, 8838.690645827397, 8840.734777956664, 9111.316651251149, 9131.634734610607, 9161.450312981226, 9183.632654693803, 9231.446113233194, 9234.307426942989, 2602.8698565071763, 2606.6871975362983, 2615.659964204651, 2616.4897844920247, 2622.6459427977265, 2235.441139715071, 2537.9619305710335, 2614.398688301692, 2619.7118316985116, 2624.002878906013, 2328.8239439083322, 2592.7018392884775, 2600.4929038837436, 2608.32183632256, 2650.9469810603778, 2509.2472258322523, 2583.9483210335784, 2615.9084432044883, 2616.6859976802716, 2626.461575377062, 2093.9790602093935, 2479.752024797524, 2507.4608959073885, 2514.660520829683, 2524.9621255680995, 7903.565303908283, 9238.214751746109, 9291.070892910722, 9325.440473571607, 9334.579943902541, 4786.635808794512, 5806.59353845231, 5916.467517923407, 6030.216071910623, 6034.818955431344, 3300.785448945811, 3479.5128681984543, 3722.9813850930573, 3764.9435258471144, 3766.0584853786554, 1006.2503434940943, 1107.900288973996, 1109.5339957217989, 1110.7278716714457, 1112.1825458288151, 12598.81101783483, 12603.306818125, 12646.936765316206, 12652.873471265262, 12670.683232919158, 4482.8206871725415, 5094.108531007059, 5094.490550944876, 5156.871078223075, 5220.5121540360815, 10805.891941080488, 10835.67492975212, 10847.186461015455, 10860.67417977462, 10874.238803283693, 5776.364599894008, 5879.206307148565, 5891.823245302648, 5946.0189068803165, 5993.0710739835395, 4061.3501839705505, 5125.1799712045895, 5158.690478571298, 5158.871028224325, 5359.624826262202, 4099.508059032935, 4163.375493675972, 4171.144915292358, 4179.4357761701785, 4187.413762073312, 1159.8054004375506, 1356.1388518290955, 1375.2642336350204, 1396.0367346530861, 1396.5740449163102, 1390.2214759734582, 1408.2395506426415, 1429.1353730992807, 1435.6985033142948, 1466.5307101727558, 7297.525660832029, 8601.698940537177, 8605.397622166389, 8636.222739953435, 8644.265237454762, 541.2800974703634, 554.3458718711959, 556.8524341049625, 557.877267001259, 560.0702833296708, 6308.905366419507, 6312.621242725451, 6426.260979987268, 6473.449756770633, 6520.869582608321, 9842.557084931195, 11421.714457138638, 11512.021478174449, 11569.305841649528, 11823.940880295544, 10686.198535109897, 11019.173561982883, 11406.714832129263, 11460.656180314603, 11614.128940329509, 8598.226159645537, 8601.053884072748, 8681.782955426164, 8691.435056721293, 8834.028256891737, 8365.790855228668, 8426.831463370576, 8521.957390213009, 8551.700690476053, 8597.52713600758, 9876.012398760067, 9974.900250997398, 10006.499675016366, 10028.34815737004, 10106.797864042825, 10107.0, 10394.168466522811, 10465.0, 10613.840792387966, 10707.250492465453, 7263.4915555910575, 7746.767596972099, 7838.686452542062, 7887.211278872067, 8015.959920200362, 8589.828203436022, 8653.653853845775, 8673.436226645248, 8689.348298877483, 8721.65113395457, 7515.323620874147, 7675.654595543326, 7717.884231736416, 7724.6910123594425, 7804.609769511615, 7478.1774004858335, 7520.210377910241, 7556.055493063367, 7602.6198690066385, 7702.345300649507, 8118.188181181835, 8149.959250203949, 8195.672173113247, 8204.056533498724, 8209.589520524069, 6977.058097157022, 7169.677364518505, 7231.240719724353, 7235.927640723736, 7345.081864766904, 7476.439267054884, 7480.551166930001, 7495.925040749523, 7497.662605182671, 7536.773896783106], "unit": "score", "name": "mootools.html", "value": 5070.038286231313}, {"lowerIsBetter": false, "replicates": [302.8627503723518, 318.83261287823905, 335.6391878730365, 337.20251604954285, 346.08633208330014, 6803.863922721549, 6838.829029274264, 6885.414739747128, 6939.23668396476, 6944.652767361631, 5424.674519528821, 6763.458923286137, 6804.727810887571, 6807.421369183626, 6822.351876571718, 4350.934735978963, 4796.520347965193, 4856.465788763233, 4932.6793758405765, 4986.476419975905, 4900.926486102711, 4932.704037757746, 4998.200287953928, 5006.799728010875, 5056.620753443488, 4938.950610493903, 4955.851324460254, 4963.826266080688, 4969.453360130383, 4969.503049695019, 4950.55445009949, 4989.875253118665, 4990.65065445419, 4995.300657907896, 5002.549770520671, 3524.7180225582147, 3627.6553727395853, 4212.262854000538, 4302.935455968163, 4304.827806887721, 5704.515116215127, 6209.192804935329, 6248.343923887972, 6415.390537898892, 6541.47668186544, 5081.441041485434, 5656.1798539211995, 5870.412958704095, 5935.9406405935815, 5959.910601340984, 7181.6409179540815, 7216.3505284524645, 7233.421326293884, 7234.782956511313, 7254.129504459446, 4821.975890120562, 4827.179379505457, 4833.613310935152, 4845.515448455127, 4864.294677271811, 5781.566382521286, 7622.428317876182, 7652.349550288232, 7652.387808975269, 7669.38644908406, 4166.0, 4769.30845027476, 4898.24077268024, 4908.803647854117, 4927.704337739747, 4955.578775804025, 5006.148954677714, 5014.122528557488, 5041.117804384218, 5051.1413059779925, 4353.955050787788, 4975.979924115548, 5930.822075337746, 5931.169636250928, 5932.911006334908, 5862.560307976919, 5967.6419414835245, 7221.711131554784, 7222.96388518054, 7234.276572342724, 1704.4971733338696, 1757.5606098475382, 1760.1903124562589, 1761.198654612174, 1761.5331937036694, 1083.91870609703, 1123.634818683931, 1136.420425582942, 1139.515705825021, 1148.8506494155874, 6707.1616047994, 6711.295313992058, 6747.0, 6755.594664320157, 6763.898541521881, 7578.696852125853, 7581.886271705929, 7585.772426827204, 7603.581803000888, 7620.390368770485, 5642.379338272786, 5877.177195192677, 6463.159789227464, 6692.765753198614, 6933.1333583302085, 4034.1528279061727, 4045.959540404617, 4060.6548443382053, 4074.6536544393466, 4082.2039702257775, 8078.798030049178, 8088.797780055428, 8104.149064348277, 8123.6750529978135, 8127.146649601825, 7704.229577042251, 7926.048874135141, 7944.364450843918, 7944.960275198702, 7965.203479652105, 6570.605763654196, 8079.878801817978, 8085.353171746364, 8099.595020248964, 8151.30713889326, 6532.281449040697, 7721.227877212234, 7721.7683469496005, 7725.266099720518, 7753.302202801549, 7543.660535275816, 7560.810979725551, 7565.016547848964, 7582.810429739301, 7601.885971710318, 6398.424141827258, 6537.346265373425, 7781.805454863447, 8073.87889181651, 8100.068492123246, 4794.664373493822, 4822.445418776745, 4836.588889944253, 4844.346013288302, 4849.0, 6477.578957367756, 7137.964310178416, 7706.152323244327, 7712.884306735291, 7751.418643601865, 5268.947310526845, 6289.654069026247, 7123.536970096927, 7125.0, 7454.58999754997, 6668.099806526056, 7161.713531458683, 7361.337479626645, 7376.889346659697, 7386.778396648109, 7184.712611495482, 7225.421966242793, 7230.6384680766805, 7241.058662373857, 7249.456290778318, 7401.259874012771, 7420.925790742023, 7426.777196684108, 7438.218987006503, 7461.813454663677, 7261.891071634035, 7264.745733899288, 7273.0908636420445, 7276.090488688914, 7284.854302914018], "unit": "score", "name": "prototype.html", "value": 5270.635134068252}, {"lowerIsBetter": false, "replicates": [711.8327193109615, 718.8598223346446, 734.8750712378895, 794.833085052139, 808.9110197878234, 15547.0, 15878.285477153528, 15904.84095159048, 15933.283002264898, 15958.76061859073, 14764.926175369135, 14824.925875370607, 14841.851581484181, 14882.25588720565, 14947.327370268364, 805.9798505037392, 806.7055524733489, 989.8267803134459, 993.9602415903391, 1022.9897701022969, 971.2958105373618, 1000.0949141027381, 1004.1213937804421, 1008.6973907827659, 1018.0735530667057, 1065.7761870007268, 1074.8334008228737, 1078.687180717591, 1079.8596182496265, 1081.0540776820283, 1057.8043062033548, 1063.1760385701066, 1073.441810258665, 1075.5805235957982, 1076.8115579773507, 791.5409062743653, 803.6102490292203, 804.0431886055634, 805.1867613710136, 808.1756608259601, 15576.61058473536, 15793.763093553605, 15877.0, 15922.0, 15930.362785488567, 13429.932850335783, 13458.730825383536, 13503.392347344394, 13508.257045862474, 13573.59279221625, 8567.82529372766, 10357.689269321858, 10381.79236415275, 10526.684199473952, 10712.51793669279, 3569.9111770909863, 3589.4795254688183, 3596.316699827051, 3682.779033258013, 3706.314331848617, 82892.0, 84339.0, 85318.57340713318, 85738.99999999937, 86119.56940215321, 9734.123928846439, 9753.65862194834, 9987.151092157103, 10005.0, 10223.948880255552, 23066.0, 23180.0, 23201.3039608812, 23284.41788955256, 23321.650175247385, 7692.7692169235015, 7788.260115289152, 7788.299053085249, 7894.960525197337, 7939.28546430824, 4189.350650649093, 4235.915281694349, 4249.298865687127, 4298.742075475481, 4309.116631090619, 3617.764845285048, 3664.8350824212976, 3666.1201311685536, 3667.5965643779164, 3670.50448189491, 702.4485778663826, 703.9084918960605, 709.2942522190453, 716.3123401534481, 737.498501019312, 528.6880740363205, 538.63103773915, 585.3297973819953, 590.4685782795518, 634.2420807135521, 6620.867582648417, 8211.0, 8265.8346833063, 8283.544405057779, 8547.871781923277, 610.5207412181505, 614.98462538436, 627.1188979483742, 628.5788521690479, 628.8396458902951, 3936.4882565266903, 3975.968543542029, 4043.454133691973, 4055.47278853753, 4057.736247143985, 94373.5281323589, 95146.5242673796, 95810.0, 99120.0, 101484.0, 76982.0, 77675.0, 80152.0, 82295.17704822875, 82314.0, 66972.33027669661, 67815.66092169507, 68433.0, 68733.65633171803, 69344.0, 8726.956365218388, 8804.427712198687, 8917.0, 8945.418547794372, 9010.32422568323, 47709.284360733924, 50092.499075010244, 50298.0, 52197.478025219265, 52695.0, 66816.0, 68386.65806670935, 69586.9561956561, 69834.65082674554, 70463.0, 11235.0, 11281.21031527785, 11310.94344528272, 11490.82763758561, 11611.187216895074, 11878.46546905374, 11899.405029748652, 12036.097292703258, 12108.697282567653, 12455.314957677414, 9614.519274036411, 11742.236754610924, 12088.093392995384, 12242.387880605756, 12330.938345308216, 11733.706657333634, 11797.88202117968, 11904.166708330335, 12009.519619215134, 12244.877551224374, 11462.94268528652, 11463.885361146615, 11509.366984815915, 11665.300081995107, 11729.061675065803, 9860.0, 10094.091531762177, 10192.898071019195, 10218.284720069523, 10471.267011309135, 8523.531205783493, 9598.040195980346, 10087.445190514592, 10118.89881101209, 10137.847932281169], "unit": "score", "name": "yui.html", "value": 7226.348900793932}], "name": "dromaeo_css", "value": 7731.588291551817}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [116.0, 114.0, 115.0, 121.0, 166.0, 182.0, 114.0, 113.0, 115.0, 117.0], "unit": "score", "name": "ai-astar", "value": 127.3}, {"lowerIsBetter": true, "replicates": [149.0, 120.0, 153.0, 148.0, 118.0, 126.0, 169.0, 147.0, 187.0, 121.0], "unit": "score", "name": "audio-beat-detection", "value": 143.8}, {"lowerIsBetter": true, "replicates": [192.0, 195.0, 217.0, 178.0, 183.0, 273.0, 234.0, 275.0, 240.0, 197.0], "unit": "score", "name": "audio-dft", "value": 218.4}, {"lowerIsBetter": true, "replicates": [71.0, 71.0, 72.0, 115.0, 57.0, 58.0, 58.0, 58.0, 139.0, 56.0], "unit": "score", "name": "audio-fft", "value": 75.5}, {"lowerIsBetter": true, "replicates": [80.0, 88.0, 125.0, 84.0, 74.0, 80.0, 123.0, 79.0, 80.0, 121.0], "unit": "score", "name": "audio-oscillator", "value": 93.4}, {"lowerIsBetter": true, "replicates": [89.0, 87.0, 89.0, 87.0, 89.0, 86.0, 105.0, 110.0, 87.0, 86.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 91.5}, {"lowerIsBetter": true, "replicates": [94.0, 95.0, 96.0, 95.0, 105.0, 121.0, 96.0, 118.0, 100.0, 148.0], "unit": "score", "name": "imaging-darkroom", "value": 106.8}, {"lowerIsBetter": true, "replicates": [90.0, 86.0, 94.0, 88.0, 126.0, 140.0, 85.0, 97.0, 133.0, 136.0], "unit": "score", "name": "imaging-desaturate", "value": 107.5}, {"lowerIsBetter": true, "replicates": [51.0, 54.0, 79.0, 70.0, 61.0, 50.0, 48.0, 57.0, 47.0, 44.0], "unit": "score", "name": "json-parse-financial", "value": 56.1}, {"lowerIsBetter": true, "replicates": [61.0, 61.0, 88.0, 90.0, 71.0, 67.0, 75.0, 94.0, 97.0, 74.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 77.8}, {"lowerIsBetter": true, "replicates": [85.0, 80.0, 82.0, 83.0, 85.0, 77.0, 81.0, 72.0, 86.0, 111.0], "unit": "score", "name": "stanford-crypto-aes", "value": 84.2}, {"lowerIsBetter": true, "replicates": [130.0, 132.0, 132.0, 131.0, 144.0, 131.0, 129.0, 136.0, 141.0, 140.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 134.6}, {"lowerIsBetter": true, "replicates": [137.0, 134.0, 135.0, 135.0, 121.0, 162.0, 171.0, 143.0, 138.0, 124.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 140.0}, {"lowerIsBetter": true, "replicates": [70.0, 67.0, 68.0, 64.0, 67.0, 62.0, 64.0, 68.0, 83.0, 81.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 69.4}], "name": "kraken", "value": 1526.3}]} 02:43:28 INFO - Return code: 0 02:43:28 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 02:43:28 ERROR - # TBPL SUCCESS # 02:43:28 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:43:28 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:43:28 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:43:28 INFO - 2016-05-12 02:43:28.261 screenresolution[2105:15144] starting screenresolution argv=screenresolution get 02:43:28 INFO - 2016-05-12 02:43:28.263 screenresolution[2105:15144] Display 0: 1600x1200x32@60 02:43:28 INFO - 2016-05-12 02:43:28.268 screenresolution[2106:15147] starting screenresolution argv=screenresolution list 02:43:28 INFO - Available Modes on Display 0 02:43:28 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:43:28 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:43:28 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:43:28 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:43:28 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:43:28 INFO - Intel Iris: 02:43:28 INFO - Chipset Model: Intel Iris 02:43:28 INFO - Type: GPU 02:43:28 INFO - Bus: Built-In 02:43:28 INFO - VRAM (Dynamic, Max): 1536 MB 02:43:28 INFO - Vendor: Intel (0x8086) 02:43:28 INFO - Device ID: 0x0a2e 02:43:28 INFO - Revision ID: 0x0009 02:43:28 INFO - Displays: 02:43:28 INFO - Display: 02:43:28 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:43:28 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:43:28 INFO - Display Serial Number: 1600x1200 60 02:43:28 INFO - Main Display: Yes 02:43:28 INFO - Mirror: Off 02:43:28 INFO - Online: Yes 02:43:28 INFO - Rotation: Supported 02:43:28 INFO - Return code: 0 02:43:28 INFO - Running post-action listener: _resource_record_post_action 02:43:28 INFO - Running post-run listener: _resource_record_post_run 02:43:29 INFO - Total resource usage - Wall time: 1192s; CPU: 54.0%; Read bytes: 30375424; Write bytes: 510573056; Read time: 302; Write time: 1667 02:43:29 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 196819456; Write bytes: 198096384; Read time: 18005; Write time: 440 02:43:29 INFO - run-tests - Wall time: 1170s; CPU: 54.0%; Read bytes: 30375424; Write bytes: 302519296; Read time: 302; Write time: 1199 02:43:29 INFO - Running post-run listener: _upload_blobber_files 02:43:29 INFO - Blob upload gear active. 02:43:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:43:29 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 02:43:29 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'] 02:43:29 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 02:43:29 INFO - (blobuploader) - INFO - Open directory for files ... 02:43:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:43:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:43:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:43:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:43:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:43:31 INFO - (blobuploader) - INFO - Done attempting. 02:43:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:43:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:43:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:43:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:43:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:43:31 INFO - (blobuploader) - INFO - Done attempting. 02:43:31 INFO - (blobuploader) - INFO - Iteration through files over. 02:43:31 INFO - Return code: 0 02:43:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:43:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:43:31 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cb670be971c96561a68ae648caba22904b4cabff972d8b30ad69820a50439a9bf50f1a7d73b775d7300449efcf513b8a254f1a0feea426162daaadfdb7e934bc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e74930c1b5fb2e5b567b4bce3d429fb188646a0747c873b6a663a7e29eb67383afc4f90590c64bfec9b3bbaf2d7e6d1f3759c65b3be8824f7605590339ebec7c"} 02:43:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:43:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:43:31 INFO - Contents: 02:43:31 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cb670be971c96561a68ae648caba22904b4cabff972d8b30ad69820a50439a9bf50f1a7d73b775d7300449efcf513b8a254f1a0feea426162daaadfdb7e934bc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e74930c1b5fb2e5b567b4bce3d429fb188646a0747c873b6a663a7e29eb67383afc4f90590c64bfec9b3bbaf2d7e6d1f3759c65b3be8824f7605590339ebec7c"} 02:43:31 INFO - Running post-run listener: copy_logs_to_upload_dir 02:43:31 INFO - Copying logs to upload dir... 02:43:31 INFO - mkdir: /builds/slave/test/build/upload/logs 02:43:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1250.438345 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 50 secs) (at 2016-05-12 02:43:32.025200) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 02:43:32.028311) ========= 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.U72f5jhXVm/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.YGXSZLM6VV/Listeners TMPDIR=/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cb670be971c96561a68ae648caba22904b4cabff972d8b30ad69820a50439a9bf50f1a7d73b775d7300449efcf513b8a254f1a0feea426162daaadfdb7e934bc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e74930c1b5fb2e5b567b4bce3d429fb188646a0747c873b6a663a7e29eb67383afc4f90590c64bfec9b3bbaf2d7e6d1f3759c65b3be8824f7605590339ebec7c"} build_url:https://queue.taskcluster.net/v1/task/KxyjPdUHTBGHEsDmVYVgrQ/artifacts/public/build/firefox-47.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011012 build_url: 'https://queue.taskcluster.net/v1/task/KxyjPdUHTBGHEsDmVYVgrQ/artifacts/public/build/firefox-47.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/cb670be971c96561a68ae648caba22904b4cabff972d8b30ad69820a50439a9bf50f1a7d73b775d7300449efcf513b8a254f1a0feea426162daaadfdb7e934bc", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e74930c1b5fb2e5b567b4bce3d429fb188646a0747c873b6a663a7e29eb67383afc4f90590c64bfec9b3bbaf2d7e6d1f3759c65b3be8824f7605590339ebec7c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-12 02:43:32.058587) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 02:43:32.058942) ========= 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.U72f5jhXVm/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.YGXSZLM6VV/Listeners TMPDIR=/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005209 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-12 02:43:32.106901) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 02:43:32.107221) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-12 02:43:32.107539) ========= ========= Total master_lag: 0.18 =========