builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0111 starttime: 1462963538.36 results: success (0) buildid: 20160510142413 builduid: 93e058acf8334e7aae447a8479c457fb revision: 1dd42a351054c5b697873d647169019d4d77d762 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 03:45:38.360243) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-11 03:45:38.360672) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 03:45:38.360946) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-11 03:45:38.379907) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 03:45:38.380228) ========= 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.Dg5QJn5r7a/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.Y2E1dIrdav/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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-11 03:45:38-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.1M=0.001s 2016-05-11 03:45:38 (15.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.126579 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 03:45:38.523841) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 03:45:38.524170) ========= 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.Dg5QJn5r7a/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.Y2E1dIrdav/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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.036039 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 03:45:38.575446) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 03:45:38.575800) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 1dd42a351054c5b697873d647169019d4d77d762 --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 1dd42a351054c5b697873d647169019d4d77d762 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Dg5QJn5r7a/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.Y2E1dIrdav/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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-11 03:45:38,649 truncating revision to first 12 chars 2016-05-11 03:45:38,649 Setting DEBUG logging. 2016-05-11 03:45:38,649 attempt 1/10 2016-05-11 03:45:38,649 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/1dd42a351054?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-11 03:45:39,323 unpacking tar archive at: mozilla-aurora-1dd42a351054/testing/mozharness/ program finished with exit code 0 elapsedTime=0.914439 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 03:45:39.510235) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 03:45:39.510776) ========= script_repo_revision: 1dd42a351054c5b697873d647169019d4d77d762 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-11 03:45:39.511428) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 03:45:39.511888) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-11 03:45:39.526119) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-05-11 03:45:39.526472) ========= /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.Dg5QJn5r7a/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.Y2E1dIrdav/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 03:45:39 INFO - MultiFileLogger online at 20160511 03:45:39 in /builds/slave/test 03:45:39 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 03:45:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:45:39 INFO - {'append_to_log': False, 03:45:39 INFO - 'base_work_dir': '/builds/slave/test', 03:45:39 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:45:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:45:39 INFO - 'branch': 'Mozilla-Aurora', 03:45:39 INFO - 'buildbot_json_path': 'buildprops.json', 03:45:39 INFO - 'config_files': ('talos/mac_config.py',), 03:45:39 INFO - 'default_actions': ('clobber', 03:45:39 INFO - 'read-buildbot-config', 03:45:39 INFO - 'download-and-extract', 03:45:39 INFO - 'populate-webroot', 03:45:39 INFO - 'create-virtualenv', 03:45:39 INFO - 'install', 03:45:39 INFO - 'run-tests'), 03:45:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:45:39 INFO - 'download_minidump_stackwalk': True, 03:45:39 INFO - 'download_symbols': 'ondemand', 03:45:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:45:39 INFO - 'tooltool.py': '/tools/tooltool.py', 03:45:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:45:39 INFO - '/tools/misc-python/virtualenv.py')}, 03:45:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:45:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:45:39 INFO - 'installer_path': 'installer.exe', 03:45:39 INFO - 'log_level': 'info', 03:45:39 INFO - 'log_name': 'talos', 03:45:39 INFO - 'log_to_console': True, 03:45:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:45:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:45:39 INFO - 'opt_config_files': (), 03:45:39 INFO - 'pip_index': False, 03:45:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:45:39 INFO - 'cmd': ('bash', 03:45:39 INFO - '-c', 03:45:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:45:39 INFO - 'enabled': True, 03:45:39 INFO - 'halt_on_failure': False, 03:45:39 INFO - 'name': 'check_screen_resolution'},), 03:45:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:45:39 INFO - 'cmd': ('bash', 03:45:39 INFO - '-c', 03:45:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:45:39 INFO - 'enabled': True, 03:45:39 INFO - 'halt_on_failure': False, 03:45:39 INFO - 'name': 'check_screen_resolution'},), 03:45:39 INFO - 'run_cmd_checks_enabled': True, 03:45:39 INFO - 'sps_profile': False, 03:45:39 INFO - 'sps_profile_interval': 0, 03:45:39 INFO - 'suite': 'dromaeojs', 03:45:39 INFO - 'system_bits': '32', 03:45:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:45:39 INFO - 'title': 't-yosemite-r7-0111', 03:45:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:45:39 INFO - 'use_talos_json': True, 03:45:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:45:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:45:39 INFO - 'work_dir': 'build'} 03:45:39 INFO - ##### 03:45:39 INFO - ##### Running clobber step. 03:45:39 INFO - ##### 03:45:39 INFO - Running pre-action listener: _resource_record_pre_action 03:45:39 INFO - Running main action method: clobber 03:45:39 INFO - rmtree: /builds/slave/test/build 03:45:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:45:41 INFO - Running post-action listener: _resource_record_post_action 03:45:41 INFO - ##### 03:45:41 INFO - ##### Running read-buildbot-config step. 03:45:41 INFO - ##### 03:45:41 INFO - Running pre-action listener: _resource_record_pre_action 03:45:41 INFO - Running main action method: read_buildbot_config 03:45:41 INFO - Using buildbot properties: 03:45:41 INFO - { 03:45:41 INFO - "project": "", 03:45:41 INFO - "product": "firefox", 03:45:41 INFO - "script_repo_revision": "production", 03:45:41 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 03:45:41 INFO - "repository": "", 03:45:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 03:45:41 INFO - "buildid": "20160510142413", 03:45:41 INFO - "pgo_build": "False", 03:45:41 INFO - "basedir": "/builds/slave/test", 03:45:41 INFO - "buildnumber": 7, 03:45:41 INFO - "slavename": "t-yosemite-r7-0111", 03:45:41 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 03:45:41 INFO - "platform": "yosemite_r7", 03:45:41 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:45:41 INFO - "branch": "mozilla-aurora", 03:45:41 INFO - "repo_path": "releases/mozilla-aurora", 03:45:41 INFO - "revision": "1dd42a351054c5b697873d647169019d4d77d762", 03:45:41 INFO - "stage_platform": "macosx64", 03:45:41 INFO - "builduid": "93e058acf8334e7aae447a8479c457fb", 03:45:41 INFO - "slavebuilddir": "test" 03:45:41 INFO - } 03:45:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 03:45:41 INFO - Running post-action listener: _resource_record_post_action 03:45:41 INFO - ##### 03:45:41 INFO - ##### Running download-and-extract step. 03:45:41 INFO - ##### 03:45:41 INFO - Running pre-action listener: _resource_record_pre_action 03:45:41 INFO - Running main action method: download_and_extract 03:45:41 INFO - mkdir: /builds/slave/test/build/tests 03:45:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:45:41 INFO - https://queue.taskcluster.net/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 03:45:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 03:45:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 03:45:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 03:45:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 03:45:42 INFO - Downloaded 1199 bytes. 03:45:42 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 03:45:42 INFO - Using the following test package requirements: 03:45:42 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 03:45:42 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:45:42 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 03:45:42 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:45:42 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 03:45:42 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:45:42 INFO - u'jsshell-mac.zip'], 03:45:42 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:45:42 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 03:45:42 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 03:45:42 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:45:42 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 03:45:42 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:45:42 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 03:45:42 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:45:42 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 03:45:42 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:45:42 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 03:45:42 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:45:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:45:42 INFO - https://queue.taskcluster.net/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:45:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:45:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:45:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:45:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:45:44 INFO - Downloaded 18089246 bytes. 03:45: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'] 03:45: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 03:45:47 INFO - Return code: 0 03:45: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 03:45: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')]} 03:45:47 INFO - https://queue.taskcluster.net/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:45:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:45:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:45:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:45:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:45:48 INFO - Downloaded 18089246 bytes. 03:45:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:45:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:45:52 INFO - Return code: 0 03:45:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:45:52 INFO - https://queue.taskcluster.net/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 03:45:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 03:45:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 03:45:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 03:45:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 03:45:53 INFO - Downloaded 10840145 bytes. 03:45:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:45:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 03:45:54 INFO - Return code: 0 03:45: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')]} 03:45:54 INFO - https://queue.taskcluster.net/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 03:45:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:45:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:45:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 03:45:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 03:45:57 INFO - Downloaded 114088564 bytes. 03:45:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:45:57 INFO - mkdir: /builds/slave/test/properties 03:45:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:45:57 INFO - Writing to file /builds/slave/test/properties/build_url 03:45:57 INFO - Contents: 03:45:57 INFO - build_url:https://queue.taskcluster.net/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:45:58 INFO - Running post-action listener: _resource_record_post_action 03:45:58 INFO - Running post-action listener: set_extra_try_arguments 03:45:58 INFO - ##### 03:45:58 INFO - ##### Running populate-webroot step. 03:45:58 INFO - ##### 03:45:58 INFO - Running pre-action listener: _resource_record_pre_action 03:45:58 INFO - Running main action method: populate_webroot 03:45:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:45:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:45:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:45:58 INFO - '--tppagecycles', 03:45:58 INFO - '7'], 03:45:58 INFO - 'tests': ['tsvgm']}}, 03:45:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:45:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:45:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:45:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:45:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:45:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:45:58 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:45:58 INFO - 'talos_options': ['--disable-e10s'], 03:45:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:45:58 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:45:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:45:58 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:45:58 INFO - 'talos_options': ['--disable-e10s'], 03:45:58 INFO - 'tests': ['damp', 'tps']}, 03:45:58 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:45:58 INFO - 'tests': ['damp', 'tps']}, 03:45:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:45:58 INFO - 'tests': ['dromaeo_dom']}, 03:45:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:45:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:45:58 INFO - 'tests': ['a11yr', 03:45:58 INFO - 'ts_paint', 03:45:58 INFO - 'tpaint', 03:45:58 INFO - 'sessionrestore', 03:45:58 INFO - 'sessionrestore_no_auto_restore', 03:45:58 INFO - 'tabpaint']}, 03:45:58 INFO - 'other-e10s': {'tests': ['a11yr', 03:45:58 INFO - 'ts_paint', 03:45:58 INFO - 'tpaint', 03:45:58 INFO - 'sessionrestore', 03:45:58 INFO - 'sessionrestore_no_auto_restore', 03:45:58 INFO - 'tabpaint']}, 03:45:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:45:58 INFO - 'ts_paint', 03:45:58 INFO - 'tpaint', 03:45:58 INFO - 'sessionrestore', 03:45:58 INFO - 'sessionrestore_no_auto_restore', 03:45:58 INFO - 'tabpaint']}, 03:45:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:45:58 INFO - 'ts_paint', 03:45:58 INFO - 'tpaint', 03:45:58 INFO - 'sessionrestore', 03:45:58 INFO - 'sessionrestore_no_auto_restore', 03:45:58 INFO - 'tabpaint']}, 03:45:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:45:58 INFO - 'tests': ['a11yr', 03:45:58 INFO - 'ts_paint', 03:45:58 INFO - 'tpaint', 03:45:58 INFO - 'sessionrestore', 03:45:58 INFO - 'sessionrestore_no_auto_restore', 03:45:58 INFO - 'tabpaint']}, 03:45:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:45:58 INFO - 'tests': ['a11yr', 03:45:58 INFO - 'ts_paint', 03:45:58 INFO - 'tpaint', 03:45:58 INFO - 'sessionrestore', 03:45:58 INFO - 'sessionrestore_no_auto_restore', 03:45:58 INFO - 'tabpaint']}, 03:45:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:45:58 INFO - 'tests': ['tsvgx', 03:45:58 INFO - 'tsvgr_opacity', 03:45:58 INFO - 'tart', 03:45:58 INFO - 'tscrollx', 03:45:58 INFO - 'cart']}, 03:45:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:45:58 INFO - 'tsvgr_opacity', 03:45:58 INFO - 'tart', 03:45:58 INFO - 'tscrollx', 03:45:58 INFO - 'cart']}, 03:45:58 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:45:58 INFO - 'talos_options': ['--disable-e10s'], 03:45:58 INFO - 'tests': ['tp5o']}, 03:45:58 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:45:58 INFO - 'tests': ['tp5o']}, 03:45:58 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:45:58 INFO - 'talos_options': ['--disable-e10s', 03:45:58 INFO - '--xperf_path', 03:45:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:45:58 INFO - 'tests': ['tp5n']}, 03:45:58 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:45:58 INFO - 'talos_options': ['--xperf_path', 03:45:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:45:58 INFO - 'tests': ['tp5n']}}, 03:45:58 INFO - 'talos.zip': {'path': '', 03:45:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:45:58 INFO - Running post-action listener: _resource_record_post_action 03:45:58 INFO - ##### 03:45:58 INFO - ##### Running create-virtualenv step. 03:45:58 INFO - ##### 03:45:58 INFO - Running pre-action listener: _resource_record_pre_action 03:45:58 INFO - Running main action method: create_virtualenv 03:45:58 INFO - Creating virtualenv /builds/slave/test/build/venv 03:45:58 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 03:45:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:45:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:45:58 INFO - Using real prefix '/tools/python27' 03:45:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:45:59 INFO - Installing distribute.............................................................................................................................................................................................done. 03:46:02 INFO - Installing pip.................done. 03:46:02 INFO - Return code: 0 03:46:02 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:46:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46:02 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 0x10f578a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f588420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98714d6680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f417e00>, '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 0x10f280db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f412b20>, '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.Dg5QJn5r7a/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.Y2E1dIrdav/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:46:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 03:46:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 03:46:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Dg5QJn5r7a/Render', 03:46:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:46:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:46:02 INFO - 'HOME': '/Users/cltbld', 03:46:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:46:02 INFO - 'LOGNAME': 'cltbld', 03:46:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:02 INFO - 'MOZ_NO_REMOTE': '1', 03:46:02 INFO - 'NO_EM_RESTART': '1', 03:46:02 INFO - 'PAGER': '/bin/cat', 03:46:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:46:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:02 INFO - 'PWD': '/builds/slave/test', 03:46:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:46:02 INFO - 'SHELL': '/bin/bash', 03:46:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y2E1dIrdav/Listeners', 03:46:02 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 03:46:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:46:02 INFO - 'USER': 'cltbld', 03:46:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:46:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:46:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:02 INFO - 'XPC_FLAGS': '0x0', 03:46:02 INFO - 'XPC_SERVICE_NAME': '0', 03:46:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:46:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:02 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 03:46:02 INFO - Cleaning up... 03:46:02 INFO - Return code: 0 03:46:02 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 03:46:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46:02 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 0x10f578a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f588420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98714d6680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f417e00>, '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 0x10f280db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f412b20>, '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.Dg5QJn5r7a/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.Y2E1dIrdav/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:46:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 03:46:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 03:46:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Dg5QJn5r7a/Render', 03:46:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:46:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:46:02 INFO - 'HOME': '/Users/cltbld', 03:46:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:46:02 INFO - 'LOGNAME': 'cltbld', 03:46:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:02 INFO - 'MOZ_NO_REMOTE': '1', 03:46:02 INFO - 'NO_EM_RESTART': '1', 03:46:02 INFO - 'PAGER': '/bin/cat', 03:46:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:46:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:02 INFO - 'PWD': '/builds/slave/test', 03:46:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:46:02 INFO - 'SHELL': '/bin/bash', 03:46:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y2E1dIrdav/Listeners', 03:46:02 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 03:46:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:46:02 INFO - 'USER': 'cltbld', 03:46:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:46:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:46:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:02 INFO - 'XPC_FLAGS': '0x0', 03:46:02 INFO - 'XPC_SERVICE_NAME': '0', 03:46:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:46:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:02 INFO - Downloading/unpacking psutil>=0.7.1 03:46:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:46:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:46:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:46:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:46:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:46:05 INFO - Installing collected packages: psutil 03:46:05 INFO - Running setup.py install for psutil 03:46:05 INFO - building 'psutil._psutil_osx' extension 03:46:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 03:46:06 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 03:46:06 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 03:46:06 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 03:46:06 INFO - building 'psutil._psutil_posix' extension 03:46:06 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 03:46:06 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:46:06 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:46:06 INFO - ^ 03:46:06 INFO - 1 warning generated. 03:46:06 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 03:46:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:46:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:46:06 INFO - Successfully installed psutil 03:46:06 INFO - Cleaning up... 03:46:07 INFO - Return code: 0 03:46:07 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 03:46: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')]} 03:46:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46: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')]} 03:46:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46:07 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 0x10f578a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f588420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98714d6680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f417e00>, '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 0x10f280db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f412b20>, '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.Dg5QJn5r7a/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.Y2E1dIrdav/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:46:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 03:46:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 03:46:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Dg5QJn5r7a/Render', 03:46:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:46:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:46:07 INFO - 'HOME': '/Users/cltbld', 03:46:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:46:07 INFO - 'LOGNAME': 'cltbld', 03:46:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:07 INFO - 'MOZ_NO_REMOTE': '1', 03:46:07 INFO - 'NO_EM_RESTART': '1', 03:46:07 INFO - 'PAGER': '/bin/cat', 03:46:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:46:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:07 INFO - 'PWD': '/builds/slave/test', 03:46:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:46:07 INFO - 'SHELL': '/bin/bash', 03:46:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y2E1dIrdav/Listeners', 03:46:07 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 03:46:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:46:07 INFO - 'USER': 'cltbld', 03:46:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:46:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:46:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:07 INFO - 'XPC_FLAGS': '0x0', 03:46:07 INFO - 'XPC_SERVICE_NAME': '0', 03:46:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:46:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:07 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 03:46:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:46:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:46:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:09 INFO - Downloading mozsystemmonitor-0.0.tar.gz 03:46:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:46:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 03:46:09 INFO - Installing collected packages: mozsystemmonitor 03:46:09 INFO - Running setup.py install for mozsystemmonitor 03:46:09 INFO - Successfully installed mozsystemmonitor 03:46:09 INFO - Cleaning up... 03:46:09 INFO - Return code: 0 03:46:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:46:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46:09 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 0x10f578a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f588420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98714d6680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f417e00>, '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 0x10f280db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f412b20>, '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.Dg5QJn5r7a/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.Y2E1dIrdav/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:46:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:46:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:46:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Dg5QJn5r7a/Render', 03:46:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:46:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:46:09 INFO - 'HOME': '/Users/cltbld', 03:46:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:46:09 INFO - 'LOGNAME': 'cltbld', 03:46:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:09 INFO - 'MOZ_NO_REMOTE': '1', 03:46:09 INFO - 'NO_EM_RESTART': '1', 03:46:09 INFO - 'PAGER': '/bin/cat', 03:46:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:46:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:09 INFO - 'PWD': '/builds/slave/test', 03:46:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:46:09 INFO - 'SHELL': '/bin/bash', 03:46:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y2E1dIrdav/Listeners', 03:46:09 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 03:46:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:46:09 INFO - 'USER': 'cltbld', 03:46:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:46:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:46:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:09 INFO - 'XPC_FLAGS': '0x0', 03:46:09 INFO - 'XPC_SERVICE_NAME': '0', 03:46:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:46:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:10 INFO - Downloading/unpacking blobuploader==1.2.4 03:46:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:46:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:46:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:12 INFO - Downloading blobuploader-1.2.4.tar.gz 03:46:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:46:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:46: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 03:46: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 03:46: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 03:46: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 03:46: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 03:46: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 03:46:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:46:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:46:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:46:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:46:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:13 INFO - Downloading docopt-0.6.1.tar.gz 03:46:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:46:13 INFO - Installing collected packages: blobuploader, requests, docopt 03:46:13 INFO - Running setup.py install for blobuploader 03:46:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:46:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:46:14 INFO - Running setup.py install for requests 03:46:14 INFO - Running setup.py install for docopt 03:46:14 INFO - Successfully installed blobuploader requests docopt 03:46:14 INFO - Cleaning up... 03:46:14 INFO - Return code: 0 03:46:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:46:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46:14 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 0x10f578a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f588420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98714d6680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f417e00>, '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 0x10f280db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f412b20>, '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.Dg5QJn5r7a/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.Y2E1dIrdav/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:46:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:46:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:46:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Dg5QJn5r7a/Render', 03:46:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:46:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:46:14 INFO - 'HOME': '/Users/cltbld', 03:46:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:46:14 INFO - 'LOGNAME': 'cltbld', 03:46:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:14 INFO - 'MOZ_NO_REMOTE': '1', 03:46:14 INFO - 'NO_EM_RESTART': '1', 03:46:14 INFO - 'PAGER': '/bin/cat', 03:46:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:46:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:14 INFO - 'PWD': '/builds/slave/test', 03:46:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:46:14 INFO - 'SHELL': '/bin/bash', 03:46:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y2E1dIrdav/Listeners', 03:46:14 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 03:46:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:46:14 INFO - 'USER': 'cltbld', 03:46:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:46:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:46:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:14 INFO - 'XPC_FLAGS': '0x0', 03:46:14 INFO - 'XPC_SERVICE_NAME': '0', 03:46:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:46:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:46:15 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-AWcBv6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:46:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:46:15 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-PlCJJB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:46:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:46:15 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-XRWSim-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:46:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:46:15 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-nnyEJb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:46:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:46:15 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-oEfYVs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:46:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:46:15 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-rflROM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:46:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:46:15 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-XgAhjt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:46:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:46:15 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-zYYdsr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:46:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:46:15 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-JztUnL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:46:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:46:15 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-VlS1UM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:46:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:46:16 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-AkC8bE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:46:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:46:16 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-4_4ThZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:46:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:46:16 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-sgObzX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:46:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:46:16 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-CaEDTJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:46:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:46:16 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-3fI1Sv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:46:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:46:16 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-zQm40U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:46:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:46:16 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-bap89k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:46:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:46:16 INFO - Running setup.py install for manifestparser 03:46:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:46:16 INFO - Running setup.py install for mozcrash 03:46:17 INFO - Running setup.py install for mozdebug 03:46:17 INFO - Running setup.py install for mozdevice 03:46:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:46:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:46:17 INFO - Running setup.py install for mozfile 03:46:17 INFO - Running setup.py install for mozhttpd 03:46:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:46:17 INFO - Running setup.py install for mozinfo 03:46:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:46:17 INFO - Running setup.py install for mozInstall 03:46:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:46:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:46:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:46:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:46:17 INFO - Running setup.py install for mozleak 03:46:18 INFO - Running setup.py install for mozlog 03:46:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:46:18 INFO - Running setup.py install for moznetwork 03:46:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:46:18 INFO - Running setup.py install for mozprocess 03:46:18 INFO - Running setup.py install for mozprofile 03:46:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:46:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:46:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:46:18 INFO - Running setup.py install for mozrunner 03:46:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:46:18 INFO - Running setup.py install for mozscreenshot 03:46:19 INFO - Running setup.py install for moztest 03:46:19 INFO - Running setup.py install for mozversion 03:46:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:46:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:46:19 INFO - Cleaning up... 03:46:19 INFO - Return code: 0 03:46:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:46:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46:19 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 0x10f578a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f588420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98714d6680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f417e00>, '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 0x10f280db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f412b20>, '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.Dg5QJn5r7a/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.Y2E1dIrdav/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:46:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 03:46:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:46:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Dg5QJn5r7a/Render', 03:46:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:46:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:46:19 INFO - 'HOME': '/Users/cltbld', 03:46:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:46:19 INFO - 'LOGNAME': 'cltbld', 03:46:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:19 INFO - 'MOZ_NO_REMOTE': '1', 03:46:19 INFO - 'NO_EM_RESTART': '1', 03:46:19 INFO - 'PAGER': '/bin/cat', 03:46:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:46:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:19 INFO - 'PWD': '/builds/slave/test', 03:46:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:46:19 INFO - 'SHELL': '/bin/bash', 03:46:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y2E1dIrdav/Listeners', 03:46:19 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 03:46:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:46:19 INFO - 'USER': 'cltbld', 03:46:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:46:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:46:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:19 INFO - 'XPC_FLAGS': '0x0', 03:46:19 INFO - 'XPC_SERVICE_NAME': '0', 03:46:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:46:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:46:19 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-ZG4C1P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:46:19 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 03:46:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:46:19 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-cfVpW7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:46:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:46:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:46:19 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-l1ODWx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:46:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:46:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:46:19 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-UQTCJq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:46:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:46:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:46:20 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-YUAbpN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:46:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 03:46:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:46:20 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-UjoE60-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:46:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 03:46:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:46:20 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-GnbIdk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:46:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 03:46:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:46:20 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-MsFJjj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:46:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 03:46:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:46:20 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-92aee3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:46:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 03:46:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:46:20 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-3kY9Cs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:46:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:46:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:46:20 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-7jR0zQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:46:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 03:46:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:46:20 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-uxYne9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:46:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 03:46:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:46:20 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-7OCgIB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:46:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 03:46:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:46:20 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-wEA80r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:46:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 03:46:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:46:21 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-edlCrC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:46:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 03:46:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:46:21 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-FzDiHJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:46:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 03:46:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:46:21 INFO - Running setup.py (path:/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/pip-hnHnLe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:46:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 03:46:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:46:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 03:46:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 03:46:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:46:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 03:46:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:46:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:46:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:46:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:23 INFO - Downloading blessings-1.6.tar.gz 03:46:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:46:23 INFO - Installing collected packages: blessings 03:46:23 INFO - Running setup.py install for blessings 03:46:24 INFO - Successfully installed blessings 03:46:24 INFO - Cleaning up... 03:46:24 INFO - Return code: 0 03:46:24 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:46:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:46:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:46:24 INFO - Reading from file tmpfile_stdout 03:46:24 INFO - Current package versions: 03:46:24 INFO - blessings == 1.6 03:46:24 INFO - blobuploader == 1.2.4 03:46:24 INFO - docopt == 0.6.1 03:46:24 INFO - manifestparser == 1.1 03:46:24 INFO - mozInstall == 1.12 03:46:24 INFO - mozcrash == 0.17 03:46:24 INFO - mozdebug == 0.1 03:46:24 INFO - mozdevice == 0.48 03:46:24 INFO - mozfile == 1.2 03:46:24 INFO - mozhttpd == 0.7 03:46:24 INFO - mozinfo == 0.9 03:46:24 INFO - mozleak == 0.1 03:46:24 INFO - mozlog == 3.1 03:46:24 INFO - moznetwork == 0.27 03:46:24 INFO - mozprocess == 0.22 03:46:24 INFO - mozprofile == 0.28 03:46:24 INFO - mozrunner == 6.11 03:46:24 INFO - mozscreenshot == 0.1 03:46:24 INFO - mozsystemmonitor == 0.0 03:46:24 INFO - moztest == 0.7 03:46:24 INFO - mozversion == 1.4 03:46:24 INFO - psutil == 3.1.1 03:46:24 INFO - requests == 1.2.3 03:46:24 INFO - wsgiref == 0.1.2 03:46:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:46:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46:24 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 0x10f578a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f588420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98714d6680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f417e00>, '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 0x10f280db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f412b20>, '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.Dg5QJn5r7a/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.Y2E1dIrdav/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:46:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 03:46:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 03:46:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Dg5QJn5r7a/Render', 03:46:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:46:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:46:24 INFO - 'HOME': '/Users/cltbld', 03:46:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:46:24 INFO - 'LOGNAME': 'cltbld', 03:46:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:24 INFO - 'MOZ_NO_REMOTE': '1', 03:46:24 INFO - 'NO_EM_RESTART': '1', 03:46:24 INFO - 'PAGER': '/bin/cat', 03:46:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:46:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:24 INFO - 'PWD': '/builds/slave/test', 03:46:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:46:24 INFO - 'SHELL': '/bin/bash', 03:46:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y2E1dIrdav/Listeners', 03:46:24 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 03:46:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:46:24 INFO - 'USER': 'cltbld', 03:46:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:46:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:46:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:24 INFO - 'XPC_FLAGS': '0x0', 03:46:24 INFO - 'XPC_SERVICE_NAME': '0', 03:46:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:46:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:46:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 03:46:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 03:46:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:46:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 03:46:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 03:46:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 03:46:24 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 03:46:24 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 03:46:24 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 03:46:24 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 03:46:24 INFO - Cleaning up... 03:46:24 INFO - Return code: 0 03:46:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:46:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46:24 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 0x10f578a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f588420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98714d6680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f417e00>, '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 0x10f280db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f412b20>, '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.Dg5QJn5r7a/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.Y2E1dIrdav/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:46:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 03:46:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 03:46:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Dg5QJn5r7a/Render', 03:46:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:46:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:46:24 INFO - 'HOME': '/Users/cltbld', 03:46:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:46:24 INFO - 'LOGNAME': 'cltbld', 03:46:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:24 INFO - 'MOZ_NO_REMOTE': '1', 03:46:24 INFO - 'NO_EM_RESTART': '1', 03:46:24 INFO - 'PAGER': '/bin/cat', 03:46:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:46:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:24 INFO - 'PWD': '/builds/slave/test', 03:46:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:46:24 INFO - 'SHELL': '/bin/bash', 03:46:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y2E1dIrdav/Listeners', 03:46:24 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 03:46:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:46:24 INFO - 'USER': 'cltbld', 03:46:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:46:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:46:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:24 INFO - 'XPC_FLAGS': '0x0', 03:46:24 INFO - 'XPC_SERVICE_NAME': '0', 03:46:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:46:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:24 INFO - Downloading/unpacking jsonschema 03:46:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:46:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:46:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:27 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:46:27 INFO - Downloading/unpacking functools32 (from jsonschema) 03:46: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 03:46: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 03:46: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 03:46: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 03:46: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 03:46: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 03:46:27 INFO - Downloading functools32-3.2.3-2.tar.gz 03:46:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:46:27 INFO - warning: no files found matching '*.txt' 03:46:27 INFO - no previously-included directories found matching 'build' 03:46:27 INFO - no previously-included directories found matching 'dist' 03:46:27 INFO - no previously-included directories found matching '.git*' 03:46:27 INFO - Installing collected packages: jsonschema, functools32 03:46:27 INFO - Running setup.py install for functools32 03:46:27 INFO - warning: no files found matching '*.txt' 03:46:27 INFO - no previously-included directories found matching 'build' 03:46:27 INFO - no previously-included directories found matching 'dist' 03:46:27 INFO - no previously-included directories found matching '.git*' 03:46:28 INFO - Successfully installed jsonschema functools32 03:46:28 INFO - Cleaning up... 03:46:28 INFO - Return code: 0 03:46:28 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:46:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:46:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:46:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:46:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:46:28 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f578a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f588420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98714d6680>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f417e00>, '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 0x10f280db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f412b20>, '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.Dg5QJn5r7a/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.Y2E1dIrdav/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 03:46:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 03:46:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 03:46:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Dg5QJn5r7a/Render', 03:46:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:46:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:46:28 INFO - 'HOME': '/Users/cltbld', 03:46:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:46:28 INFO - 'LOGNAME': 'cltbld', 03:46:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:46:28 INFO - 'MOZ_NO_REMOTE': '1', 03:46:28 INFO - 'NO_EM_RESTART': '1', 03:46:28 INFO - 'PAGER': '/bin/cat', 03:46:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:46:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:46:28 INFO - 'PWD': '/builds/slave/test', 03:46:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:46:28 INFO - 'SHELL': '/bin/bash', 03:46:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y2E1dIrdav/Listeners', 03:46:28 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 03:46:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:46:28 INFO - 'USER': 'cltbld', 03:46:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:46:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:46:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:46:28 INFO - 'XPC_FLAGS': '0x0', 03:46:28 INFO - 'XPC_SERVICE_NAME': '0', 03:46:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:46:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:46:28 INFO - Downloading/unpacking flake8 03:46:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:46:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:46:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:46:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:46:30 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:46:30 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:46: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 03:46: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 03:46: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 03:46: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 03:46: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 03:46: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 03:46:31 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:46:31 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:46: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 03:46: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 03:46: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 03:46: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 03:46: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 03:46: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 03:46:31 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:46: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 03:46: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 03:46: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 03:46: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 03:46: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 03:46: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 03:46:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:46:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:46:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:46:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:46:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:46:31 INFO - no previously-included directories found matching 'docs/_build' 03:46:31 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:46:32 INFO - Running setup.py install for pep8 03:46:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:46:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:46:32 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:46:32 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:46:32 INFO - no previously-included directories found matching 'docs/_build' 03:46:32 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:46:32 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:46:32 INFO - Cleaning up... 03:46:32 INFO - Return code: 0 03:46:32 INFO - Running post-action listener: _resource_record_post_action 03:46:32 INFO - Running post-action listener: _start_resource_monitoring 03:46:32 INFO - Starting resource monitoring. 03:46:32 INFO - ##### 03:46:32 INFO - ##### Running install step. 03:46:32 INFO - ##### 03:46:32 INFO - Running pre-action listener: _resource_record_pre_action 03:46:32 INFO - Running main action method: install 03:46:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:46:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:46:32 INFO - Reading from file tmpfile_stdout 03:46:32 INFO - Detecting whether we're running mozinstall >=1.0... 03:46:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:46:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:46:32 INFO - Reading from file tmpfile_stdout 03:46:32 INFO - Output received: 03:46:32 INFO - Usage: mozinstall [options] installer 03:46:32 INFO - Options: 03:46:32 INFO - -h, --help show this help message and exit 03:46:32 INFO - -d DEST, --destination=DEST 03:46:32 INFO - Directory to install application into. [default: 03:46:32 INFO - "/builds/slave/test"] 03:46:32 INFO - --app=APP Application being installed. [default: firefox] 03:46:32 INFO - mkdir: /builds/slave/test/build/application 03:46:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:46:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 03:47:04 INFO - Reading from file tmpfile_stdout 03:47:04 INFO - Output received: 03:47:04 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:47:04 INFO - Running post-action listener: _resource_record_post_action 03:47:04 INFO - ##### 03:47:04 INFO - ##### Running run-tests step. 03:47:04 INFO - ##### 03:47:04 INFO - Running pre-action listener: _resource_record_pre_action 03:47:04 INFO - Running main action method: run_tests 03:47:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:47:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:47:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:47:04 INFO - 2016-05-11 03:47:04.620 screenresolution[1642:7479] starting screenresolution argv=screenresolution get 03:47:04 INFO - 2016-05-11 03:47:04.623 screenresolution[1642:7479] Display 0: 1600x1200x32@60 03:47:04 INFO - 2016-05-11 03:47:04.629 screenresolution[1643:7482] starting screenresolution argv=screenresolution list 03:47:04 INFO - Available Modes on Display 0 03:47:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:47:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:47:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:47:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:47:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:47:04 INFO - Intel Iris: 03:47:04 INFO - Chipset Model: Intel Iris 03:47:04 INFO - Type: GPU 03:47:04 INFO - Bus: Built-In 03:47:04 INFO - VRAM (Dynamic, Max): 1536 MB 03:47:04 INFO - Vendor: Intel (0x8086) 03:47:04 INFO - Device ID: 0x0a2e 03:47:04 INFO - Revision ID: 0x0009 03:47:04 INFO - Displays: 03:47:04 INFO - Display: 03:47:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:47:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:47:04 INFO - Display Serial Number: 1600x1200 60 03:47:04 INFO - Main Display: Yes 03:47:04 INFO - Mirror: Off 03:47:04 INFO - Online: Yes 03:47:04 INFO - Rotation: Supported 03:47:04 INFO - Return code: 0 03:47:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:47:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:47:04 INFO - Python 2.7.3 03:47:04 INFO - Return code: 0 03:47:04 INFO - grabbing minidump binary from tooltool 03:47: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')]} 03:47:04 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 0x10f417e00>, '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 0x10f280db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f412b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:47:04 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 03:47:04 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 03:47:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:47:04 INFO - Return code: 0 03:47:04 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:47:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:47:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:47:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:47:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:47:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:47:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:47:06 INFO - Return code: 0 03:47:06 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/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0111', '--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 03:47:06 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/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0111 --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 03:47:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Dg5QJn5r7a/Render', 03:47:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:47:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:47:06 INFO - 'HOME': '/Users/cltbld', 03:47:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:47:06 INFO - 'LOGNAME': 'cltbld', 03:47:06 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:47:06 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:47:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:47:06 INFO - 'MOZ_NO_REMOTE': '1', 03:47:06 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:47:06 INFO - 'NO_EM_RESTART': '1', 03:47:06 INFO - 'PAGER': '/bin/cat', 03:47:06 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:47:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:47:06 INFO - 'PWD': '/builds/slave/test', 03:47:06 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:47:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:47:06 INFO - 'SHELL': '/bin/bash', 03:47:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y2E1dIrdav/Listeners', 03:47:06 INFO - 'TMPDIR': '/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/', 03:47:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:47:06 INFO - 'USER': 'cltbld', 03:47:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:47:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:47:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:47:06 INFO - 'XPC_FLAGS': '0x0', 03:47:06 INFO - 'XPC_SERVICE_NAME': '0', 03:47:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:47:06 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/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0111', '--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 03:47:06 INFO - mozversion application_buildid: 20160510142413 03:47:06 INFO - mozversion application_changeset: 1dd42a351054c5b697873d647169019d4d77d762 03:47:06 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:47:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:47:06 INFO - mozversion application_name: Firefox 03:47:06 INFO - mozversion application_remotingname: firefox-dev 03:47:06 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:47:06 INFO - mozversion application_vendor: Mozilla 03:47:06 INFO - mozversion application_version: 48.0a2 03:47:06 INFO - mozversion platform_buildid: 20160510142413 03:47:06 INFO - mozversion platform_changeset: 1dd42a351054c5b697873d647169019d4d77d762 03:47:06 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:47:06 INFO - mozversion platform_version: 48.0a2 03:47:06 INFO - using testdate: 1462963626 03:47:06 INFO - actual date: 1462963626 03:47:06 INFO - starting webserver on 'localhost:49275' 03:47:06 INFO - SUITE-START | Running 2 tests 03:47:06 INFO - TEST-START | dromaeo_css 03:47:06 INFO - operating with platform_type : mac_ 03:47:06 INFO - Initialising browser for dromaeo_css test... 03:47:06 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpomI0MB/profile http://localhost:49275/getInfo.html) 03:47:08 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 03:47:08 INFO - PROCESS | 1661 | colorDepth:24 03:47:08 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 03:47:08 INFO - PROCESS | 1661 | __metrics 03:47:08 INFO - PROCESS | 1661 | 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" 03:47:08 ERROR - PROCESS | 1661 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:47:09 INFO - TEST-INFO | 1661: exit 0 03:47:09 INFO - Browser initialized. 03:47:09 INFO - Running cycle 1/1 for dromaeo_css test... 03:47:09 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpomI0MB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:47:21 INFO - PROCESS | 1663 | RSS: Main: 211374080 03:47:21 INFO - PROCESS | 1663 | 03:48:12 INFO - PROCESS | 1663 | 1462963692453 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 03:50:24 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 03:50:25 INFO - PROCESS | 1663 | RSS: Main: 341573632 03:50:25 INFO - PROCESS | 1663 | 03:53:28 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 03:53:29 INFO - PROCESS | 1663 | RSS: Main: 356638720 03:53:29 INFO - PROCESS | 1663 | 03:56:31 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 03:56:31 INFO - PROCESS | 1663 | RSS: Main: 360378368 03:56:31 INFO - PROCESS | 1663 | 03:59:34 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 03:59:34 INFO - PROCESS | 1663 | RSS: Main: 352247808 03:59:34 INFO - PROCESS | 1663 | 04:02:37 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 04:02:38 INFO - PROCESS | 1663 | RSS: Main: 324739072 04:02:38 INFO - PROCESS | 1663 | 04:05:41 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 04:05:41 INFO - PROCESS | 1663 | RSS: Main: 377294848 04:05:41 INFO - PROCESS | 1663 | 04:05:41 INFO - PROCESS | 1663 | __start_tp_report 04:05:41 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 04:05:41 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 04:05:41 INFO - PROCESS | 1663 | |i|pagename|runs| 04:05:41 INFO - PROCESS | 1663 | |0;dojo.html;3411.573553305837;3439.1917899293676;3460.273342598054;3482.37317282889;3498.107982464471;5392.110301800199;5648.858778530539;5652.660840349572;5657.717114144287;5701.828945131642;5664.801731939383;5681.23303354047;5683.119116536944;5685.658860468375;5720.027595308797;5078.238264260353;5123.795048198086;5152.587792976572;5185.688858668473;5211.45279745627;5126.384833819947;5148.665336753118;5177.974110129463;5188.403333616645;5268.657537260085;5196.480351964811;5199.844004679866;5203.973980130094;5212.635115541913;5251.684898906078;5196.272521846944;5196.974015129938;5203.505666961671;5204.869878253036;5207.6354655174155;5082.6188035897085;5096.082705113078;5126.102931986925;5152.278680984665;5162.380514338303;3177.523371494294;5459.535939445152;5461.863453413656;5532.1425179097305;5541.5289700375515;6159.291681456601;6204.441600255998;6220.284667263232;6252.093446450239;6252.124702541648;6241.594296370721;6322.178116844781;6326.715297811609;6327.303996560375;6331.398517140864;5632.690202038927;5706.657600543981;5747.339056008613;5799.217105690762;5835.795747148872;6223.688815559204;6317.4946004319545;6322.968385158045;6337.904931426032;6366.363363663597;6118.510519158545;6149.446549810538;6152.723127459275;6157.076438534255;6160.9383906160365;6152.846178845564;6191.9690401547705;6299.748010079637;6308.085327627468;6309.305976342599;6117.663528505886;6181;6188.597741146811;6196.039613859859;6220.34686357935;6213.316535181127;6222.688865556704;6225.62646241227;6260.24877014752;6265.780697675559;4821.831235906727;4867.075255701406;4919.2129259318335;4945.332380128709;4981.32752078472;4396.1867054595;4416.24923762961;4426.955730442719;4426.959664478845;4436.977815110904;5521.558275337963;5621.887562248814;5630.4369563043365;5645.887082258332;5697.800576979786;6185.443310102112;6194.256689197326;6278.937210627835;6282.968585157045;6338.841528961812;5665.830025099253;5666.575006874501;5687.573431992617;5688.544916406678;5689.146628005832;5307.8142265021315;5361.731913404392;5367.704776237344;5375.75809088592;5388.380336261302;6270.623762574169;6288.465480434214;6346.365363463617;6346.460550853045;6351.301356850835;6306.684665766785;6340.30256671775;6353.396427339396;6354.205724284465;6375.5218358624215;6298.496120310456;6314.242290924934;6356.396142366468;6362.618242905441;6366.649834258975;5142.948570514397;6103.145559621567;6185.907211391743;6240.313565507699;6273.215848018997;6256;6257.09272155544;6261.24865016201;6281.842953926189;6284.5600807943;5902.380250073852;5922.348541660328;5939.643621382731;5942.702864856654;6011.308699499614;5689.2319536862;5751.1085781703905;5850.619709718854;5897.321807992136;5953.285605727167;5673.290838645169;5696.658200507983;5698.2592263005545;5714.885702286014;5735.799247026334;5121.8719532012;5820.592558520862;5833.329167145663;5867.559933005127;5900.409959004065;5771.97114014444;5852.18069470266;5893.734781934909;5924;5963.850903727442 04:05:41 INFO - PROCESS | 1663 | |1;ext.html;508.6185360979268;509.5388673250708;586.5366360575146;593.5014587746292;617.8918689229387;23446;37765.62234377655;38702.8064859676;38814.611853881484;39085.8045709771;40974.59025409745;41533.37699934496;41583;41617.791911040404;41816.79091604545;8328.62521186545;9390.248780097576;9593.712188634317;9594.376365536253;9608.903910960906;8454.957725211365;9618.374805637612;10259.640912568062;11031.558737650525;11590.072794176445;11366.943165284161;11450.255733377346;11452.255603385795;11460.770784584303;11491.712707182345;8550.78623034429;11290.774184516305;11392.943035284812;11611.129165312635;11796.29222246668;11330.263532870336;11473.48369323374;11475.540978360856;11477.483513241925;11483.368414737186;37371;37645.247095057945;37782.055448613726;37798.81100594507;37819.054523636856;3139.686031396842;3166.9208269793207;3195.536647186168;3273.312604353089;3306.0081975407284;22428.66357004646;28468.572971405447;28503;28819;28992.42015159706;2721.21084885383;2726.1548919834913;2746;2759.434315965222;2787.647990724497;465035.6748216271;466769;466992;467402;467686;59676.70161649251;59937;60002;60052.69973650104;60067;63812;67645.66177169082;68423.31576684269;68502.65748671225;68632.6568367155;17198.31206751716;17271.3955011574;17379.304827806747;17383.304667813147;17396.130193490273;12418.316992565495;12669.429875655433;13250.536231231861;13395.598132056053;13617.319134043259;19073.332433364765;19124.33064842724;19157.521061973282;19211.711824322647;19235.51911202231;7989.920100799034;8153.347732181412;8161.836763264821;8188;8296.917030829734;937.4843835890233;1008.1380419741016;1022.4580972084807;1100.4937728644913;1105.5412004018403;29486.8205271791;29606.259843504085;30031.099067028023;30337.544936825965;30392.848035759678;1120.943952802373;1124.6063877642923;1139.6467095200514;1156.8900954409319;1202.5189924030458;14973.475928342456;15063.472778452702;15066.849331506544;15382.000169989138;15499.302531386114;726633;729955;732427;732739;735072;56720.43279567234;58223.417765821796;58268;58306;58425.415745842845;1395.1001603965587;1437.2238990944982;1499.3552772307794;1518.476125736619;1594.689035638086;28098.2975425616;28463.43073138484;29270.83023691383;29343.41313173682;30027.249318767204;50418.49581504138;50594.74702626463;51220.74389628028;51246.48753512417;51252.48747512477;502161.4891925517;502602;506520;508531;508706;23975.400614984766;26904.865475672497;27023.729762702907;27198.1840544784;27243;40469.78859513289;42933;43434.56565434432;43635.690929272176;43862.78068609637;35381;35811.92564223077;36389.90830275096;36425;37082;35619.46570801388;35736.821315894296;36086.819565902006;36366.818165909004;36429;29570.408591828473;30237.546436803907;35247.82769550753;35329.64670353366;36022.63977360193;31210.68789312078;32035.839820800746;32166.51750223795;32220.677793222705;32246.19384515406;23871.04515819417;24126.396840078436;24154.913028914092;24383.390415239763;24441.633375499736 04:05:41 INFO - PROCESS | 1663 | |2;jquery.html;405.6896474197239;406.0964354311656;407.421461524635;407.7818367173561;419.68104240777;13676.452941882313;20057.699134512975;20525;20737.792622073757;20741.274055408063;16414.589635259126;18296.72554911674;20343.186272549116;20597.38207853763;20810.271640492585;5792.275965504323;6107;6123.846903827395;6131.3562075982045;6135.785247516338;6080.361562035989;6100.816975490742;6195.473384762301;6195.628262304276;6200.410960958701;5844.1818145459665;6072.119542666333;6101.603395779282;6109.816705498843;6110.816675499742;6008.579399442041;6097.512199024068;6114.602550834226;6115.113308570255;6134;5155.845324640267;5261.289725887033;5315.3621565412395;5316.73416329182;5334.146536554172;19090.522736931547;24844.751552484424;25493.96048316149;25548.97804087834;26507.867460662765;2194.1771835561663;2267.693861328716;2330.1494954341542;2347.694799676048;2363.196513185525;15019.047523821833;15023;15100.244987750568;15191.62020949474;15458.5362439127;1740.704080306351;1795.0127429913496;1819.5905921167764;1831.1210618903;1873.9505876709086;109578;111347;112847.99999999919;116803.83196168099;118274;10816.78366432667;11204.943975280072;11296.474975878316;11343.319400835977;11528.423578821026;14089.577312680636;14155.433782648784;14160.937929655318;14172.362243699059;14238.504956979576;7322.37493868363;7724.304812566784;8640.049594544595;8672.349573781992;9021.368504204775;7671.347935425551;8863.202311791969;9048.457092574467;9087.727718119468;9175.495347755805;5530.474604912527;6595.670216489157;6726.495512836557;6789.558678685869;6798.966005170041;1906.5233691577105;1926.0658580588404;1932.8743631663897;1933.9903300483409;1937.4865660599953;919.2875521470913;943.2265542255421;945.0596656326999;960.4477425480377;1067.962621308266;19851.503712407306;21389.67915481269;21455.78544214569;21578.46053848635;21967.340979770634;990.4008075114596;995.2784231432154;999.8000399920045;1020.5305559442739;1025.220832167558;11194.160437967023;11532.423378831192;11566.826497602542;11647.359395233174;11657.300561966309;512132.4393378009;515234;515532.4223378859;606186;610861;127700;133815;135246;137138;141258.29370853284;1009.6365308488938;1038.1539045677748;1053.262716098734;1055;1057.1489950589848;17918;17985.550361240552;18007.639847203245;18369.54076148107;18718.906405467886;34150;36391.63608363988;52953.20570191373;53990.920181596935;56501;83428.16571834446;83729;86787.56606216928;88686;88832.66750998869;7740.225977402215;8031.1567285434185;8211.17888211198;8236.546989915612;8242.835143297221;9123.543822808966;10295.330803497749;11031.83452248233;11545.769084618092;12243.57147499869;10699;10742.248042637253;11012.5044373005;11085.722856928642;11115.166362522681;8892.510911899908;10372.533236004247;10529.315594486334;10823.945880270549;10900.40047797347;10701.304415212986;10825.67522974312;11040;11236.325820450473;11271.605493807678;6355.586886852339;7378.483506154517;8156.0212774467445;8358.623861926106;8389.370797190113;7101.502894797314;10067.798644026932;10999.23005389615;11458;11477.540898364306 04:05:41 INFO - PROCESS | 1663 | |3;mootools.html;1380.5582213691623;1418.5389748331795;1503.458754848254;1555.323434306077;1910.3218357483086;7686.039245094364;8236.588170591469;8383.748487545383;8386.706465273717;8390.454620449667;6376.808695739135;8380.664773409057;8392.874106888401;8396.580170991456;8401.663933442655;2175.619266628342;2407.9759202408013;2415.492746523232;2468.0744720729726;2468.5926822074334;2497.300755788382;2499.600063989753;2505.3235626380865;2508.272600945733;2511.7237103918555;2400.4478969836946;2442.7557244275517;2460.8892599833052;2466.0628960994795;2474.3319303787966;2163.0806907064434;2386.868722220277;2444.706635203769;2448.1064411489856;2466.66699995501;2428.757124287557;2435.8261145990637;2457.6313552967017;2458.840175388594;2461.372350050726;8487.66049358025;8535.530545819978;8547.230749232598;8550.187732165434;8555.101714320032;5260.316158899357;5413.188021796723;5418.4039755626845;5458.536024437927;5470.753816078286;3466.5493485846678;3530.8411121499594;3579.588347340037;3600.531930848999;3619.0952261934517;1026.7587117027492;1045.8640376750973;1050.2963014780116;1052.4790228836698;1053.0417320238623;11267.549298028069;11440.542378304941;11446.313221206754;11469.827952580717;11470.139739519485;3910.9804450977563;4665.533446655375;4768.856934291976;4774.95225047745;4788.712677239307;9846.556904939294;9852.901470985342;9859.161971232383;9866.210703143875;9869.11177993984;5348.625596208306;5387.380451248078;5395.919061214085;5399.271098401666;5402.000629883484;4298.4197133387215;4409.294512877924;4651.279051746985;4802.903941921142;4856.077345304381;3897.064704470963;3970.9602903971168;3987.0829709167056;4000.8599699010397;4098.610631989956;1202.374765122149;1289.348878816192;1329.8803107720353;1350.648831303868;1361.8978576606794;806.3992325717261;814.995925020383;905.4152123578825;915.6703586708779;928.095107270406;5566.471185237477;5588.748506317225;5607.943920560824;5760.53915686744;5844.707764611752;853.9146085391535;976.1117383181413;990.4453506036642;1010.7611640216808;1068.7221322456062;3470.993411910518;3589.587197472324;3732.5520937487677;3779.074126838887;3785.73499855013;9733.756656083655;10044.547995340226;10095.949520252352;10144.847827282596;10187.08316251541;9930.354526955725;9933.80132397348;9955.104040636377;9956.253281003808;10083.14293285079;7566.621668916532;7896.32881205104;7896.526208427513;7954.840903181904;7960.601969901598;5332.120200166929;5571.8885622288135;5578;5588.38527761954;5687.829365119053;8940.061293564082;8954.328425367994;9222.723318300461;9244.630214791334;9262.16640502358;9230.861537076815;9263.814723705624;9307.674231401868;9334.579943902405;9373.625054997723;6540.705668245036;6734.4275736560985;7056.470764692566;7144.3927266182955;7275.563466192046;7974.960125199337;8014.679412823421;8051.436399452216;8057.153998830038;8060.959695201486;7023.508354415142;7087.255838136922;7113.644317784194;7260.818479538054;7262.164851041986;7045.542039767399;7062.788116356517;7062.823429414306;7168.462365322517;7171.175314838861;6592.274849766618;6690.832729181615;6697.129373181455;6792.388685018372;6794.592324460548;4582.518835522222;4689.718616882998;4701.388819453449;4708.623310135113;4711.575958163782;4527.660425468037;4582.816687332469;4604.677672562889;4734.928976065293;4822.373091498082 04:05:41 INFO - PROCESS | 1663 | |4;prototype.html;304.6222683871999;323.2355479291475;326.53794880244465;329.01132098045355;333.8063922924704;6585.407313341799;6605.3064428235;6722.226943901453;6732.966335168329;6741.258461569223;5320.228566857813;6642.734290628381;6651.866962660744;6657.300983396746;6682.097916781233;4698.55363740445;4727.929081063787;4842.636802239828;4860.951390486085;4910.288008238803;4808.663393562452;4862.562369386755;4869.707817530942;4878.170710979142;4891.1195984722735;4854.878628034292;4859.368282123319;4870.707757534542;4880.829170979016;4891.706497610137;4671.205894997858;4771.427428708543;4867.223463435856;4885.120678277908;4888.951110488921;4176.0603864130635;4195.7063005589625;4204.138151678899;4206.789660516993;4216.662666986634;6280.15217945576;6400.263969643457;6408.615483070984;6419.743210271584;6429.710663020175;5849.678267695275;5853.092770620518;5868.853278668024;5870.530357571427;5873.531617095726;6497.350264973512;6734.2592314845815;6845.5550389224545;7104.609246491441;7110.32451917067;3915.393114067324;4771.33201351811;4785.114753770529;4798.208295631217;4802.975985120087;7370.926290737131;7461.515001524884;7471.925280747178;7473.887891681629;7492.100947886289;4815.036992601494;4824.300476430968;4831.4685384607665;4834.129856625771;4841.031793641286;4894.3147959285725;4894.94758626897;4896.828610998598;4903.754812259373;4908.656394052382;5606.355269144019;5853.707314634251;5862.912056319074;5864.736086876101;5881.500072493886;5635.718214089279;5841.32824725162;7093.184283807428;7159.928400715926;7181.856362872713;1672.7992640882928;1711.7689111969974;1716.364944970369;1718.1065845760136;1720.4838548435418;1023.2837014090018;1035.8498017787379;1073.3238060022136;1089.0634054712941;1109.7447587055024;6493;6632.900506492407;6635.170603674541;6649.767258145942;6766.560173588799;7290.45321600882;7302.890456643155;7304.634768261566;7321.267873212636;7335.963320183365;6599.60402375859;6600.735970561123;6610.86778264432;6618.305077966744;6628.370304821035;3713.2573485303046;3834.9041273967814;3876.321643712339;3881.3207688654375;3893.279743247508;7661.46369754123;7667.769966901002;7699.846003079907;7707.11368192665;7707.267809558083;7491.250874912465;7559.092908850973;7562.962185189039;7575.280348366897;7579.090509138939;7646.3882889368715;7649.082110146818;7649.61751912391;7654.8086297843;7663.655135518915;7038.2961703829205;7290.817729556804;7293.671784769592;7301.963490182515;7334;7198.7840364789145;7228;7256;7256.020437240905;7279.980802687522;6153.230846144232;7622.428317876071;7627.351675107456;7647.502912310682;7658.540487570764;4795.280707893844;4802.447718512416;4807.4471435785335;4810.182269014206;4812.783424745965;6024.246969128859;7270.854582908207;7277.781666550012;7295.562266264041;7296.233895440902;6959.756408525677;7013.649317534205;7016.807352654632;7017.2631873652535;7017.333353331425;6918.757843475454;6965.930340696528;6967.651617419007;6978.232394436506;6993.19578248508;6738.427233685192;6757.290484499057;6767.424768894502;6773.221079575912;6782.287859774653;6692.330766923269;6918.135233095863;6957.79126626202;6986.056882321025;6998.300169982961;5837.532997360286;6463.030545418225;6694.497912656473;6778.186617605918;6784.59292442455 04:05:41 INFO - PROCESS | 1663 | |5;yui.html;579.623244890821;725.7097161135544;726.378946001169;740.3780824107748;740.526063319476;14699.706005879876;14801;15166.317515711791;15175.165365904872;15188.936774425794;14174.78737818931;14195.71608567828;14199.006069575134;14199.645008874757;14206.289685515734;724.3734169942987;892.4110087342356;894.6600291889089;900.3022657440471;902.8916530016375;736.7347754808292;907.8728977943093;929.1173385284008;930.2929773372218;930.5719369090226;971.7570607348163;971.9125278724913;974.697843668465;975.2149519636725;979.0943377375899;763.5609524523366;951.1202138022302;968.3753978683744;969.4328817641652;970.4759429907876;715.9856802863914;717.1465955512923;719.481972979454;720.5892641194469;724.5326764237037;13957.79063314051;14056.226907520082;14069.5075672352;14076.366563504667;14076.859231407658;12347.073969452327;12349.876501234963;12527.937360313232;12641.43113559892;12868.034897382733;10008.39949603026;10065.396076235449;10170.847437288447;10200.897991020069;10716.785664286748;3447.5518182636347;3455.447128459434;3503.894883153484;3504.8948531544092;3541.9728278799116;82631.17368826355;83967.74048389279;84437.15562844355;84461.15538844594;84909;7992.680292788224;9229.215516681023;9283.535823208813;9460.385074970105;9804.411735295906;20316.492087697927;20502.692459613118;20548.38354849357;20551;20557.383278501668;7283.235260297698;7379.6679149438405;7406.481546291816;7530.322270995636;7587.772366829004;4142.875713728593;4171.416001759756;4211.263028969918;4252.55348188442;4384.956150438518;3561.679448849616;3571.94642080369;3616.2405894762396;3620.981895090508;3621.909452263662;643.7746788623945;662.9933700663028;664.2527156948433;671;674.6323253826677;589.0986790210984;601.1974014690437;603.4237303375266;607.3683369295883;613.4662843326246;7605.391568674493;7958.522488650699;8130.796730081677;8222.8355432891;8268.2558569729;525.7003508000403;566.8894565559676;575.5711994564076;601.2184160591302;611.7736437518147;3684.2263124744104;3768.0768211788286;3791.1280405506886;3826.1773718650065;3843.442700808423;65875.67062164658;85200.57399713085;86003.56998215093;86774;87246.56376718076;79748;80017;80341;80661.19338806537;82151;62877.371226288065;64564.67717661476;65617.34382656208;66042.66978665076;66075;7434.702611895464;7496.325330720043;7512.436567257369;7725.768226953201;7843;45161.77419112883;45506.77246613746;45788.77105614584;46310.30534542052;47471.52528474671;65320.020199697996;68068.97896531457;68626.65686671535;70080;70128.94806577804;9012.774680633036;10754.784904302027;10843.1867609928;10882.183836212158;11711.88288117108;11668.416579171177;11804.76390472203;12001.219920705125;12010;12496.87503124957;9069.435534802336;10472.476376181314;11229.606963756229;11279.492422841156;11331.206815522833;11011.935909895157;11106.722331941766;11258.380789056353;11475.713107172387;12266.282720419327;8922.509261990654;10682.89317106819;11268.211225214483;11292.378919159524;11311.71720706956;9473.384230025027;9599.520023998912;9785.315027947976;9918.454485003394;9974.869514788463;9092.045335239969;9479.857802132836;9554.61781528759;9562.043795620382;9563.856542151734 04:05:41 INFO - PROCESS | 1663 | __end_tp_report 04:05:41 INFO - PROCESS | 1663 | __start_cc_report 04:05:41 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,1238 04:05:41 INFO - PROCESS | 1663 | __end_cc_report 04:05:41 INFO - PROCESS | 1663 | __startTimestamp1462964741491__endTimestamp 04:05:41 INFO - PROCESS | 1663 | 04:05:41 INFO - PROCESS | 1663 | ------- Summary: start ------- 04:05:41 INFO - PROCESS | 1663 | Number of tests: 6 04:05:41 INFO - PROCESS | 1663 | 04:05:41 INFO - PROCESS | 1663 | [#0] dojo.html Cycles:165 Average:5686.11 Median:5771.97 stddev:665.39 (11.5%) stddev-sans-first:643.06 04:05:41 INFO - PROCESS | 1663 | Values: 3411.6 3439.2 3460.3 3482.4 3498.1 5392.1 5648.9 5652.7 5657.7 5701.8 5664.8 5681.2 5683.1 5685.7 5720.0 5078.2 5123.8 5152.6 5185.7 5211.5 5126.4 5148.7 5178.0 5188.4 5268.7 5196.5 5199.8 5204.0 5212.6 5251.7 5196.3 5197.0 5203.5 5204.9 5207.6 5082.6 5096.1 5126.1 5152.3 5162.4 3177.5 5459.5 5461.9 5532.1 5541.5 6159.3 6204.4 6220.3 6252.1 6252.1 6241.6 6322.2 6326.7 6327.3 6331.4 5632.7 5706.7 5747.3 5799.2 5835.8 6223.7 6317.5 6323.0 6337.9 6366.4 6118.5 6149.4 6152.7 6157.1 6160.9 6152.8 6192.0 6299.7 6308.1 6309.3 6117.7 6181.0 6188.6 6196.0 6220.3 6213.3 6222.7 6225.6 6260.2 6265.8 4821.8 4867.1 4919.2 4945.3 4981.3 4396.2 4416.2 4427.0 4427.0 4437.0 5521.6 5621.9 5630.4 5645.9 5697.8 6185.4 6194.3 6278.9 6283.0 6338.8 5665.8 5666.6 5687.6 5688.5 5689.1 5307.8 5361.7 5367.7 5375.8 5388.4 6270.6 6288.5 6346.4 6346.5 6351.3 6306.7 6340.3 6353.4 6354.2 6375.5 6298.5 6314.2 6356.4 6362.6 6366.6 5142.9 6103.1 6185.9 6240.3 6273.2 6256.0 6257.1 6261.2 6281.8 6284.6 5902.4 5922.3 5939.6 5942.7 6011.3 5689.2 5751.1 5850.6 5897.3 5953.3 5673.3 5696.7 5698.3 5714.9 5735.8 5121.9 5820.6 5833.3 5867.6 5900.4 5772.0 5852.2 5893.7 5924.0 5963.9 04:05:41 INFO - PROCESS | 1663 | 04:05:41 INFO - PROCESS | 1663 | [#1] ext.html Cycles:180 Average:69689.41 Median:32233.44 stddev:155382.60 (482.1%) stddev-sans-first:155731.67 04:05:41 INFO - PROCESS | 1663 | Values: 508.6 509.5 586.5 593.5 617.9 23446.0 37765.6 38702.8 38814.6 39085.8 40974.6 41533.4 41583.0 41617.8 41816.8 8328.6 9390.2 9593.7 9594.4 9608.9 8455.0 9618.4 10259.6 11031.6 11590.1 11366.9 11450.3 11452.3 11460.8 11491.7 8550.8 11290.8 11392.9 11611.1 11796.3 11330.3 11473.5 11475.5 11477.5 11483.4 37371.0 37645.2 37782.1 37798.8 37819.1 3139.7 3166.9 3195.5 3273.3 3306.0 22428.7 28468.6 28503.0 28819.0 28992.4 2721.2 2726.2 2746.0 2759.4 2787.6 465035.7 466769.0 466992.0 467402.0 467686.0 59676.7 59937.0 60002.0 60052.7 60067.0 63812.0 67645.7 68423.3 68502.7 68632.7 17198.3 17271.4 17379.3 17383.3 17396.1 12418.3 12669.4 13250.5 13395.6 13617.3 19073.3 19124.3 19157.5 19211.7 19235.5 7989.9 8153.3 8161.8 8188.0 8296.9 937.5 1008.1 1022.5 1100.5 1105.5 29486.8 29606.3 30031.1 30337.5 30392.8 1120.9 1124.6 1139.6 1156.9 1202.5 14973.5 15063.5 15066.8 15382.0 15499.3 726633.0 729955.0 732427.0 732739.0 735072.0 56720.4 58223.4 58268.0 58306.0 58425.4 1395.1 1437.2 1499.4 1518.5 1594.7 28098.3 28463.4 29270.8 29343.4 30027.2 50418.5 50594.7 51220.7 51246.5 51252.5 502161.5 502602.0 506520.0 508531.0 508706.0 23975.4 26904.9 27023.7 27198.2 27243.0 40469.8 42933.0 43434.6 43635.7 43862.8 35381.0 35811.9 36389.9 36425.0 37082.0 35619.5 35736.8 36086.8 36366.8 36429.0 29570.4 30237.5 35247.8 35329.6 36022.6 31210.7 32035.8 32166.5 32220.7 32246.2 23871.0 24126.4 24154.9 24383.4 24441.6 04:05:41 INFO - PROCESS | 1663 | 04:05:41 INFO - PROCESS | 1663 | [#2] jquery.html Cycles:180 Average:33886.51 Median:12080.76 stddev:93103.94 (770.7%) stddev-sans-first:93331.18 04:05:41 INFO - PROCESS | 1663 | Values: 405.7 406.1 407.4 407.8 419.7 13676.5 20057.7 20525.0 20737.8 20741.3 16414.6 18296.7 20343.2 20597.4 20810.3 5792.3 6107.0 6123.8 6131.4 6135.8 6080.4 6100.8 6195.5 6195.6 6200.4 5844.2 6072.1 6101.6 6109.8 6110.8 6008.6 6097.5 6114.6 6115.1 6134.0 5155.8 5261.3 5315.4 5316.7 5334.1 19090.5 24844.8 25494.0 25549.0 26507.9 2194.2 2267.7 2330.1 2347.7 2363.2 15019.0 15023.0 15100.2 15191.6 15458.5 1740.7 1795.0 1819.6 1831.1 1874.0 109578.0 111347.0 112848.0 116803.8 118274.0 10816.8 11204.9 11296.5 11343.3 11528.4 14089.6 14155.4 14160.9 14172.4 14238.5 7322.4 7724.3 8640.0 8672.3 9021.4 7671.3 8863.2 9048.5 9087.7 9175.5 5530.5 6595.7 6726.5 6789.6 6799.0 1906.5 1926.1 1932.9 1934.0 1937.5 919.3 943.2 945.1 960.4 1068.0 19851.5 21389.7 21455.8 21578.5 21967.3 990.4 995.3 999.8 1020.5 1025.2 11194.2 11532.4 11566.8 11647.4 11657.3 512132.4 515234.0 515532.4 606186.0 610861.0 127700.0 133815.0 135246.0 137138.0 141258.3 1009.6 1038.2 1053.3 1055.0 1057.1 17918.0 17985.6 18007.6 18369.5 18718.9 34150.0 36391.6 52953.2 53990.9 56501.0 83428.2 83729.0 86787.6 88686.0 88832.7 7740.2 8031.2 8211.2 8236.5 8242.8 9123.5 10295.3 11031.8 11545.8 12243.6 10699.0 10742.2 11012.5 11085.7 11115.2 8892.5 10372.5 10529.3 10823.9 10900.4 10701.3 10825.7 11040.0 11236.3 11271.6 6355.6 7378.5 8156.0 8358.6 8389.4 7101.5 10067.8 10999.2 11458.0 11477.5 04:05:41 INFO - PROCESS | 1663 | 04:05:41 INFO - PROCESS | 1663 | [#3] mootools.html Cycles:180 Average:5498.59 Median:5391.65 stddev:2996.13 (55.6%) stddev-sans-first:2988.54 04:05:41 INFO - PROCESS | 1663 | Values: 1380.6 1418.5 1503.5 1555.3 1910.3 7686.0 8236.6 8383.7 8386.7 8390.5 6376.8 8380.7 8392.9 8396.6 8401.7 2175.6 2408.0 2415.5 2468.1 2468.6 2497.3 2499.6 2505.3 2508.3 2511.7 2400.4 2442.8 2460.9 2466.1 2474.3 2163.1 2386.9 2444.7 2448.1 2466.7 2428.8 2435.8 2457.6 2458.8 2461.4 8487.7 8535.5 8547.2 8550.2 8555.1 5260.3 5413.2 5418.4 5458.5 5470.8 3466.5 3530.8 3579.6 3600.5 3619.1 1026.8 1045.9 1050.3 1052.5 1053.0 11267.5 11440.5 11446.3 11469.8 11470.1 3911.0 4665.5 4768.9 4775.0 4788.7 9846.6 9852.9 9859.2 9866.2 9869.1 5348.6 5387.4 5395.9 5399.3 5402.0 4298.4 4409.3 4651.3 4802.9 4856.1 3897.1 3971.0 3987.1 4000.9 4098.6 1202.4 1289.3 1329.9 1350.6 1361.9 806.4 815.0 905.4 915.7 928.1 5566.5 5588.7 5607.9 5760.5 5844.7 853.9 976.1 990.4 1010.8 1068.7 3471.0 3589.6 3732.6 3779.1 3785.7 9733.8 10044.5 10095.9 10144.8 10187.1 9930.4 9933.8 9955.1 9956.3 10083.1 7566.6 7896.3 7896.5 7954.8 7960.6 5332.1 5571.9 5578.0 5588.4 5687.8 8940.1 8954.3 9222.7 9244.6 9262.2 9230.9 9263.8 9307.7 9334.6 9373.6 6540.7 6734.4 7056.5 7144.4 7275.6 7975.0 8014.7 8051.4 8057.2 8061.0 7023.5 7087.3 7113.6 7260.8 7262.2 7045.5 7062.8 7062.8 7168.5 7171.2 6592.3 6690.8 6697.1 6792.4 6794.6 4582.5 4689.7 4701.4 4708.6 4711.6 4527.7 4582.8 4604.7 4734.9 4822.4 04:05:41 INFO - PROCESS | 1663 | 04:05:41 INFO - PROCESS | 1663 | [#4] prototype.html Cycles:180 Average:5781.99 Median:6600.17 stddev:1818.20 (27.5%) stddev-sans-first:1776.21 04:05:41 INFO - PROCESS | 1663 | Values: 304.6 323.2 326.5 329.0 333.8 6585.4 6605.3 6722.2 6733.0 6741.3 5320.2 6642.7 6651.9 6657.3 6682.1 4698.6 4727.9 4842.6 4861.0 4910.3 4808.7 4862.6 4869.7 4878.2 4891.1 4854.9 4859.4 4870.7 4880.8 4891.7 4671.2 4771.4 4867.2 4885.1 4889.0 4176.1 4195.7 4204.1 4206.8 4216.7 6280.2 6400.3 6408.6 6419.7 6429.7 5849.7 5853.1 5868.9 5870.5 5873.5 6497.4 6734.3 6845.6 7104.6 7110.3 3915.4 4771.3 4785.1 4798.2 4803.0 7370.9 7461.5 7471.9 7473.9 7492.1 4815.0 4824.3 4831.5 4834.1 4841.0 4894.3 4894.9 4896.8 4903.8 4908.7 5606.4 5853.7 5862.9 5864.7 5881.5 5635.7 5841.3 7093.2 7159.9 7181.9 1672.8 1711.8 1716.4 1718.1 1720.5 1023.3 1035.8 1073.3 1089.1 1109.7 6493.0 6632.9 6635.2 6649.8 6766.6 7290.5 7302.9 7304.6 7321.3 7336.0 6599.6 6600.7 6610.9 6618.3 6628.4 3713.3 3834.9 3876.3 3881.3 3893.3 7661.5 7667.8 7699.8 7707.1 7707.3 7491.3 7559.1 7563.0 7575.3 7579.1 7646.4 7649.1 7649.6 7654.8 7663.7 7038.3 7290.8 7293.7 7302.0 7334.0 7198.8 7228.0 7256.0 7256.0 7280.0 6153.2 7622.4 7627.4 7647.5 7658.5 4795.3 4802.4 4807.4 4810.2 4812.8 6024.2 7270.9 7277.8 7295.6 7296.2 6959.8 7013.6 7016.8 7017.3 7017.3 6918.8 6965.9 6967.7 6978.2 6993.2 6738.4 6757.3 6767.4 6773.2 6782.3 6692.3 6918.1 6957.8 6986.1 6998.3 5837.5 6463.0 6694.5 6778.2 6784.6 04:05:41 INFO - PROCESS | 1663 | 04:05:41 INFO - PROCESS | 1663 | [#5] yui.html Cycles:180 Average:17828.31 Median:64942.35 stddev:25027.25 (38.5%) stddev-sans-first:25063.94 04:05:41 INFO - PROCESS | 1663 | Values: 579.6 725.7 726.4 740.4 740.5 14699.7 14801.0 15166.3 15175.2 15188.9 14174.8 14195.7 14199.0 14199.6 14206.3 724.4 892.4 894.7 900.3 902.9 736.7 907.9 929.1 930.3 930.6 971.8 971.9 974.7 975.2 979.1 763.6 951.1 968.4 969.4 970.5 716.0 717.1 719.5 720.6 724.5 13957.8 14056.2 14069.5 14076.4 14076.9 12347.1 12349.9 12527.9 12641.4 12868.0 10008.4 10065.4 10170.8 10200.9 10716.8 3447.6 3455.4 3503.9 3504.9 3542.0 82631.2 83967.7 84437.2 84461.2 84909.0 7992.7 9229.2 9283.5 9460.4 9804.4 20316.5 20502.7 20548.4 20551.0 20557.4 7283.2 7379.7 7406.5 7530.3 7587.8 4142.9 4171.4 4211.3 4252.6 4385.0 3561.7 3571.9 3616.2 3621.0 3621.9 643.8 663.0 664.3 671.0 674.6 589.1 601.2 603.4 607.4 613.5 7605.4 7958.5 8130.8 8222.8 8268.3 525.7 566.9 575.6 601.2 611.8 3684.2 3768.1 3791.1 3826.2 3843.4 65875.7 85200.6 86003.6 86774.0 87246.6 79748.0 80017.0 80341.0 80661.2 82151.0 62877.4 64564.7 65617.3 66042.7 66075.0 7434.7 7496.3 7512.4 7725.8 7843.0 45161.8 45506.8 45788.8 46310.3 47471.5 65320.0 68069.0 68626.7 70080.0 70128.9 9012.8 10754.8 10843.2 10882.2 11711.9 11668.4 11804.8 12001.2 12010.0 12496.9 9069.4 10472.5 11229.6 11279.5 11331.2 11011.9 11106.7 11258.4 11475.7 12266.3 8922.5 10682.9 11268.2 11292.4 11311.7 9473.4 9599.5 9785.3 9918.5 9974.9 9092.0 9479.9 9554.6 9562.0 9563.9 04:05:41 INFO - PROCESS | 1663 | -------- Summary: end -------- 04:05:41 INFO - PROCESS | 1663 | 04:05:42 INFO - TEST-INFO | 1663: exit 0 04:05:42 INFO - TEST-OK | dromaeo_css | took 1115764ms 04:05:42 INFO - TEST-START | kraken 04:05:42 INFO - operating with platform_type : mac_ 04:05:42 INFO - Initialising browser for kraken test... 04:05:42 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpXXE6R_/profile http://localhost:49275/getInfo.html) 04:05:44 INFO - PROCESS | 1677 | __metrics Screen width/height:1600/1200 04:05:44 INFO - PROCESS | 1677 | colorDepth:24 04:05:44 INFO - PROCESS | 1677 | Browser inner width/height: 1024/689 04:05:44 INFO - PROCESS | 1677 | __metrics 04:05:44 INFO - PROCESS | 1677 | 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" 04:05:44 ERROR - PROCESS | 1677 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:05:44 INFO - TEST-INFO | 1677: exit 0 04:05:44 INFO - Browser initialized. 04:05:44 INFO - Running cycle 1/1 for kraken test... 04:05:44 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/T/tmpXXE6R_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:05:56 INFO - PROCESS | 1679 | RSS: Main: 211361792 04:05:56 INFO - PROCESS | 1679 | 04:05:59 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 04:06:00 INFO - PROCESS | 1679 | RSS: Main: 272326656 04:06:00 INFO - PROCESS | 1679 | 04:06:03 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 04:06:03 INFO - PROCESS | 1679 | RSS: Main: 286343168 04:06:03 INFO - PROCESS | 1679 | 04:06:06 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 04:06:07 INFO - PROCESS | 1679 | RSS: Main: 373112832 04:06:07 INFO - PROCESS | 1679 | 04:06:09 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 04:06:10 INFO - PROCESS | 1679 | RSS: Main: 278618112 04:06:10 INFO - PROCESS | 1679 | 04:06:12 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:06:12 INFO - PROCESS | 1679 | RSS: Main: 280457216 04:06:12 INFO - PROCESS | 1679 | 04:06:15 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 04:06:16 INFO - PROCESS | 1679 | RSS: Main: 319545344 04:06:16 INFO - PROCESS | 1679 | 04:06:18 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 04:06:19 INFO - PROCESS | 1679 | RSS: Main: 320704512 04:06:19 INFO - PROCESS | 1679 | 04:06:22 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 04:06:23 INFO - PROCESS | 1679 | RSS: Main: 318201856 04:06:23 INFO - PROCESS | 1679 | 04:06:24 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:06:25 INFO - PROCESS | 1679 | RSS: Main: 309551104 04:06:25 INFO - PROCESS | 1679 | 04:06:27 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:06:27 INFO - PROCESS | 1679 | RSS: Main: 316416000 04:06:27 INFO - PROCESS | 1679 | 04:06:30 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:06:30 INFO - PROCESS | 1679 | RSS: Main: 319279104 04:06:30 INFO - PROCESS | 1679 | 04:06:33 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:06:34 INFO - PROCESS | 1679 | RSS: Main: 324071424 04:06:34 INFO - PROCESS | 1679 | 04:06:37 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:06:37 INFO - PROCESS | 1679 | RSS: Main: 322076672 04:06:37 INFO - PROCESS | 1679 | 04:06:39 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 04:06:39 INFO - PROCESS | 1679 | RSS: Main: 387088384 04:06:39 INFO - PROCESS | 1679 | 04:06:39 INFO - PROCESS | 1679 | __start_tp_report 04:06:39 INFO - PROCESS | 1679 | _x_x_mozilla_page_load 04:06:39 INFO - PROCESS | 1679 | _x_x_mozilla_page_load_details 04:06:39 INFO - PROCESS | 1679 | |i|pagename|runs| 04:06:39 INFO - PROCESS | 1679 | |0;ai-astar;115;115;121;113;112;113;114;113;114;136 04:06:39 INFO - PROCESS | 1679 | |1;audio-beat-detection;157;122;145;173;151;169;122;154;155;114 04:06:39 INFO - PROCESS | 1679 | |2;audio-dft;184;188;185;185;186;186;185;185;187;184 04:06:39 INFO - PROCESS | 1679 | |3;audio-fft;72;77;75;151;56;56;59;62;141;55 04:06:39 INFO - PROCESS | 1679 | |4;audio-oscillator;92;88;142;78;80;90;119;78;75;119 04:06:39 INFO - PROCESS | 1679 | |5;imaging-gaussian-blur;88;85;89;85;88;85;88;85;90;86 04:06:39 INFO - PROCESS | 1679 | |6;imaging-darkroom;94;95;94;96;95;95;95;95;93;94 04:06:39 INFO - PROCESS | 1679 | |7;imaging-desaturate;84;91;130;84;129;136;122;86;84;85 04:06:39 INFO - PROCESS | 1679 | |8;json-parse-financial;61;63;63;63;63;64;65;85;58;58 04:06:39 INFO - PROCESS | 1679 | |9;json-stringify-tinderbox;63;67;68;71;75;77;73;69;70;69 04:06:39 INFO - PROCESS | 1679 | |10;stanford-crypto-aes;83;82;81;80;79;96;106;105;83;85 04:06:39 INFO - PROCESS | 1679 | |11;stanford-crypto-ccm;134;157;146;122;128;128;130;129;149;129 04:06:39 INFO - PROCESS | 1679 | |12;stanford-crypto-pbkdf2;166;169;177;170;166;177;216;208;190;163 04:06:39 INFO - PROCESS | 1679 | |13;stanford-crypto-sha256-iterative;67;67;69;68;67;67;63;75;99;91 04:06:39 INFO - PROCESS | 1679 | __end_tp_report 04:06:39 INFO - PROCESS | 1679 | __start_cc_report 04:06:39 INFO - PROCESS | 1679 | _x_x_mozilla_cycle_collect,2017 04:06:39 INFO - PROCESS | 1679 | __end_cc_report 04:06:39 INFO - PROCESS | 1679 | __startTimestamp1462964799828__endTimestamp 04:06:39 INFO - PROCESS | 1679 | 04:06:39 INFO - PROCESS | 1679 | ------- Summary: start ------- 04:06:39 INFO - PROCESS | 1679 | Number of tests: 14 04:06:39 INFO - PROCESS | 1679 | 04:06:39 INFO - PROCESS | 1679 | [#0] ai-astar Cycles:10 Average:116.60 Median:114.50 stddev:7.26 (6.3%) stddev-sans-first:7.68 04:06:39 INFO - PROCESS | 1679 | Values: 115.0 115.0 121.0 113.0 112.0 113.0 114.0 113.0 114.0 136.0 04:06:39 INFO - PROCESS | 1679 | 04:06:39 INFO - PROCESS | 1679 | [#1] audio-beat-detection Cycles:10 Average:146.20 Median:154.50 stddev:20.35 (13.2%) stddev-sans-first:21.20 04:06:39 INFO - PROCESS | 1679 | Values: 157.0 122.0 145.0 173.0 151.0 169.0 122.0 154.0 155.0 114.0 04:06:39 INFO - PROCESS | 1679 | 04:06:39 INFO - PROCESS | 1679 | [#2] audio-dft Cycles:10 Average:185.50 Median:185.50 stddev:1.27 (0.7%) stddev-sans-first:1.22 04:06:39 INFO - PROCESS | 1679 | Values: 184.0 188.0 185.0 185.0 186.0 186.0 185.0 185.0 187.0 184.0 04:06:39 INFO - PROCESS | 1679 | 04:06:39 INFO - PROCESS | 1679 | [#3] audio-fft Cycles:10 Average:80.40 Median:60.50 stddev:35.59 (58.8%) stddev-sans-first:37.62 04:06:39 INFO - PROCESS | 1679 | Values: 72.0 77.0 75.0 151.0 56.0 56.0 59.0 62.0 141.0 55.0 04:06:39 INFO - PROCESS | 1679 | 04:06:39 INFO - PROCESS | 1679 | [#4] audio-oscillator Cycles:10 Average:96.10 Median:79.00 stddev:22.69 (28.7%) stddev-sans-first:24.02 04:06:39 INFO - PROCESS | 1679 | Values: 92.0 88.0 142.0 78.0 80.0 90.0 119.0 78.0 75.0 119.0 04:06:39 INFO - PROCESS | 1679 | 04:06:39 INFO - PROCESS | 1679 | [#5] imaging-gaussian-blur Cycles:10 Average:86.90 Median:88.00 stddev:1.91 (2.2%) stddev-sans-first:1.99 04:06:39 INFO - PROCESS | 1679 | Values: 88.0 85.0 89.0 85.0 88.0 85.0 88.0 85.0 90.0 86.0 04:06:39 INFO - PROCESS | 1679 | 04:06:39 INFO - PROCESS | 1679 | [#6] imaging-darkroom Cycles:10 Average:94.60 Median:95.00 stddev:0.84 (0.9%) stddev-sans-first:0.87 04:06:39 INFO - PROCESS | 1679 | Values: 94.0 95.0 94.0 96.0 95.0 95.0 95.0 95.0 93.0 94.0 04:06:39 INFO - PROCESS | 1679 | 04:06:39 INFO - PROCESS | 1679 | [#7] imaging-desaturate Cycles:10 Average:103.10 Median:84.00 stddev:22.84 (27.2%) stddev-sans-first:23.16 04:06:39 INFO - PROCESS | 1679 | Values: 84.0 91.0 130.0 84.0 129.0 136.0 122.0 86.0 84.0 85.0 04:06:39 INFO - PROCESS | 1679 | 04:06:39 INFO - PROCESS | 1679 | [#8] json-parse-financial Cycles:10 Average:64.30 Median:63.00 stddev:7.65 (12.1%) stddev-sans-first:8.02 04:06:39 INFO - PROCESS | 1679 | Values: 61.0 63.0 63.0 63.0 63.0 64.0 65.0 85.0 58.0 58.0 04:06:39 INFO - PROCESS | 1679 | 04:06:39 INFO - PROCESS | 1679 | [#9] json-stringify-tinderbox Cycles:10 Average:70.20 Median:70.50 stddev:4.05 (5.7%) stddev-sans-first:3.35 04:06:39 INFO - PROCESS | 1679 | Values: 63.0 67.0 68.0 71.0 75.0 77.0 73.0 69.0 70.0 69.0 04:06:39 INFO - PROCESS | 1679 | 04:06:39 INFO - PROCESS | 1679 | [#10] stanford-crypto-aes Cycles:10 Average:88.00 Median:82.50 stddev:10.36 (12.6%) stddev-sans-first:10.83 04:06:39 INFO - PROCESS | 1679 | Values: 83.0 82.0 81.0 80.0 79.0 96.0 106.0 105.0 83.0 85.0 04:06:39 INFO - PROCESS | 1679 | 04:06:39 INFO - PROCESS | 1679 | [#11] stanford-crypto-ccm Cycles:10 Average:135.20 Median:132.00 stddev:11.38 (8.6%) stddev-sans-first:12.06 04:06:39 INFO - PROCESS | 1679 | Values: 134.0 157.0 146.0 122.0 128.0 128.0 130.0 129.0 149.0 129.0 04:06:39 INFO - PROCESS | 1679 | 04:06:39 INFO - PROCESS | 1679 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.20 Median:177.00 stddev:18.56 (10.5%) stddev-sans-first:18.96 04:06:39 INFO - PROCESS | 1679 | Values: 166.0 169.0 177.0 170.0 166.0 177.0 216.0 208.0 190.0 163.0 04:06:39 INFO - PROCESS | 1679 | 04:06:39 INFO - PROCESS | 1679 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.30 Median:68.50 stddev:11.96 (17.5%) stddev-sans-first:12.47 04:06:39 INFO - PROCESS | 1679 | Values: 67.0 67.0 69.0 68.0 67.0 67.0 63.0 75.0 99.0 91.0 04:06:39 INFO - PROCESS | 1679 | -------- Summary: end -------- 04:06:39 INFO - PROCESS | 1679 | 04:06:40 INFO - TEST-INFO | 1679: exit 0 04:06:40 INFO - TEST-OK | kraken | took 58584ms 04:06:40 INFO - SUITE-END | took 1174s 04:06:40 INFO - Completed test suite (00:19:34) 04:06:40 INFO - javascript benchmark 04:06:41 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3411.573553305837, 3439.1917899293676, 3460.273342598054, 3482.37317282889, 3498.107982464471, 5392.110301800199, 5648.858778530539, 5652.660840349572, 5657.717114144287, 5701.828945131642, 5664.801731939383, 5681.23303354047, 5683.119116536944, 5685.658860468375, 5720.027595308797, 5078.238264260353, 5123.795048198086, 5152.587792976572, 5185.688858668473, 5211.45279745627, 5126.384833819947, 5148.665336753118, 5177.974110129463, 5188.403333616645, 5268.657537260085, 5196.480351964811, 5199.844004679866, 5203.973980130094, 5212.635115541913, 5251.684898906078, 5196.272521846944, 5196.974015129938, 5203.505666961671, 5204.869878253036, 5207.6354655174155, 5082.6188035897085, 5096.082705113078, 5126.102931986925, 5152.278680984665, 5162.380514338303, 3177.523371494294, 5459.535939445152, 5461.863453413656, 5532.1425179097305, 5541.5289700375515, 6159.291681456601, 6204.441600255998, 6220.284667263232, 6252.093446450239, 6252.124702541648, 6241.594296370721, 6322.178116844781, 6326.715297811609, 6327.303996560375, 6331.398517140864, 5632.690202038927, 5706.657600543981, 5747.339056008613, 5799.217105690762, 5835.795747148872, 6223.688815559204, 6317.4946004319545, 6322.968385158045, 6337.904931426032, 6366.363363663597, 6118.510519158545, 6149.446549810538, 6152.723127459275, 6157.076438534255, 6160.9383906160365, 6152.846178845564, 6191.9690401547705, 6299.748010079637, 6308.085327627468, 6309.305976342599, 6117.663528505886, 6181.0, 6188.597741146811, 6196.039613859859, 6220.34686357935, 6213.316535181127, 6222.688865556704, 6225.62646241227, 6260.24877014752, 6265.780697675559, 4821.831235906727, 4867.075255701406, 4919.2129259318335, 4945.332380128709, 4981.32752078472, 4396.1867054595, 4416.24923762961, 4426.955730442719, 4426.959664478845, 4436.977815110904, 5521.558275337963, 5621.887562248814, 5630.4369563043365, 5645.887082258332, 5697.800576979786, 6185.443310102112, 6194.256689197326, 6278.937210627835, 6282.968585157045, 6338.841528961812, 5665.830025099253, 5666.575006874501, 5687.573431992617, 5688.544916406678, 5689.146628005832, 5307.8142265021315, 5361.731913404392, 5367.704776237344, 5375.75809088592, 5388.380336261302, 6270.623762574169, 6288.465480434214, 6346.365363463617, 6346.460550853045, 6351.301356850835, 6306.684665766785, 6340.30256671775, 6353.396427339396, 6354.205724284465, 6375.5218358624215, 6298.496120310456, 6314.242290924934, 6356.396142366468, 6362.618242905441, 6366.649834258975, 5142.948570514397, 6103.145559621567, 6185.907211391743, 6240.313565507699, 6273.215848018997, 6256.0, 6257.09272155544, 6261.24865016201, 6281.842953926189, 6284.5600807943, 5902.380250073852, 5922.348541660328, 5939.643621382731, 5942.702864856654, 6011.308699499614, 5689.2319536862, 5751.1085781703905, 5850.619709718854, 5897.321807992136, 5953.285605727167, 5673.290838645169, 5696.658200507983, 5698.2592263005545, 5714.885702286014, 5735.799247026334, 5121.8719532012, 5820.592558520862, 5833.329167145663, 5867.559933005127, 5900.409959004065, 5771.97114014444, 5852.18069470266, 5893.734781934909, 5924.0, 5963.850903727442], "value": 5645.271017076018, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [508.6185360979268, 509.5388673250708, 586.5366360575146, 593.5014587746292, 617.8918689229387, 23446.0, 37765.62234377655, 38702.8064859676, 38814.611853881484, 39085.8045709771, 40974.59025409745, 41533.37699934496, 41583.0, 41617.791911040404, 41816.79091604545, 8328.62521186545, 9390.248780097576, 9593.712188634317, 9594.376365536253, 9608.903910960906, 8454.957725211365, 9618.374805637612, 10259.640912568062, 11031.558737650525, 11590.072794176445, 11366.943165284161, 11450.255733377346, 11452.255603385795, 11460.770784584303, 11491.712707182345, 8550.78623034429, 11290.774184516305, 11392.943035284812, 11611.129165312635, 11796.29222246668, 11330.263532870336, 11473.48369323374, 11475.540978360856, 11477.483513241925, 11483.368414737186, 37371.0, 37645.247095057945, 37782.055448613726, 37798.81100594507, 37819.054523636856, 3139.686031396842, 3166.9208269793207, 3195.536647186168, 3273.312604353089, 3306.0081975407284, 22428.66357004646, 28468.572971405447, 28503.0, 28819.0, 28992.42015159706, 2721.21084885383, 2726.1548919834913, 2746.0, 2759.434315965222, 2787.647990724497, 465035.6748216271, 466769.0, 466992.0, 467402.0, 467686.0, 59676.70161649251, 59937.0, 60002.0, 60052.69973650104, 60067.0, 63812.0, 67645.66177169082, 68423.31576684269, 68502.65748671225, 68632.6568367155, 17198.31206751716, 17271.3955011574, 17379.304827806747, 17383.304667813147, 17396.130193490273, 12418.316992565495, 12669.429875655433, 13250.536231231861, 13395.598132056053, 13617.319134043259, 19073.332433364765, 19124.33064842724, 19157.521061973282, 19211.711824322647, 19235.51911202231, 7989.920100799034, 8153.347732181412, 8161.836763264821, 8188.0, 8296.917030829734, 937.4843835890233, 1008.1380419741016, 1022.4580972084807, 1100.4937728644913, 1105.5412004018403, 29486.8205271791, 29606.259843504085, 30031.099067028023, 30337.544936825965, 30392.848035759678, 1120.943952802373, 1124.6063877642923, 1139.6467095200514, 1156.8900954409319, 1202.5189924030458, 14973.475928342456, 15063.472778452702, 15066.849331506544, 15382.000169989138, 15499.302531386114, 726633.0, 729955.0, 732427.0, 732739.0, 735072.0, 56720.43279567234, 58223.417765821796, 58268.0, 58306.0, 58425.415745842845, 1395.1001603965587, 1437.2238990944982, 1499.3552772307794, 1518.476125736619, 1594.689035638086, 28098.2975425616, 28463.43073138484, 29270.83023691383, 29343.41313173682, 30027.249318767204, 50418.49581504138, 50594.74702626463, 51220.74389628028, 51246.48753512417, 51252.48747512477, 502161.4891925517, 502602.0, 506520.0, 508531.0, 508706.0, 23975.400614984766, 26904.865475672497, 27023.729762702907, 27198.1840544784, 27243.0, 40469.78859513289, 42933.0, 43434.56565434432, 43635.690929272176, 43862.78068609637, 35381.0, 35811.92564223077, 36389.90830275096, 36425.0, 37082.0, 35619.46570801388, 35736.821315894296, 36086.819565902006, 36366.818165909004, 36429.0, 29570.408591828473, 30237.546436803907, 35247.82769550753, 35329.64670353366, 36022.63977360193, 31210.68789312078, 32035.839820800746, 32166.51750223795, 32220.677793222705, 32246.19384515406, 23871.04515819417, 24126.396840078436, 24154.913028914092, 24383.390415239763, 24441.633375499736], "value": 19990.10934910374, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [405.6896474197239, 406.0964354311656, 407.421461524635, 407.7818367173561, 419.68104240777, 13676.452941882313, 20057.699134512975, 20525.0, 20737.792622073757, 20741.274055408063, 16414.589635259126, 18296.72554911674, 20343.186272549116, 20597.38207853763, 20810.271640492585, 5792.275965504323, 6107.0, 6123.846903827395, 6131.3562075982045, 6135.785247516338, 6080.361562035989, 6100.816975490742, 6195.473384762301, 6195.628262304276, 6200.410960958701, 5844.1818145459665, 6072.119542666333, 6101.603395779282, 6109.816705498843, 6110.816675499742, 6008.579399442041, 6097.512199024068, 6114.602550834226, 6115.113308570255, 6134.0, 5155.845324640267, 5261.289725887033, 5315.3621565412395, 5316.73416329182, 5334.146536554172, 19090.522736931547, 24844.751552484424, 25493.96048316149, 25548.97804087834, 26507.867460662765, 2194.1771835561663, 2267.693861328716, 2330.1494954341542, 2347.694799676048, 2363.196513185525, 15019.047523821833, 15023.0, 15100.244987750568, 15191.62020949474, 15458.5362439127, 1740.704080306351, 1795.0127429913496, 1819.5905921167764, 1831.1210618903, 1873.9505876709086, 109578.0, 111347.0, 112847.99999999919, 116803.83196168099, 118274.0, 10816.78366432667, 11204.943975280072, 11296.474975878316, 11343.319400835977, 11528.423578821026, 14089.577312680636, 14155.433782648784, 14160.937929655318, 14172.362243699059, 14238.504956979576, 7322.37493868363, 7724.304812566784, 8640.049594544595, 8672.349573781992, 9021.368504204775, 7671.347935425551, 8863.202311791969, 9048.457092574467, 9087.727718119468, 9175.495347755805, 5530.474604912527, 6595.670216489157, 6726.495512836557, 6789.558678685869, 6798.966005170041, 1906.5233691577105, 1926.0658580588404, 1932.8743631663897, 1933.9903300483409, 1937.4865660599953, 919.2875521470913, 943.2265542255421, 945.0596656326999, 960.4477425480377, 1067.962621308266, 19851.503712407306, 21389.67915481269, 21455.78544214569, 21578.46053848635, 21967.340979770634, 990.4008075114596, 995.2784231432154, 999.8000399920045, 1020.5305559442739, 1025.220832167558, 11194.160437967023, 11532.423378831192, 11566.826497602542, 11647.359395233174, 11657.300561966309, 512132.4393378009, 515234.0, 515532.4223378859, 606186.0, 610861.0, 127700.0, 133815.0, 135246.0, 137138.0, 141258.29370853284, 1009.6365308488938, 1038.1539045677748, 1053.262716098734, 1055.0, 1057.1489950589848, 17918.0, 17985.550361240552, 18007.639847203245, 18369.54076148107, 18718.906405467886, 34150.0, 36391.63608363988, 52953.20570191373, 53990.920181596935, 56501.0, 83428.16571834446, 83729.0, 86787.56606216928, 88686.0, 88832.66750998869, 7740.225977402215, 8031.1567285434185, 8211.17888211198, 8236.546989915612, 8242.835143297221, 9123.543822808966, 10295.330803497749, 11031.83452248233, 11545.769084618092, 12243.57147499869, 10699.0, 10742.248042637253, 11012.5044373005, 11085.722856928642, 11115.166362522681, 8892.510911899908, 10372.533236004247, 10529.315594486334, 10823.945880270549, 10900.40047797347, 10701.304415212986, 10825.67522974312, 11040.0, 11236.325820450473, 11271.605493807678, 6355.586886852339, 7378.483506154517, 8156.0212774467445, 8358.623861926106, 8389.370797190113, 7101.502894797314, 10067.798644026932, 10999.23005389615, 11458.0, 11477.540898364306], "value": 9693.194916819837, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1380.5582213691623, 1418.5389748331795, 1503.458754848254, 1555.323434306077, 1910.3218357483086, 7686.039245094364, 8236.588170591469, 8383.748487545383, 8386.706465273717, 8390.454620449667, 6376.808695739135, 8380.664773409057, 8392.874106888401, 8396.580170991456, 8401.663933442655, 2175.619266628342, 2407.9759202408013, 2415.492746523232, 2468.0744720729726, 2468.5926822074334, 2497.300755788382, 2499.600063989753, 2505.3235626380865, 2508.272600945733, 2511.7237103918555, 2400.4478969836946, 2442.7557244275517, 2460.8892599833052, 2466.0628960994795, 2474.3319303787966, 2163.0806907064434, 2386.868722220277, 2444.706635203769, 2448.1064411489856, 2466.66699995501, 2428.757124287557, 2435.8261145990637, 2457.6313552967017, 2458.840175388594, 2461.372350050726, 8487.66049358025, 8535.530545819978, 8547.230749232598, 8550.187732165434, 8555.101714320032, 5260.316158899357, 5413.188021796723, 5418.4039755626845, 5458.536024437927, 5470.753816078286, 3466.5493485846678, 3530.8411121499594, 3579.588347340037, 3600.531930848999, 3619.0952261934517, 1026.7587117027492, 1045.8640376750973, 1050.2963014780116, 1052.4790228836698, 1053.0417320238623, 11267.549298028069, 11440.542378304941, 11446.313221206754, 11469.827952580717, 11470.139739519485, 3910.9804450977563, 4665.533446655375, 4768.856934291976, 4774.95225047745, 4788.712677239307, 9846.556904939294, 9852.901470985342, 9859.161971232383, 9866.210703143875, 9869.11177993984, 5348.625596208306, 5387.380451248078, 5395.919061214085, 5399.271098401666, 5402.000629883484, 4298.4197133387215, 4409.294512877924, 4651.279051746985, 4802.903941921142, 4856.077345304381, 3897.064704470963, 3970.9602903971168, 3987.0829709167056, 4000.8599699010397, 4098.610631989956, 1202.374765122149, 1289.348878816192, 1329.8803107720353, 1350.648831303868, 1361.8978576606794, 806.3992325717261, 814.995925020383, 905.4152123578825, 915.6703586708779, 928.095107270406, 5566.471185237477, 5588.748506317225, 5607.943920560824, 5760.53915686744, 5844.707764611752, 853.9146085391535, 976.1117383181413, 990.4453506036642, 1010.7611640216808, 1068.7221322456062, 3470.993411910518, 3589.587197472324, 3732.5520937487677, 3779.074126838887, 3785.73499855013, 9733.756656083655, 10044.547995340226, 10095.949520252352, 10144.847827282596, 10187.08316251541, 9930.354526955725, 9933.80132397348, 9955.104040636377, 9956.253281003808, 10083.14293285079, 7566.621668916532, 7896.32881205104, 7896.526208427513, 7954.840903181904, 7960.601969901598, 5332.120200166929, 5571.8885622288135, 5578.0, 5588.38527761954, 5687.829365119053, 8940.061293564082, 8954.328425367994, 9222.723318300461, 9244.630214791334, 9262.16640502358, 9230.861537076815, 9263.814723705624, 9307.674231401868, 9334.579943902405, 9373.625054997723, 6540.705668245036, 6734.4275736560985, 7056.470764692566, 7144.3927266182955, 7275.563466192046, 7974.960125199337, 8014.679412823421, 8051.436399452216, 8057.153998830038, 8060.959695201486, 7023.508354415142, 7087.255838136922, 7113.644317784194, 7260.818479538054, 7262.164851041986, 7045.542039767399, 7062.788116356517, 7062.823429414306, 7168.462365322517, 7171.175314838861, 6592.274849766618, 6690.832729181615, 6697.129373181455, 6792.388685018372, 6794.592324460548, 4582.518835522222, 4689.718616882998, 4701.388819453449, 4708.623310135113, 4711.575958163782, 4527.660425468037, 4582.816687332469, 4604.677672562889, 4734.928976065293, 4822.373091498082], "value": 4477.683799252808, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [304.6222683871999, 323.2355479291475, 326.53794880244465, 329.01132098045355, 333.8063922924704, 6585.407313341799, 6605.3064428235, 6722.226943901453, 6732.966335168329, 6741.258461569223, 5320.228566857813, 6642.734290628381, 6651.866962660744, 6657.300983396746, 6682.097916781233, 4698.55363740445, 4727.929081063787, 4842.636802239828, 4860.951390486085, 4910.288008238803, 4808.663393562452, 4862.562369386755, 4869.707817530942, 4878.170710979142, 4891.1195984722735, 4854.878628034292, 4859.368282123319, 4870.707757534542, 4880.829170979016, 4891.706497610137, 4671.205894997858, 4771.427428708543, 4867.223463435856, 4885.120678277908, 4888.951110488921, 4176.0603864130635, 4195.7063005589625, 4204.138151678899, 4206.789660516993, 4216.662666986634, 6280.15217945576, 6400.263969643457, 6408.615483070984, 6419.743210271584, 6429.710663020175, 5849.678267695275, 5853.092770620518, 5868.853278668024, 5870.530357571427, 5873.531617095726, 6497.350264973512, 6734.2592314845815, 6845.5550389224545, 7104.609246491441, 7110.32451917067, 3915.393114067324, 4771.33201351811, 4785.114753770529, 4798.208295631217, 4802.975985120087, 7370.926290737131, 7461.515001524884, 7471.925280747178, 7473.887891681629, 7492.100947886289, 4815.036992601494, 4824.300476430968, 4831.4685384607665, 4834.129856625771, 4841.031793641286, 4894.3147959285725, 4894.94758626897, 4896.828610998598, 4903.754812259373, 4908.656394052382, 5606.355269144019, 5853.707314634251, 5862.912056319074, 5864.736086876101, 5881.500072493886, 5635.718214089279, 5841.32824725162, 7093.184283807428, 7159.928400715926, 7181.856362872713, 1672.7992640882928, 1711.7689111969974, 1716.364944970369, 1718.1065845760136, 1720.4838548435418, 1023.2837014090018, 1035.8498017787379, 1073.3238060022136, 1089.0634054712941, 1109.7447587055024, 6493.0, 6632.900506492407, 6635.170603674541, 6649.767258145942, 6766.560173588799, 7290.45321600882, 7302.890456643155, 7304.634768261566, 7321.267873212636, 7335.963320183365, 6599.60402375859, 6600.735970561123, 6610.86778264432, 6618.305077966744, 6628.370304821035, 3713.2573485303046, 3834.9041273967814, 3876.321643712339, 3881.3207688654375, 3893.279743247508, 7661.46369754123, 7667.769966901002, 7699.846003079907, 7707.11368192665, 7707.267809558083, 7491.250874912465, 7559.092908850973, 7562.962185189039, 7575.280348366897, 7579.090509138939, 7646.3882889368715, 7649.082110146818, 7649.61751912391, 7654.8086297843, 7663.655135518915, 7038.2961703829205, 7290.817729556804, 7293.671784769592, 7301.963490182515, 7334.0, 7198.7840364789145, 7228.0, 7256.0, 7256.020437240905, 7279.980802687522, 6153.230846144232, 7622.428317876071, 7627.351675107456, 7647.502912310682, 7658.540487570764, 4795.280707893844, 4802.447718512416, 4807.4471435785335, 4810.182269014206, 4812.783424745965, 6024.246969128859, 7270.854582908207, 7277.781666550012, 7295.562266264041, 7296.233895440902, 6959.756408525677, 7013.649317534205, 7016.807352654632, 7017.2631873652535, 7017.333353331425, 6918.757843475454, 6965.930340696528, 6967.651617419007, 6978.232394436506, 6993.19578248508, 6738.427233685192, 6757.290484499057, 6767.424768894502, 6773.221079575912, 6782.287859774653, 6692.330766923269, 6918.135233095863, 6957.79126626202, 6986.056882321025, 6998.300169982961, 5837.532997360286, 6463.030545418225, 6694.497912656473, 6778.186617605918, 6784.59292442455], "value": 5172.197846806135, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [579.623244890821, 725.7097161135544, 726.378946001169, 740.3780824107748, 740.526063319476, 14699.706005879876, 14801.0, 15166.317515711791, 15175.165365904872, 15188.936774425794, 14174.78737818931, 14195.71608567828, 14199.006069575134, 14199.645008874757, 14206.289685515734, 724.3734169942987, 892.4110087342356, 894.6600291889089, 900.3022657440471, 902.8916530016375, 736.7347754808292, 907.8728977943093, 929.1173385284008, 930.2929773372218, 930.5719369090226, 971.7570607348163, 971.9125278724913, 974.697843668465, 975.2149519636725, 979.0943377375899, 763.5609524523366, 951.1202138022302, 968.3753978683744, 969.4328817641652, 970.4759429907876, 715.9856802863914, 717.1465955512923, 719.481972979454, 720.5892641194469, 724.5326764237037, 13957.79063314051, 14056.226907520082, 14069.5075672352, 14076.366563504667, 14076.859231407658, 12347.073969452327, 12349.876501234963, 12527.937360313232, 12641.43113559892, 12868.034897382733, 10008.39949603026, 10065.396076235449, 10170.847437288447, 10200.897991020069, 10716.785664286748, 3447.5518182636347, 3455.447128459434, 3503.894883153484, 3504.8948531544092, 3541.9728278799116, 82631.17368826355, 83967.74048389279, 84437.15562844355, 84461.15538844594, 84909.0, 7992.680292788224, 9229.215516681023, 9283.535823208813, 9460.385074970105, 9804.411735295906, 20316.492087697927, 20502.692459613118, 20548.38354849357, 20551.0, 20557.383278501668, 7283.235260297698, 7379.6679149438405, 7406.481546291816, 7530.322270995636, 7587.772366829004, 4142.875713728593, 4171.416001759756, 4211.263028969918, 4252.55348188442, 4384.956150438518, 3561.679448849616, 3571.94642080369, 3616.2405894762396, 3620.981895090508, 3621.909452263662, 643.7746788623945, 662.9933700663028, 664.2527156948433, 671.0, 674.6323253826677, 589.0986790210984, 601.1974014690437, 603.4237303375266, 607.3683369295883, 613.4662843326246, 7605.391568674493, 7958.522488650699, 8130.796730081677, 8222.8355432891, 8268.2558569729, 525.7003508000403, 566.8894565559676, 575.5711994564076, 601.2184160591302, 611.7736437518147, 3684.2263124744104, 3768.0768211788286, 3791.1280405506886, 3826.1773718650065, 3843.442700808423, 65875.67062164658, 85200.57399713085, 86003.56998215093, 86774.0, 87246.56376718076, 79748.0, 80017.0, 80341.0, 80661.19338806537, 82151.0, 62877.371226288065, 64564.67717661476, 65617.34382656208, 66042.66978665076, 66075.0, 7434.702611895464, 7496.325330720043, 7512.436567257369, 7725.768226953201, 7843.0, 45161.77419112883, 45506.77246613746, 45788.77105614584, 46310.30534542052, 47471.52528474671, 65320.020199697996, 68068.97896531457, 68626.65686671535, 70080.0, 70128.94806577804, 9012.774680633036, 10754.784904302027, 10843.1867609928, 10882.183836212158, 11711.88288117108, 11668.416579171177, 11804.76390472203, 12001.219920705125, 12010.0, 12496.87503124957, 9069.435534802336, 10472.476376181314, 11229.606963756229, 11279.492422841156, 11331.206815522833, 11011.935909895157, 11106.722331941766, 11258.380789056353, 11475.713107172387, 12266.282720419327, 8922.509261990654, 10682.89317106819, 11268.211225214483, 11292.378919159524, 11311.71720706956, 9473.384230025027, 9599.520023998912, 9785.315027947976, 9918.454485003394, 9974.869514788463, 9092.045335239969, 9479.857802132836, 9554.61781528759, 9562.043795620382, 9563.856542151734], "value": 6800.440182474939, "unit": "score"}], "name": "dromaeo_css", "value": 7459.537583774424, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 121.0, 113.0, 112.0, 113.0, 114.0, 113.0, 114.0, 136.0], "value": 116.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 122.0, 145.0, 173.0, 151.0, 169.0, 122.0, 154.0, 155.0, 114.0], "value": 146.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 188.0, 185.0, 185.0, 186.0, 186.0, 185.0, 185.0, 187.0, 184.0], "value": 185.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 77.0, 75.0, 151.0, 56.0, 56.0, 59.0, 62.0, 141.0, 55.0], "value": 80.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 88.0, 142.0, 78.0, 80.0, 90.0, 119.0, 78.0, 75.0, 119.0], "value": 96.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 89.0, 85.0, 88.0, 85.0, 88.0, 85.0, 90.0, 86.0], "value": 86.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 96.0, 95.0, 95.0, 95.0, 95.0, 93.0, 94.0], "value": 94.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 91.0, 130.0, 84.0, 129.0, 136.0, 122.0, 86.0, 84.0, 85.0], "value": 103.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 63.0, 63.0, 63.0, 63.0, 64.0, 65.0, 85.0, 58.0, 58.0], "value": 64.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 67.0, 68.0, 71.0, 75.0, 77.0, 73.0, 69.0, 70.0, 69.0], "value": 70.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 81.0, 80.0, 79.0, 96.0, 106.0, 105.0, 83.0, 85.0], "value": 88.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 157.0, 146.0, 122.0, 128.0, 128.0, 130.0, 129.0, 149.0, 129.0], "value": 135.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 169.0, 177.0, 170.0, 166.0, 177.0, 216.0, 208.0, 190.0, 163.0], "value": 180.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 69.0, 68.0, 67.0, 67.0, 63.0, 75.0, 99.0, 91.0], "value": 73.3, "unit": "score"}], "name": "kraken", "value": 1520.6, "alertThreshold": 2.0}]} 04:06:41 INFO - Return code: 0 04:06:41 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 04:06:41 ERROR - # TBPL SUCCESS # 04:06:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:06:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:06:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:06:41 INFO - 2016-05-11 04:06:41.116 screenresolution[1683:9908] starting screenresolution argv=screenresolution get 04:06:41 INFO - 2016-05-11 04:06:41.119 screenresolution[1683:9908] Display 0: 1600x1200x32@60 04:06:41 INFO - 2016-05-11 04:06:41.125 screenresolution[1684:9911] starting screenresolution argv=screenresolution list 04:06:41 INFO - Available Modes on Display 0 04:06:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:06:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:06:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:06:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:06:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:06:41 INFO - Intel Iris: 04:06:41 INFO - Chipset Model: Intel Iris 04:06:41 INFO - Type: GPU 04:06:41 INFO - Bus: Built-In 04:06:41 INFO - VRAM (Dynamic, Max): 1536 MB 04:06:41 INFO - Vendor: Intel (0x8086) 04:06:41 INFO - Device ID: 0x0a2e 04:06:41 INFO - Revision ID: 0x0009 04:06:41 INFO - Displays: 04:06:41 INFO - Display: 04:06:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:06:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:06:41 INFO - Display Serial Number: 1600x1200 60 04:06:41 INFO - Main Display: Yes 04:06:41 INFO - Mirror: Off 04:06:41 INFO - Online: Yes 04:06:41 INFO - Rotation: Supported 04:06:41 INFO - Return code: 0 04:06:41 INFO - Running post-action listener: _resource_record_post_action 04:06:41 INFO - Running post-run listener: _resource_record_post_run 04:06:42 INFO - Total resource usage - Wall time: 1208s; CPU: 53.0%; Read bytes: 38184448; Write bytes: 653182464; Read time: 406; Write time: 1873 04:06:42 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 353292800; Write bytes: 346949632; Read time: 27773; Write time: 621 04:06:42 INFO - run-tests - Wall time: 1177s; CPU: 52.0%; Read bytes: 36210176; Write bytes: 296439296; Read time: 379; Write time: 1216 04:06:42 INFO - Running post-run listener: _upload_blobber_files 04:06:42 INFO - Blob upload gear active. 04:06:42 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:06:42 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 04:06:42 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'] 04:06:42 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 04:06:42 INFO - (blobuploader) - INFO - Open directory for files ... 04:06:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:06:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:06:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:06:44 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:06:44 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:06:44 INFO - (blobuploader) - INFO - Done attempting. 04:06:44 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:06:44 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:06:44 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:06:45 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:06:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:06:45 INFO - (blobuploader) - INFO - Done attempting. 04:06:45 INFO - (blobuploader) - INFO - Iteration through files over. 04:06:45 INFO - Return code: 0 04:06:45 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:06:45 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:06:45 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a23ff39b2b9307716332b1cec47625d6ce7a77829daaa12b695dc43b2cd6ea7ef61158ab3bacdfb08203dac2382cd4c2f1cefab782315e5de12969a1cc87b5f1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e274ac2241ee59d80d7549231ac72a5b9902ccfa28a185cc3047239e0079945c051216cd1009b752c38ff70c6a51a312ee3ac504392be2875630910e696b7b53"} 04:06:45 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:06:45 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:06:45 INFO - Contents: 04:06:45 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/a23ff39b2b9307716332b1cec47625d6ce7a77829daaa12b695dc43b2cd6ea7ef61158ab3bacdfb08203dac2382cd4c2f1cefab782315e5de12969a1cc87b5f1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e274ac2241ee59d80d7549231ac72a5b9902ccfa28a185cc3047239e0079945c051216cd1009b752c38ff70c6a51a312ee3ac504392be2875630910e696b7b53"} 04:06:45 INFO - Running post-run listener: copy_logs_to_upload_dir 04:06:45 INFO - Copying logs to upload dir... 04:06:45 INFO - mkdir: /builds/slave/test/build/upload/logs 04:06:45 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.877577 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2016-05-11 04:06:45.436521) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 04:06:45.440421) ========= 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.Dg5QJn5r7a/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.Y2E1dIrdav/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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/a23ff39b2b9307716332b1cec47625d6ce7a77829daaa12b695dc43b2cd6ea7ef61158ab3bacdfb08203dac2382cd4c2f1cefab782315e5de12969a1cc87b5f1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e274ac2241ee59d80d7549231ac72a5b9902ccfa28a185cc3047239e0079945c051216cd1009b752c38ff70c6a51a312ee3ac504392be2875630910e696b7b53"} build_url:https://queue.taskcluster.net/v1/task/D8CVojXuQjGmk8NvQeFISg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010971 build_url: 'https://queue.taskcluster.net/v1/task/D8CVojXuQjGmk8NvQeFISg/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/a23ff39b2b9307716332b1cec47625d6ce7a77829daaa12b695dc43b2cd6ea7ef61158ab3bacdfb08203dac2382cd4c2f1cefab782315e5de12969a1cc87b5f1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e274ac2241ee59d80d7549231ac72a5b9902ccfa28a185cc3047239e0079945c051216cd1009b752c38ff70c6a51a312ee3ac504392be2875630910e696b7b53"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-11 04:06:45.467018) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 04:06:45.467338) ========= 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.Dg5QJn5r7a/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.Y2E1dIrdav/Listeners TMPDIR=/var/folders/kr/nz0rmqgx4cz8g886_dmw_z7m00000w/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.005084 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-11 04:06:45.514731) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 04:06:45.515029) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-11 04:06:45.515335) ========= ========= Total master_lag: 0.14 =========