builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0388 starttime: 1463966420.8 results: success (0) buildid: 20160522162928 builduid: 85d713b6e9974a8cb8042c065bafad7d revision: 40994e384c130872bb8430268780726d4416cf40 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-22 18:20:20.798587) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-22 18:20:20.798976) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-22 18:20:20.799289) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-22 18:20:20.878201) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-22 18:20:20.879045) ========= 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.b2lLYxBBzp/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.fgQbzQ06uT/Listeners TMPDIR=/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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-22 18:20:20-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 168M=0s 2016-05-22 18:20:20 (168 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.117702 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-22 18:20:21.033199) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-22 18:20:21.033503) ========= 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.b2lLYxBBzp/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.fgQbzQ06uT/Listeners TMPDIR=/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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.025630 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-22 18:20:21.122378) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-22 18:20:21.122681) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 40994e384c130872bb8430268780726d4416cf40 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 40994e384c130872bb8430268780726d4416cf40 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.b2lLYxBBzp/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.fgQbzQ06uT/Listeners TMPDIR=/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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-22 18:20:20,780 truncating revision to first 12 chars 2016-05-22 18:20:20,781 Setting DEBUG logging. 2016-05-22 18:20:20,781 attempt 1/10 2016-05-22 18:20:20,781 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/40994e384c13?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-22 18:20:22,096 unpacking tar archive at: mozilla-aurora-40994e384c13/testing/mozharness/ program finished with exit code 0 elapsedTime=1.573189 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-22 18:20:22.723119) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-22 18:20:22.723438) ========= script_repo_revision: 40994e384c130872bb8430268780726d4416cf40 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-22 18:20:22.723825) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-22 18:20:22.724478) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-22 18:20:22.739189) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 24 secs) (at 2016-05-22 18:20:22.739542) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.b2lLYxBBzp/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.fgQbzQ06uT/Listeners TMPDIR=/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 18:20:22 INFO - MultiFileLogger online at 20160522 18:20:22 in /builds/slave/test 18:20:22 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 18:20:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:20:22 INFO - {'append_to_log': False, 18:20:22 INFO - 'base_work_dir': '/builds/slave/test', 18:20:22 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 18:20:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:20:22 INFO - 'branch': 'Mozilla-Aurora', 18:20:22 INFO - 'buildbot_json_path': 'buildprops.json', 18:20:22 INFO - 'config_files': ('talos/mac_config.py',), 18:20:22 INFO - 'default_actions': ('clobber', 18:20:22 INFO - 'read-buildbot-config', 18:20:22 INFO - 'download-and-extract', 18:20:22 INFO - 'populate-webroot', 18:20:22 INFO - 'create-virtualenv', 18:20:22 INFO - 'install', 18:20:22 INFO - 'run-tests'), 18:20:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:20:22 INFO - 'download_minidump_stackwalk': True, 18:20:22 INFO - 'download_symbols': 'ondemand', 18:20:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:20:22 INFO - 'tooltool.py': '/tools/tooltool.py', 18:20:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:20:22 INFO - '/tools/misc-python/virtualenv.py')}, 18:20:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:20:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:20:22 INFO - 'installer_path': 'installer.exe', 18:20:22 INFO - 'log_level': 'info', 18:20:22 INFO - 'log_name': 'talos', 18:20:22 INFO - 'log_to_console': True, 18:20:22 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:20:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:20:22 INFO - 'opt_config_files': (), 18:20:22 INFO - 'pip_index': False, 18:20:22 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:20:22 INFO - 'cmd': ('bash', 18:20:22 INFO - '-c', 18:20:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:20:22 INFO - 'enabled': True, 18:20:22 INFO - 'halt_on_failure': False, 18:20:22 INFO - 'name': 'check_screen_resolution'},), 18:20:22 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:20:22 INFO - 'cmd': ('bash', 18:20:22 INFO - '-c', 18:20:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:20:22 INFO - 'enabled': True, 18:20:22 INFO - 'halt_on_failure': False, 18:20:22 INFO - 'name': 'check_screen_resolution'},), 18:20:22 INFO - 'run_cmd_checks_enabled': True, 18:20:22 INFO - 'sps_profile': False, 18:20:22 INFO - 'sps_profile_interval': 0, 18:20:22 INFO - 'suite': 'dromaeojs', 18:20:22 INFO - 'system_bits': '32', 18:20:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:20:22 INFO - 'title': 't-yosemite-r7-0388', 18:20:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:20:22 INFO - 'use_talos_json': True, 18:20:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:20:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:20:22 INFO - 'work_dir': 'build'} 18:20:22 INFO - ##### 18:20:22 INFO - ##### Running clobber step. 18:20:22 INFO - ##### 18:20:22 INFO - Running pre-action listener: _resource_record_pre_action 18:20:22 INFO - Running main action method: clobber 18:20:22 INFO - rmtree: /builds/slave/test/build 18:20:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:20:24 INFO - Running post-action listener: _resource_record_post_action 18:20:24 INFO - ##### 18:20:24 INFO - ##### Running read-buildbot-config step. 18:20:24 INFO - ##### 18:20:24 INFO - Running pre-action listener: _resource_record_pre_action 18:20:24 INFO - Running main action method: read_buildbot_config 18:20:24 INFO - Using buildbot properties: 18:20:24 INFO - { 18:20:24 INFO - "project": "", 18:20:24 INFO - "product": "firefox", 18:20:24 INFO - "script_repo_revision": "production", 18:20:24 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 18:20:24 INFO - "repository": "", 18:20:24 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 18:20:24 INFO - "buildid": "20160522162928", 18:20:24 INFO - "pgo_build": "False", 18:20:24 INFO - "basedir": "/builds/slave/test", 18:20:24 INFO - "buildnumber": 8, 18:20:24 INFO - "slavename": "t-yosemite-r7-0388", 18:20:24 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 18:20:24 INFO - "platform": "yosemite_r7", 18:20:24 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 18:20:24 INFO - "branch": "mozilla-aurora", 18:20:24 INFO - "repo_path": "releases/mozilla-aurora", 18:20:24 INFO - "revision": "40994e384c130872bb8430268780726d4416cf40", 18:20:24 INFO - "stage_platform": "macosx64", 18:20:24 INFO - "builduid": "85d713b6e9974a8cb8042c065bafad7d", 18:20:24 INFO - "slavebuilddir": "test" 18:20:24 INFO - } 18:20:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/EpU-4wcQRv69BuVe3rbgWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 18:20:24 INFO - Running post-action listener: _resource_record_post_action 18:20:24 INFO - ##### 18:20:24 INFO - ##### Running download-and-extract step. 18:20:24 INFO - ##### 18:20:24 INFO - Running pre-action listener: _resource_record_pre_action 18:20:24 INFO - Running main action method: download_and_extract 18:20:24 INFO - mkdir: /builds/slave/test/build/tests 18:20:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:20:24 INFO - https://queue.taskcluster.net/v1/task/EpU-4wcQRv69BuVe3rbgWw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 18:20:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EpU-4wcQRv69BuVe3rbgWw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 18:20:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EpU-4wcQRv69BuVe3rbgWw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 18:20:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EpU-4wcQRv69BuVe3rbgWw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 18:20:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EpU-4wcQRv69BuVe3rbgWw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 18:20:55 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EpU-4wcQRv69BuVe3rbgWw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json: timed out 18:20:55 INFO - retry: attempt #1 caught exception: timed out 18:20:55 INFO - retry: Failed, sleeping 30 seconds before retrying 18:21:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EpU-4wcQRv69BuVe3rbgWw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #2 18:21:38 INFO - Downloaded 1199 bytes. 18:21:38 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 18:21:38 INFO - Using the following test package requirements: 18:21:38 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 18:21:38 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:21:38 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 18:21:38 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:21:38 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 18:21:38 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:21:38 INFO - u'jsshell-mac.zip'], 18:21:38 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:21:38 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 18:21:38 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 18:21:38 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:21:38 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 18:21:38 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:21:38 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 18:21:38 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:21:38 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 18:21:38 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 18:21:38 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 18:21:38 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 18:21:38 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')]} 18:21:38 INFO - https://queue.taskcluster.net/v1/task/EpU-4wcQRv69BuVe3rbgWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:21:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EpU-4wcQRv69BuVe3rbgWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 18:21:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EpU-4wcQRv69BuVe3rbgWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 18:21:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EpU-4wcQRv69BuVe3rbgWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 18:21:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EpU-4wcQRv69BuVe3rbgWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 18:21:44 INFO - Downloaded 18102457 bytes. 18:21:44 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:21:44 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 18:21:47 INFO - Return code: 0 18:21:47 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 18:21:47 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')]} 18:21:47 INFO - https://queue.taskcluster.net/v1/task/EpU-4wcQRv69BuVe3rbgWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 18:21:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EpU-4wcQRv69BuVe3rbgWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 18:21:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EpU-4wcQRv69BuVe3rbgWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 18:21:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EpU-4wcQRv69BuVe3rbgWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 18:21:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EpU-4wcQRv69BuVe3rbgWw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 18:21:49 INFO - Downloaded 18102457 bytes. 18:21:49 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:21:49 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 18:21:53 INFO - Return code: 0 18:21:53 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')]} 18:21:53 INFO - https://queue.taskcluster.net/v1/task/EpU-4wcQRv69BuVe3rbgWw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 18:21:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EpU-4wcQRv69BuVe3rbgWw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 18:21:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EpU-4wcQRv69BuVe3rbgWw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 18:21:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EpU-4wcQRv69BuVe3rbgWw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 18:21:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EpU-4wcQRv69BuVe3rbgWw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 18:21:54 INFO - Downloaded 10840145 bytes. 18:21:54 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 18:21:54 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 18:21:54 INFO - Return code: 0 18:21: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')]} 18:21:54 INFO - https://queue.taskcluster.net/v1/task/EpU-4wcQRv69BuVe3rbgWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 18:21:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EpU-4wcQRv69BuVe3rbgWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 18:21:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EpU-4wcQRv69BuVe3rbgWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 18:21:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EpU-4wcQRv69BuVe3rbgWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 18:21:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/EpU-4wcQRv69BuVe3rbgWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 18:21:58 INFO - Downloaded 114135679 bytes. 18:21:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/EpU-4wcQRv69BuVe3rbgWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 18:21:58 INFO - mkdir: /builds/slave/test/properties 18:21:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:21:58 INFO - Writing to file /builds/slave/test/properties/build_url 18:21:58 INFO - Contents: 18:21:58 INFO - build_url:https://queue.taskcluster.net/v1/task/EpU-4wcQRv69BuVe3rbgWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 18:22:00 INFO - Running post-action listener: _resource_record_post_action 18:22:00 INFO - Running post-action listener: set_extra_try_arguments 18:22:00 INFO - ##### 18:22:00 INFO - ##### Running populate-webroot step. 18:22:00 INFO - ##### 18:22:00 INFO - Running pre-action listener: _resource_record_pre_action 18:22:00 INFO - Running main action method: populate_webroot 18:22:00 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:22:00 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:22:00 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:22:00 INFO - '--tppagecycles', 18:22:00 INFO - '7'], 18:22:00 INFO - 'tests': ['tsvgm']}}, 18:22:00 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:22:00 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:22:00 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:22:00 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:22:00 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:22:00 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:22:00 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:22:00 INFO - 'talos_options': ['--disable-e10s'], 18:22:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:22:00 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:22:00 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:22:00 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:22:00 INFO - 'talos_options': ['--disable-e10s'], 18:22:00 INFO - 'tests': ['damp', 'tps']}, 18:22:00 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:22:00 INFO - 'tests': ['damp', 'tps']}, 18:22:00 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:22:00 INFO - 'tests': ['dromaeo_dom']}, 18:22:00 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:22:00 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:22:00 INFO - 'tests': ['a11yr', 18:22:00 INFO - 'ts_paint', 18:22:00 INFO - 'tpaint', 18:22:00 INFO - 'sessionrestore', 18:22:00 INFO - 'sessionrestore_no_auto_restore', 18:22:00 INFO - 'tabpaint']}, 18:22:00 INFO - 'other-e10s': {'tests': ['a11yr', 18:22:00 INFO - 'ts_paint', 18:22:00 INFO - 'tpaint', 18:22:00 INFO - 'sessionrestore', 18:22:00 INFO - 'sessionrestore_no_auto_restore', 18:22:00 INFO - 'tabpaint']}, 18:22:00 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:22:00 INFO - 'ts_paint', 18:22:00 INFO - 'tpaint', 18:22:00 INFO - 'sessionrestore', 18:22:00 INFO - 'sessionrestore_no_auto_restore', 18:22:00 INFO - 'tabpaint']}, 18:22:00 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:22:00 INFO - 'ts_paint', 18:22:00 INFO - 'tpaint', 18:22:00 INFO - 'sessionrestore', 18:22:00 INFO - 'sessionrestore_no_auto_restore', 18:22:00 INFO - 'tabpaint']}, 18:22:00 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:22:00 INFO - 'tests': ['a11yr', 18:22:00 INFO - 'ts_paint', 18:22:00 INFO - 'tpaint', 18:22:00 INFO - 'sessionrestore', 18:22:00 INFO - 'sessionrestore_no_auto_restore', 18:22:00 INFO - 'tabpaint']}, 18:22:00 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:22:00 INFO - 'tests': ['a11yr', 18:22:00 INFO - 'ts_paint', 18:22:00 INFO - 'tpaint', 18:22:00 INFO - 'sessionrestore', 18:22:00 INFO - 'sessionrestore_no_auto_restore', 18:22:00 INFO - 'tabpaint']}, 18:22:00 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:22:00 INFO - 'tests': ['tsvgx', 18:22:00 INFO - 'tsvgr_opacity', 18:22:00 INFO - 'tart', 18:22:00 INFO - 'tscrollx', 18:22:00 INFO - 'cart']}, 18:22:00 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:22:00 INFO - 'tsvgr_opacity', 18:22:00 INFO - 'tart', 18:22:00 INFO - 'tscrollx', 18:22:00 INFO - 'cart']}, 18:22:00 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:22:00 INFO - 'talos_options': ['--disable-e10s'], 18:22:00 INFO - 'tests': ['tp5o']}, 18:22:00 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:22:00 INFO - 'tests': ['tp5o']}, 18:22:00 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:22:00 INFO - 'talos_options': ['--disable-e10s', 18:22:00 INFO - '--xperf_path', 18:22:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:22:00 INFO - 'tests': ['tp5n']}, 18:22:00 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:22:00 INFO - 'talos_options': ['--xperf_path', 18:22:00 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:22:00 INFO - 'tests': ['tp5n']}}, 18:22:00 INFO - 'talos.zip': {'path': '', 18:22:00 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:22:00 INFO - Running post-action listener: _resource_record_post_action 18:22:00 INFO - ##### 18:22:00 INFO - ##### Running create-virtualenv step. 18:22:00 INFO - ##### 18:22:00 INFO - Running pre-action listener: _resource_record_pre_action 18:22:00 INFO - Running main action method: create_virtualenv 18:22:00 INFO - Creating virtualenv /builds/slave/test/build/venv 18:22:00 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 18:22:00 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:22:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:22:00 INFO - Using real prefix '/tools/python27' 18:22:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:22:00 INFO - Installing distribute.............................................................................................................................................................................................done. 18:22:03 INFO - Installing pip.................done. 18:22:03 INFO - Return code: 0 18:22:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:22:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:22:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:22:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:03 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 0x105022a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105032420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0abd9a900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ec1e00>, '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 0x104d2adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ebcb20>, '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.b2lLYxBBzp/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.fgQbzQ06uT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 18:22:03 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 18:22:03 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 18:22:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b2lLYxBBzp/Render', 18:22:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:22:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:22:03 INFO - 'HOME': '/Users/cltbld', 18:22:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:22:03 INFO - 'LOGNAME': 'cltbld', 18:22:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:03 INFO - 'MOZ_NO_REMOTE': '1', 18:22:03 INFO - 'NO_EM_RESTART': '1', 18:22:03 INFO - 'PAGER': '/bin/cat', 18:22:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:22:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:03 INFO - 'PWD': '/builds/slave/test', 18:22:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:22:03 INFO - 'SHELL': '/bin/bash', 18:22:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fgQbzQ06uT/Listeners', 18:22:03 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 18:22:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:22:03 INFO - 'USER': 'cltbld', 18:22:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:22:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:22:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:03 INFO - 'XPC_FLAGS': '0x0', 18:22:03 INFO - 'XPC_SERVICE_NAME': '0', 18:22:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:22:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:04 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 18:22:04 INFO - Cleaning up... 18:22:04 INFO - Return code: 0 18:22:04 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 18:22:04 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')]} 18:22:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:04 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')]} 18:22:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:04 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 0x105022a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105032420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0abd9a900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ec1e00>, '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 0x104d2adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ebcb20>, '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.b2lLYxBBzp/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.fgQbzQ06uT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 18:22:04 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 18:22:04 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 18:22:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b2lLYxBBzp/Render', 18:22:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:22:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:22:04 INFO - 'HOME': '/Users/cltbld', 18:22:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:22:04 INFO - 'LOGNAME': 'cltbld', 18:22:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:04 INFO - 'MOZ_NO_REMOTE': '1', 18:22:04 INFO - 'NO_EM_RESTART': '1', 18:22:04 INFO - 'PAGER': '/bin/cat', 18:22:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:22:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:04 INFO - 'PWD': '/builds/slave/test', 18:22:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:22:04 INFO - 'SHELL': '/bin/bash', 18:22:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fgQbzQ06uT/Listeners', 18:22:04 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 18:22:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:22:04 INFO - 'USER': 'cltbld', 18:22:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:22:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:22:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:04 INFO - 'XPC_FLAGS': '0x0', 18:22:04 INFO - 'XPC_SERVICE_NAME': '0', 18:22:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:22:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:04 INFO - Downloading/unpacking psutil>=0.7.1 18:22:04 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 18:22:04 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 18:22:04 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 18:22:04 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 18:22:04 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 18:22:04 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 18:22:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:22:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:22:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:22:07 INFO - Installing collected packages: psutil 18:22:07 INFO - Running setup.py install for psutil 18:22:07 INFO - building 'psutil._psutil_osx' extension 18:22:07 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 18:22:08 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 18:22:08 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 18:22:08 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 18:22:08 INFO - building 'psutil._psutil_posix' extension 18:22:08 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 18:22:08 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:22:08 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:22:08 INFO - ^ 18:22:08 INFO - 1 warning generated. 18:22:08 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 18:22:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:22:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:22:08 INFO - Successfully installed psutil 18:22:08 INFO - Cleaning up... 18:22:08 INFO - Return code: 0 18:22:08 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 18:22:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:22:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:22:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105022a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105032420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0abd9a900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ec1e00>, '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 0x104d2adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ebcb20>, '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.b2lLYxBBzp/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.fgQbzQ06uT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 18:22:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 18:22:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 18:22:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b2lLYxBBzp/Render', 18:22:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:22:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:22:08 INFO - 'HOME': '/Users/cltbld', 18:22:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:22:08 INFO - 'LOGNAME': 'cltbld', 18:22:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:08 INFO - 'MOZ_NO_REMOTE': '1', 18:22:08 INFO - 'NO_EM_RESTART': '1', 18:22:08 INFO - 'PAGER': '/bin/cat', 18:22:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:22:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:08 INFO - 'PWD': '/builds/slave/test', 18:22:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:22:08 INFO - 'SHELL': '/bin/bash', 18:22:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fgQbzQ06uT/Listeners', 18:22:08 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 18:22:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:22:08 INFO - 'USER': 'cltbld', 18:22:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:22:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:22:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:08 INFO - 'XPC_FLAGS': '0x0', 18:22:08 INFO - 'XPC_SERVICE_NAME': '0', 18:22:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:22:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:09 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:22:09 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 18:22:09 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 18:22:09 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 18:22:09 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 18:22:09 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 18:22:09 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 18:22:11 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:22:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:22:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:22:11 INFO - Installing collected packages: mozsystemmonitor 18:22:11 INFO - Running setup.py install for mozsystemmonitor 18:22:11 INFO - Successfully installed mozsystemmonitor 18:22:11 INFO - Cleaning up... 18:22:11 INFO - Return code: 0 18:22:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:22:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:22:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:22:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:11 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 0x105022a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105032420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0abd9a900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ec1e00>, '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 0x104d2adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ebcb20>, '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.b2lLYxBBzp/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.fgQbzQ06uT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 18:22:11 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 18:22:11 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 18:22:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b2lLYxBBzp/Render', 18:22:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:22:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:22:11 INFO - 'HOME': '/Users/cltbld', 18:22:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:22:11 INFO - 'LOGNAME': 'cltbld', 18:22:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:11 INFO - 'MOZ_NO_REMOTE': '1', 18:22:11 INFO - 'NO_EM_RESTART': '1', 18:22:11 INFO - 'PAGER': '/bin/cat', 18:22:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:22:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:11 INFO - 'PWD': '/builds/slave/test', 18:22:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:22:11 INFO - 'SHELL': '/bin/bash', 18:22:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fgQbzQ06uT/Listeners', 18:22:11 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 18:22:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:22:11 INFO - 'USER': 'cltbld', 18:22:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:22:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:22:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:11 INFO - 'XPC_FLAGS': '0x0', 18:22:11 INFO - 'XPC_SERVICE_NAME': '0', 18:22:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:22:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:12 INFO - Downloading/unpacking blobuploader==1.2.4 18:22:12 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 18:22:12 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 18:22:12 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 18:22:12 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 18:22:12 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 18:22:12 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 18:22:14 INFO - Downloading blobuploader-1.2.4.tar.gz 18:22:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:22:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:22:14 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 18:22:14 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 18:22:14 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 18:22:14 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 18:22:14 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 18:22:14 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 18:22:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:22:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:22:15 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 18:22:15 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 18:22:15 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 18:22:15 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 18:22:15 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 18:22:15 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 18:22:15 INFO - Downloading docopt-0.6.1.tar.gz 18:22:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:22:15 INFO - Installing collected packages: blobuploader, requests, docopt 18:22:15 INFO - Running setup.py install for blobuploader 18:22:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:22:16 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:22:16 INFO - Running setup.py install for requests 18:22:16 INFO - Running setup.py install for docopt 18:22:16 INFO - Successfully installed blobuploader requests docopt 18:22:16 INFO - Cleaning up... 18:22:16 INFO - Return code: 0 18:22:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:22:16 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')]} 18:22:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:16 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')]} 18:22:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:16 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 0x105022a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105032420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0abd9a900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ec1e00>, '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 0x104d2adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ebcb20>, '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.b2lLYxBBzp/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.fgQbzQ06uT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 18:22:16 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 18:22:16 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 18:22:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b2lLYxBBzp/Render', 18:22:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:22:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:22:16 INFO - 'HOME': '/Users/cltbld', 18:22:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:22:16 INFO - 'LOGNAME': 'cltbld', 18:22:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:16 INFO - 'MOZ_NO_REMOTE': '1', 18:22:16 INFO - 'NO_EM_RESTART': '1', 18:22:16 INFO - 'PAGER': '/bin/cat', 18:22:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:22:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:16 INFO - 'PWD': '/builds/slave/test', 18:22:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:22:16 INFO - 'SHELL': '/bin/bash', 18:22:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fgQbzQ06uT/Listeners', 18:22:16 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 18:22:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:22:16 INFO - 'USER': 'cltbld', 18:22:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:22:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:22:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:16 INFO - 'XPC_FLAGS': '0x0', 18:22:16 INFO - 'XPC_SERVICE_NAME': '0', 18:22:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:22:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:22:17 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-FU2aSm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:22:17 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-L3rsUv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:22:17 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-0dufgT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:22:17 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-h8sksa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:22:17 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-BBZ5LP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:22:17 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-_mg3OC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:22:17 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-2P_zza-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:22:17 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-l2bFYm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:22:17 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-zKr5WG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:22:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:22:17 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-LoLY0L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:22:18 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-LnYCDV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:22:18 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-3a89K_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:22:18 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-1fQ9Fw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:22:18 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-4Kc_mQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:22:18 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-3xej0o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:22:18 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-NGie0s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:22:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:22:18 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-5qok9n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:22:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:22:18 INFO - Running setup.py install for manifestparser 18:22:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:22:18 INFO - Running setup.py install for mozcrash 18:22:19 INFO - Running setup.py install for mozdebug 18:22:19 INFO - Running setup.py install for mozdevice 18:22:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:22:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:22:19 INFO - Running setup.py install for mozfile 18:22:19 INFO - Running setup.py install for mozhttpd 18:22:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:22:19 INFO - Running setup.py install for mozinfo 18:22:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:22:19 INFO - Running setup.py install for mozInstall 18:22:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:22:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:22:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:22:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:22:19 INFO - Running setup.py install for mozleak 18:22:20 INFO - Running setup.py install for mozlog 18:22:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:22:20 INFO - Running setup.py install for moznetwork 18:22:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:22:20 INFO - Running setup.py install for mozprocess 18:22:20 INFO - Running setup.py install for mozprofile 18:22:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:22:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:22:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:22:20 INFO - Running setup.py install for mozrunner 18:22:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:22:20 INFO - Running setup.py install for mozscreenshot 18:22:21 INFO - Running setup.py install for moztest 18:22:21 INFO - Running setup.py install for mozversion 18:22:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:22:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:22:21 INFO - Cleaning up... 18:22:21 INFO - Return code: 0 18:22:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:22:21 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')]} 18:22:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:21 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')]} 18:22:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:21 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 0x105022a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105032420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0abd9a900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ec1e00>, '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 0x104d2adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ebcb20>, '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.b2lLYxBBzp/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.fgQbzQ06uT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 18:22:21 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 18:22:21 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 18:22:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b2lLYxBBzp/Render', 18:22:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:22:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:22:21 INFO - 'HOME': '/Users/cltbld', 18:22:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:22:21 INFO - 'LOGNAME': 'cltbld', 18:22:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:21 INFO - 'MOZ_NO_REMOTE': '1', 18:22:21 INFO - 'NO_EM_RESTART': '1', 18:22:21 INFO - 'PAGER': '/bin/cat', 18:22:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:22:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:21 INFO - 'PWD': '/builds/slave/test', 18:22:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:22:21 INFO - 'SHELL': '/bin/bash', 18:22:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fgQbzQ06uT/Listeners', 18:22:21 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 18:22:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:22:21 INFO - 'USER': 'cltbld', 18:22:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:22:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:22:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:21 INFO - 'XPC_FLAGS': '0x0', 18:22:21 INFO - 'XPC_SERVICE_NAME': '0', 18:22:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:22:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:22:21 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-3aLZdb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:22:21 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)) 18:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:22:21 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-JjfOKy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:22:21 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)) 18:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:22:21 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-8e71GV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:22:21 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)) 18:22:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:22:21 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-hAuX7d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:22:22 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)) 18:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:22:22 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-0Yvoqp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:22:22 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)) 18:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:22:22 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-A0Fg2o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:22:22 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)) 18:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:22:22 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-drajAh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:22:22 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)) 18:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:22:22 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-z719KV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:22:22 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)) 18:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:22:22 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-1xDZoa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:22:22 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)) 18:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:22:22 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-qtFSH1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:22:22 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)) 18:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:22:22 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-1OzlTe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:22:22 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)) 18:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:22:22 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-fwRKmx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:22:22 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)) 18:22:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:22:22 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-ZYJKTg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:22:23 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)) 18:22:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:22:23 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-BvggeH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:22:23 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)) 18:22:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:22:23 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-8KISfT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:22:23 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)) 18:22:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:22:23 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-9AfHiF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:22:23 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)) 18:22:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:22:23 INFO - Running setup.py (path:/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/pip-wsw7bB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:22:23 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)) 18:22:23 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)) 18:22:23 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)) 18:22:23 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)) 18:22:23 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)) 18:22:23 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)) 18:22:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:22:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:22:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:22:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:26 INFO - Downloading blessings-1.6.tar.gz 18:22:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:22:26 INFO - Installing collected packages: blessings 18:22:26 INFO - Running setup.py install for blessings 18:22:26 INFO - Successfully installed blessings 18:22:26 INFO - Cleaning up... 18:22:26 INFO - Return code: 0 18:22:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:22:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:22:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:22:27 INFO - Reading from file tmpfile_stdout 18:22:27 INFO - Current package versions: 18:22:27 INFO - blessings == 1.6 18:22:27 INFO - blobuploader == 1.2.4 18:22:27 INFO - docopt == 0.6.1 18:22:27 INFO - manifestparser == 1.1 18:22:27 INFO - mozInstall == 1.12 18:22:27 INFO - mozcrash == 0.17 18:22:27 INFO - mozdebug == 0.1 18:22:27 INFO - mozdevice == 0.48 18:22:27 INFO - mozfile == 1.2 18:22:27 INFO - mozhttpd == 0.7 18:22:27 INFO - mozinfo == 0.9 18:22:27 INFO - mozleak == 0.1 18:22:27 INFO - mozlog == 3.1 18:22:27 INFO - moznetwork == 0.27 18:22:27 INFO - mozprocess == 0.22 18:22:27 INFO - mozprofile == 0.28 18:22:27 INFO - mozrunner == 6.11 18:22:27 INFO - mozscreenshot == 0.1 18:22:27 INFO - mozsystemmonitor == 0.0 18:22:27 INFO - moztest == 0.7 18:22:27 INFO - mozversion == 1.4 18:22:27 INFO - psutil == 3.1.1 18:22:27 INFO - requests == 1.2.3 18:22:27 INFO - wsgiref == 0.1.2 18:22:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:22: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')]} 18:22:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22: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')]} 18:22:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22: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 0x105022a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105032420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0abd9a900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ec1e00>, '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 0x104d2adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ebcb20>, '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.b2lLYxBBzp/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.fgQbzQ06uT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 18:22: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 18:22: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 18:22:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b2lLYxBBzp/Render', 18:22:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:22:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:22:27 INFO - 'HOME': '/Users/cltbld', 18:22:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:22:27 INFO - 'LOGNAME': 'cltbld', 18:22:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:27 INFO - 'MOZ_NO_REMOTE': '1', 18:22:27 INFO - 'NO_EM_RESTART': '1', 18:22:27 INFO - 'PAGER': '/bin/cat', 18:22:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:22:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:27 INFO - 'PWD': '/builds/slave/test', 18:22:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:22:27 INFO - 'SHELL': '/bin/bash', 18:22:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fgQbzQ06uT/Listeners', 18:22:27 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 18:22:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:22:27 INFO - 'USER': 'cltbld', 18:22:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:22:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:22:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:27 INFO - 'XPC_FLAGS': '0x0', 18:22:27 INFO - 'XPC_SERVICE_NAME': '0', 18:22:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:22:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:27 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)) 18:22:27 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)) 18:22:27 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)) 18:22:27 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)) 18:22:27 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)) 18:22:27 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)) 18:22:27 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)) 18:22:27 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)) 18:22:27 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)) 18:22:27 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)) 18:22:27 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)) 18:22:27 INFO - Cleaning up... 18:22:27 INFO - Return code: 0 18:22:27 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:22: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')]} 18:22:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22: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')]} 18:22:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:27 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 0x105022a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105032420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0abd9a900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ec1e00>, '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 0x104d2adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ebcb20>, '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.b2lLYxBBzp/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.fgQbzQ06uT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 18:22:27 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 18:22:27 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 18:22:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b2lLYxBBzp/Render', 18:22:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:22:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:22:27 INFO - 'HOME': '/Users/cltbld', 18:22:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:22:27 INFO - 'LOGNAME': 'cltbld', 18:22:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:27 INFO - 'MOZ_NO_REMOTE': '1', 18:22:27 INFO - 'NO_EM_RESTART': '1', 18:22:27 INFO - 'PAGER': '/bin/cat', 18:22:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:22:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:27 INFO - 'PWD': '/builds/slave/test', 18:22:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:22:27 INFO - 'SHELL': '/bin/bash', 18:22:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fgQbzQ06uT/Listeners', 18:22:27 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 18:22:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:22:27 INFO - 'USER': 'cltbld', 18:22:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:22:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:22:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:27 INFO - 'XPC_FLAGS': '0x0', 18:22:27 INFO - 'XPC_SERVICE_NAME': '0', 18:22:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:22:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:27 INFO - Downloading/unpacking jsonschema 18:22:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:22:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:22:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:30 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:22:30 INFO - Downloading/unpacking functools32 (from jsonschema) 18:22: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 18:22: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 18:22: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 18:22: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 18:22: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 18:22: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 18:22:30 INFO - Downloading functools32-3.2.3-2.tar.gz 18:22:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:22:30 INFO - warning: no files found matching '*.txt' 18:22:30 INFO - no previously-included directories found matching 'build' 18:22:30 INFO - no previously-included directories found matching 'dist' 18:22:30 INFO - no previously-included directories found matching '.git*' 18:22:30 INFO - Installing collected packages: jsonschema, functools32 18:22:30 INFO - Running setup.py install for functools32 18:22:30 INFO - warning: no files found matching '*.txt' 18:22:30 INFO - no previously-included directories found matching 'build' 18:22:30 INFO - no previously-included directories found matching 'dist' 18:22:30 INFO - no previously-included directories found matching '.git*' 18:22:30 INFO - Successfully installed jsonschema functools32 18:22:30 INFO - Cleaning up... 18:22:30 INFO - Return code: 0 18:22:30 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:22:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:22:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:22:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:22:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:22:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:22:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105022a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105032420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0abd9a900>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ec1e00>, '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 0x104d2adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ebcb20>, '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.b2lLYxBBzp/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.fgQbzQ06uT/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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 18:22:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 18:22:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 18:22:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b2lLYxBBzp/Render', 18:22:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:22:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:22:30 INFO - 'HOME': '/Users/cltbld', 18:22:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:22:30 INFO - 'LOGNAME': 'cltbld', 18:22:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:22:30 INFO - 'MOZ_NO_REMOTE': '1', 18:22:30 INFO - 'NO_EM_RESTART': '1', 18:22:30 INFO - 'PAGER': '/bin/cat', 18:22:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:22:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:22:30 INFO - 'PWD': '/builds/slave/test', 18:22:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:22:30 INFO - 'SHELL': '/bin/bash', 18:22:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fgQbzQ06uT/Listeners', 18:22:30 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 18:22:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:22:30 INFO - 'USER': 'cltbld', 18:22:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:22:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:22:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:22:30 INFO - 'XPC_FLAGS': '0x0', 18:22:30 INFO - 'XPC_SERVICE_NAME': '0', 18:22:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:22:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:22:31 INFO - Downloading/unpacking flake8 18:22: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 18:22: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 18:22: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 18:22: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 18:22: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 18:22: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 18:22:33 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:22:33 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:22:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:22:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:22:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:22:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:22:34 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:22:34 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:22: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 18:22: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 18:22: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 18:22: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 18:22: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 18:22: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 18:22:34 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:22: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 18:22: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 18:22: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 18:22: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 18:22: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 18:22: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 18:22:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:22:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:22:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:22:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:22:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:22:34 INFO - no previously-included directories found matching 'docs/_build' 18:22:34 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:22:35 INFO - Running setup.py install for pep8 18:22:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:22:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:22:35 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:22:35 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:22:35 INFO - no previously-included directories found matching 'docs/_build' 18:22:35 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:22:35 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:22:35 INFO - Cleaning up... 18:22:35 INFO - Return code: 0 18:22:35 INFO - Running post-action listener: _resource_record_post_action 18:22:35 INFO - Running post-action listener: _start_resource_monitoring 18:22:35 INFO - Starting resource monitoring. 18:22:35 INFO - ##### 18:22:35 INFO - ##### Running install step. 18:22:35 INFO - ##### 18:22:35 INFO - Running pre-action listener: _resource_record_pre_action 18:22:35 INFO - Running main action method: install 18:22:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:22:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:22:35 INFO - Reading from file tmpfile_stdout 18:22:35 INFO - Detecting whether we're running mozinstall >=1.0... 18:22:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:22:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:22:35 INFO - Reading from file tmpfile_stdout 18:22:35 INFO - Output received: 18:22:35 INFO - Usage: mozinstall [options] installer 18:22:35 INFO - Options: 18:22:35 INFO - -h, --help show this help message and exit 18:22:35 INFO - -d DEST, --destination=DEST 18:22:35 INFO - Directory to install application into. [default: 18:22:35 INFO - "/builds/slave/test"] 18:22:35 INFO - --app=APP Application being installed. [default: firefox] 18:22:35 INFO - mkdir: /builds/slave/test/build/application 18:22:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:22:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 18:23:07 INFO - Reading from file tmpfile_stdout 18:23:07 INFO - Output received: 18:23:07 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 18:23:07 INFO - Running post-action listener: _resource_record_post_action 18:23:07 INFO - ##### 18:23:07 INFO - ##### Running run-tests step. 18:23:07 INFO - ##### 18:23:07 INFO - Running pre-action listener: _resource_record_pre_action 18:23:07 INFO - Running main action method: run_tests 18:23:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:23:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:23:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:23:07 INFO - 2016-05-22 18:23:07.048 screenresolution[1654:8951] starting screenresolution argv=screenresolution get 18:23:07 INFO - 2016-05-22 18:23:07.051 screenresolution[1654:8951] Display 0: 1600x1200x32@60 18:23:07 INFO - 2016-05-22 18:23:07.056 screenresolution[1655:8953] starting screenresolution argv=screenresolution list 18:23:07 INFO - Available Modes on Display 0 18:23:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:23:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:23:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:23:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:23:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:23:07 INFO - Intel Iris: 18:23:07 INFO - Chipset Model: Intel Iris 18:23:07 INFO - Type: GPU 18:23:07 INFO - Bus: Built-In 18:23:07 INFO - VRAM (Dynamic, Max): 1536 MB 18:23:07 INFO - Vendor: Intel (0x8086) 18:23:07 INFO - Device ID: 0x0a2e 18:23:07 INFO - Revision ID: 0x0009 18:23:07 INFO - Displays: 18:23:07 INFO - Display: 18:23:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:23:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:23:07 INFO - Display Serial Number: 1600x1200 60 18:23:07 INFO - Main Display: Yes 18:23:07 INFO - Mirror: Off 18:23:07 INFO - Online: Yes 18:23:07 INFO - Rotation: Supported 18:23:07 INFO - Return code: 0 18:23:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:23:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:23:07 INFO - Python 2.7.3 18:23:07 INFO - Return code: 0 18:23:07 INFO - grabbing minidump binary from tooltool 18:23:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 18:23:07 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 0x104ec1e00>, '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 0x104d2adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104ebcb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:23:07 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 18:23:07 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 18:23:07 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:23:07 INFO - Return code: 0 18:23:07 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:23:07 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:23:07 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:23:07 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:23:07 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:23:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:23:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:23:08 INFO - Return code: 0 18:23:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EpU-4wcQRv69BuVe3rbgWw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0388', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 18:23:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/EpU-4wcQRv69BuVe3rbgWw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0388 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 18:23:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.b2lLYxBBzp/Render', 18:23:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:23:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:23:08 INFO - 'HOME': '/Users/cltbld', 18:23:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:23:08 INFO - 'LOGNAME': 'cltbld', 18:23:08 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:23:08 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:23:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:23:08 INFO - 'MOZ_NO_REMOTE': '1', 18:23:08 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:23:08 INFO - 'NO_EM_RESTART': '1', 18:23:08 INFO - 'PAGER': '/bin/cat', 18:23:08 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:23:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:23:08 INFO - 'PWD': '/builds/slave/test', 18:23:08 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:23:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:23:08 INFO - 'SHELL': '/bin/bash', 18:23:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.fgQbzQ06uT/Listeners', 18:23:08 INFO - 'TMPDIR': '/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/', 18:23:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:23:08 INFO - 'USER': 'cltbld', 18:23:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:23:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:23:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:23:08 INFO - 'XPC_FLAGS': '0x0', 18:23:08 INFO - 'XPC_SERVICE_NAME': '0', 18:23:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:23:08 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/EpU-4wcQRv69BuVe3rbgWw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0388', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 18:23:08 INFO - mozversion application_buildid: 20160522162928 18:23:08 INFO - mozversion application_changeset: 40994e384c130872bb8430268780726d4416cf40 18:23:08 INFO - mozversion application_display_name: FirefoxDeveloperEdition 18:23:08 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:23:08 INFO - mozversion application_name: Firefox 18:23:08 INFO - mozversion application_remotingname: firefox-dev 18:23:08 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:23:08 INFO - mozversion application_vendor: Mozilla 18:23:08 INFO - mozversion application_version: 48.0a2 18:23:08 INFO - mozversion platform_buildid: 20160522162928 18:23:08 INFO - mozversion platform_changeset: 40994e384c130872bb8430268780726d4416cf40 18:23:08 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 18:23:08 INFO - mozversion platform_version: 48.0a2 18:23:08 INFO - using testdate: 1463966588 18:23:08 INFO - actual date: 1463966588 18:23:08 INFO - starting webserver on 'localhost:49285' 18:23:08 INFO - SUITE-START | Running 2 tests 18:23:08 INFO - TEST-START | dromaeo_css 18:23:08 INFO - operating with platform_type : mac_ 18:23:08 INFO - Initialising browser for dromaeo_css test... 18:23:08 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/tmp5SnXst/profile http://localhost:49285/getInfo.html) 18:23:11 INFO - PROCESS | 1673 | __metrics Screen width/height:1600/1200 18:23:11 INFO - PROCESS | 1673 | colorDepth:24 18:23:11 INFO - PROCESS | 1673 | Browser inner width/height: 1024/689 18:23:11 INFO - PROCESS | 1673 | __metrics 18:23:11 INFO - PROCESS | 1673 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:23:11 ERROR - PROCESS | 1673 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:23:11 INFO - TEST-INFO | 1673: exit 0 18:23:11 INFO - Browser initialized. 18:23:11 INFO - Running cycle 1/1 for dromaeo_css test... 18:23:11 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/tmp5SnXst/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:23:23 INFO - PROCESS | 1676 | RSS: Main: 211595264 18:23:23 INFO - PROCESS | 1676 | 18:24:15 INFO - PROCESS | 1676 | 1463966655766 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] 18:26:26 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49285/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49285/tests/dromaeo/cssquery-ext.html) 18:26:27 INFO - PROCESS | 1676 | RSS: Main: 339312640 18:26:27 INFO - PROCESS | 1676 | 18:29:30 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49285/tests/dromaeo/cssquery-ext.html (next: http://localhost:49285/tests/dromaeo/cssquery-jquery.html) 18:29:31 INFO - PROCESS | 1676 | RSS: Main: 356659200 18:29:31 INFO - PROCESS | 1676 | 18:32:33 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49285/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49285/tests/dromaeo/cssquery-mootools.html) 18:32:33 INFO - PROCESS | 1676 | RSS: Main: 361218048 18:32:33 INFO - PROCESS | 1676 | 18:35:36 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49285/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49285/tests/dromaeo/cssquery-prototype.html) 18:35:36 INFO - PROCESS | 1676 | RSS: Main: 362778624 18:35:36 INFO - PROCESS | 1676 | 18:38:40 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49285/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49285/tests/dromaeo/cssquery-yui.html) 18:38:40 INFO - PROCESS | 1676 | RSS: Main: 328511488 18:38:40 INFO - PROCESS | 1676 | 18:41:43 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49285/tests/dromaeo/cssquery-yui.html (next: http://localhost:49285/tests/dromaeo/cssquery-dojo.html) 18:41:43 INFO - PROCESS | 1676 | RSS: Main: 369582080 18:41:43 INFO - PROCESS | 1676 | 18:41:43 INFO - PROCESS | 1676 | __start_tp_report 18:41:43 INFO - PROCESS | 1676 | _x_x_mozilla_page_load 18:41:43 INFO - PROCESS | 1676 | _x_x_mozilla_page_load_details 18:41:43 INFO - PROCESS | 1676 | |i|pagename|runs| 18:41:43 INFO - PROCESS | 1676 | |0;dojo.html;3372.392969265532;3467.7919324840514;3479.373712731708;3503.2292895563;3530.6469353064695;5671.971640141799;5680.659160450371;5714.599978001542;5714.857128571788;5729.39841316662;5697.743601537931;5730.169125476814;5736.340320863102;5746.48281654651;5749.65502069875;5106.69359838409;5155.871103222412;5164.561012313958;5257.474252574731;5266.894662106755;5163.251328557357;5248.343957005374;5258.842234732945;5267.446918073604;5295.602829787762;5221.634485586011;5222.63441559091;5247.422783493832;5283.418823929364;5307.495787900143;5255.369355677324;5273.523413444242;5275.947240527584;5278.788848446057;5293.973530132324;5115.87210319745;5149.871253218662;5166.225066240057;5209.791608335662;5217.869553261161;5588;5630.211770352154;5644.266245388073;5667.688277144755;5669.801556945528;6225.408586184305;6241.500679945639;6248.3751624837605;6250.249970003583;6284.842878928018;6111.379037751179;6117.522930757332;6227.782027629011;6317.431431171217;6344.6510441925675;5797.130430435384;5832.708364581754;5833.7958171464215;5835.183074369592;5842.065269556851;6336.366363363719;6339.334369891188;6344.302126766052;6346.841328966812;6362.491000719931;6042.818715438544;6068.770081222976;6086.817395478142;6160.229971253593;6178.443940045418;6259.530535209878;6267.1539342188225;6273.215848018997;6275.995840665471;6303.117563541108;5256.237845512379;5866.504041469483;6148.877022459616;6207.751689932443;6225.657588832658;5521.972390138104;5970.552208584373;6055.545834062463;6073.848153796102;6076.574639775262;4905.730184839868;4914.950850491521;4926.162552366106;4934.9013019739405;4950;4405.66957478184;4428.22506061438;4483.417155769795;4505.121501307213;4539.341795439643;5704.80033198836;5710.343310519261;5722.170285308607;5724.284464441945;5743.6266642668925;6136.580349164012;6240.812775616648;6318.08387783778;6330.082138090043;6353.110564520971;5672.319321681424;5718.313802343662;5751.223584815996;5768.53851691872;5779.33537643168;5355.518003379714;5367.973160134253;5370.355557333145;5378.031954248273;5380.650257733235;6256.374362563707;6309.305976342507;6341.587796793194;6353.96823015882;6377.298497165308;6273.059041143682;6277.591956522811;6288.308286088435;6303.338149494237;6314.873702526015;6338.112664227104;6351.68241587928;6369.363063693779;6383.616982981036;6399.8080057598345;5084.3644544431945;5998.280206375263;6062.484688801324;6219.346968568416;6230.6884655767935;6261.78083767066;6280.874382512416;6285.120083188266;6287.717052732546;6299.559030867796;5066.898662026665;6063.181470501602;6087.57386982907;6108.633481991094;6110.26676798787;5953.434423729739;6044.33512313654;6050.606710563799;6057.363976782374;6087.36082711309;5818.738156782871;5935.940640593712;5964.105384192406;5981.431763982415;5989.790357337472;6110.327863935053;6116.694165291807;6127.785527506516;6128;6135.478484328703;4682.555157260055;4811.542903424169;5943.346231914573;5948.167256583995;5950.107483877453 18:41:43 INFO - PROCESS | 1676 | |1;ext.html;518.4245487508864;522.2636083122796;547.0727117535779;602.015704323431;602.9788957386493;24540.754592454072;30912.84543577282;39901.80049099754;39924.80037599808;39985;44254.336184957174;45024;45042.32436513447;45159.54840451595;45197.096058078816;8549.102344253857;9725.708228753148;9956.103950644441;9984.50077496126;9986.450745209011;8692.174243446863;11810.291382517033;12009.699757506089;12020;12024.939875300612;11714.062874970024;11780.823287650692;11808.17342786001;11810.7637847243;11922.642320730349;8711.172438618321;11630.476628551693;11701.356425396641;11728.237664551776;11755.35345555994;11660.242084264495;11775.470103845262;11789.938905498546;11790.469428875722;11797.233179843368;38637.806810966045;39033;39052;39121.80439097815;39260.80369598134;3207.262329664167;3363.411403004489;3417.2994536120036;3544.8227588620466;3761.717871159674;27971.58042629362;28076.43847123046;28725.138245852657;28856.27859303513;29601.259968500744;2268.3988742983124;2643.4977354302623;2666.7733242674394;2746.752792248687;2852.0873320537435;347715;350873;350874;351322;352508;35897.282054358766;36895.262094757956;36941.07647308839;37014;58168.41831581714;35870.28259434797;35954.28091438157;36024.279514409565;36047.639523604426;36121.45817812735;15611.687766244611;15830.445934392472;15838.9208053959;15839.208039597974;15848.841511584968;12171.2697238166;12352.197107188003;13289.601311960656;13537.729245415037;13961.441542338396;8186.345092392594;8198.467099638505;8205.384596155312;8285;8303.750887473385;3384.390809654286;3442.6933954250712;3452.5511683480986;3453.2575496268423;3479.8956031319103;1036.8444733289916;1060.2525219720078;1060.941648209356;1091.8689757229183;1158.0851127609094;30129.397412052076;30180.698193018226;30890.918817841266;30947;31104.844475777478;980.1423754215061;1010.6260683547134;1110.4392281897751;1144.490701637763;1145.570411095839;12623;14989.70020599582;15000.249987500581;15017.098974061591;15385.153816539974;595374;598344;602801;603745;604758;60665.393346066856;61246.38753612496;61317;61501;61622.69188654118;1372.9038967272195;1408.1621435246013;1441.1857300625322;1538.007984849788;1549.287327829211;28501;30130;30133.69866301309;30708.84645576758;30842.84578577093;50033.749831250614;50153.24770128524;50652.49347506478;50703.985920282124;50742.73143171302;402568.9871550623;405904;406545;406799;406962;23922.76077239275;24332;26627.600585990836;27121.321966950196;27432.628368581092;42797.930051748954;47305.52694473011;47971.76014119907;48331.51668483411;48728;29016.56475152832;36692.266154677294;38273.2345353097;38305.6169438302;39046;37361.81319093387;37661;37741;38237;38357;37518.812405937795;37889;38636;38671;38780;31747.84126079355;32426.027219183463;32562.674373255963;32678.673213268514;32704.182395440304;25069.99720011252;25322.873385632953;25334.746652533977;25787.96848126129;25829.741702582734 18:41:43 INFO - PROCESS | 1676 | |2;jquery.html;399.5385329943915;401.5101576077185;403.03473181729737;405.8437501561898;413.38612160941;14492.54323568409;20440.795592044076;20476.590468190665;21149.682754758673;21198.788012119854;16382.426615068474;18792.906035469838;20757.273495427664;20845.374638760823;21043;5922.674252916088;6235.158253635769;6238.719257633417;6243;6245.063240513914;5835.35811060783;6014.909776353336;6088.178095957055;6090.634561926277;6096.024636058231;6122.540809439288;6139.508839292846;6153.7230824613;6161.19904412428;6167.691615419233;6026.156338112668;6027.668478233695;6139.2632884053755;6144.354842741514;6159;5276.683398996072;5339.172428273624;5381.973090134564;5394.973025134888;5396.78412863485;26684;26760.59859102115;26796.59805102925;27258.863705681542;27333.316667083283;2268.1040988809327;2353.011735071274;2395.2814155753204;2841.985790071057;2949.7787665925143;12325.260484370967;15144.318505667161;15270.694586108326;15437.536873893689;15604;1734.6790843693957;1750.7986581543157;1766.152246921472;1768.9469315920544;1783.5184500185007;94616.52691736566;109706.45146774215;109733.45133274283;110132.34801477984;116400;11408.144389170846;11829.408529573488;11862.347570883685;11866.228695134789;11936.58221962227;15184.316705748268;15218.858585606124;15309.387624495119;15357.6160595986;15380.615484612974;7896.921030789734;8050.396220283502;9008.864867027;9044.186023257938;9174.128457796498;9071.319651026199;9076.228520575693;9077.183053525214;9092.136247056518;9137.862932055892;3846.673032792244;3881.146147847469;3904.6485816276104;4749.881252968704;4758.095961767253;1957.5399781051442;2022.6055919095636;2034.4100210938957;2052.6613108837173;2066.8139867412006;899.3165194452264;948.374073111743;970.5486948568949;982.4989255479613;1080.2384319055047;21101.472463188235;21385.89307053455;21541.892290538446;21670.34988950334;21714.24000159987;1000.9649662261786;1034.3535290443472;1061.1828891753307;1062.6811956413046;1077.719792853848;10444.746630404401;11003;11490.885091149148;11529.250598711056;11535.423228838692;514580;520690;521198;569185;628014;122984.15523767151;127881;134565;138848;143942;1036.8237399642078;1045.9215558833118;1048.4757621189406;1078.5793540519203;1082.410086502858;18455.446336609923;18716.812831871506;19052.714209286594;19182.13680384414;19278.807211927702;33930.49104263487;51609.483905160465;54729.631759206335;56070.43929560652;58640.70679646745;57365;70615.64692176506;77430;78614.606926965;79894;7919.722809701633;8265.338772898034;8366.665333386596;8418.789530261547;8438.409311348147;9295.698602195833;12499.687507812378;12521.49914003466;12868.806967895676;12970.870291297344;9056.818863622822;10461;11007.284526505751;11117.388543630179;11337.773244535229;10776.730581735519;10825.891741082489;10963.397013164033;11120.61077862271;11283.435828208392;9256.49089300068;11253.606123785628;11281.435928203393;11438.599649012578;11803.409829508319;8160.714374997084;8239.423240373117;8290.502569845828;8327.875081873655;8518.020427650652;8265.669373225244;10366.896331036593;11173.05029072538;11425.028872545927;11473.483693233658 18:41:43 INFO - PROCESS | 1676 | |3;mootools.html;1391.506015364545;1572.3396173607086;1574;1586.9843300287814;1945.8929758863258;7481.775546733593;8528.786780330487;8678.86981695275;8710.912890871105;8715.91284087159;7088.610126443056;8680.175383338588;8707.521086340264;8725.520096394695;8771.56142192891;2140.197425965263;2382.5711371953043;2423.127674037345;2446.739928936599;2449.5590793657134;2464.963025554618;2480.355107672009;2480.7395223501544;2482.366996415911;2483.081259933827;2367.0531787285054;2396.8561886286793;2416.1060407649106;2418.6372044193336;2420.757924207574;2125.989370053155;2370.6444033394955;2422.5033868057008;2423.260905423845;2430.0644251962945;2392.102961389479;2418.600930846408;2421.33413311339;2423.127674037345;2427.720812106613;8589.742307730778;8650.567471626393;8661;8664.610092545787;8667.21995020445;5244.921326180111;5276.445973172838;5390.9191362129595;5441.129419292934;5451.890962180773;3508.491268766015;3600.5499312585926;3636.072801435617;3640.5631324240994;3653.3971894637352;862.8144948835968;1026.620150544296;1048.2400259811575;1049.863517742696;1061.490484567412;11953.163278570504;11976.820347694791;11989.280643161439;11991.580294689644;12024.819627705592;4733.908453478352;4804.639652026112;4810.494898035656;4848.224284114524;4868.586170175504;10125.443100629536;10199.643012494527;10201.13290370312;10223.846642300372;10308.32995855267;5455.563554915597;5500.4499550044675;5508.55931525485;5509.614326997152;5511.338639363302;4379.802908869108;4483.170613436525;4769.713817170911;4948.950510494921;4954.603631709455;3999.6400323970984;4020.296448121567;4067.6949228807957;4128.174365126986;4130.91738165235;1327.4336283185933;1331.187975335045;1336.5589355512657;1340.2427628389928;1355.8440779310506;791.3945831438954;869.8782170496136;906.7778394293308;919.7274587314585;926.6293482607011;4987.002599480118;4990.925136122961;5797.768089276382;5869.501092407193;6068.635881847015;916.4684482999844;929.0987741890356;960.2030314838668;985.3398223190479;1000.1498726082917;3184.1880320518585;3823.483829682957;3856.0745421098736;3936.0553467167674;3939.1727737175515;10276.383416994855;10491.796722688168;10629.5216715248;10657.147428205724;10961.177911656658;10334.689959301233;10822.40476773785;10828.620998265023;10829.35023898553;10831.512581933832;7973.524897893908;8182.304504117098;8237.711680091168;8299.95850020746;8334.416590838584;5462.781488740565;5528.143137813645;5562.666240025612;5638.464345887134;5837.474627283565;8915.821683566423;9279.164875161268;9349.579268932779;9373.671921482715;9721.611135554498;9288.814223715623;9388.812223755624;9482.715518534455;9500.38247513937;9690.563924623268;6551.377619126175;6594.571352862049;7367.594782287026;7453.552786832807;7504.249575042452;8134.105248422788;8167.469114507538;8195.590220488832;8228.958855205685;8237.052738934859;6828.658567071528;6830.282820303995;7004.719811207495;7238.059052323164;7353.080864891888;7178.353948144483;7195.172555156224;7231.132264128339;7247.456440766858;7314.305141011595;6690.799276021728;6958.652067396509;6986.755463558751;6991.615461149686;7000.0199972004975;4709.764511774466;4749.833755818529;4755.667103302736;4867.537583929521;4930;4820.590249828804;4855.781489833037;4864.8054077837905;4912.042151780512;5050.292958985671 18:41:43 INFO - PROCESS | 1676 | |4;prototype.html;297.3369386268621;326.0723242375441;328.4039468364918;333.22691356053963;337.3219828145427;5326.414094449612;5330.866728331794;6629.867402651944;6632.170978627665;6636.4690824734025;6542.214934207902;6629.90055149172;6718.227403848559;6718.46252299816;6748.291429399916;4664.953350466486;4767.761611919407;4797.568218860302;4849.612031037508;4891.14404979129;4783.1868582341085;4855.56299933006;4877.829275975341;4885.975570122145;4890.951090489103;4825.613950883921;4831.420229572456;4834.709917404967;4840.298156767267;4860.829870954517;4626.8149274029;4707.6233901287815;4804.927926081112;4823.9035219295765;4835.419749630032;4153.7507749534825;4155.937660935088;4166.416701661769;4169.145325208325;4193.433886425354;6358.823617630753;6378.808635740936;6380.425761681425;6384;6385.457236134934;5792.855178620526;5823.272090988627;5838.153467747153;5841.912371314434;5854.941450585482;6790.98135279712;7070.8939365909555;7078.539894906815;7093.339997500937;7107.040549525851;4759.071980963713;4762.3094651275715;4776.904461910777;4791.856244312677;4801.2798080287885;7441.107067151922;7479;7497.400207983348;7497.512661676974;7517.248275172494;3934.5475270343704;4810.11012962603;4826.32431459596;4838.395200599925;4855.562999330077;4902.436219834764;4910.533499317559;4918.9508104919205;4922.975385123052;4924.655274130777;5559.9444005560235;5735.283089613798;5757.222774925332;5760.423957604206;5788.0739081746715;7108.933659950945;7134.179569349488;7166.964165179245;7176.892346614805;7177.354038136593;1677.966440671204;1714.5970696886222;1716.8969861808332;1722.001239281239;1723.991464992968;858.9914100859037;1025.0876719719456;1080.8919108089285;1094.5348227003492;1108.8281316395971;6540.9345906541275;6720.764773232913;6746.831329216809;6763.188417389944;6766.424853887376;5636.774529018793;7206.423486121098;7487.8502429951095;7500.287472690085;7503.474756766974;6716.865662686719;6720.630365329856;6721.26066132725;6737.326267373223;6749.223839258548;3779.4897688812207;3893.5717071122153;3944.5463771666628;3950.6641935435237;3951.7233793634746;7724.845503089907;7740.651670674833;7743.612819359009;7746;7754.340881025063;7608.429367797437;7683.769486915401;7688.807779805549;7693.49992250502;7728.806779830436;7742.496737712031;7743.064233941514;7747.496412733154;7762.7671169865;7781.688732450752;7109.253528379446;7424.480286379901;7429.925700742923;7439.293267139613;7471.775846724607;5976.8804623908145;7221.53060051095;7298.197198308288;7353.338199562065;7358.521696089737;6927.480438967198;7472.439567032603;7798;7809.960950195213;7810.804729881798;4509.23343031692;4515.209838278354;4527.818887244605;4529.275315949432;4532.841350552715;7122;7414.666340014604;7418.18399976013;7422.591757453392;7435.739749108755;6154.79981403622;7124.3944264738075;7149.213586505376;7185.53294035886;7192.2088570258275;6826.897596536155;7172.175199852084;7173.46199035064;7175.103112110986;7183.317584829433;7015.508914375945;7021.05215795861;7028.718851246097;7029.226785053742;7030.894536581853;5541.030319694118;5804.73878675469;7160.427165826825;7175.31834475724;7189.5686258824635;5432.402435732145;6891.965540172267;6918.861622767416;7044.119485064367;7067.116610423697 18:41:43 INFO - PROCESS | 1676 | |5;yui.html;655.5509476008934;673.5520878615721;734.5702763883128;739.5525706947295;754.9056367954006;15050.924745376271;15279.77080343793;15403.84596154038;15439.614009649764;15440.227988600553;12618.684532886682;12832;12836.358182090882;12854.871451285484;12855.100142989995;743.0563184755357;758.3099379564597;908.7410088124869;926.8331700293944;944.905509449053;783.7883771381752;901.9774505637346;931.0642803981989;936.1013427109982;936.1901954809075;944.3436811416069;978.5351957820014;980.9852852207187;982.0670363154995;985.7781999050228;775.1086250811555;965.0108638645339;967.0716112531978;967.2117224462111;971.8833739951216;713.8536599996988;714.0610097721479;714.4034730999622;717.8348979734691;729.2452311857203;15006.249687515581;15091.471798487;15103.4713785017;15118.09291442506;15143.242837858063;12079.27524348542;12469.812952805714;12509.437075331633;12761.234325940473;12805.87194128056;10440.895591044144;11026.944865275702;11062.778744425066;11359.716007099889;11667.241629294069;3486.773359731609;3519.225770330523;3526.947095793565;3569.607343192247;3721.2557488502407;57798.7110064447;76530.23469765286;77311;77942;78274.21725782726;9339.579718912666;9411.43531388119;9524.190443812353;9952.601895924226;10071.992800719869;19088.14103365352;21425.892870535547;21474.355769326947;21476.78523214779;21511.354659360244;6074.908876366858;6900.827479312957;7016.824579385556;7186.928130718626;7476.439267054884;4098.262312783668;4103.712551010204;4107.178564287155;4186.330187170038;4383.517813040563;3577.35607590631;3620.5655321361605;3632.2917031178667;3636.381815091441;3636.9272614547563;636.147562266565;646.1406329581645;647.0520687193284;647.582309410437;654.5777973207255;512.633467071046;613.5521069619114;616.4144063140035;616.6146158650844;624.5409623926411;6839.863202735917;7300.16048154469;7670.463067585327;8081.838363232702;8456.534890581077;490.4213028626184;576.0581449330375;587.6239206907497;595.6217801695955;622.184937731564;3813.4470501777087;3829.5787463379006;3862.111714305669;3948.7828169450954;4084.81618327176;88022.55988720016;88104;88429;88440;88860.5556972224;72631.63684181651;79798;79830.6008469954;80350.59824700838;82547.5872620633;69592;70533;70549.64725176444;70761;71071;6324.588901721558;7519.398448124246;7745.186755390603;7857.646405911633;7917.247861453153;42592.36111458269;44930.60832700097;45022.874428139556;46812.29781553211;48217.51782482271;62220.37779622327;64950.35049649443;65292;66046;66493;9692.17616502577;9734.805303894023;9870.457124857914;9901.603935842486;10150.644727434505;8055.556944368116;10841.970012848768;10878.619248326588;10907.618233361794;11144.996950274513;10126.89873101289;10235.48822558884;10247.641332553325;10430.217733669853;10701.785964280514;9927.354721943051;10123.797524049625;10280.126189273697;10299.485025748832;10428.739281517719;9939.850902236327;10053.648122315684;10257.435841028517;10281.640142595272;10560.524776384927;8645.783855403664;8663.696770613251;8682.161320862388;8796.956015219883;8877.467351958903;8767.736967890973;8794.516301603473;8871.822563548822;8951.28389728807;8983.955080224818 18:41:43 INFO - PROCESS | 1676 | __end_tp_report 18:41:43 INFO - PROCESS | 1676 | __start_cc_report 18:41:43 INFO - PROCESS | 1676 | _x_x_mozilla_cycle_collect,1148 18:41:43 INFO - PROCESS | 1676 | __end_cc_report 18:41:43 INFO - PROCESS | 1676 | __startTimestamp1463967703468__endTimestamp 18:41:43 INFO - PROCESS | 1676 | 18:41:43 INFO - PROCESS | 1676 | ------- Summary: start ------- 18:41:43 INFO - PROCESS | 1676 | Number of tests: 6 18:41:43 INFO - PROCESS | 1676 | 18:41:43 INFO - PROCESS | 1676 | [#0] dojo.html Cycles:165 Average:5719.58 Median:5866.50 stddev:634.27 (10.8%) stddev-sans-first:608.90 18:41:43 INFO - PROCESS | 1676 | Values: 3372.4 3467.8 3479.4 3503.2 3530.6 5672.0 5680.7 5714.6 5714.9 5729.4 5697.7 5730.2 5736.3 5746.5 5749.7 5106.7 5155.9 5164.6 5257.5 5266.9 5163.3 5248.3 5258.8 5267.4 5295.6 5221.6 5222.6 5247.4 5283.4 5307.5 5255.4 5273.5 5275.9 5278.8 5294.0 5115.9 5149.9 5166.2 5209.8 5217.9 5588.0 5630.2 5644.3 5667.7 5669.8 6225.4 6241.5 6248.4 6250.2 6284.8 6111.4 6117.5 6227.8 6317.4 6344.7 5797.1 5832.7 5833.8 5835.2 5842.1 6336.4 6339.3 6344.3 6346.8 6362.5 6042.8 6068.8 6086.8 6160.2 6178.4 6259.5 6267.2 6273.2 6276.0 6303.1 5256.2 5866.5 6148.9 6207.8 6225.7 5522.0 5970.6 6055.5 6073.8 6076.6 4905.7 4915.0 4926.2 4934.9 4950.0 4405.7 4428.2 4483.4 4505.1 4539.3 5704.8 5710.3 5722.2 5724.3 5743.6 6136.6 6240.8 6318.1 6330.1 6353.1 5672.3 5718.3 5751.2 5768.5 5779.3 5355.5 5368.0 5370.4 5378.0 5380.7 6256.4 6309.3 6341.6 6354.0 6377.3 6273.1 6277.6 6288.3 6303.3 6314.9 6338.1 6351.7 6369.4 6383.6 6399.8 5084.4 5998.3 6062.5 6219.3 6230.7 6261.8 6280.9 6285.1 6287.7 6299.6 5066.9 6063.2 6087.6 6108.6 6110.3 5953.4 6044.3 6050.6 6057.4 6087.4 5818.7 5935.9 5964.1 5981.4 5989.8 6110.3 6116.7 6127.8 6128.0 6135.5 4682.6 4811.5 5943.3 5948.2 5950.1 18:41:43 INFO - PROCESS | 1676 | 18:41:43 INFO - PROCESS | 1676 | [#1] ext.html Cycles:180 Average:58855.18 Median:3452.90 stddev:124075.39 (3593.4%) stddev-sans-first:124346.14 18:41:43 INFO - PROCESS | 1676 | Values: 518.4 522.3 547.1 602.0 603.0 24540.8 30912.8 39901.8 39924.8 39985.0 44254.3 45024.0 45042.3 45159.5 45197.1 8549.1 9725.7 9956.1 9984.5 9986.5 8692.2 11810.3 12009.7 12020.0 12024.9 11714.1 11780.8 11808.2 11810.8 11922.6 8711.2 11630.5 11701.4 11728.2 11755.4 11660.2 11775.5 11789.9 11790.5 11797.2 38637.8 39033.0 39052.0 39121.8 39260.8 3207.3 3363.4 3417.3 3544.8 3761.7 27971.6 28076.4 28725.1 28856.3 29601.3 2268.4 2643.5 2666.8 2746.8 2852.1 347715.0 350873.0 350874.0 351322.0 352508.0 35897.3 36895.3 36941.1 37014.0 58168.4 35870.3 35954.3 36024.3 36047.6 36121.5 15611.7 15830.4 15838.9 15839.2 15848.8 12171.3 12352.2 13289.6 13537.7 13961.4 8186.3 8198.5 8205.4 8285.0 8303.8 3384.4 3442.7 3452.6 3453.3 3479.9 1036.8 1060.3 1060.9 1091.9 1158.1 30129.4 30180.7 30890.9 30947.0 31104.8 980.1 1010.6 1110.4 1144.5 1145.6 12623.0 14989.7 15000.2 15017.1 15385.2 595374.0 598344.0 602801.0 603745.0 604758.0 60665.4 61246.4 61317.0 61501.0 61622.7 1372.9 1408.2 1441.2 1538.0 1549.3 28501.0 30130.0 30133.7 30708.8 30842.8 50033.7 50153.2 50652.5 50704.0 50742.7 402569.0 405904.0 406545.0 406799.0 406962.0 23922.8 24332.0 26627.6 27121.3 27432.6 42797.9 47305.5 47971.8 48331.5 48728.0 29016.6 36692.3 38273.2 38305.6 39046.0 37361.8 37661.0 37741.0 38237.0 38357.0 37518.8 37889.0 38636.0 38671.0 38780.0 31747.8 32426.0 32562.7 32678.7 32704.2 25070.0 25322.9 25334.7 25788.0 25829.7 18:41:43 INFO - PROCESS | 1676 | 18:41:43 INFO - PROCESS | 1676 | [#2] jquery.html Cycles:180 Average:33587.82 Median:11991.17 stddev:92519.16 (771.6%) stddev-sans-first:92745.14 18:41:43 INFO - PROCESS | 1676 | Values: 399.5 401.5 403.0 405.8 413.4 14492.5 20440.8 20476.6 21149.7 21198.8 16382.4 18792.9 20757.3 20845.4 21043.0 5922.7 6235.2 6238.7 6243.0 6245.1 5835.4 6014.9 6088.2 6090.6 6096.0 6122.5 6139.5 6153.7 6161.2 6167.7 6026.2 6027.7 6139.3 6144.4 6159.0 5276.7 5339.2 5382.0 5395.0 5396.8 26684.0 26760.6 26796.6 27258.9 27333.3 2268.1 2353.0 2395.3 2842.0 2949.8 12325.3 15144.3 15270.7 15437.5 15604.0 1734.7 1750.8 1766.2 1768.9 1783.5 94616.5 109706.5 109733.5 110132.3 116400.0 11408.1 11829.4 11862.3 11866.2 11936.6 15184.3 15218.9 15309.4 15357.6 15380.6 7896.9 8050.4 9008.9 9044.2 9174.1 9071.3 9076.2 9077.2 9092.1 9137.9 3846.7 3881.1 3904.6 4749.9 4758.1 1957.5 2022.6 2034.4 2052.7 2066.8 899.3 948.4 970.5 982.5 1080.2 21101.5 21385.9 21541.9 21670.3 21714.2 1001.0 1034.4 1061.2 1062.7 1077.7 10444.7 11003.0 11490.9 11529.3 11535.4 514580.0 520690.0 521198.0 569185.0 628014.0 122984.2 127881.0 134565.0 138848.0 143942.0 1036.8 1045.9 1048.5 1078.6 1082.4 18455.4 18716.8 19052.7 19182.1 19278.8 33930.5 51609.5 54729.6 56070.4 58640.7 57365.0 70615.6 77430.0 78614.6 79894.0 7919.7 8265.3 8366.7 8418.8 8438.4 9295.7 12499.7 12521.5 12868.8 12970.9 9056.8 10461.0 11007.3 11117.4 11337.8 10776.7 10825.9 10963.4 11120.6 11283.4 9256.5 11253.6 11281.4 11438.6 11803.4 8160.7 8239.4 8290.5 8327.9 8518.0 8265.7 10366.9 11173.1 11425.0 11473.5 18:41:43 INFO - PROCESS | 1676 | 18:41:43 INFO - PROCESS | 1676 | [#3] mootools.html Cycles:180 Average:5638.25 Median:4921.02 stddev:3142.19 (63.9%) stddev-sans-first:3134.79 18:41:43 INFO - PROCESS | 1676 | Values: 1391.5 1572.3 1574.0 1587.0 1945.9 7481.8 8528.8 8678.9 8710.9 8715.9 7088.6 8680.2 8707.5 8725.5 8771.6 2140.2 2382.6 2423.1 2446.7 2449.6 2465.0 2480.4 2480.7 2482.4 2483.1 2367.1 2396.9 2416.1 2418.6 2420.8 2126.0 2370.6 2422.5 2423.3 2430.1 2392.1 2418.6 2421.3 2423.1 2427.7 8589.7 8650.6 8661.0 8664.6 8667.2 5244.9 5276.4 5390.9 5441.1 5451.9 3508.5 3600.5 3636.1 3640.6 3653.4 862.8 1026.6 1048.2 1049.9 1061.5 11953.2 11976.8 11989.3 11991.6 12024.8 4733.9 4804.6 4810.5 4848.2 4868.6 10125.4 10199.6 10201.1 10223.8 10308.3 5455.6 5500.4 5508.6 5509.6 5511.3 4379.8 4483.2 4769.7 4949.0 4954.6 3999.6 4020.3 4067.7 4128.2 4130.9 1327.4 1331.2 1336.6 1340.2 1355.8 791.4 869.9 906.8 919.7 926.6 4987.0 4990.9 5797.8 5869.5 6068.6 916.5 929.1 960.2 985.3 1000.1 3184.2 3823.5 3856.1 3936.1 3939.2 10276.4 10491.8 10629.5 10657.1 10961.2 10334.7 10822.4 10828.6 10829.4 10831.5 7973.5 8182.3 8237.7 8300.0 8334.4 5462.8 5528.1 5562.7 5638.5 5837.5 8915.8 9279.2 9349.6 9373.7 9721.6 9288.8 9388.8 9482.7 9500.4 9690.6 6551.4 6594.6 7367.6 7453.6 7504.2 8134.1 8167.5 8195.6 8229.0 8237.1 6828.7 6830.3 7004.7 7238.1 7353.1 7178.4 7195.2 7231.1 7247.5 7314.3 6690.8 6958.7 6986.8 6991.6 7000.0 4709.8 4749.8 4755.7 4867.5 4930.0 4820.6 4855.8 4864.8 4912.0 5050.3 18:41:43 INFO - PROCESS | 1676 | 18:41:43 INFO - PROCESS | 1676 | [#4] prototype.html Cycles:180 Average:5805.63 Median:6631.04 stddev:1863.56 (28.1%) stddev-sans-first:1822.35 18:41:43 INFO - PROCESS | 1676 | Values: 297.3 326.1 328.4 333.2 337.3 5326.4 5330.9 6629.9 6632.2 6636.5 6542.2 6629.9 6718.2 6718.5 6748.3 4665.0 4767.8 4797.6 4849.6 4891.1 4783.2 4855.6 4877.8 4886.0 4891.0 4825.6 4831.4 4834.7 4840.3 4860.8 4626.8 4707.6 4804.9 4823.9 4835.4 4153.8 4155.9 4166.4 4169.1 4193.4 6358.8 6378.8 6380.4 6384.0 6385.5 5792.9 5823.3 5838.2 5841.9 5854.9 6791.0 7070.9 7078.5 7093.3 7107.0 4759.1 4762.3 4776.9 4791.9 4801.3 7441.1 7479.0 7497.4 7497.5 7517.2 3934.5 4810.1 4826.3 4838.4 4855.6 4902.4 4910.5 4919.0 4923.0 4924.7 5559.9 5735.3 5757.2 5760.4 5788.1 7108.9 7134.2 7167.0 7176.9 7177.4 1678.0 1714.6 1716.9 1722.0 1724.0 859.0 1025.1 1080.9 1094.5 1108.8 6540.9 6720.8 6746.8 6763.2 6766.4 5636.8 7206.4 7487.9 7500.3 7503.5 6716.9 6720.6 6721.3 6737.3 6749.2 3779.5 3893.6 3944.5 3950.7 3951.7 7724.8 7740.7 7743.6 7746.0 7754.3 7608.4 7683.8 7688.8 7693.5 7728.8 7742.5 7743.1 7747.5 7762.8 7781.7 7109.3 7424.5 7429.9 7439.3 7471.8 5976.9 7221.5 7298.2 7353.3 7358.5 6927.5 7472.4 7798.0 7810.0 7810.8 4509.2 4515.2 4527.8 4529.3 4532.8 7122.0 7414.7 7418.2 7422.6 7435.7 6154.8 7124.4 7149.2 7185.5 7192.2 6826.9 7172.2 7173.5 7175.1 7183.3 7015.5 7021.1 7028.7 7029.2 7030.9 5541.0 5804.7 7160.4 7175.3 7189.6 5432.4 6892.0 6918.9 7044.1 7067.1 18:41:43 INFO - PROCESS | 1676 | 18:41:43 INFO - PROCESS | 1676 | [#5] yui.html Cycles:180 Average:17565.75 Median:647.32 stddev:24820.17 (3834.3%) stddev-sans-first:24857.32 18:41:43 INFO - PROCESS | 1676 | Values: 655.6 673.6 734.6 739.6 754.9 15050.9 15279.8 15403.8 15439.6 15440.2 12618.7 12832.0 12836.4 12854.9 12855.1 743.1 758.3 908.7 926.8 944.9 783.8 902.0 931.1 936.1 936.2 944.3 978.5 981.0 982.1 985.8 775.1 965.0 967.1 967.2 971.9 713.9 714.1 714.4 717.8 729.2 15006.2 15091.5 15103.5 15118.1 15143.2 12079.3 12469.8 12509.4 12761.2 12805.9 10440.9 11026.9 11062.8 11359.7 11667.2 3486.8 3519.2 3526.9 3569.6 3721.3 57798.7 76530.2 77311.0 77942.0 78274.2 9339.6 9411.4 9524.2 9952.6 10072.0 19088.1 21425.9 21474.4 21476.8 21511.4 6074.9 6900.8 7016.8 7186.9 7476.4 4098.3 4103.7 4107.2 4186.3 4383.5 3577.4 3620.6 3632.3 3636.4 3636.9 636.1 646.1 647.1 647.6 654.6 512.6 613.6 616.4 616.6 624.5 6839.9 7300.2 7670.5 8081.8 8456.5 490.4 576.1 587.6 595.6 622.2 3813.4 3829.6 3862.1 3948.8 4084.8 88022.6 88104.0 88429.0 88440.0 88860.6 72631.6 79798.0 79830.6 80350.6 82547.6 69592.0 70533.0 70549.6 70761.0 71071.0 6324.6 7519.4 7745.2 7857.6 7917.2 42592.4 44930.6 45022.9 46812.3 48217.5 62220.4 64950.4 65292.0 66046.0 66493.0 9692.2 9734.8 9870.5 9901.6 10150.6 8055.6 10842.0 10878.6 10907.6 11145.0 10126.9 10235.5 10247.6 10430.2 10701.8 9927.4 10123.8 10280.1 10299.5 10428.7 9939.9 10053.6 10257.4 10281.6 10560.5 8645.8 8663.7 8682.2 8797.0 8877.5 8767.7 8794.5 8871.8 8951.3 8984.0 18:41:43 INFO - PROCESS | 1676 | -------- Summary: end -------- 18:41:43 INFO - PROCESS | 1676 | 18:41:44 INFO - TEST-INFO | 1676: exit 0 18:41:44 INFO - TEST-OK | dromaeo_css | took 1115366ms 18:41:44 INFO - TEST-START | kraken 18:41:44 INFO - operating with platform_type : mac_ 18:41:44 INFO - Initialising browser for kraken test... 18:41:44 INFO - TEST-INFO | started process 1683 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/tmprFAtxC/profile http://localhost:49285/getInfo.html) 18:41:46 INFO - PROCESS | 1683 | __metrics Screen width/height:1600/1200 18:41:46 INFO - PROCESS | 1683 | colorDepth:24 18:41:46 INFO - PROCESS | 1683 | Browser inner width/height: 1024/689 18:41:46 INFO - PROCESS | 1683 | __metrics 18:41:46 INFO - PROCESS | 1683 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:41:46 ERROR - PROCESS | 1683 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 18:41:46 INFO - TEST-INFO | 1683: exit 0 18:41:46 INFO - Browser initialized. 18:41:46 INFO - Running cycle 1/1 for kraken test... 18:41:46 INFO - TEST-INFO | started process 1685 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/tmprFAtxC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:41:58 INFO - PROCESS | 1685 | RSS: Main: 211075072 18:41:58 INFO - PROCESS | 1685 | 18:42:01 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49285/tests/kraken/driver.html?testName=audio-beat-detection) 18:42:02 INFO - PROCESS | 1685 | RSS: Main: 271675392 18:42:02 INFO - PROCESS | 1685 | 18:42:04 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49285/tests/kraken/driver.html?testName=audio-dft) 18:42:05 INFO - PROCESS | 1685 | RSS: Main: 285540352 18:42:05 INFO - PROCESS | 1685 | 18:42:08 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49285/tests/kraken/driver.html?testName=audio-fft) 18:42:09 INFO - PROCESS | 1685 | RSS: Main: 372072448 18:42:09 INFO - PROCESS | 1685 | 18:42:11 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49285/tests/kraken/driver.html?testName=audio-oscillator) 18:42:11 INFO - PROCESS | 1685 | RSS: Main: 278769664 18:42:11 INFO - PROCESS | 1685 | 18:42:14 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49285/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:42:14 INFO - PROCESS | 1685 | RSS: Main: 280023040 18:42:14 INFO - PROCESS | 1685 | 18:42:17 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49285/tests/kraken/driver.html?testName=imaging-darkroom) 18:42:18 INFO - PROCESS | 1685 | RSS: Main: 318693376 18:42:18 INFO - PROCESS | 1685 | 18:42:20 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49285/tests/kraken/driver.html?testName=imaging-desaturate) 18:42:21 INFO - PROCESS | 1685 | RSS: Main: 318930944 18:42:21 INFO - PROCESS | 1685 | 18:42:24 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49285/tests/kraken/driver.html?testName=json-parse-financial) 18:42:24 INFO - PROCESS | 1685 | RSS: Main: 316780544 18:42:24 INFO - PROCESS | 1685 | 18:42:26 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49285/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:42:27 INFO - PROCESS | 1685 | RSS: Main: 308842496 18:42:27 INFO - PROCESS | 1685 | 18:42:28 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49285/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:42:29 INFO - PROCESS | 1685 | RSS: Main: 316317696 18:42:29 INFO - PROCESS | 1685 | 18:42:31 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49285/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:42:32 INFO - PROCESS | 1685 | RSS: Main: 318693376 18:42:32 INFO - PROCESS | 1685 | 18:42:35 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49285/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:42:35 INFO - PROCESS | 1685 | RSS: Main: 321462272 18:42:35 INFO - PROCESS | 1685 | 18:42:38 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49285/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:42:39 INFO - PROCESS | 1685 | RSS: Main: 321802240 18:42:39 INFO - PROCESS | 1685 | 18:42:41 INFO - PROCESS | 1685 | Cycle 1(1): loaded http://localhost:49285/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49285/tests/kraken/driver.html?testName=ai-astar) 18:42:41 INFO - PROCESS | 1685 | RSS: Main: 386383872 18:42:41 INFO - PROCESS | 1685 | 18:42:41 INFO - PROCESS | 1685 | __start_tp_report 18:42:41 INFO - PROCESS | 1685 | _x_x_mozilla_page_load 18:42:41 INFO - PROCESS | 1685 | _x_x_mozilla_page_load_details 18:42:41 INFO - PROCESS | 1685 | |i|pagename|runs| 18:42:41 INFO - PROCESS | 1685 | |0;ai-astar;113;113;120;110;111;112;114;113;113;139 18:42:41 INFO - PROCESS | 1685 | |1;audio-beat-detection;153;124;139;165;120;143;136;120;169;118 18:42:41 INFO - PROCESS | 1685 | |2;audio-dft;185;185;183;183;179;183;182;181;182;184 18:42:41 INFO - PROCESS | 1685 | |3;audio-fft;70;78;76;150;56;55;57;63;132;56 18:42:41 INFO - PROCESS | 1685 | |4;audio-oscillator;95;88;146;77;81;78;127;82;115;167 18:42:41 INFO - PROCESS | 1685 | |5;imaging-gaussian-blur;87;85;92;84;88;83;89;85;90;84 18:42:41 INFO - PROCESS | 1685 | |6;imaging-darkroom;93;93;93;93;93;92;94;94;93;97 18:42:41 INFO - PROCESS | 1685 | |7;imaging-desaturate;83;85;84;85;87;91;84;83;85;86 18:42:41 INFO - PROCESS | 1685 | |8;json-parse-financial;61;64;64;65;65;65;64;80;61;60 18:42:41 INFO - PROCESS | 1685 | |9;json-stringify-tinderbox;65;64;72;73;77;72;70;68;67;68 18:42:41 INFO - PROCESS | 1685 | |10;stanford-crypto-aes;82;81;80;81;81;83;93;104;104;84 18:42:41 INFO - PROCESS | 1685 | |11;stanford-crypto-ccm;129;129;128;152;145;141;127;127;186;143 18:42:41 INFO - PROCESS | 1685 | |12;stanford-crypto-pbkdf2;167;165;166;166;168;163;153;172;165;165 18:42:41 INFO - PROCESS | 1685 | |13;stanford-crypto-sha256-iterative;67;67;66;67;67;68;66;69;66;69 18:42:41 INFO - PROCESS | 1685 | __end_tp_report 18:42:41 INFO - PROCESS | 1685 | __start_cc_report 18:42:41 INFO - PROCESS | 1685 | _x_x_mozilla_cycle_collect,2018 18:42:41 INFO - PROCESS | 1685 | __end_cc_report 18:42:41 INFO - PROCESS | 1685 | __startTimestamp1463967761314__endTimestamp 18:42:41 INFO - PROCESS | 1685 | 18:42:41 INFO - PROCESS | 1685 | ------- Summary: start ------- 18:42:41 INFO - PROCESS | 1685 | Number of tests: 14 18:42:41 INFO - PROCESS | 1685 | 18:42:41 INFO - PROCESS | 1685 | [#0] ai-astar Cycles:10 Average:115.80 Median:113.00 stddev:8.57 (7.6%) stddev-sans-first:9.03 18:42:41 INFO - PROCESS | 1685 | Values: 113.0 113.0 120.0 110.0 111.0 112.0 114.0 113.0 113.0 139.0 18:42:41 INFO - PROCESS | 1685 | 18:42:41 INFO - PROCESS | 1685 | [#1] audio-beat-detection Cycles:10 Average:138.70 Median:141.00 stddev:18.81 (13.3%) stddev-sans-first:19.23 18:42:41 INFO - PROCESS | 1685 | Values: 153.0 124.0 139.0 165.0 120.0 143.0 136.0 120.0 169.0 118.0 18:42:41 INFO - PROCESS | 1685 | 18:42:41 INFO - PROCESS | 1685 | [#2] audio-dft Cycles:10 Average:182.70 Median:183.00 stddev:1.83 (1.0%) stddev-sans-first:1.74 18:42:41 INFO - PROCESS | 1685 | Values: 185.0 185.0 183.0 183.0 179.0 183.0 182.0 181.0 182.0 184.0 18:42:41 INFO - PROCESS | 1685 | 18:42:41 INFO - PROCESS | 1685 | [#3] audio-fft Cycles:10 Average:79.30 Median:60.00 stddev:33.85 (56.4%) stddev-sans-first:35.74 18:42:41 INFO - PROCESS | 1685 | Values: 70.0 78.0 76.0 150.0 56.0 55.0 57.0 63.0 132.0 56.0 18:42:41 INFO - PROCESS | 1685 | 18:42:41 INFO - PROCESS | 1685 | [#4] audio-oscillator Cycles:10 Average:105.60 Median:79.50 stddev:31.82 (40.0%) stddev-sans-first:33.52 18:42:41 INFO - PROCESS | 1685 | Values: 95.0 88.0 146.0 77.0 81.0 78.0 127.0 82.0 115.0 167.0 18:42:41 INFO - PROCESS | 1685 | 18:42:41 INFO - PROCESS | 1685 | [#5] imaging-gaussian-blur Cycles:10 Average:86.70 Median:87.50 stddev:2.98 (3.4%) stddev-sans-first:3.16 18:42:41 INFO - PROCESS | 1685 | Values: 87.0 85.0 92.0 84.0 88.0 83.0 89.0 85.0 90.0 84.0 18:42:41 INFO - PROCESS | 1685 | 18:42:41 INFO - PROCESS | 1685 | [#6] imaging-darkroom Cycles:10 Average:93.50 Median:93.00 stddev:1.35 (1.5%) stddev-sans-first:1.42 18:42:41 INFO - PROCESS | 1685 | Values: 93.0 93.0 93.0 93.0 93.0 92.0 94.0 94.0 93.0 97.0 18:42:41 INFO - PROCESS | 1685 | 18:42:41 INFO - PROCESS | 1685 | [#7] imaging-desaturate Cycles:10 Average:85.30 Median:85.00 stddev:2.36 (2.8%) stddev-sans-first:2.35 18:42:41 INFO - PROCESS | 1685 | Values: 83.0 85.0 84.0 85.0 87.0 91.0 84.0 83.0 85.0 86.0 18:42:41 INFO - PROCESS | 1685 | 18:42:41 INFO - PROCESS | 1685 | [#8] json-parse-financial Cycles:10 Average:64.90 Median:64.50 stddev:5.63 (8.7%) stddev-sans-first:5.79 18:42:41 INFO - PROCESS | 1685 | Values: 61.0 64.0 64.0 65.0 65.0 65.0 64.0 80.0 61.0 60.0 18:42:41 INFO - PROCESS | 1685 | 18:42:41 INFO - PROCESS | 1685 | [#9] json-stringify-tinderbox Cycles:10 Average:69.60 Median:71.00 stddev:3.98 (5.6%) stddev-sans-first:3.86 18:42:41 INFO - PROCESS | 1685 | Values: 65.0 64.0 72.0 73.0 77.0 72.0 70.0 68.0 67.0 68.0 18:42:41 INFO - PROCESS | 1685 | 18:42:41 INFO - PROCESS | 1685 | [#10] stanford-crypto-aes Cycles:10 Average:87.30 Median:81.50 stddev:9.55 (11.7%) stddev-sans-first:9.93 18:42:41 INFO - PROCESS | 1685 | Values: 82.0 81.0 80.0 81.0 81.0 83.0 93.0 104.0 104.0 84.0 18:42:41 INFO - PROCESS | 1685 | 18:42:41 INFO - PROCESS | 1685 | [#11] stanford-crypto-ccm Cycles:10 Average:140.70 Median:142.00 stddev:18.30 (12.9%) stddev-sans-first:18.91 18:42:41 INFO - PROCESS | 1685 | Values: 129.0 129.0 128.0 152.0 145.0 141.0 127.0 127.0 186.0 143.0 18:42:41 INFO - PROCESS | 1685 | 18:42:41 INFO - PROCESS | 1685 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.00 Median:166.00 stddev:4.85 (2.9%) stddev-sans-first:5.09 18:42:41 INFO - PROCESS | 1685 | Values: 167.0 165.0 166.0 166.0 168.0 163.0 153.0 172.0 165.0 165.0 18:42:41 INFO - PROCESS | 1685 | 18:42:41 INFO - PROCESS | 1685 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.20 Median:67.00 stddev:1.14 (1.7%) stddev-sans-first:1.20 18:42:41 INFO - PROCESS | 1685 | Values: 67.0 67.0 66.0 67.0 67.0 68.0 66.0 69.0 66.0 69.0 18:42:41 INFO - PROCESS | 1685 | -------- Summary: end -------- 18:42:41 INFO - PROCESS | 1685 | 18:42:42 INFO - TEST-INFO | 1685: exit 0 18:42:42 INFO - TEST-OK | kraken | took 58046ms 18:42:42 INFO - SUITE-END | took 1173s 18:42:42 INFO - Completed test suite (00:19:33) 18:42:42 INFO - javascript benchmark 18:42:42 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3372.392969265532, 3467.7919324840514, 3479.373712731708, 3503.2292895563, 3530.6469353064695, 5671.971640141799, 5680.659160450371, 5714.599978001542, 5714.857128571788, 5729.39841316662, 5697.743601537931, 5730.169125476814, 5736.340320863102, 5746.48281654651, 5749.65502069875, 5106.69359838409, 5155.871103222412, 5164.561012313958, 5257.474252574731, 5266.894662106755, 5163.251328557357, 5248.343957005374, 5258.842234732945, 5267.446918073604, 5295.602829787762, 5221.634485586011, 5222.63441559091, 5247.422783493832, 5283.418823929364, 5307.495787900143, 5255.369355677324, 5273.523413444242, 5275.947240527584, 5278.788848446057, 5293.973530132324, 5115.87210319745, 5149.871253218662, 5166.225066240057, 5209.791608335662, 5217.869553261161, 5588.0, 5630.211770352154, 5644.266245388073, 5667.688277144755, 5669.801556945528, 6225.408586184305, 6241.500679945639, 6248.3751624837605, 6250.249970003583, 6284.842878928018, 6111.379037751179, 6117.522930757332, 6227.782027629011, 6317.431431171217, 6344.6510441925675, 5797.130430435384, 5832.708364581754, 5833.7958171464215, 5835.183074369592, 5842.065269556851, 6336.366363363719, 6339.334369891188, 6344.302126766052, 6346.841328966812, 6362.491000719931, 6042.818715438544, 6068.770081222976, 6086.817395478142, 6160.229971253593, 6178.443940045418, 6259.530535209878, 6267.1539342188225, 6273.215848018997, 6275.995840665471, 6303.117563541108, 5256.237845512379, 5866.504041469483, 6148.877022459616, 6207.751689932443, 6225.657588832658, 5521.972390138104, 5970.552208584373, 6055.545834062463, 6073.848153796102, 6076.574639775262, 4905.730184839868, 4914.950850491521, 4926.162552366106, 4934.9013019739405, 4950.0, 4405.66957478184, 4428.22506061438, 4483.417155769795, 4505.121501307213, 4539.341795439643, 5704.80033198836, 5710.343310519261, 5722.170285308607, 5724.284464441945, 5743.6266642668925, 6136.580349164012, 6240.812775616648, 6318.08387783778, 6330.082138090043, 6353.110564520971, 5672.319321681424, 5718.313802343662, 5751.223584815996, 5768.53851691872, 5779.33537643168, 5355.518003379714, 5367.973160134253, 5370.355557333145, 5378.031954248273, 5380.650257733235, 6256.374362563707, 6309.305976342507, 6341.587796793194, 6353.96823015882, 6377.298497165308, 6273.059041143682, 6277.591956522811, 6288.308286088435, 6303.338149494237, 6314.873702526015, 6338.112664227104, 6351.68241587928, 6369.363063693779, 6383.616982981036, 6399.8080057598345, 5084.3644544431945, 5998.280206375263, 6062.484688801324, 6219.346968568416, 6230.6884655767935, 6261.78083767066, 6280.874382512416, 6285.120083188266, 6287.717052732546, 6299.559030867796, 5066.898662026665, 6063.181470501602, 6087.57386982907, 6108.633481991094, 6110.26676798787, 5953.434423729739, 6044.33512313654, 6050.606710563799, 6057.363976782374, 6087.36082711309, 5818.738156782871, 5935.940640593712, 5964.105384192406, 5981.431763982415, 5989.790357337472, 6110.327863935053, 6116.694165291807, 6127.785527506516, 6128.0, 6135.478484328703, 4682.555157260055, 4811.542903424169, 5943.346231914573, 5948.167256583995, 5950.107483877453], "value": 5681.617289951338, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [518.4245487508864, 522.2636083122796, 547.0727117535779, 602.015704323431, 602.9788957386493, 24540.754592454072, 30912.84543577282, 39901.80049099754, 39924.80037599808, 39985.0, 44254.336184957174, 45024.0, 45042.32436513447, 45159.54840451595, 45197.096058078816, 8549.102344253857, 9725.708228753148, 9956.103950644441, 9984.50077496126, 9986.450745209011, 8692.174243446863, 11810.291382517033, 12009.699757506089, 12020.0, 12024.939875300612, 11714.062874970024, 11780.823287650692, 11808.17342786001, 11810.7637847243, 11922.642320730349, 8711.172438618321, 11630.476628551693, 11701.356425396641, 11728.237664551776, 11755.35345555994, 11660.242084264495, 11775.470103845262, 11789.938905498546, 11790.469428875722, 11797.233179843368, 38637.806810966045, 39033.0, 39052.0, 39121.80439097815, 39260.80369598134, 3207.262329664167, 3363.411403004489, 3417.2994536120036, 3544.8227588620466, 3761.717871159674, 27971.58042629362, 28076.43847123046, 28725.138245852657, 28856.27859303513, 29601.259968500744, 2268.3988742983124, 2643.4977354302623, 2666.7733242674394, 2746.752792248687, 2852.0873320537435, 347715.0, 350873.0, 350874.0, 351322.0, 352508.0, 35897.282054358766, 36895.262094757956, 36941.07647308839, 37014.0, 58168.41831581714, 35870.28259434797, 35954.28091438157, 36024.279514409565, 36047.639523604426, 36121.45817812735, 15611.687766244611, 15830.445934392472, 15838.9208053959, 15839.208039597974, 15848.841511584968, 12171.2697238166, 12352.197107188003, 13289.601311960656, 13537.729245415037, 13961.441542338396, 8186.345092392594, 8198.467099638505, 8205.384596155312, 8285.0, 8303.750887473385, 3384.390809654286, 3442.6933954250712, 3452.5511683480986, 3453.2575496268423, 3479.8956031319103, 1036.8444733289916, 1060.2525219720078, 1060.941648209356, 1091.8689757229183, 1158.0851127609094, 30129.397412052076, 30180.698193018226, 30890.918817841266, 30947.0, 31104.844475777478, 980.1423754215061, 1010.6260683547134, 1110.4392281897751, 1144.490701637763, 1145.570411095839, 12623.0, 14989.70020599582, 15000.249987500581, 15017.098974061591, 15385.153816539974, 595374.0, 598344.0, 602801.0, 603745.0, 604758.0, 60665.393346066856, 61246.38753612496, 61317.0, 61501.0, 61622.69188654118, 1372.9038967272195, 1408.1621435246013, 1441.1857300625322, 1538.007984849788, 1549.287327829211, 28501.0, 30130.0, 30133.69866301309, 30708.84645576758, 30842.84578577093, 50033.749831250614, 50153.24770128524, 50652.49347506478, 50703.985920282124, 50742.73143171302, 402568.9871550623, 405904.0, 406545.0, 406799.0, 406962.0, 23922.76077239275, 24332.0, 26627.600585990836, 27121.321966950196, 27432.628368581092, 42797.930051748954, 47305.52694473011, 47971.76014119907, 48331.51668483411, 48728.0, 29016.56475152832, 36692.266154677294, 38273.2345353097, 38305.6169438302, 39046.0, 37361.81319093387, 37661.0, 37741.0, 38237.0, 38357.0, 37518.812405937795, 37889.0, 38636.0, 38671.0, 38780.0, 31747.84126079355, 32426.027219183463, 32562.674373255963, 32678.673213268514, 32704.182395440304, 25069.99720011252, 25322.873385632953, 25334.746652533977, 25787.96848126129, 25829.741702582734], "value": 18505.15537173491, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [399.5385329943915, 401.5101576077185, 403.03473181729737, 405.8437501561898, 413.38612160941, 14492.54323568409, 20440.795592044076, 20476.590468190665, 21149.682754758673, 21198.788012119854, 16382.426615068474, 18792.906035469838, 20757.273495427664, 20845.374638760823, 21043.0, 5922.674252916088, 6235.158253635769, 6238.719257633417, 6243.0, 6245.063240513914, 5835.35811060783, 6014.909776353336, 6088.178095957055, 6090.634561926277, 6096.024636058231, 6122.540809439288, 6139.508839292846, 6153.7230824613, 6161.19904412428, 6167.691615419233, 6026.156338112668, 6027.668478233695, 6139.2632884053755, 6144.354842741514, 6159.0, 5276.683398996072, 5339.172428273624, 5381.973090134564, 5394.973025134888, 5396.78412863485, 26684.0, 26760.59859102115, 26796.59805102925, 27258.863705681542, 27333.316667083283, 2268.1040988809327, 2353.011735071274, 2395.2814155753204, 2841.985790071057, 2949.7787665925143, 12325.260484370967, 15144.318505667161, 15270.694586108326, 15437.536873893689, 15604.0, 1734.6790843693957, 1750.7986581543157, 1766.152246921472, 1768.9469315920544, 1783.5184500185007, 94616.52691736566, 109706.45146774215, 109733.45133274283, 110132.34801477984, 116400.0, 11408.144389170846, 11829.408529573488, 11862.347570883685, 11866.228695134789, 11936.58221962227, 15184.316705748268, 15218.858585606124, 15309.387624495119, 15357.6160595986, 15380.615484612974, 7896.921030789734, 8050.396220283502, 9008.864867027, 9044.186023257938, 9174.128457796498, 9071.319651026199, 9076.228520575693, 9077.183053525214, 9092.136247056518, 9137.862932055892, 3846.673032792244, 3881.146147847469, 3904.6485816276104, 4749.881252968704, 4758.095961767253, 1957.5399781051442, 2022.6055919095636, 2034.4100210938957, 2052.6613108837173, 2066.8139867412006, 899.3165194452264, 948.374073111743, 970.5486948568949, 982.4989255479613, 1080.2384319055047, 21101.472463188235, 21385.89307053455, 21541.892290538446, 21670.34988950334, 21714.24000159987, 1000.9649662261786, 1034.3535290443472, 1061.1828891753307, 1062.6811956413046, 1077.719792853848, 10444.746630404401, 11003.0, 11490.885091149148, 11529.250598711056, 11535.423228838692, 514580.0, 520690.0, 521198.0, 569185.0, 628014.0, 122984.15523767151, 127881.0, 134565.0, 138848.0, 143942.0, 1036.8237399642078, 1045.9215558833118, 1048.4757621189406, 1078.5793540519203, 1082.410086502858, 18455.446336609923, 18716.812831871506, 19052.714209286594, 19182.13680384414, 19278.807211927702, 33930.49104263487, 51609.483905160465, 54729.631759206335, 56070.43929560652, 58640.70679646745, 57365.0, 70615.64692176506, 77430.0, 78614.606926965, 79894.0, 7919.722809701633, 8265.338772898034, 8366.665333386596, 8418.789530261547, 8438.409311348147, 9295.698602195833, 12499.687507812378, 12521.49914003466, 12868.806967895676, 12970.870291297344, 9056.818863622822, 10461.0, 11007.284526505751, 11117.388543630179, 11337.773244535229, 10776.730581735519, 10825.891741082489, 10963.397013164033, 11120.61077862271, 11283.435828208392, 9256.49089300068, 11253.606123785628, 11281.435928203393, 11438.599649012578, 11803.409829508319, 8160.714374997084, 8239.423240373117, 8290.502569845828, 8327.875081873655, 8518.020427650652, 8265.669373225244, 10366.896331036593, 11173.05029072538, 11425.028872545927, 11473.483693233658], "value": 9757.087781024813, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1391.506015364545, 1572.3396173607086, 1574.0, 1586.9843300287814, 1945.8929758863258, 7481.775546733593, 8528.786780330487, 8678.86981695275, 8710.912890871105, 8715.91284087159, 7088.610126443056, 8680.175383338588, 8707.521086340264, 8725.520096394695, 8771.56142192891, 2140.197425965263, 2382.5711371953043, 2423.127674037345, 2446.739928936599, 2449.5590793657134, 2464.963025554618, 2480.355107672009, 2480.7395223501544, 2482.366996415911, 2483.081259933827, 2367.0531787285054, 2396.8561886286793, 2416.1060407649106, 2418.6372044193336, 2420.757924207574, 2125.989370053155, 2370.6444033394955, 2422.5033868057008, 2423.260905423845, 2430.0644251962945, 2392.102961389479, 2418.600930846408, 2421.33413311339, 2423.127674037345, 2427.720812106613, 8589.742307730778, 8650.567471626393, 8661.0, 8664.610092545787, 8667.21995020445, 5244.921326180111, 5276.445973172838, 5390.9191362129595, 5441.129419292934, 5451.890962180773, 3508.491268766015, 3600.5499312585926, 3636.072801435617, 3640.5631324240994, 3653.3971894637352, 862.8144948835968, 1026.620150544296, 1048.2400259811575, 1049.863517742696, 1061.490484567412, 11953.163278570504, 11976.820347694791, 11989.280643161439, 11991.580294689644, 12024.819627705592, 4733.908453478352, 4804.639652026112, 4810.494898035656, 4848.224284114524, 4868.586170175504, 10125.443100629536, 10199.643012494527, 10201.13290370312, 10223.846642300372, 10308.32995855267, 5455.563554915597, 5500.4499550044675, 5508.55931525485, 5509.614326997152, 5511.338639363302, 4379.802908869108, 4483.170613436525, 4769.713817170911, 4948.950510494921, 4954.603631709455, 3999.6400323970984, 4020.296448121567, 4067.6949228807957, 4128.174365126986, 4130.91738165235, 1327.4336283185933, 1331.187975335045, 1336.5589355512657, 1340.2427628389928, 1355.8440779310506, 791.3945831438954, 869.8782170496136, 906.7778394293308, 919.7274587314585, 926.6293482607011, 4987.002599480118, 4990.925136122961, 5797.768089276382, 5869.501092407193, 6068.635881847015, 916.4684482999844, 929.0987741890356, 960.2030314838668, 985.3398223190479, 1000.1498726082917, 3184.1880320518585, 3823.483829682957, 3856.0745421098736, 3936.0553467167674, 3939.1727737175515, 10276.383416994855, 10491.796722688168, 10629.5216715248, 10657.147428205724, 10961.177911656658, 10334.689959301233, 10822.40476773785, 10828.620998265023, 10829.35023898553, 10831.512581933832, 7973.524897893908, 8182.304504117098, 8237.711680091168, 8299.95850020746, 8334.416590838584, 5462.781488740565, 5528.143137813645, 5562.666240025612, 5638.464345887134, 5837.474627283565, 8915.821683566423, 9279.164875161268, 9349.579268932779, 9373.671921482715, 9721.611135554498, 9288.814223715623, 9388.812223755624, 9482.715518534455, 9500.38247513937, 9690.563924623268, 6551.377619126175, 6594.571352862049, 7367.594782287026, 7453.552786832807, 7504.249575042452, 8134.105248422788, 8167.469114507538, 8195.590220488832, 8228.958855205685, 8237.052738934859, 6828.658567071528, 6830.282820303995, 7004.719811207495, 7238.059052323164, 7353.080864891888, 7178.353948144483, 7195.172555156224, 7231.132264128339, 7247.456440766858, 7314.305141011595, 6690.799276021728, 6958.652067396509, 6986.755463558751, 6991.615461149686, 7000.0199972004975, 4709.764511774466, 4749.833755818529, 4755.667103302736, 4867.537583929521, 4930.0, 4820.590249828804, 4855.781489833037, 4864.8054077837905, 4912.042151780512, 5050.292958985671], "value": 4554.526741914703, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.3369386268621, 326.0723242375441, 328.4039468364918, 333.22691356053963, 337.3219828145427, 5326.414094449612, 5330.866728331794, 6629.867402651944, 6632.170978627665, 6636.4690824734025, 6542.214934207902, 6629.90055149172, 6718.227403848559, 6718.46252299816, 6748.291429399916, 4664.953350466486, 4767.761611919407, 4797.568218860302, 4849.612031037508, 4891.14404979129, 4783.1868582341085, 4855.56299933006, 4877.829275975341, 4885.975570122145, 4890.951090489103, 4825.613950883921, 4831.420229572456, 4834.709917404967, 4840.298156767267, 4860.829870954517, 4626.8149274029, 4707.6233901287815, 4804.927926081112, 4823.9035219295765, 4835.419749630032, 4153.7507749534825, 4155.937660935088, 4166.416701661769, 4169.145325208325, 4193.433886425354, 6358.823617630753, 6378.808635740936, 6380.425761681425, 6384.0, 6385.457236134934, 5792.855178620526, 5823.272090988627, 5838.153467747153, 5841.912371314434, 5854.941450585482, 6790.98135279712, 7070.8939365909555, 7078.539894906815, 7093.339997500937, 7107.040549525851, 4759.071980963713, 4762.3094651275715, 4776.904461910777, 4791.856244312677, 4801.2798080287885, 7441.107067151922, 7479.0, 7497.400207983348, 7497.512661676974, 7517.248275172494, 3934.5475270343704, 4810.11012962603, 4826.32431459596, 4838.395200599925, 4855.562999330077, 4902.436219834764, 4910.533499317559, 4918.9508104919205, 4922.975385123052, 4924.655274130777, 5559.9444005560235, 5735.283089613798, 5757.222774925332, 5760.423957604206, 5788.0739081746715, 7108.933659950945, 7134.179569349488, 7166.964165179245, 7176.892346614805, 7177.354038136593, 1677.966440671204, 1714.5970696886222, 1716.8969861808332, 1722.001239281239, 1723.991464992968, 858.9914100859037, 1025.0876719719456, 1080.8919108089285, 1094.5348227003492, 1108.8281316395971, 6540.9345906541275, 6720.764773232913, 6746.831329216809, 6763.188417389944, 6766.424853887376, 5636.774529018793, 7206.423486121098, 7487.8502429951095, 7500.287472690085, 7503.474756766974, 6716.865662686719, 6720.630365329856, 6721.26066132725, 6737.326267373223, 6749.223839258548, 3779.4897688812207, 3893.5717071122153, 3944.5463771666628, 3950.6641935435237, 3951.7233793634746, 7724.845503089907, 7740.651670674833, 7743.612819359009, 7746.0, 7754.340881025063, 7608.429367797437, 7683.769486915401, 7688.807779805549, 7693.49992250502, 7728.806779830436, 7742.496737712031, 7743.064233941514, 7747.496412733154, 7762.7671169865, 7781.688732450752, 7109.253528379446, 7424.480286379901, 7429.925700742923, 7439.293267139613, 7471.775846724607, 5976.8804623908145, 7221.53060051095, 7298.197198308288, 7353.338199562065, 7358.521696089737, 6927.480438967198, 7472.439567032603, 7798.0, 7809.960950195213, 7810.804729881798, 4509.23343031692, 4515.209838278354, 4527.818887244605, 4529.275315949432, 4532.841350552715, 7122.0, 7414.666340014604, 7418.18399976013, 7422.591757453392, 7435.739749108755, 6154.79981403622, 7124.3944264738075, 7149.213586505376, 7185.53294035886, 7192.2088570258275, 6826.897596536155, 7172.175199852084, 7173.46199035064, 7175.103112110986, 7183.317584829433, 7015.508914375945, 7021.05215795861, 7028.718851246097, 7029.226785053742, 7030.894536581853, 5541.030319694118, 5804.73878675469, 7160.427165826825, 7175.31834475724, 7189.5686258824635, 5432.402435732145, 6891.965540172267, 6918.861622767416, 7044.119485064367, 7067.116610423697], "value": 5181.597776655374, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [655.5509476008934, 673.5520878615721, 734.5702763883128, 739.5525706947295, 754.9056367954006, 15050.924745376271, 15279.77080343793, 15403.84596154038, 15439.614009649764, 15440.227988600553, 12618.684532886682, 12832.0, 12836.358182090882, 12854.871451285484, 12855.100142989995, 743.0563184755357, 758.3099379564597, 908.7410088124869, 926.8331700293944, 944.905509449053, 783.7883771381752, 901.9774505637346, 931.0642803981989, 936.1013427109982, 936.1901954809075, 944.3436811416069, 978.5351957820014, 980.9852852207187, 982.0670363154995, 985.7781999050228, 775.1086250811555, 965.0108638645339, 967.0716112531978, 967.2117224462111, 971.8833739951216, 713.8536599996988, 714.0610097721479, 714.4034730999622, 717.8348979734691, 729.2452311857203, 15006.249687515581, 15091.471798487, 15103.4713785017, 15118.09291442506, 15143.242837858063, 12079.27524348542, 12469.812952805714, 12509.437075331633, 12761.234325940473, 12805.87194128056, 10440.895591044144, 11026.944865275702, 11062.778744425066, 11359.716007099889, 11667.241629294069, 3486.773359731609, 3519.225770330523, 3526.947095793565, 3569.607343192247, 3721.2557488502407, 57798.7110064447, 76530.23469765286, 77311.0, 77942.0, 78274.21725782726, 9339.579718912666, 9411.43531388119, 9524.190443812353, 9952.601895924226, 10071.992800719869, 19088.14103365352, 21425.892870535547, 21474.355769326947, 21476.78523214779, 21511.354659360244, 6074.908876366858, 6900.827479312957, 7016.824579385556, 7186.928130718626, 7476.439267054884, 4098.262312783668, 4103.712551010204, 4107.178564287155, 4186.330187170038, 4383.517813040563, 3577.35607590631, 3620.5655321361605, 3632.2917031178667, 3636.381815091441, 3636.9272614547563, 636.147562266565, 646.1406329581645, 647.0520687193284, 647.582309410437, 654.5777973207255, 512.633467071046, 613.5521069619114, 616.4144063140035, 616.6146158650844, 624.5409623926411, 6839.863202735917, 7300.16048154469, 7670.463067585327, 8081.838363232702, 8456.534890581077, 490.4213028626184, 576.0581449330375, 587.6239206907497, 595.6217801695955, 622.184937731564, 3813.4470501777087, 3829.5787463379006, 3862.111714305669, 3948.7828169450954, 4084.81618327176, 88022.55988720016, 88104.0, 88429.0, 88440.0, 88860.5556972224, 72631.63684181651, 79798.0, 79830.6008469954, 80350.59824700838, 82547.5872620633, 69592.0, 70533.0, 70549.64725176444, 70761.0, 71071.0, 6324.588901721558, 7519.398448124246, 7745.186755390603, 7857.646405911633, 7917.247861453153, 42592.36111458269, 44930.60832700097, 45022.874428139556, 46812.29781553211, 48217.51782482271, 62220.37779622327, 64950.35049649443, 65292.0, 66046.0, 66493.0, 9692.17616502577, 9734.805303894023, 9870.457124857914, 9901.603935842486, 10150.644727434505, 8055.556944368116, 10841.970012848768, 10878.619248326588, 10907.618233361794, 11144.996950274513, 10126.89873101289, 10235.48822558884, 10247.641332553325, 10430.217733669853, 10701.785964280514, 9927.354721943051, 10123.797524049625, 10280.126189273697, 10299.485025748832, 10428.739281517719, 9939.850902236327, 10053.648122315684, 10257.435841028517, 10281.640142595272, 10560.524776384927, 8645.783855403664, 8663.696770613251, 8682.161320862388, 8796.956015219883, 8877.467351958903, 8767.736967890973, 8794.516301603473, 8871.822563548822, 8951.28389728807, 8983.955080224818], "value": 6686.753551426756, "unit": "score"}], "name": "dromaeo_css", "value": 7382.562403360207, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 120.0, 110.0, 111.0, 112.0, 114.0, 113.0, 113.0, 139.0], "value": 115.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 124.0, 139.0, 165.0, 120.0, 143.0, 136.0, 120.0, 169.0, 118.0], "value": 138.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 183.0, 183.0, 179.0, 183.0, 182.0, 181.0, 182.0, 184.0], "value": 182.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 78.0, 76.0, 150.0, 56.0, 55.0, 57.0, 63.0, 132.0, 56.0], "value": 79.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 88.0, 146.0, 77.0, 81.0, 78.0, 127.0, 82.0, 115.0, 167.0], "value": 105.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 92.0, 84.0, 88.0, 83.0, 89.0, 85.0, 90.0, 84.0], "value": 86.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 93.0, 93.0, 93.0, 92.0, 94.0, 94.0, 93.0, 97.0], "value": 93.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 85.0, 84.0, 85.0, 87.0, 91.0, 84.0, 83.0, 85.0, 86.0], "value": 85.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 64.0, 65.0, 65.0, 65.0, 64.0, 80.0, 61.0, 60.0], "value": 64.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 72.0, 73.0, 77.0, 72.0, 70.0, 68.0, 67.0, 68.0], "value": 69.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 80.0, 81.0, 81.0, 83.0, 93.0, 104.0, 104.0, 84.0], "value": 87.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 129.0, 128.0, 152.0, 145.0, 141.0, 127.0, 127.0, 186.0, 143.0], "value": 140.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 165.0, 166.0, 166.0, 168.0, 163.0, 153.0, 172.0, 165.0, 165.0], "value": 165.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 66.0, 67.0, 67.0, 68.0, 66.0, 69.0, 66.0, 69.0], "value": 67.2, "unit": "score"}], "name": "kraken", "value": 1482.3000000000002, "alertThreshold": 2.0}]} 18:42:42 INFO - Return code: 0 18:42:42 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:42:42 ERROR - # TBPL SUCCESS # 18:42:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:42:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:42:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:42:42 INFO - 2016-05-22 18:42:42.612 screenresolution[1689:11035] starting screenresolution argv=screenresolution get 18:42:42 INFO - 2016-05-22 18:42:42.615 screenresolution[1689:11035] Display 0: 1600x1200x32@60 18:42:42 INFO - 2016-05-22 18:42:42.621 screenresolution[1690:11038] starting screenresolution argv=screenresolution list 18:42:42 INFO - Available Modes on Display 0 18:42:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:42:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:42:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:42:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:42:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:42:42 INFO - Intel Iris: 18:42:42 INFO - Chipset Model: Intel Iris 18:42:42 INFO - Type: GPU 18:42:42 INFO - Bus: Built-In 18:42:42 INFO - VRAM (Dynamic, Max): 1536 MB 18:42:42 INFO - Vendor: Intel (0x8086) 18:42:42 INFO - Device ID: 0x0a2e 18:42:42 INFO - Revision ID: 0x0009 18:42:42 INFO - Displays: 18:42:42 INFO - Display: 18:42:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:42:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:42:42 INFO - Display Serial Number: 1600x1200 60 18:42:42 INFO - Main Display: Yes 18:42:42 INFO - Mirror: Off 18:42:42 INFO - Online: Yes 18:42:42 INFO - Rotation: Supported 18:42:42 INFO - Return code: 0 18:42:42 INFO - Running post-action listener: _resource_record_post_action 18:42:42 INFO - Running post-run listener: _resource_record_post_run 18:42:43 INFO - Total resource usage - Wall time: 1207s; CPU: 52.0%; Read bytes: 31456768; Write bytes: 657575936; Read time: 196; Write time: 1850 18:42:43 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 346853888; Write bytes: 345712128; Read time: 26561; Write time: 690 18:42:43 INFO - run-tests - Wall time: 1176s; CPU: 52.0%; Read bytes: 30535168; Write bytes: 301906432; Read time: 185; Write time: 1125 18:42:43 INFO - Running post-run listener: _upload_blobber_files 18:42:43 INFO - Blob upload gear active. 18:42:43 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:42:43 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 18:42:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 18:42:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 18:42:44 INFO - (blobuploader) - INFO - Open directory for files ... 18:42:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 18:42:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:42:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:42:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 18:42:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:42:45 INFO - (blobuploader) - INFO - Done attempting. 18:42:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 18:42:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:42:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:42:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 18:42:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:42:46 INFO - (blobuploader) - INFO - Done attempting. 18:42:46 INFO - (blobuploader) - INFO - Iteration through files over. 18:42:46 INFO - Return code: 0 18:42:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:42:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:42:46 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/19b02c0e5502abc450b5c359adbb50b410240243f13604e0cd89294bae4b593ae13d205d4f362557fa6cd224d261e013d69f621a6a90001a3d01ea9b71d05ce3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d529172c50a3ecc113a7e0be95d56c4e2ede226654c0972d2734deff326a287b9d969fdacb351c74a1c81d8fd7ee8d2e253a67d7abcf14bbbb269d1f5a32b69f"} 18:42:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:42:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:42:46 INFO - Contents: 18:42:46 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/19b02c0e5502abc450b5c359adbb50b410240243f13604e0cd89294bae4b593ae13d205d4f362557fa6cd224d261e013d69f621a6a90001a3d01ea9b71d05ce3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d529172c50a3ecc113a7e0be95d56c4e2ede226654c0972d2734deff326a287b9d969fdacb351c74a1c81d8fd7ee8d2e253a67d7abcf14bbbb269d1f5a32b69f"} 18:42:46 INFO - Running post-run listener: copy_logs_to_upload_dir 18:42:46 INFO - Copying logs to upload dir... 18:42:46 INFO - mkdir: /builds/slave/test/build/upload/logs 18:42:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1344.112440 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 24 secs) (at 2016-05-22 18:42:46.886816) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-22 18:42:46.890709) ========= 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.b2lLYxBBzp/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.fgQbzQ06uT/Listeners TMPDIR=/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/19b02c0e5502abc450b5c359adbb50b410240243f13604e0cd89294bae4b593ae13d205d4f362557fa6cd224d261e013d69f621a6a90001a3d01ea9b71d05ce3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d529172c50a3ecc113a7e0be95d56c4e2ede226654c0972d2734deff326a287b9d969fdacb351c74a1c81d8fd7ee8d2e253a67d7abcf14bbbb269d1f5a32b69f"} build_url:https://queue.taskcluster.net/v1/task/EpU-4wcQRv69BuVe3rbgWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011569 build_url: 'https://queue.taskcluster.net/v1/task/EpU-4wcQRv69BuVe3rbgWw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/19b02c0e5502abc450b5c359adbb50b410240243f13604e0cd89294bae4b593ae13d205d4f362557fa6cd224d261e013d69f621a6a90001a3d01ea9b71d05ce3", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d529172c50a3ecc113a7e0be95d56c4e2ede226654c0972d2734deff326a287b9d969fdacb351c74a1c81d8fd7ee8d2e253a67d7abcf14bbbb269d1f5a32b69f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-22 18:42:46.920689) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-22 18:42:46.921202) ========= 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.b2lLYxBBzp/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.fgQbzQ06uT/Listeners TMPDIR=/var/folders/yy/_44wh5bs15g2887w0j7qn4n400000w/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.005171 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-22 18:42:46.970124) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-22 18:42:46.973453) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-22 18:42:46.973792) ========= ========= Total master_lag: 0.22 =========