builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0355 starttime: 1464734821.3 results: success (0) buildid: 20160531143941 builduid: d1cce16d3c2f432ea5770999edf23521 revision: 3cc4a98864ad31001292b38c97df30962b9e94d0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-31 15:47:01.302919) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-31 15:47:01.303402) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-31 15:47:01.303684) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-31 15:47:01.326369) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 15:47:01.326762) ========= 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.yQkPQPJPKE/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.XBnOKfa7uS/Listeners TMPDIR=/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/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-31 15:47:01-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.4M=0.001s 2016-05-31 15:47:01 (18.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.078835 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 15:47:01.426052) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 15:47:01.426404) ========= 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.yQkPQPJPKE/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.XBnOKfa7uS/Listeners TMPDIR=/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/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.026311 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 15:47:01.475358) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-31 15:47:01.475795) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3cc4a98864ad31001292b38c97df30962b9e94d0 --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 3cc4a98864ad31001292b38c97df30962b9e94d0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.yQkPQPJPKE/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.XBnOKfa7uS/Listeners TMPDIR=/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/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-31 15:47:01,555 truncating revision to first 12 chars 2016-05-31 15:47:01,555 Setting DEBUG logging. 2016-05-31 15:47:01,555 attempt 1/10 2016-05-31 15:47:01,555 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/3cc4a98864ad?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-31 15:47:03,110 unpacking tar archive at: mozilla-aurora-3cc4a98864ad/testing/mozharness/ program finished with exit code 0 elapsedTime=1.832876 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-31 15:47:03.328767) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-31 15:47:03.329164) ========= script_repo_revision: 3cc4a98864ad31001292b38c97df30962b9e94d0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-31 15:47:03.329656) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-31 15:47:03.329933) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-31 15:47:03.346172) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-05-31 15:47:03.346611) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.yQkPQPJPKE/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.XBnOKfa7uS/Listeners TMPDIR=/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/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 15:47:03 INFO - MultiFileLogger online at 20160531 15:47:03 in /builds/slave/test 15:47:03 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 15:47:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:47:03 INFO - {'append_to_log': False, 15:47:03 INFO - 'base_work_dir': '/builds/slave/test', 15:47:03 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 15:47:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:47:03 INFO - 'branch': 'Mozilla-Aurora', 15:47:03 INFO - 'buildbot_json_path': 'buildprops.json', 15:47:03 INFO - 'config_files': ('talos/mac_config.py',), 15:47:03 INFO - 'default_actions': ('clobber', 15:47:03 INFO - 'read-buildbot-config', 15:47:03 INFO - 'download-and-extract', 15:47:03 INFO - 'populate-webroot', 15:47:03 INFO - 'create-virtualenv', 15:47:03 INFO - 'install', 15:47:03 INFO - 'run-tests'), 15:47:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:47:03 INFO - 'download_minidump_stackwalk': True, 15:47:03 INFO - 'download_symbols': 'ondemand', 15:47:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:47:03 INFO - 'tooltool.py': '/tools/tooltool.py', 15:47:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:47:03 INFO - '/tools/misc-python/virtualenv.py')}, 15:47:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:47:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:47:03 INFO - 'installer_path': 'installer.exe', 15:47:03 INFO - 'log_level': 'info', 15:47:03 INFO - 'log_name': 'talos', 15:47:03 INFO - 'log_to_console': True, 15:47:03 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:47:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:47:03 INFO - 'opt_config_files': (), 15:47:03 INFO - 'pip_index': False, 15:47:03 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:47:03 INFO - 'cmd': ('bash', 15:47:03 INFO - '-c', 15:47:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:47:03 INFO - 'enabled': True, 15:47:03 INFO - 'halt_on_failure': False, 15:47:03 INFO - 'name': 'check_screen_resolution'},), 15:47:03 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:47:03 INFO - 'cmd': ('bash', 15:47:03 INFO - '-c', 15:47:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:47:03 INFO - 'enabled': True, 15:47:03 INFO - 'halt_on_failure': False, 15:47:03 INFO - 'name': 'check_screen_resolution'},), 15:47:03 INFO - 'run_cmd_checks_enabled': True, 15:47:03 INFO - 'sps_profile': False, 15:47:03 INFO - 'sps_profile_interval': 0, 15:47:03 INFO - 'suite': 'dromaeojs-e10s', 15:47:03 INFO - 'system_bits': '32', 15:47:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:47:03 INFO - 'title': 't-yosemite-r7-0355', 15:47:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:47:03 INFO - 'use_talos_json': True, 15:47:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:47:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:47:03 INFO - 'work_dir': 'build'} 15:47:03 INFO - ##### 15:47:03 INFO - ##### Running clobber step. 15:47:03 INFO - ##### 15:47:03 INFO - Running pre-action listener: _resource_record_pre_action 15:47:03 INFO - Running main action method: clobber 15:47:03 INFO - rmtree: /builds/slave/test/build 15:47:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:47:05 INFO - Running post-action listener: _resource_record_post_action 15:47:05 INFO - ##### 15:47:05 INFO - ##### Running read-buildbot-config step. 15:47:05 INFO - ##### 15:47:05 INFO - Running pre-action listener: _resource_record_pre_action 15:47:05 INFO - Running main action method: read_buildbot_config 15:47:05 INFO - Using buildbot properties: 15:47:05 INFO - { 15:47:05 INFO - "project": "", 15:47:05 INFO - "product": "firefox", 15:47:05 INFO - "script_repo_revision": "production", 15:47:05 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 15:47:05 INFO - "repository": "", 15:47:05 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 15:47:05 INFO - "buildid": "20160531143941", 15:47:05 INFO - "pgo_build": "False", 15:47:05 INFO - "basedir": "/builds/slave/test", 15:47:05 INFO - "buildnumber": 30, 15:47:05 INFO - "slavename": "t-yosemite-r7-0355", 15:47:05 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 15:47:05 INFO - "platform": "yosemite_r7", 15:47:05 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 15:47:05 INFO - "branch": "mozilla-aurora", 15:47:05 INFO - "repo_path": "releases/mozilla-aurora", 15:47:05 INFO - "revision": "3cc4a98864ad31001292b38c97df30962b9e94d0", 15:47:05 INFO - "stage_platform": "macosx64", 15:47:05 INFO - "builduid": "d1cce16d3c2f432ea5770999edf23521", 15:47:05 INFO - "slavebuilddir": "test" 15:47:05 INFO - } 15:47:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/XAKk8H5wSjiTkjP_j5fo8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 15:47:05 INFO - Running post-action listener: _resource_record_post_action 15:47:05 INFO - ##### 15:47:05 INFO - ##### Running download-and-extract step. 15:47:05 INFO - ##### 15:47:05 INFO - Running pre-action listener: _resource_record_pre_action 15:47:05 INFO - Running main action method: download_and_extract 15:47:05 INFO - mkdir: /builds/slave/test/build/tests 15:47:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:47:05 INFO - https://queue.taskcluster.net/v1/task/XAKk8H5wSjiTkjP_j5fo8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 15:47:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XAKk8H5wSjiTkjP_j5fo8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 15:47:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XAKk8H5wSjiTkjP_j5fo8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 15:47:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XAKk8H5wSjiTkjP_j5fo8Q/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 15:47:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XAKk8H5wSjiTkjP_j5fo8Q/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 15:47:06 INFO - Downloaded 1199 bytes. 15:47:06 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 15:47:06 INFO - Using the following test package requirements: 15:47:06 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 15:47:06 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:47:06 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 15:47:06 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:47:06 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 15:47:06 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:47:06 INFO - u'jsshell-mac.zip'], 15:47:06 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:47:06 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 15:47:06 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 15:47:06 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:47:06 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 15:47:06 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:47:06 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 15:47:06 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:47:06 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 15:47:06 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 15:47:06 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 15:47:06 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 15:47:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:47:06 INFO - https://queue.taskcluster.net/v1/task/XAKk8H5wSjiTkjP_j5fo8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:47:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XAKk8H5wSjiTkjP_j5fo8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:47:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XAKk8H5wSjiTkjP_j5fo8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:47:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XAKk8H5wSjiTkjP_j5fo8Q/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 15:47:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XAKk8H5wSjiTkjP_j5fo8Q/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 15:47:08 INFO - Downloaded 18463775 bytes. 15:47:08 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'] 15:47:08 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 15:47:11 INFO - Return code: 0 15:47:11 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 15:47:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:47:11 INFO - https://queue.taskcluster.net/v1/task/XAKk8H5wSjiTkjP_j5fo8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 15:47:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XAKk8H5wSjiTkjP_j5fo8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:47:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XAKk8H5wSjiTkjP_j5fo8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 15:47:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XAKk8H5wSjiTkjP_j5fo8Q/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 15:47:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XAKk8H5wSjiTkjP_j5fo8Q/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 15:47:12 INFO - Downloaded 18463775 bytes. 15:47:12 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'] 15:47:12 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 15:47:16 INFO - Return code: 0 15:47:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:47:16 INFO - https://queue.taskcluster.net/v1/task/XAKk8H5wSjiTkjP_j5fo8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 15:47:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XAKk8H5wSjiTkjP_j5fo8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 15:47:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XAKk8H5wSjiTkjP_j5fo8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 15:47:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XAKk8H5wSjiTkjP_j5fo8Q/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 15:47:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XAKk8H5wSjiTkjP_j5fo8Q/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 15:47:17 INFO - Downloaded 10840145 bytes. 15:47:17 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'] 15:47:17 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 15:47:17 INFO - Return code: 0 15:47:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:47:17 INFO - https://queue.taskcluster.net/v1/task/XAKk8H5wSjiTkjP_j5fo8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 15:47:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XAKk8H5wSjiTkjP_j5fo8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 15:47:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XAKk8H5wSjiTkjP_j5fo8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 15:47:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XAKk8H5wSjiTkjP_j5fo8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 15:47:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XAKk8H5wSjiTkjP_j5fo8Q/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 15:47:20 INFO - Downloaded 114158163 bytes. 15:47:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XAKk8H5wSjiTkjP_j5fo8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 15:47:20 INFO - mkdir: /builds/slave/test/properties 15:47:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:47:20 INFO - Writing to file /builds/slave/test/properties/build_url 15:47:20 INFO - Contents: 15:47:20 INFO - build_url:https://queue.taskcluster.net/v1/task/XAKk8H5wSjiTkjP_j5fo8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 15:47:21 INFO - Running post-action listener: _resource_record_post_action 15:47:21 INFO - Running post-action listener: set_extra_try_arguments 15:47:21 INFO - ##### 15:47:21 INFO - ##### Running populate-webroot step. 15:47:21 INFO - ##### 15:47:21 INFO - Running pre-action listener: _resource_record_pre_action 15:47:21 INFO - Running main action method: populate_webroot 15:47:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:47:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:47:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:47:21 INFO - '--tppagecycles', 15:47:21 INFO - '7'], 15:47:21 INFO - 'tests': ['tsvgm']}}, 15:47:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:47:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:47:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:47:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:47:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:47:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:47:21 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:47:21 INFO - 'talos_options': ['--disable-e10s'], 15:47:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:47:21 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:47:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:47:21 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:47:21 INFO - 'talos_options': ['--disable-e10s'], 15:47:21 INFO - 'tests': ['damp', 'tps']}, 15:47:21 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:47:21 INFO - 'tests': ['damp', 'tps']}, 15:47:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:47:21 INFO - 'tests': ['dromaeo_dom']}, 15:47:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:47:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:47:21 INFO - 'tests': ['a11yr', 15:47:21 INFO - 'ts_paint', 15:47:21 INFO - 'tpaint', 15:47:21 INFO - 'sessionrestore', 15:47:21 INFO - 'sessionrestore_no_auto_restore', 15:47:21 INFO - 'tabpaint']}, 15:47:21 INFO - 'other-e10s': {'tests': ['a11yr', 15:47:21 INFO - 'ts_paint', 15:47:21 INFO - 'tpaint', 15:47:21 INFO - 'sessionrestore', 15:47:21 INFO - 'sessionrestore_no_auto_restore', 15:47:21 INFO - 'tabpaint']}, 15:47:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:47:21 INFO - 'ts_paint', 15:47:21 INFO - 'tpaint', 15:47:21 INFO - 'sessionrestore', 15:47:21 INFO - 'sessionrestore_no_auto_restore', 15:47:21 INFO - 'tabpaint']}, 15:47:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:47:21 INFO - 'ts_paint', 15:47:21 INFO - 'tpaint', 15:47:21 INFO - 'sessionrestore', 15:47:21 INFO - 'sessionrestore_no_auto_restore', 15:47:21 INFO - 'tabpaint']}, 15:47:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:47:21 INFO - 'tests': ['a11yr', 15:47:21 INFO - 'ts_paint', 15:47:21 INFO - 'tpaint', 15:47:21 INFO - 'sessionrestore', 15:47:21 INFO - 'sessionrestore_no_auto_restore', 15:47:21 INFO - 'tabpaint']}, 15:47:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:47:21 INFO - 'tests': ['a11yr', 15:47:21 INFO - 'ts_paint', 15:47:21 INFO - 'tpaint', 15:47:21 INFO - 'sessionrestore', 15:47:21 INFO - 'sessionrestore_no_auto_restore', 15:47:21 INFO - 'tabpaint']}, 15:47:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:47:21 INFO - 'tests': ['tsvgx', 15:47:21 INFO - 'tsvgr_opacity', 15:47:21 INFO - 'tart', 15:47:21 INFO - 'tscrollx', 15:47:21 INFO - 'cart']}, 15:47:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:47:21 INFO - 'tsvgr_opacity', 15:47:21 INFO - 'tart', 15:47:21 INFO - 'tscrollx', 15:47:21 INFO - 'cart']}, 15:47:21 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:47:21 INFO - 'talos_options': ['--disable-e10s'], 15:47:21 INFO - 'tests': ['tp5o']}, 15:47:21 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:47:21 INFO - 'tests': ['tp5o']}, 15:47:21 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:47:21 INFO - 'talos_options': ['--disable-e10s', 15:47:21 INFO - '--xperf_path', 15:47:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:47:21 INFO - 'tests': ['tp5n']}, 15:47:21 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 15:47:21 INFO - 'talos_options': ['--xperf_path', 15:47:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:47:21 INFO - 'tests': ['tp5n']}}, 15:47:21 INFO - 'talos.zip': {'path': '', 15:47:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:47:21 INFO - Running post-action listener: _resource_record_post_action 15:47:21 INFO - ##### 15:47:21 INFO - ##### Running create-virtualenv step. 15:47:21 INFO - ##### 15:47:21 INFO - Running pre-action listener: _resource_record_pre_action 15:47:21 INFO - Running main action method: create_virtualenv 15:47:21 INFO - Creating virtualenv /builds/slave/test/build/venv 15:47:21 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 15:47:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:47:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:47:21 INFO - Using real prefix '/tools/python27' 15:47:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:47:22 INFO - Installing distribute.............................................................................................................................................................................................done. 15:47:25 INFO - Installing pip.................done. 15:47:25 INFO - Return code: 0 15:47:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:47:25 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')]} 15:47:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47:25 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')]} 15:47:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47:25 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 0x1051eea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051fe420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6c0c22b70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10508de00>, '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 0x104ef6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105088b20>, '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.yQkPQPJPKE/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.XBnOKfa7uS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/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 15:47:25 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 15:47:25 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 15:47:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yQkPQPJPKE/Render', 15:47:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:47:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:47:25 INFO - 'HOME': '/Users/cltbld', 15:47:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:47:25 INFO - 'LOGNAME': 'cltbld', 15:47:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:25 INFO - 'MOZ_NO_REMOTE': '1', 15:47:25 INFO - 'NO_EM_RESTART': '1', 15:47:25 INFO - 'PAGER': '/bin/cat', 15:47:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:47:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:25 INFO - 'PWD': '/builds/slave/test', 15:47:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:47:25 INFO - 'SHELL': '/bin/bash', 15:47:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XBnOKfa7uS/Listeners', 15:47:25 INFO - 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/', 15:47:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:47:25 INFO - 'USER': 'cltbld', 15:47:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:47:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:47:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:25 INFO - 'XPC_FLAGS': '0x0', 15:47:25 INFO - 'XPC_SERVICE_NAME': '0', 15:47:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:47:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:25 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 15:47:25 INFO - Cleaning up... 15:47:25 INFO - Return code: 0 15:47:25 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 15:47:25 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')]} 15:47:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47:25 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')]} 15:47:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47:25 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 0x1051eea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051fe420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6c0c22b70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10508de00>, '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 0x104ef6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105088b20>, '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.yQkPQPJPKE/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.XBnOKfa7uS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/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 15:47:25 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 15:47:25 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 15:47:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yQkPQPJPKE/Render', 15:47:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:47:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:47:25 INFO - 'HOME': '/Users/cltbld', 15:47:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:47:25 INFO - 'LOGNAME': 'cltbld', 15:47:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:25 INFO - 'MOZ_NO_REMOTE': '1', 15:47:25 INFO - 'NO_EM_RESTART': '1', 15:47:25 INFO - 'PAGER': '/bin/cat', 15:47:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:47:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:25 INFO - 'PWD': '/builds/slave/test', 15:47:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:47:25 INFO - 'SHELL': '/bin/bash', 15:47:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XBnOKfa7uS/Listeners', 15:47:25 INFO - 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/', 15:47:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:47:25 INFO - 'USER': 'cltbld', 15:47:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:47:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:47:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:25 INFO - 'XPC_FLAGS': '0x0', 15:47:25 INFO - 'XPC_SERVICE_NAME': '0', 15:47:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:47:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:25 INFO - Downloading/unpacking psutil>=0.7.1 15:47:25 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 15:47:25 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 15:47:25 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 15:47:25 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 15:47:25 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 15:47:25 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 15:47:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:47:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:47:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:47:28 INFO - Installing collected packages: psutil 15:47:28 INFO - Running setup.py install for psutil 15:47:28 INFO - building 'psutil._psutil_osx' extension 15:47:28 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 15:47:29 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 15:47:29 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 15:47:29 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 15:47:29 INFO - building 'psutil._psutil_posix' extension 15:47:29 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 15:47:29 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:47:29 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:47:29 INFO - ^ 15:47:29 INFO - 1 warning generated. 15:47:29 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 15:47:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:47:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:47:29 INFO - Successfully installed psutil 15:47:29 INFO - Cleaning up... 15:47:30 INFO - Return code: 0 15:47:30 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 15:47:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:47:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:47:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47:30 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1051eea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051fe420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6c0c22b70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10508de00>, '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 0x104ef6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105088b20>, '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.yQkPQPJPKE/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.XBnOKfa7uS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/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 15:47:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 15:47:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 15:47:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yQkPQPJPKE/Render', 15:47:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:47:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:47:30 INFO - 'HOME': '/Users/cltbld', 15:47:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:47:30 INFO - 'LOGNAME': 'cltbld', 15:47:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:30 INFO - 'MOZ_NO_REMOTE': '1', 15:47:30 INFO - 'NO_EM_RESTART': '1', 15:47:30 INFO - 'PAGER': '/bin/cat', 15:47:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:47:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:30 INFO - 'PWD': '/builds/slave/test', 15:47:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:47:30 INFO - 'SHELL': '/bin/bash', 15:47:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XBnOKfa7uS/Listeners', 15:47:30 INFO - 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/', 15:47:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:47:30 INFO - 'USER': 'cltbld', 15:47:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:47:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:47:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:30 INFO - 'XPC_FLAGS': '0x0', 15:47:30 INFO - 'XPC_SERVICE_NAME': '0', 15:47:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:47:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:30 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 15:47: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 15:47: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 15:47: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 15:47: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 15:47: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 15:47: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 15:47:32 INFO - Downloading mozsystemmonitor-0.0.tar.gz 15:47:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:47:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 15:47:32 INFO - Installing collected packages: mozsystemmonitor 15:47:32 INFO - Running setup.py install for mozsystemmonitor 15:47:32 INFO - Successfully installed mozsystemmonitor 15:47:32 INFO - Cleaning up... 15:47:33 INFO - Return code: 0 15:47:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:47:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:47:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:47:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1051eea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051fe420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6c0c22b70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10508de00>, '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 0x104ef6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105088b20>, '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.yQkPQPJPKE/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.XBnOKfa7uS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/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 15:47:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:47:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:47:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yQkPQPJPKE/Render', 15:47:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:47:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:47:33 INFO - 'HOME': '/Users/cltbld', 15:47:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:47:33 INFO - 'LOGNAME': 'cltbld', 15:47:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:33 INFO - 'MOZ_NO_REMOTE': '1', 15:47:33 INFO - 'NO_EM_RESTART': '1', 15:47:33 INFO - 'PAGER': '/bin/cat', 15:47:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:47:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:33 INFO - 'PWD': '/builds/slave/test', 15:47:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:47:33 INFO - 'SHELL': '/bin/bash', 15:47:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XBnOKfa7uS/Listeners', 15:47:33 INFO - 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/', 15:47:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:47:33 INFO - 'USER': 'cltbld', 15:47:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:47:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:47:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:33 INFO - 'XPC_FLAGS': '0x0', 15:47:33 INFO - 'XPC_SERVICE_NAME': '0', 15:47:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:47:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:33 INFO - Downloading/unpacking blobuploader==1.2.4 15:47:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:47:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:47:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:47:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:47:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:47:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:47:35 INFO - Downloading blobuploader-1.2.4.tar.gz 15:47:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:47:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:47:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:47:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:47:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:47:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:47:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:47:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:47:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:47:36 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:47:36 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 15:47:36 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 15:47:36 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 15:47:36 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 15:47:36 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 15:47:36 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 15:47:36 INFO - Downloading docopt-0.6.1.tar.gz 15:47:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:47:37 INFO - Installing collected packages: blobuploader, requests, docopt 15:47:37 INFO - Running setup.py install for blobuploader 15:47:37 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:47:37 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:47:37 INFO - Running setup.py install for requests 15:47:37 INFO - Running setup.py install for docopt 15:47:37 INFO - Successfully installed blobuploader requests docopt 15:47:37 INFO - Cleaning up... 15:47:37 INFO - Return code: 0 15:47:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:47:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:47:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:47:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47:37 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 0x1051eea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051fe420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6c0c22b70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10508de00>, '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 0x104ef6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105088b20>, '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.yQkPQPJPKE/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.XBnOKfa7uS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/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 15:47:37 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 15:47:37 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 15:47:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yQkPQPJPKE/Render', 15:47:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:47:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:47:37 INFO - 'HOME': '/Users/cltbld', 15:47:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:47:37 INFO - 'LOGNAME': 'cltbld', 15:47:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:37 INFO - 'MOZ_NO_REMOTE': '1', 15:47:37 INFO - 'NO_EM_RESTART': '1', 15:47:37 INFO - 'PAGER': '/bin/cat', 15:47:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:47:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:37 INFO - 'PWD': '/builds/slave/test', 15:47:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:47:37 INFO - 'SHELL': '/bin/bash', 15:47:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XBnOKfa7uS/Listeners', 15:47:37 INFO - 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/', 15:47:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:47:37 INFO - 'USER': 'cltbld', 15:47:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:47:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:47:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:37 INFO - 'XPC_FLAGS': '0x0', 15:47:37 INFO - 'XPC_SERVICE_NAME': '0', 15:47:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:47:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:47:38 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-6ZyyIO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:47:38 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-grj3D2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:47:38 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-DKL0lB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:47:38 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-WQoes6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:47:38 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-yzJ4R9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:47:38 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-q5zGU1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:47:38 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-hA6CRh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:47:38 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-xVPDxn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:47:38 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-IWswI1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:47:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:47:39 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-ZMnsCR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:47:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:47:39 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-G7nNAX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:47:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:47:39 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-idjgPD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:47:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:47:39 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-OTVetD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:47:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:47:39 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-RgoMJk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:47:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:47:39 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-IOujR1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:47:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:47:39 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-BqeuDZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:47:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:47:39 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-twRpUR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:47:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:47:39 INFO - Running setup.py install for manifestparser 15:47:40 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:47:40 INFO - Running setup.py install for mozcrash 15:47:40 INFO - Running setup.py install for mozdebug 15:47:40 INFO - Running setup.py install for mozdevice 15:47:40 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:47:40 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:47:40 INFO - Running setup.py install for mozfile 15:47:40 INFO - Running setup.py install for mozhttpd 15:47:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:47:40 INFO - Running setup.py install for mozinfo 15:47:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:47:40 INFO - Running setup.py install for mozInstall 15:47:41 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:47:41 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:47:41 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:47:41 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:47:41 INFO - Running setup.py install for mozleak 15:47:41 INFO - Running setup.py install for mozlog 15:47:41 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:47:41 INFO - Running setup.py install for moznetwork 15:47:41 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:47:41 INFO - Running setup.py install for mozprocess 15:47:41 INFO - Running setup.py install for mozprofile 15:47:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:47:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:47:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:47:41 INFO - Running setup.py install for mozrunner 15:47:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:47:41 INFO - Running setup.py install for mozscreenshot 15:47:42 INFO - Running setup.py install for moztest 15:47:42 INFO - Running setup.py install for mozversion 15:47:42 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:47:42 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:47:42 INFO - Cleaning up... 15:47:42 INFO - Return code: 0 15:47:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:47: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')]} 15:47:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47: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')]} 15:47:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47:42 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 0x1051eea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051fe420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6c0c22b70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10508de00>, '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 0x104ef6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105088b20>, '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.yQkPQPJPKE/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.XBnOKfa7uS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/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 15:47:42 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 15:47:42 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 15:47:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yQkPQPJPKE/Render', 15:47:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:47:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:47:42 INFO - 'HOME': '/Users/cltbld', 15:47:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:47:42 INFO - 'LOGNAME': 'cltbld', 15:47:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:42 INFO - 'MOZ_NO_REMOTE': '1', 15:47:42 INFO - 'NO_EM_RESTART': '1', 15:47:42 INFO - 'PAGER': '/bin/cat', 15:47:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:47:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:42 INFO - 'PWD': '/builds/slave/test', 15:47:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:47:42 INFO - 'SHELL': '/bin/bash', 15:47:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XBnOKfa7uS/Listeners', 15:47:42 INFO - 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/', 15:47:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:47:42 INFO - 'USER': 'cltbld', 15:47:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:47:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:47:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:42 INFO - 'XPC_FLAGS': '0x0', 15:47:42 INFO - 'XPC_SERVICE_NAME': '0', 15:47:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:47:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:47:42 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-y_mpzw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:47:42 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)) 15:47:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:47:42 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-hK49Pv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:47:42 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)) 15:47:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:47:42 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-xdMAVV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:47:43 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)) 15:47:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:47:43 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-MNSCM9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:47:43 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)) 15:47:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:47:43 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-8Pdtfo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:47:43 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)) 15:47:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:47:43 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-EMNq65-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:47:43 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)) 15:47:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:47:43 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-T7d15N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:47:43 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)) 15:47:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:47:43 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-yC_7Cr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:47:43 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)) 15:47:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:47:43 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-NnYDbg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:47:43 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)) 15:47:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:47:43 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-sHsHfJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:47:43 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)) 15:47:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:47:43 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-JQq3Fu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:47:43 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)) 15:47:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:47:43 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-2HFpZx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:47:43 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)) 15:47:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:47:43 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-KM24UK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:47:44 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)) 15:47:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:47:44 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-o8QNLb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:47:44 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)) 15:47:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:47:44 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-Pa3C1Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:47:44 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)) 15:47:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:47:44 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-ANq_7t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:47:44 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)) 15:47:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:47:44 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-om0o3F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:47:44 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)) 15:47:44 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)) 15:47:44 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)) 15:47:44 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)) 15:47:44 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)) 15:47:44 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)) 15:47:44 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:47:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:47:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:47:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:47:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:47:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:47:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:47:46 INFO - Downloading blessings-1.6.tar.gz 15:47:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:47:47 INFO - Installing collected packages: blessings 15:47:47 INFO - Running setup.py install for blessings 15:47:47 INFO - Successfully installed blessings 15:47:47 INFO - Cleaning up... 15:47:47 INFO - Return code: 0 15:47:47 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:47:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:47:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:47:47 INFO - Reading from file tmpfile_stdout 15:47:47 INFO - Current package versions: 15:47:47 INFO - blessings == 1.6 15:47:47 INFO - blobuploader == 1.2.4 15:47:47 INFO - docopt == 0.6.1 15:47:47 INFO - manifestparser == 1.1 15:47:47 INFO - mozInstall == 1.12 15:47:47 INFO - mozcrash == 0.17 15:47:47 INFO - mozdebug == 0.1 15:47:47 INFO - mozdevice == 0.48 15:47:47 INFO - mozfile == 1.2 15:47:47 INFO - mozhttpd == 0.7 15:47:47 INFO - mozinfo == 0.9 15:47:47 INFO - mozleak == 0.1 15:47:47 INFO - mozlog == 3.1 15:47:47 INFO - moznetwork == 0.27 15:47:47 INFO - mozprocess == 0.22 15:47:47 INFO - mozprofile == 0.28 15:47:47 INFO - mozrunner == 6.11 15:47:47 INFO - mozscreenshot == 0.1 15:47:47 INFO - mozsystemmonitor == 0.0 15:47:47 INFO - moztest == 0.7 15:47:47 INFO - mozversion == 1.4 15:47:47 INFO - psutil == 3.1.1 15:47:47 INFO - requests == 1.2.3 15:47:47 INFO - wsgiref == 0.1.2 15:47:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:47: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')]} 15:47:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47: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')]} 15:47:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47:47 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 0x1051eea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051fe420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6c0c22b70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10508de00>, '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 0x104ef6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105088b20>, '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.yQkPQPJPKE/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.XBnOKfa7uS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/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 15:47:47 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 15:47:47 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 15:47:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yQkPQPJPKE/Render', 15:47:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:47:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:47:47 INFO - 'HOME': '/Users/cltbld', 15:47:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:47:47 INFO - 'LOGNAME': 'cltbld', 15:47:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:47 INFO - 'MOZ_NO_REMOTE': '1', 15:47:47 INFO - 'NO_EM_RESTART': '1', 15:47:47 INFO - 'PAGER': '/bin/cat', 15:47:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:47:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:47 INFO - 'PWD': '/builds/slave/test', 15:47:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:47:47 INFO - 'SHELL': '/bin/bash', 15:47:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XBnOKfa7uS/Listeners', 15:47:47 INFO - 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/', 15:47:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:47:47 INFO - 'USER': 'cltbld', 15:47:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:47:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:47:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:47 INFO - 'XPC_FLAGS': '0x0', 15:47:47 INFO - 'XPC_SERVICE_NAME': '0', 15:47:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:47:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:47 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)) 15:47:47 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)) 15:47:47 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)) 15:47:47 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)) 15:47:47 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)) 15:47:47 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)) 15:47:47 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)) 15:47:47 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)) 15:47:47 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)) 15:47:47 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)) 15:47:47 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)) 15:47:47 INFO - Cleaning up... 15:47:47 INFO - Return code: 0 15:47:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:47: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')]} 15:47:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47: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')]} 15:47:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47:47 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 0x1051eea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051fe420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6c0c22b70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10508de00>, '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 0x104ef6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105088b20>, '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.yQkPQPJPKE/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.XBnOKfa7uS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/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 15:47:47 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 15:47:47 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 15:47:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yQkPQPJPKE/Render', 15:47:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:47:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:47:47 INFO - 'HOME': '/Users/cltbld', 15:47:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:47:47 INFO - 'LOGNAME': 'cltbld', 15:47:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:47 INFO - 'MOZ_NO_REMOTE': '1', 15:47:47 INFO - 'NO_EM_RESTART': '1', 15:47:47 INFO - 'PAGER': '/bin/cat', 15:47:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:47:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:47 INFO - 'PWD': '/builds/slave/test', 15:47:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:47:47 INFO - 'SHELL': '/bin/bash', 15:47:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XBnOKfa7uS/Listeners', 15:47:47 INFO - 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/', 15:47:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:47:47 INFO - 'USER': 'cltbld', 15:47:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:47:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:47:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:47 INFO - 'XPC_FLAGS': '0x0', 15:47:47 INFO - 'XPC_SERVICE_NAME': '0', 15:47:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:47:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:48 INFO - Downloading/unpacking jsonschema 15:47:48 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 15:47:48 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 15:47:48 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 15:47:48 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 15:47:48 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 15:47:48 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 15:47:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:47:50 INFO - Downloading/unpacking functools32 (from jsonschema) 15:47:50 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 15:47:50 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 15:47:50 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 15:47:50 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 15:47:50 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 15:47:50 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 15:47:50 INFO - Downloading functools32-3.2.3-2.tar.gz 15:47:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:47:51 INFO - warning: no files found matching '*.txt' 15:47:51 INFO - no previously-included directories found matching 'build' 15:47:51 INFO - no previously-included directories found matching 'dist' 15:47:51 INFO - no previously-included directories found matching '.git*' 15:47:51 INFO - Installing collected packages: jsonschema, functools32 15:47:51 INFO - Running setup.py install for functools32 15:47:51 INFO - warning: no files found matching '*.txt' 15:47:51 INFO - no previously-included directories found matching 'build' 15:47:51 INFO - no previously-included directories found matching 'dist' 15:47:51 INFO - no previously-included directories found matching '.git*' 15:47:51 INFO - Successfully installed jsonschema functools32 15:47:51 INFO - Cleaning up... 15:47:51 INFO - Return code: 0 15:47:51 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 15:47:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:47:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:47:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:47:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:47:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:47:51 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 0x1051eea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1051fe420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6c0c22b70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10508de00>, '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 0x104ef6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105088b20>, '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.yQkPQPJPKE/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.XBnOKfa7uS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/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 15:47:51 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 15:47:51 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 15:47:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yQkPQPJPKE/Render', 15:47:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:47:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:47:51 INFO - 'HOME': '/Users/cltbld', 15:47:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:47:51 INFO - 'LOGNAME': 'cltbld', 15:47:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:47:51 INFO - 'MOZ_NO_REMOTE': '1', 15:47:51 INFO - 'NO_EM_RESTART': '1', 15:47:51 INFO - 'PAGER': '/bin/cat', 15:47:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:47:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:47:51 INFO - 'PWD': '/builds/slave/test', 15:47:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:47:51 INFO - 'SHELL': '/bin/bash', 15:47:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XBnOKfa7uS/Listeners', 15:47:51 INFO - 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/', 15:47:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:47:51 INFO - 'USER': 'cltbld', 15:47:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:47:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:47:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:47:51 INFO - 'XPC_FLAGS': '0x0', 15:47:51 INFO - 'XPC_SERVICE_NAME': '0', 15:47:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:47:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:47:51 INFO - Downloading/unpacking flake8 15:47:51 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 15:47:51 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 15:47:51 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 15:47:51 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 15:47:51 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 15:47:51 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 15:47:54 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 15:47:54 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 15:47:54 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 15:47:54 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 15:47:54 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 15:47:54 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 15:47:54 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 15:47:54 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 15:47:54 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 15:47:54 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 15:47:54 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 15:47:54 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 15:47:54 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 15:47:54 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 15:47:54 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 15:47:54 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 15:47:54 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 15:47:54 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 15:47:54 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 15:47:54 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 15:47:54 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 15:47:54 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 15:47:54 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 15:47:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 15:47:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:47:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:47:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:47:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:47:55 INFO - no previously-included directories found matching 'docs/_build' 15:47:55 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 15:47:55 INFO - Running setup.py install for pep8 15:47:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 15:47:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 15:47:55 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 15:47:55 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 15:47:55 INFO - no previously-included directories found matching 'docs/_build' 15:47:55 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 15:47:55 INFO - Successfully installed flake8 mccabe pyflakes pep8 15:47:55 INFO - Cleaning up... 15:47:55 INFO - Return code: 0 15:47:55 INFO - Running post-action listener: _resource_record_post_action 15:47:55 INFO - Running post-action listener: _start_resource_monitoring 15:47:55 INFO - Starting resource monitoring. 15:47:55 INFO - ##### 15:47:55 INFO - ##### Running install step. 15:47:55 INFO - ##### 15:47:55 INFO - Running pre-action listener: _resource_record_pre_action 15:47:55 INFO - Running main action method: install 15:47:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:47:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:47:56 INFO - Reading from file tmpfile_stdout 15:47:56 INFO - Detecting whether we're running mozinstall >=1.0... 15:47:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:47:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:47:56 INFO - Reading from file tmpfile_stdout 15:47:56 INFO - Output received: 15:47:56 INFO - Usage: mozinstall [options] installer 15:47:56 INFO - Options: 15:47:56 INFO - -h, --help show this help message and exit 15:47:56 INFO - -d DEST, --destination=DEST 15:47:56 INFO - Directory to install application into. [default: 15:47:56 INFO - "/builds/slave/test"] 15:47:56 INFO - --app=APP Application being installed. [default: firefox] 15:47:56 INFO - mkdir: /builds/slave/test/build/application 15:47:56 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'] 15:47:56 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 15:48:27 INFO - Reading from file tmpfile_stdout 15:48:27 INFO - Output received: 15:48:27 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 15:48:27 INFO - Running post-action listener: _resource_record_post_action 15:48:27 INFO - ##### 15:48:27 INFO - ##### Running run-tests step. 15:48:27 INFO - ##### 15:48:27 INFO - Running pre-action listener: _resource_record_pre_action 15:48:27 INFO - Running main action method: run_tests 15:48:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:48:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:48:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:48:27 INFO - 2016-05-31 15:48:27.492 screenresolution[1653:7632] starting screenresolution argv=screenresolution get 15:48:27 INFO - 2016-05-31 15:48:27.495 screenresolution[1653:7632] Display 0: 1600x1200x32@60 15:48:27 INFO - 2016-05-31 15:48:27.501 screenresolution[1654:7635] starting screenresolution argv=screenresolution list 15:48:27 INFO - Available Modes on Display 0 15:48:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:48:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:48:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:48:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:48:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:48:27 INFO - Intel Iris: 15:48:27 INFO - Chipset Model: Intel Iris 15:48:27 INFO - Type: GPU 15:48:27 INFO - Bus: Built-In 15:48:27 INFO - VRAM (Dynamic, Max): 1536 MB 15:48:27 INFO - Vendor: Intel (0x8086) 15:48:27 INFO - Device ID: 0x0a2e 15:48:27 INFO - Revision ID: 0x0009 15:48:27 INFO - Displays: 15:48:27 INFO - Display: 15:48:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:48:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:48:27 INFO - Display Serial Number: 1600x1200 60 15:48:27 INFO - Main Display: Yes 15:48:27 INFO - Mirror: Off 15:48:27 INFO - Online: Yes 15:48:27 INFO - Rotation: Supported 15:48:27 INFO - Return code: 0 15:48:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:48:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:48:27 INFO - Python 2.7.3 15:48:27 INFO - Return code: 0 15:48:27 INFO - grabbing minidump binary from tooltool 15:48:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 15:48:27 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 0x10508de00>, '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 0x104ef6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105088b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:48:27 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 15:48:27 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 15:48:27 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:48:27 INFO - Return code: 0 15:48:27 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:48:27 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:48:27 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:48:27 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:48:27 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:48:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 15:48:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 15:48:28 INFO - Return code: 0 15:48:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XAKk8H5wSjiTkjP_j5fo8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0355', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 15:48:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XAKk8H5wSjiTkjP_j5fo8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0355 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 15:48:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.yQkPQPJPKE/Render', 15:48:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:48:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:48:28 INFO - 'HOME': '/Users/cltbld', 15:48:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:48:28 INFO - 'LOGNAME': 'cltbld', 15:48:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:48:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:48:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:48:28 INFO - 'MOZ_NO_REMOTE': '1', 15:48:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:48:28 INFO - 'NO_EM_RESTART': '1', 15:48:28 INFO - 'PAGER': '/bin/cat', 15:48:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:48:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:48:28 INFO - 'PWD': '/builds/slave/test', 15:48:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:48:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:48:28 INFO - 'SHELL': '/bin/bash', 15:48:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XBnOKfa7uS/Listeners', 15:48:28 INFO - 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/', 15:48:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:48:28 INFO - 'USER': 'cltbld', 15:48:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:48:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:48:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:48:28 INFO - 'XPC_FLAGS': '0x0', 15:48:28 INFO - 'XPC_SERVICE_NAME': '0', 15:48:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:48:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XAKk8H5wSjiTkjP_j5fo8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0355', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 15:48:29 INFO - mozversion application_buildid: 20160531143941 15:48:29 INFO - mozversion application_changeset: 3cc4a98864ad31001292b38c97df30962b9e94d0 15:48:29 INFO - mozversion application_display_name: FirefoxDeveloperEdition 15:48:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:48:29 INFO - mozversion application_name: Firefox 15:48:29 INFO - mozversion application_remotingname: firefox-dev 15:48:29 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:48:29 INFO - mozversion application_vendor: Mozilla 15:48:29 INFO - mozversion application_version: 48.0a2 15:48:29 INFO - mozversion platform_buildid: 20160531143941 15:48:29 INFO - mozversion platform_changeset: 3cc4a98864ad31001292b38c97df30962b9e94d0 15:48:29 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 15:48:29 INFO - mozversion platform_version: 48.0a2 15:48:29 INFO - using testdate: 1464734909 15:48:29 INFO - actual date: 1464734909 15:48:29 INFO - starting webserver on 'localhost:49275' 15:48:29 INFO - SUITE-START | Running 2 tests 15:48:29 INFO - TEST-START | dromaeo_css 15:48:29 INFO - operating with platform_type : mac_ 15:48:29 INFO - Initialising browser for dromaeo_css test... 15:48:29 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/tmp46whMe/profile http://localhost:49275/getInfo.html) 15:48:32 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 15:48:32 INFO - PROCESS | 1672 | colorDepth:24 15:48:32 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 15:48:32 INFO - PROCESS | 1672 | __metrics 15:48:32 ERROR - PROCESS | 1672 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2940: TypeError: this.tabs is undefined 15:48:32 INFO - TEST-INFO | 1672: exit 0 15:48:32 INFO - Browser initialized. 15:48:32 INFO - Running cycle 1/1 for dromaeo_css test... 15:48:32 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/tmp46whMe/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:48:44 INFO - PROCESS | 1675 | RSS: Main: 221257728 15:48:44 INFO - PROCESS | 1675 | 15:49:34 INFO - PROCESS | 1675 | 1464734974291 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] 15:51:45 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 15:51:46 INFO - PROCESS | 1675 | RSS: Main: 231251968 15:51:46 INFO - PROCESS | 1675 | 15:54:48 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 15:54:49 INFO - PROCESS | 1675 | RSS: Main: 243281920 15:54:49 INFO - PROCESS | 1675 | 15:57:51 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 15:57:51 INFO - PROCESS | 1675 | RSS: Main: 245448704 15:57:51 INFO - PROCESS | 1675 | 16:00:53 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 16:00:54 INFO - PROCESS | 1675 | RSS: Main: 246833152 16:00:54 INFO - PROCESS | 1675 | 16:03:56 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 16:03:57 INFO - PROCESS | 1675 | RSS: Main: 249761792 16:03:57 INFO - PROCESS | 1675 | 16:06:59 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 16:06:59 INFO - PROCESS | 1675 | RSS: Main: 251047936 16:06:59 INFO - PROCESS | 1675 | 16:06:59 INFO - PROCESS | 1675 | __start_tp_report 16:06:59 INFO - PROCESS | 1675 | _x_x_mozilla_page_load 16:06:59 INFO - PROCESS | 1675 | _x_x_mozilla_page_load_details 16:06:59 INFO - PROCESS | 1675 | |i|pagename|runs| 16:06:59 INFO - PROCESS | 1675 | |0;dojo.html;3126.7811253212276;3466.6186719460857;3471.0801637566065;3524.5946716127637;3551.8401671924776;5005.949940500603;5738.053221218499;5754.453326933945;5778.711064446777;5789.797357092502;5783;5801.593888427812;5803.796867109652;5822.097574875901;5824;5246.658967167132;5287.233351164079;5347;5355.169948657945;5393.2449457076045;4343.2182207202695;5304.99205151022;5327.7336133193185;5405.837824865261;5452.945470545284;5367.221752845835;5389.407165211824;5390.245365648812;5400.270963419947;5404.027275090482;5320.148776195829;5347.25138480613;5386.973065134669;5391.568674506049;5454.318210223722;5146.588272938194;5156.226566015091;5200.141976573898;5212.895742085175;5243.79024839006;4837.516248375169;5664.065429204217;5666.744996475169;5668.97165514174;5709.200711900337;6122.916604401426;6194.690265486753;6230.003199488106;6254.781082662085;6295.433410993032;6166.969165154191;6230.2523697156175;6230.470410015108;6260.280067792171;6288.842778930518;5927.022041363213;5968.313643930916;5984.677872401772;5986.790462333797;6022.488088512482;6158.907616385758;6247.718852651642;6254.968725156436;6299.842503937438;6359.618422894641;6180.412860778219;6180.845478862974;6239.78160764371;6261.8747625047245;6282.183316168869;6233.563650544418;6237.4698150657705;6251.812445626639;6253.093301471261;6276.184096067574;5129.410117836422;6175.44421002112;6234.750609975641;6234.9688251559355;6260.342664020303;6225.28409232944;6228.906566401508;6231.968840155861;6234.812955611339;6274.6235225885675;4894.853154405374;4921.26181072842;4953.0589188054155;4964.255361695702;4974.77613507393;4395.1429471252795;4442.178197033494;4516.683832131785;4520.276755719069;4523.5024147343765;5710.8001219957105;5793.797217097382;5793.942060579425;5848.61983971049;5855.853603659857;5654.293213348332;6185.690715464209;6219.906701399483;6230.844228894314;6241.188645476059;4795.856124316276;5809.477147056785;5873.618214816023;5873.706314684248;5890.2342695449315;5325.733713314319;5332.06688829461;5333.946660533423;5340.599455040888;5348.866278343073;6237.937620623827;6299.874002519924;6338;6340.968295158495;6352.809415717536;6099.481544068626;6131.662758548145;6252.843678908063;6259.561830671809;6299.401556852092;5449.2371068049715;6420.0048992406255;6420.1974753155855;6455.870882582228;6457.67711614408;5178.818741344186;6207.541352112209;6233.875322493615;6257.28041275241;6258.780942666985;6127.464420129145;6140.565599444434;6161.075838624242;6210.875782484415;6231.937680623317;5970.611755297916;6128.295246046761;6141.324454309933;6160.075988601746;6179.196704428396;5945.732442040205;6134.202553667995;6168.753249869955;6180.783672571439;6195.19462469876;5888.587798854211;5934.376890426615;6025.698715064142;6068.362821903813;6118.143459915526;6162.322144564005;6171.814845554641;6186.938130618636;6196.287426945959;6208.161898143693;5935.673537955454;5992.340842507234;6108.419700128481;6114.877702446015;6141.416565426277 16:06:59 INFO - PROCESS | 1675 | |1;ext.html;494.2635473145013;536.6779932040776;581.206652918766;593.3295376224867;606.4935778624848;38075.61924380755;39165;39170.41244381333;39271;39306.60693393065;40614.59385406145;40924.79537602308;41257.793711031474;41464.17071658565;41475;9356.298277629188;9482.099200575969;9611.086946740048;9625;9639.373440726366;9485.193758530533;9719.416834989888;9738.415695058284;9760.560774765117;9791.11879930806;9653;9704.611815527405;9757.804843903154;9760.975097614719;9775.266854985868;9716.514174291293;9746.90253097467;9750.853737193947;9764.365316254492;9788.021197880225;9597.376170548892;9704.51477426133;9715.951420242924;9720.027997200294;9744.464054477;39802;39993.60006399928;40284.39573406401;40449.79775101106;40851.18297634031;2324.418895276181;3239.1254361322544;3290.7531935104726;3330.300636866261;3367.074054634995;26651.33371665724;26860.194194174204;26921.46157076867;27084.729152708416;27191.45617087667;2725.9182224533297;2778.1514530987697;2810.4519618674367;2837.886484540616;2909.1418031680705;290289;301124;320876;352637.2368138169;355380;57492.42507574871;60543.78912421727;60573.09140362898;60692.39307606956;60705.39294607086;66149;69021.61956760837;69551;70420.64789676019;70527;17614.2073606688;17677.20452579637;17759.911200444174;17805.554861128574;17875.37436189727;13532.052756306965;13603.31983400826;13645.863541364657;13655.726885462234;13670.726585468235;19274.650774445934;19412.22351105968;19572.608547829248;19657.606847863248;19933.800661993486;7909.960450197827;7995.600219988977;7995.640196191069;8014.919850801417;8067.636956336979;962.5427921737119;963.9252233759448;994.7562847102506;1010.8281592129356;1134.5235001299475;28794.136175914755;30350.241243968634;30832.69167308343;30864.382712346076;31018.68981310158;1058.9364638121738;1073.500822117719;1084.7938891610568;1094.2941802537478;1121.607437396921;12714.554990575285;13501.59495215145;14458.493952711604;14585.343659535347;14787.778183327258;461466;520078;526237;527355.3632231815;528863;59604.403955960755;60465.395346046855;61325.08012379818;61359.69320153371;61386.772264554456;1466.6993266380366;1480.9037412568364;1482.5255918106318;1489.8137732783402;1498.1610298233024;28470.43059138847;28693.856530717214;29426.411471770018;29497.557536637396;29507.557386639648;53144.46855531395;59486;60077;61457.692711536154;61669.38330616636;485419;492574;496320;497526;501831;39662;40927;41043.97390065272;42176.36735449032;42903.356449653904;44628.55371446374;45313;46414.76792616015;48225.5177448221;49679;35400;36165.27669446649;36496.81751591225;36994.07514812151;37481.6251837489;35500.467492988144;35588.82205589059;36748.63251367452;36783.63216367802;37139;33263.83368083144;36968.89093327204;37747.81126094352;38272;38281.042973925876;29714.405711886073;32547.34905301928;34933.12667183341;35149.64850351464;35273.47089793706;23336.533269334857;24457.633135503336;24483.877580611985;24677.136300229406;24808.627870581593 16:06:59 INFO - PROCESS | 1675 | |2;jquery.html;353.7187935591208;383.68346114455574;398.5934346966096;399.56447472255246;413.14272883766193;19244.23023079077;19868.90065549674;19912.20351185951;20356.98215089247;20391.796082039175;16918.915405422988;20269;21139.894300528515;21196.576068478622;21381.251656192035;5015.949840501585;5512.062949298609;5880.882382352351;6091.78678746244;6097.329293777703;3439.7076248518906;4371.256886329315;6493.123428337185;6625.801225963229;6635.104260924762;6597.076409302677;6598.076269322299;6600.042993765925;6620.205575330967;6629.8342541436605;5718.228039214715;5994.13085102662;6049.304330002018;6061.787837425713;6069.848253793669;5727.083666613322;5771.307443106854;5826.592138550303;5847.239858818369;5873.882522349529;12870;12873.420696068606;13146.671333216746;13205.405756740874;13250.66873328165;2195.4182141732294;2214.7342318921833;2235.798778109978;2374.916877909282;2795.65054368204;9642.517874106337;10092.495375231209;10096.29325947184;13437.66405839852;13799.793003104962;1414.8655877691604;1765.5497848048794;1779.4572655340116;1812.2932056497975;1840.6962851129547;113891.86108138895;115873.42063289629;117648.41175794152;123570;124252.75747242503;11522.76954460906;11671;11685.123615728855;11687.415629218674;11746.530138794524;14812.555623331318;14978.400863965537;14987.775183372258;15012.324445399983;15016.699666006838;8887.777805554784;8957.731268061969;8958.596863141043;9023.864642030374;9046.502442365732;9025.593848276843;9031.909680903238;9063.909360906306;9121.726348209564;9136.771580710403;4217.536071032184;4381.255186618282;4622.9688873002215;4692.554207350297;4742.905141897143;1930.6042261336672;1984.295575070858;2014.919403223884;2040.9285675001304;2044.407121934652;906.4153620914474;915.4187091197138;967.5549247346158;1027.3168343051786;1033.7467320506523;19303.710444343345;20247.08888100039;20287.18851245963;20306.289279875135;20629.381118566165;926.7358802741186;934.0752654871628;934.247930416008;943.4386540008684;961.7259081161835;9526;10839.620613278497;10947.646256054828;10988.780224395467;11077.279976801483;603507;611566;616587;649620;664443;132162;136239;140939;141573.29213353866;145294;997.9272282296561;1037.2687255484866;1038.6676263595728;1058.7459009837582;1074.666853275487;18006.639867202844;18091.638167236844;18122;18510;18667.626647467245;53328;54178.91642167213;55581.72209138928;57138.57153571194;57582.71208644097;62916;74388.62805685936;80931;83207.5839620798;83543;8081.797955051171;8088.838223235384;8172.877406839021;8211.95894020526;8335.666573337;8367.832643347221;11318.547258109913;12471.812922806346;12561.685957851127;12826.102172847812;10306.33008854422;10958.397288149228;11018.944905275423;11102.722431938942;11160.944195278971;8609.65561377538;10349.068583827491;10409;10682.89317106819;10739.731506712395;11290.717732056435;11351.432428378714;11473.024792892698;11592.942035289769;11628.6511404658;7212.4951253411755;7822.569758663328;8196.672133114847;8237.176282371714;8466;10558.419286939292;10889.509972051435;11073.5570577176;11103.833442498531;11318.94340528292 16:06:59 INFO - PROCESS | 1675 | |3;mootools.html;1421.2822524625062;1583.6515966487373;1764.21492435866;1858.8327050565438;1918.8273055425009;7731.845363092735;8341.290990265834;8520.40357174998;8750.912490875096;8762.780930476732;8446.535440550768;8474.618642161118;8587.012493563243;8604.440711353758;8633.48199108054;2401.903923843044;2402.1232250228713;2403.67550380699;2423.7091549014144;2432.7932125769244;2386.1648423051965;2407.7592240775957;2414.4205390706193;2420.1166574200465;2429.2955043037587;2398.496315773681;2427.66012758214;2436.597961336386;2447.19242649926;2448.6939132608422;2407.4944261705064;2410.4455975125734;2422.2248880358293;2426.502566973775;2437.4515733959715;2393.5571919195004;2421.285720712394;2424.248482970267;2426.4904370082127;2435.3059378077164;8725.650973961034;8738.519381434018;8814.162654547808;8815.942086949542;8819.073997230262;5284.5772338212855;5356.009138309425;5443.618946673734;5451.618386712932;5469.589780766419;3417.5215469834243;3424.708899743525;3462.0306314232057;3472.6874581287807;3494.9650503494895;1035.6323505155638;1038.0812980512226;1049.5801679328254;1067.4075887882223;1081.3674000709593;11801.704957376049;11872.40637968098;11898.821517677241;11919.284842909366;11933.88066119345;4728.787204575802;4758.452777930513;4759.6668233223345;4786.856394308176;4796.880077998078;9962.103410692927;10042.497875106215;10064.295499314978;10078.798424031478;10103.646372376932;5345.064613692588;5397.298351214317;5414.7834086636885;5417.485338892799;5418.83743487696;4349.195398851222;4741.502142275081;4840.080384726891;4864.197407427735;4921.409430868319;3456;3975.522937247491;4014.3376342903093;4089.3661482470266;4101.425800387948;1367.9931600341934;1370.1094288712218;1377.7726675098697;1390.5411214299256;1393.8048673185763;818.292177266672;824.4105464592845;860.5654144656908;939.9295052871062;951.6145960885852;5808.128780682931;5875.353711091777;5930.673812940244;5963.940360596339;6022.638641681513;911.4485736129546;942.472215559289;952.319091849331;956.258899353007;985.5121714751101;3037.665856755755;3159.573457583243;3988.3419235826345;3997.620226078518;4020.8994775130855;10391.168706503462;10679.519421626046;10728.624498142528;10736.516856741466;10785.191110666732;10438.425886576159;10515.737106572396;10588.364698117983;10601;10666.679999600025;7237.819054523679;8283.25450709439;8317.625706843206;8400.789980250544;8457.450265732708;4904;5706.343770466449;5793.478586927196;5824.621399609012;5849.151872978479;9038.096190381173;9186.816263674555;9333.486658233862;9376.343655944018;9508.904910951078;9384.718458446258;9512.286578506779;9567.665131720356;9628.951855240679;9689.854652180082;6747.932520674731;7179.784606461605;7226.927730722836;7452.06849143857;7470.514416562906;8119.269265765874;8174.141715119827;8192;8229.588520574067;8236.958815205886;7121.750738724327;7286.234945330664;7292.307230813064;7355.926440735524;7441.1070671519765;6217.564770466205;7173.569585824867;7186.317299856505;7220.535851793724;7300.598467084362;6945.791626251221;6999.1950925644205;7014.368706816411;7081.221065682667;7097.964510177416;4801.327814106098;4851.781669824937;4881.780319885543;4909.1654418748185;4995.200767877122;4320.697551171388;4915.557599816034;4940.654154209171;4971.130052240916;5038.798448062036 16:06:59 INFO - PROCESS | 1675 | |4;prototype.html;311.4533992842561;316.61056900012983;339.99830000849994;343.1370104187966;345.55596059064106;6564.868702625945;6685.398314151726;6809.250982391939;6872.656367181634;6875.243723190451;6190.3809619038075;6712.697928593225;6891.276415976313;6897.379235868771;6915.0318955346165;5053.393592768854;5089.541941225289;5093.745312734358;5100.974495127519;5115.104856650071;4292.484901811787;5105.642605017651;5106.055379754739;5108.208227724708;5115.386153661566;4933.777979990891;5022.623303252252;5042.142835717918;5043.747812609373;5046.772895219724;4911.187959287927;5006.123928312567;5036.9926014797;5048.7223202723835;5087.135187018216;3637.7999210043436;4327.459067616548;4351.195028919628;4354.368616550582;4362.760048197348;6567.376099270562;6567.802965911031;6572.277049524549;6580.835479113012;6608.008798680188;5960.970195148997;5962.761489540413;5973.342932277446;5978.940210597882;5984.880302393928;7396.149442814091;7398.778036658909;7406;7418.443616728767;7442.516236444614;4505.5269196734525;4719.174144524729;4723.338732577442;4856.125897338477;4868.391451068616;6287.4655654269445;7721.884171737428;7728.6522106505345;7797.922020779776;7807.219278072147;4712.75112095295;4759.881002974884;4765.761711914391;4769.165396055676;4770.260609605517;4013.0769922917893;4035.3543433050572;4090.897727556835;4138.710290279651;4863.2461968394955;5814.476697097281;5837.357890632027;5864.648121112747;5877.941220587825;5878.794242201502;5438.592105592096;6491.772787952399;6765.458763298924;7411.6664750086375;7458.179600243969;1667.3080671521184;1667.7415000674914;1686.5867862373552;1688.0462538665615;1698.0490925081995;999.6751055906859;1054.0039662518845;1054.1882750282393;1072.538808312433;1076.822324316479;6706.899396509057;6891.414229790424;6949.166100067923;6962.373386395248;6964.547304425196;7801.258880406353;7801.81549166223;7804.570748608768;7804.960975195201;7805.765827025199;6865.519413640997;6917.584944903322;6924.169099707967;6947.617881016593;6972;3966.2265858157384;4029.7380670256925;4045.815259759697;4102.446169767103;4105.97947010263;8035.919640803634;8098.595070246464;8110.3511719062335;8130.552819594979;8144.226298501633;8027;8049.87925181111;8051.194880512019;8062.355011599058;8062.919370806334;6630.569013014138;7948.887155798193;8092.271695547429;8098.028236611526;8100.756977290691;6538.117354157128;7533.434992375484;7759.224077592421;7790.14308426084;7809.804754881174;7608.277213664693;7638.15980242184;7639.88540171909;7650.540967541965;7669.846603068019;6667.49993750461;8004.639791209526;8074.232947869943;8088.676452942052;8104.351651867719;4455.465344158722;4476.858401107696;4801.831935882367;4812.831550895702;4820.108279968217;5912.497437717842;7582.582957937367;7597.696092156252;7752.65113069902;7774.222577742181;7259.12890453149;7279.381252593377;7299.978003079679;7311.8537629248185;7314;7340.522866013692;7410.407167426485;7437.181909989788;7447.404207663266;7465.029546158965;7054.142223912621;7244.058272424551;7256.2018178001435;7267.055282813412;7274.563526188445;7343.228960959236;7426.517276377018;7430.702771889064;7443;7471.850562988819;6992.300769922967;7157.498975071695;7340.15588207363;7348.926510735038;7354.889676654748 16:06:59 INFO - PROCESS | 1675 | |5;yui.html;689.1764341611773;702.7751119641714;739.9149097853746;741.4365082537277;764.8699721047418;14793.186374749384;14917.955743097988;14964.251787410612;14998.32507537162;15024.549263522098;13687.17876927385;13701.588952331445;15286;15558.611034724137;15620.2970866311;769.2922511289627;949.9762505937372;962.1773383756893;967.3808762392075;971.7716336660915;898.0884402331606;971.8007808399296;973.0512750068697;973.2992245583204;974.8586454964026;1002.6490728245129;1009.2884516415946;1015.8425444056184;1018.3024628129755;1019.0777346501428;945.8297506448837;1013.513513513514;1015.2132097624326;1016.227666973102;1017.1710056304089;703.8662654095706;734.3170851108467;767.719782279464;769.7690692792196;771.8687823070092;12820.487180512768;13853.930730346385;14809.333579988926;14841.925790371088;14887.702245955128;12676.492940282378;12915.806262906064;12916.935415322958;13083.542076027388;13096.279704616243;9076.591553380114;10123.544440500122;10282.228832837567;10444.321119127233;10601.522931468102;3613.4218525035867;3638.963610363889;3665.1020499977676;3747.9437808432895;3749.850005999757;66923;81645.18354816435;82220.9999999994;82712.58643706804;83629.58185209095;9655.951720241354;9742.269329800294;9796.804063918824;9842.065003824626;10013.449260290608;21729.782702173092;22082;22473.88763056174;22844.543109137725;22895.96968136438;7654.961725191339;7757.26306000929;7882.5270483771155;7948.801279967932;8035.276825085771;3454;4095.6313931746286;4097.89755256115;4126.216018956388;4159.563245859202;3648.0515066083008;3675.044488432973;3693.4829123922673;3714.925701485955;3719.0330514065995;660.1088530483809;670.4167374384317;677.7864972533636;695.7738734911073;703.0754557756534;475.61712821178594;607.4168797954013;608.5587948737218;632.0235236559527;641.483605697418;7431.3683336916965;8202.097769245378;8377.748667539861;8379.287760540423;8493.27807136388;621.3817251834454;622.8816524860353;634.0837489827155;645.1741770533724;649.7076315657878;3802.9429558556644;3855.614438556178;3908.3746600543777;3926.4895563577124;4023.5372932113182;91085.08914910899;92173;93534.53232733793;95322.52338738262;95866.0413395871;68415;70146.64926675335;75547;76476;79638.20361796423;49482;68162.65918670375;68342.97485537721;69020.3097969024;69607.65196173987;7650.6174691264105;7815.324573698142;7836.647350869111;7947.046354437504;8143.75568732939;47629.28556071731;49288;49419.50580494149;50228.74428139326;50612.48162554982;47457.28814067723;66417.66791166013;68679.65660171867;70793.29206707867;72153;10160.796784064425;10260.692179234635;10311.123554497952;10470.685879423629;10787.838182427427;10717.41054241993;10769.62306319275;10871.184661150604;11054.944725276322;11693.298402095901;10245.180385569285;10374.429406382722;10424.478776061014;10775.515101820594;10786.696274857408;10147.695569132939;10275.7431064221;10363.792724145324;10624.415657138623;10661.200409969128;10118.732136930716;10243.334183278062;10381.740456488647;10427.322224055411;10529.736756581147;8822.470651760916;9007.594658240263;9077.863832042656;9113.817723645358;9320.906790932004;8712.607932642919;8822.382433229612;8894.644214231617;8931.017588065437;9020.639174432948 16:06:59 INFO - PROCESS | 1675 | __end_tp_report 16:06:59 INFO - PROCESS | 1675 | __start_cc_report 16:06:59 INFO - PROCESS | 1675 | _x_x_mozilla_cycle_collect,412 16:06:59 INFO - PROCESS | 1675 | __end_cc_report 16:06:59 INFO - PROCESS | 1675 | __startTimestamp1464736019703__endTimestamp 16:06:59 INFO - PROCESS | 1675 | 16:06:59 INFO - PROCESS | 1675 | ------- Summary: start ------- 16:06:59 INFO - PROCESS | 1675 | Number of tests: 6 16:06:59 INFO - PROCESS | 1675 | 16:06:59 INFO - PROCESS | 1675 | [#0] dojo.html Cycles:165 Average:5757.72 Median:5986.79 stddev:643.51 (10.7%) stddev-sans-first:611.49 16:06:59 INFO - PROCESS | 1675 | Values: 3126.8 3466.6 3471.1 3524.6 3551.8 5005.9 5738.1 5754.5 5778.7 5789.8 5783.0 5801.6 5803.8 5822.1 5824.0 5246.7 5287.2 5347.0 5355.2 5393.2 4343.2 5305.0 5327.7 5405.8 5452.9 5367.2 5389.4 5390.2 5400.3 5404.0 5320.1 5347.3 5387.0 5391.6 5454.3 5146.6 5156.2 5200.1 5212.9 5243.8 4837.5 5664.1 5666.7 5669.0 5709.2 6122.9 6194.7 6230.0 6254.8 6295.4 6167.0 6230.3 6230.5 6260.3 6288.8 5927.0 5968.3 5984.7 5986.8 6022.5 6158.9 6247.7 6255.0 6299.8 6359.6 6180.4 6180.8 6239.8 6261.9 6282.2 6233.6 6237.5 6251.8 6253.1 6276.2 5129.4 6175.4 6234.8 6235.0 6260.3 6225.3 6228.9 6232.0 6234.8 6274.6 4894.9 4921.3 4953.1 4964.3 4974.8 4395.1 4442.2 4516.7 4520.3 4523.5 5710.8 5793.8 5793.9 5848.6 5855.9 5654.3 6185.7 6219.9 6230.8 6241.2 4795.9 5809.5 5873.6 5873.7 5890.2 5325.7 5332.1 5333.9 5340.6 5348.9 6237.9 6299.9 6338.0 6341.0 6352.8 6099.5 6131.7 6252.8 6259.6 6299.4 5449.2 6420.0 6420.2 6455.9 6457.7 5178.8 6207.5 6233.9 6257.3 6258.8 6127.5 6140.6 6161.1 6210.9 6231.9 5970.6 6128.3 6141.3 6160.1 6179.2 5945.7 6134.2 6168.8 6180.8 6195.2 5888.6 5934.4 6025.7 6068.4 6118.1 6162.3 6171.8 6186.9 6196.3 6208.2 5935.7 5992.3 6108.4 6114.9 6141.4 16:06:59 INFO - PROCESS | 1675 | 16:06:59 INFO - PROCESS | 1675 | [#1] ext.html Cycles:180 Average:60412.56 Median:39288.80 stddev:120167.69 (305.9%) stddev-sans-first:120420.59 16:06:59 INFO - PROCESS | 1675 | Values: 494.3 536.7 581.2 593.3 606.5 38075.6 39165.0 39170.4 39271.0 39306.6 40614.6 40924.8 41257.8 41464.2 41475.0 9356.3 9482.1 9611.1 9625.0 9639.4 9485.2 9719.4 9738.4 9760.6 9791.1 9653.0 9704.6 9757.8 9761.0 9775.3 9716.5 9746.9 9750.9 9764.4 9788.0 9597.4 9704.5 9716.0 9720.0 9744.5 39802.0 39993.6 40284.4 40449.8 40851.2 2324.4 3239.1 3290.8 3330.3 3367.1 26651.3 26860.2 26921.5 27084.7 27191.5 2725.9 2778.2 2810.5 2837.9 2909.1 290289.0 301124.0 320876.0 352637.2 355380.0 57492.4 60543.8 60573.1 60692.4 60705.4 66149.0 69021.6 69551.0 70420.6 70527.0 17614.2 17677.2 17759.9 17805.6 17875.4 13532.1 13603.3 13645.9 13655.7 13670.7 19274.7 19412.2 19572.6 19657.6 19933.8 7910.0 7995.6 7995.6 8014.9 8067.6 962.5 963.9 994.8 1010.8 1134.5 28794.1 30350.2 30832.7 30864.4 31018.7 1058.9 1073.5 1084.8 1094.3 1121.6 12714.6 13501.6 14458.5 14585.3 14787.8 461466.0 520078.0 526237.0 527355.4 528863.0 59604.4 60465.4 61325.1 61359.7 61386.8 1466.7 1480.9 1482.5 1489.8 1498.2 28470.4 28693.9 29426.4 29497.6 29507.6 53144.5 59486.0 60077.0 61457.7 61669.4 485419.0 492574.0 496320.0 497526.0 501831.0 39662.0 40927.0 41044.0 42176.4 42903.4 44628.6 45313.0 46414.8 48225.5 49679.0 35400.0 36165.3 36496.8 36994.1 37481.6 35500.5 35588.8 36748.6 36783.6 37139.0 33263.8 36968.9 37747.8 38272.0 38281.0 29714.4 32547.3 34933.1 35149.6 35273.5 23336.5 24457.6 24483.9 24677.1 24808.6 16:06:59 INFO - PROCESS | 1675 | 16:06:59 INFO - PROCESS | 1675 | [#2] jquery.html Cycles:180 Average:35921.83 Median:21288.91 stddev:105180.20 (494.1%) stddev-sans-first:105441.35 16:06:59 INFO - PROCESS | 1675 | Values: 353.7 383.7 398.6 399.6 413.1 19244.2 19868.9 19912.2 20357.0 20391.8 16918.9 20269.0 21139.9 21196.6 21381.3 5015.9 5512.1 5880.9 6091.8 6097.3 3439.7 4371.3 6493.1 6625.8 6635.1 6597.1 6598.1 6600.0 6620.2 6629.8 5718.2 5994.1 6049.3 6061.8 6069.8 5727.1 5771.3 5826.6 5847.2 5873.9 12870.0 12873.4 13146.7 13205.4 13250.7 2195.4 2214.7 2235.8 2374.9 2795.7 9642.5 10092.5 10096.3 13437.7 13799.8 1414.9 1765.5 1779.5 1812.3 1840.7 113891.9 115873.4 117648.4 123570.0 124252.8 11522.8 11671.0 11685.1 11687.4 11746.5 14812.6 14978.4 14987.8 15012.3 15016.7 8887.8 8957.7 8958.6 9023.9 9046.5 9025.6 9031.9 9063.9 9121.7 9136.8 4217.5 4381.3 4623.0 4692.6 4742.9 1930.6 1984.3 2014.9 2040.9 2044.4 906.4 915.4 967.6 1027.3 1033.7 19303.7 20247.1 20287.2 20306.3 20629.4 926.7 934.1 934.2 943.4 961.7 9526.0 10839.6 10947.6 10988.8 11077.3 603507.0 611566.0 616587.0 649620.0 664443.0 132162.0 136239.0 140939.0 141573.3 145294.0 997.9 1037.3 1038.7 1058.7 1074.7 18006.6 18091.6 18122.0 18510.0 18667.6 53328.0 54178.9 55581.7 57138.6 57582.7 62916.0 74388.6 80931.0 83207.6 83543.0 8081.8 8088.8 8172.9 8212.0 8335.7 8367.8 11318.5 12471.8 12561.7 12826.1 10306.3 10958.4 11018.9 11102.7 11160.9 8609.7 10349.1 10409.0 10682.9 10739.7 11290.7 11351.4 11473.0 11592.9 11628.7 7212.5 7822.6 8196.7 8237.2 8466.0 10558.4 10889.5 11073.6 11103.8 11318.9 16:06:59 INFO - PROCESS | 1675 | 16:06:59 INFO - PROCESS | 1675 | [#3] mootools.html Cycles:180 Average:5647.63 Median:4931.03 stddev:3133.53 (63.5%) stddev-sans-first:3126.23 16:06:59 INFO - PROCESS | 1675 | Values: 1421.3 1583.7 1764.2 1858.8 1918.8 7731.8 8341.3 8520.4 8750.9 8762.8 8446.5 8474.6 8587.0 8604.4 8633.5 2401.9 2402.1 2403.7 2423.7 2432.8 2386.2 2407.8 2414.4 2420.1 2429.3 2398.5 2427.7 2436.6 2447.2 2448.7 2407.5 2410.4 2422.2 2426.5 2437.5 2393.6 2421.3 2424.2 2426.5 2435.3 8725.7 8738.5 8814.2 8815.9 8819.1 5284.6 5356.0 5443.6 5451.6 5469.6 3417.5 3424.7 3462.0 3472.7 3495.0 1035.6 1038.1 1049.6 1067.4 1081.4 11801.7 11872.4 11898.8 11919.3 11933.9 4728.8 4758.5 4759.7 4786.9 4796.9 9962.1 10042.5 10064.3 10078.8 10103.6 5345.1 5397.3 5414.8 5417.5 5418.8 4349.2 4741.5 4840.1 4864.2 4921.4 3456.0 3975.5 4014.3 4089.4 4101.4 1368.0 1370.1 1377.8 1390.5 1393.8 818.3 824.4 860.6 939.9 951.6 5808.1 5875.4 5930.7 5963.9 6022.6 911.4 942.5 952.3 956.3 985.5 3037.7 3159.6 3988.3 3997.6 4020.9 10391.2 10679.5 10728.6 10736.5 10785.2 10438.4 10515.7 10588.4 10601.0 10666.7 7237.8 8283.3 8317.6 8400.8 8457.5 4904.0 5706.3 5793.5 5824.6 5849.2 9038.1 9186.8 9333.5 9376.3 9508.9 9384.7 9512.3 9567.7 9629.0 9689.9 6747.9 7179.8 7226.9 7452.1 7470.5 8119.3 8174.1 8192.0 8229.6 8237.0 7121.8 7286.2 7292.3 7355.9 7441.1 6217.6 7173.6 7186.3 7220.5 7300.6 6945.8 6999.2 7014.4 7081.2 7098.0 4801.3 4851.8 4881.8 4909.2 4995.2 4320.7 4915.6 4940.7 4971.1 5038.8 16:06:59 INFO - PROCESS | 1675 | 16:06:59 INFO - PROCESS | 1675 | [#4] prototype.html Cycles:180 Average:5990.38 Median:6739.08 stddev:1965.88 (29.2%) stddev-sans-first:1924.63 16:06:59 INFO - PROCESS | 1675 | Values: 311.5 316.6 340.0 343.1 345.6 6564.9 6685.4 6809.3 6872.7 6875.2 6190.4 6712.7 6891.3 6897.4 6915.0 5053.4 5089.5 5093.7 5101.0 5115.1 4292.5 5105.6 5106.1 5108.2 5115.4 4933.8 5022.6 5042.1 5043.7 5046.8 4911.2 5006.1 5037.0 5048.7 5087.1 3637.8 4327.5 4351.2 4354.4 4362.8 6567.4 6567.8 6572.3 6580.8 6608.0 5961.0 5962.8 5973.3 5978.9 5984.9 7396.1 7398.8 7406.0 7418.4 7442.5 4505.5 4719.2 4723.3 4856.1 4868.4 6287.5 7721.9 7728.7 7797.9 7807.2 4712.8 4759.9 4765.8 4769.2 4770.3 4013.1 4035.4 4090.9 4138.7 4863.2 5814.5 5837.4 5864.6 5877.9 5878.8 5438.6 6491.8 6765.5 7411.7 7458.2 1667.3 1667.7 1686.6 1688.0 1698.0 999.7 1054.0 1054.2 1072.5 1076.8 6706.9 6891.4 6949.2 6962.4 6964.5 7801.3 7801.8 7804.6 7805.0 7805.8 6865.5 6917.6 6924.2 6947.6 6972.0 3966.2 4029.7 4045.8 4102.4 4106.0 8035.9 8098.6 8110.4 8130.6 8144.2 8027.0 8049.9 8051.2 8062.4 8062.9 6630.6 7948.9 8092.3 8098.0 8100.8 6538.1 7533.4 7759.2 7790.1 7809.8 7608.3 7638.2 7639.9 7650.5 7669.8 6667.5 8004.6 8074.2 8088.7 8104.4 4455.5 4476.9 4801.8 4812.8 4820.1 5912.5 7582.6 7597.7 7752.7 7774.2 7259.1 7279.4 7300.0 7311.9 7314.0 7340.5 7410.4 7437.2 7447.4 7465.0 7054.1 7244.1 7256.2 7267.1 7274.6 7343.2 7426.5 7430.7 7443.0 7471.9 6992.3 7157.5 7340.2 7348.9 7354.9 16:06:59 INFO - PROCESS | 1675 | 16:06:59 INFO - PROCESS | 1675 | [#5] yui.html Cycles:180 Average:17856.55 Median:50420.61 stddev:25157.82 (49.9%) stddev-sans-first:25195.37 16:06:59 INFO - PROCESS | 1675 | Values: 689.2 702.8 739.9 741.4 764.9 14793.2 14918.0 14964.3 14998.3 15024.5 13687.2 13701.6 15286.0 15558.6 15620.3 769.3 950.0 962.2 967.4 971.8 898.1 971.8 973.1 973.3 974.9 1002.6 1009.3 1015.8 1018.3 1019.1 945.8 1013.5 1015.2 1016.2 1017.2 703.9 734.3 767.7 769.8 771.9 12820.5 13853.9 14809.3 14841.9 14887.7 12676.5 12915.8 12916.9 13083.5 13096.3 9076.6 10123.5 10282.2 10444.3 10601.5 3613.4 3639.0 3665.1 3747.9 3749.9 66923.0 81645.2 82221.0 82712.6 83629.6 9656.0 9742.3 9796.8 9842.1 10013.4 21729.8 22082.0 22473.9 22844.5 22896.0 7655.0 7757.3 7882.5 7948.8 8035.3 3454.0 4095.6 4097.9 4126.2 4159.6 3648.1 3675.0 3693.5 3714.9 3719.0 660.1 670.4 677.8 695.8 703.1 475.6 607.4 608.6 632.0 641.5 7431.4 8202.1 8377.7 8379.3 8493.3 621.4 622.9 634.1 645.2 649.7 3802.9 3855.6 3908.4 3926.5 4023.5 91085.1 92173.0 93534.5 95322.5 95866.0 68415.0 70146.6 75547.0 76476.0 79638.2 49482.0 68162.7 68343.0 69020.3 69607.7 7650.6 7815.3 7836.6 7947.0 8143.8 47629.3 49288.0 49419.5 50228.7 50612.5 47457.3 66417.7 68679.7 70793.3 72153.0 10160.8 10260.7 10311.1 10470.7 10787.8 10717.4 10769.6 10871.2 11054.9 11693.3 10245.2 10374.4 10424.5 10775.5 10786.7 10147.7 10275.7 10363.8 10624.4 10661.2 10118.7 10243.3 10381.7 10427.3 10529.7 8822.5 9007.6 9077.9 9113.8 9320.9 8712.6 8822.4 8894.6 8931.0 9020.6 16:06:59 INFO - PROCESS | 1675 | -------- Summary: end -------- 16:06:59 INFO - PROCESS | 1675 | 16:07:00 INFO - TEST-INFO | 1675: exit 0 16:07:00 INFO - TEST-OK | dromaeo_css | took 1111099ms 16:07:00 INFO - TEST-START | kraken 16:07:00 INFO - operating with platform_type : mac_ 16:07:00 INFO - Initialising browser for kraken test... 16:07:00 INFO - TEST-INFO | started process 1687 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/tmphl2Mo2/profile http://localhost:49275/getInfo.html) 16:07:03 INFO - PROCESS | 1687 | __metrics Screen width/height:1600/1200 16:07:03 INFO - PROCESS | 1687 | colorDepth:24 16:07:03 INFO - PROCESS | 1687 | Browser inner width/height: 1024/689 16:07:03 INFO - PROCESS | 1687 | __metrics 16:07:03 ERROR - PROCESS | 1687 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2940: TypeError: this.tabs is undefined 16:07:03 INFO - TEST-INFO | 1687: exit 0 16:07:03 INFO - Browser initialized. 16:07:03 INFO - Running cycle 1/1 for kraken test... 16:07:03 INFO - TEST-INFO | started process 1690 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/tmphl2Mo2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:07:16 INFO - PROCESS | 1690 | RSS: Main: 220893184 16:07:16 INFO - PROCESS | 1690 | 16:07:18 INFO - PROCESS | 1690 | 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) 16:07:19 INFO - PROCESS | 1690 | RSS: Main: 233013248 16:07:19 INFO - PROCESS | 1690 | 16:07:22 INFO - PROCESS | 1690 | 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) 16:07:23 INFO - PROCESS | 1690 | RSS: Main: 241926144 16:07:23 INFO - PROCESS | 1690 | 16:07:26 INFO - PROCESS | 1690 | 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) 16:07:27 INFO - PROCESS | 1690 | RSS: Main: 246099968 16:07:27 INFO - PROCESS | 1690 | 16:07:29 INFO - PROCESS | 1690 | 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) 16:07:29 INFO - PROCESS | 1690 | RSS: Main: 247468032 16:07:29 INFO - PROCESS | 1690 | 16:07:31 INFO - PROCESS | 1690 | 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) 16:07:32 INFO - PROCESS | 1690 | RSS: Main: 247738368 16:07:32 INFO - PROCESS | 1690 | 16:07:35 INFO - PROCESS | 1690 | 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) 16:07:35 INFO - PROCESS | 1690 | RSS: Main: 247435264 16:07:35 INFO - PROCESS | 1690 | 16:07:38 INFO - PROCESS | 1690 | 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) 16:07:39 INFO - PROCESS | 1690 | RSS: Main: 247762944 16:07:39 INFO - PROCESS | 1690 | 16:07:42 INFO - PROCESS | 1690 | 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) 16:07:42 INFO - PROCESS | 1690 | RSS: Main: 247758848 16:07:42 INFO - PROCESS | 1690 | 16:07:44 INFO - PROCESS | 1690 | 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) 16:07:45 INFO - PROCESS | 1690 | RSS: Main: 247435264 16:07:45 INFO - PROCESS | 1690 | 16:07:46 INFO - PROCESS | 1690 | 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) 16:07:47 INFO - PROCESS | 1690 | RSS: Main: 247631872 16:07:47 INFO - PROCESS | 1690 | 16:07:49 INFO - PROCESS | 1690 | 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) 16:07:50 INFO - PROCESS | 1690 | RSS: Main: 247906304 16:07:50 INFO - PROCESS | 1690 | 16:07:53 INFO - PROCESS | 1690 | 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) 16:07:53 INFO - PROCESS | 1690 | RSS: Main: 247914496 16:07:53 INFO - PROCESS | 1690 | 16:07:56 INFO - PROCESS | 1690 | 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) 16:07:57 INFO - PROCESS | 1690 | RSS: Main: 247914496 16:07:57 INFO - PROCESS | 1690 | 16:07:59 INFO - PROCESS | 1690 | 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) 16:07:59 INFO - PROCESS | 1690 | RSS: Main: 249843712 16:07:59 INFO - PROCESS | 1690 | 16:07:59 INFO - PROCESS | 1690 | __start_tp_report 16:07:59 INFO - PROCESS | 1690 | _x_x_mozilla_page_load 16:07:59 INFO - PROCESS | 1690 | _x_x_mozilla_page_load_details 16:07:59 INFO - PROCESS | 1690 | |i|pagename|runs| 16:07:59 INFO - PROCESS | 1690 | |0;ai-astar;114;119;109;117;107;169;103;166;168;166 16:07:59 INFO - PROCESS | 1690 | |1;audio-beat-detection;148;123;161;202;150;139;123;153;156;123 16:07:59 INFO - PROCESS | 1690 | |2;audio-dft;197;200;196;200;197;197;194;202;196;197 16:07:59 INFO - PROCESS | 1690 | |3;audio-fft;64;89;107;180;80;59;55;59;124;54 16:07:59 INFO - PROCESS | 1690 | |4;audio-oscillator;76;91;134;77;80;82;120;81;80;124 16:07:59 INFO - PROCESS | 1690 | |5;imaging-gaussian-blur;118;128;92;85;88;84;88;86;87;86 16:07:59 INFO - PROCESS | 1690 | |6;imaging-darkroom;95;98;149;130;94;111;122;94;95;121 16:07:59 INFO - PROCESS | 1690 | |7;imaging-desaturate;88;93;97;91;92;89;90;91;93;91 16:07:59 INFO - PROCESS | 1690 | |8;json-parse-financial;61;64;65;66;68;66;65;85;63;60 16:07:59 INFO - PROCESS | 1690 | |9;json-stringify-tinderbox;67;69;68;76;75;72;70;68;70;70 16:07:59 INFO - PROCESS | 1690 | |10;stanford-crypto-aes;95;103;107;106;81;80;81;83;87;83 16:07:59 INFO - PROCESS | 1690 | |11;stanford-crypto-ccm;129;123;146;115;149;151;143;141;153;153 16:07:59 INFO - PROCESS | 1690 | |12;stanford-crypto-pbkdf2;192;177;176;187;164;166;165;161;171;164 16:07:59 INFO - PROCESS | 1690 | |13;stanford-crypto-sha256-iterative;67;68;66;58;96;98;70;59;93;82 16:07:59 INFO - PROCESS | 1690 | __end_tp_report 16:07:59 INFO - PROCESS | 1690 | __start_cc_report 16:07:59 INFO - PROCESS | 1690 | _x_x_mozilla_cycle_collect,1110 16:07:59 INFO - PROCESS | 1690 | __end_cc_report 16:07:59 INFO - PROCESS | 1690 | __startTimestamp1464736079456__endTimestamp 16:07:59 INFO - PROCESS | 1690 | 16:07:59 INFO - PROCESS | 1690 | ------- Summary: start ------- 16:07:59 INFO - PROCESS | 1690 | Number of tests: 14 16:07:59 INFO - PROCESS | 1690 | 16:07:59 INFO - PROCESS | 1690 | [#0] ai-astar Cycles:10 Average:133.80 Median:142.50 stddev:29.17 (20.5%) stddev-sans-first:30.05 16:07:59 INFO - PROCESS | 1690 | Values: 114.0 119.0 109.0 117.0 107.0 169.0 103.0 166.0 168.0 166.0 16:07:59 INFO - PROCESS | 1690 | 16:07:59 INFO - PROCESS | 1690 | [#1] audio-beat-detection Cycles:10 Average:147.80 Median:151.50 stddev:23.88 (15.8%) stddev-sans-first:25.33 16:07:59 INFO - PROCESS | 1690 | Values: 148.0 123.0 161.0 202.0 150.0 139.0 123.0 153.0 156.0 123.0 16:07:59 INFO - PROCESS | 1690 | 16:07:59 INFO - PROCESS | 1690 | [#2] audio-dft Cycles:10 Average:197.60 Median:197.00 stddev:2.37 (1.2%) stddev-sans-first:2.50 16:07:59 INFO - PROCESS | 1690 | Values: 197.0 200.0 196.0 200.0 197.0 197.0 194.0 202.0 196.0 197.0 16:07:59 INFO - PROCESS | 1690 | 16:07:59 INFO - PROCESS | 1690 | [#3] audio-fft Cycles:10 Average:87.10 Median:59.00 stddev:40.39 (68.5%) stddev-sans-first:41.96 16:07:59 INFO - PROCESS | 1690 | Values: 64.0 89.0 107.0 180.0 80.0 59.0 55.0 59.0 124.0 54.0 16:07:59 INFO - PROCESS | 1690 | 16:07:59 INFO - PROCESS | 1690 | [#4] audio-oscillator Cycles:10 Average:94.50 Median:80.00 stddev:22.36 (28.0%) stddev-sans-first:22.69 16:07:59 INFO - PROCESS | 1690 | Values: 76.0 91.0 134.0 77.0 80.0 82.0 120.0 81.0 80.0 124.0 16:07:59 INFO - PROCESS | 1690 | 16:07:59 INFO - PROCESS | 1690 | [#5] imaging-gaussian-blur Cycles:10 Average:94.20 Median:86.50 stddev:15.51 (17.9%) stddev-sans-first:13.86 16:07:59 INFO - PROCESS | 1690 | Values: 118.0 128.0 92.0 85.0 88.0 84.0 88.0 86.0 87.0 86.0 16:07:59 INFO - PROCESS | 1690 | 16:07:59 INFO - PROCESS | 1690 | [#6] imaging-darkroom Cycles:10 Average:110.90 Median:94.00 stddev:19.10 (20.3%) stddev-sans-first:19.38 16:07:59 INFO - PROCESS | 1690 | Values: 95.0 98.0 149.0 130.0 94.0 111.0 122.0 94.0 95.0 121.0 16:07:59 INFO - PROCESS | 1690 | 16:07:59 INFO - PROCESS | 1690 | [#7] imaging-desaturate Cycles:10 Average:91.50 Median:91.50 stddev:2.51 (2.7%) stddev-sans-first:2.32 16:07:59 INFO - PROCESS | 1690 | Values: 88.0 93.0 97.0 91.0 92.0 89.0 90.0 91.0 93.0 91.0 16:07:59 INFO - PROCESS | 1690 | 16:07:59 INFO - PROCESS | 1690 | [#8] json-parse-financial Cycles:10 Average:66.30 Median:65.50 stddev:6.99 (10.7%) stddev-sans-first:7.15 16:07:59 INFO - PROCESS | 1690 | Values: 61.0 64.0 65.0 66.0 68.0 66.0 65.0 85.0 63.0 60.0 16:07:59 INFO - PROCESS | 1690 | 16:07:59 INFO - PROCESS | 1690 | [#9] json-stringify-tinderbox Cycles:10 Average:70.50 Median:70.00 stddev:2.99 (4.3%) stddev-sans-first:2.89 16:07:59 INFO - PROCESS | 1690 | Values: 67.0 69.0 68.0 76.0 75.0 72.0 70.0 68.0 70.0 70.0 16:07:59 INFO - PROCESS | 1690 | 16:07:59 INFO - PROCESS | 1690 | [#10] stanford-crypto-aes Cycles:10 Average:90.60 Median:82.00 stddev:11.08 (13.5%) stddev-sans-first:11.63 16:07:59 INFO - PROCESS | 1690 | Values: 95.0 103.0 107.0 106.0 81.0 80.0 81.0 83.0 87.0 83.0 16:07:59 INFO - PROCESS | 1690 | 16:07:59 INFO - PROCESS | 1690 | [#11] stanford-crypto-ccm Cycles:10 Average:140.30 Median:147.50 stddev:13.42 (9.1%) stddev-sans-first:13.59 16:07:59 INFO - PROCESS | 1690 | Values: 129.0 123.0 146.0 115.0 149.0 151.0 143.0 141.0 153.0 153.0 16:07:59 INFO - PROCESS | 1690 | 16:07:59 INFO - PROCESS | 1690 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.30 Median:173.50 stddev:10.54 (6.1%) stddev-sans-first:8.43 16:07:59 INFO - PROCESS | 1690 | Values: 192.0 177.0 176.0 187.0 164.0 166.0 165.0 161.0 171.0 164.0 16:07:59 INFO - PROCESS | 1690 | 16:07:59 INFO - PROCESS | 1690 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.70 Median:76.00 stddev:15.28 (20.1%) stddev-sans-first:15.88 16:07:59 INFO - PROCESS | 1690 | Values: 67.0 68.0 66.0 58.0 96.0 98.0 70.0 59.0 93.0 82.0 16:07:59 INFO - PROCESS | 1690 | -------- Summary: end -------- 16:07:59 INFO - PROCESS | 1690 | 16:08:00 INFO - TEST-INFO | 1690: exit 0 16:08:00 INFO - TEST-OK | kraken | took 60173ms 16:08:00 INFO - SUITE-END | took 1171s 16:08:00 INFO - Completed test suite (00:19:31) 16:08:00 INFO - javascript benchmark 16:08:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3126.7811253212276, 3466.6186719460857, 3471.0801637566065, 3524.5946716127637, 3551.8401671924776, 5005.949940500603, 5738.053221218499, 5754.453326933945, 5778.711064446777, 5789.797357092502, 5783.0, 5801.593888427812, 5803.796867109652, 5822.097574875901, 5824.0, 5246.658967167132, 5287.233351164079, 5347.0, 5355.169948657945, 5393.2449457076045, 4343.2182207202695, 5304.99205151022, 5327.7336133193185, 5405.837824865261, 5452.945470545284, 5367.221752845835, 5389.407165211824, 5390.245365648812, 5400.270963419947, 5404.027275090482, 5320.148776195829, 5347.25138480613, 5386.973065134669, 5391.568674506049, 5454.318210223722, 5146.588272938194, 5156.226566015091, 5200.141976573898, 5212.895742085175, 5243.79024839006, 4837.516248375169, 5664.065429204217, 5666.744996475169, 5668.97165514174, 5709.200711900337, 6122.916604401426, 6194.690265486753, 6230.003199488106, 6254.781082662085, 6295.433410993032, 6166.969165154191, 6230.2523697156175, 6230.470410015108, 6260.280067792171, 6288.842778930518, 5927.022041363213, 5968.313643930916, 5984.677872401772, 5986.790462333797, 6022.488088512482, 6158.907616385758, 6247.718852651642, 6254.968725156436, 6299.842503937438, 6359.618422894641, 6180.412860778219, 6180.845478862974, 6239.78160764371, 6261.8747625047245, 6282.183316168869, 6233.563650544418, 6237.4698150657705, 6251.812445626639, 6253.093301471261, 6276.184096067574, 5129.410117836422, 6175.44421002112, 6234.750609975641, 6234.9688251559355, 6260.342664020303, 6225.28409232944, 6228.906566401508, 6231.968840155861, 6234.812955611339, 6274.6235225885675, 4894.853154405374, 4921.26181072842, 4953.0589188054155, 4964.255361695702, 4974.77613507393, 4395.1429471252795, 4442.178197033494, 4516.683832131785, 4520.276755719069, 4523.5024147343765, 5710.8001219957105, 5793.797217097382, 5793.942060579425, 5848.61983971049, 5855.853603659857, 5654.293213348332, 6185.690715464209, 6219.906701399483, 6230.844228894314, 6241.188645476059, 4795.856124316276, 5809.477147056785, 5873.618214816023, 5873.706314684248, 5890.2342695449315, 5325.733713314319, 5332.06688829461, 5333.946660533423, 5340.599455040888, 5348.866278343073, 6237.937620623827, 6299.874002519924, 6338.0, 6340.968295158495, 6352.809415717536, 6099.481544068626, 6131.662758548145, 6252.843678908063, 6259.561830671809, 6299.401556852092, 5449.2371068049715, 6420.0048992406255, 6420.1974753155855, 6455.870882582228, 6457.67711614408, 5178.818741344186, 6207.541352112209, 6233.875322493615, 6257.28041275241, 6258.780942666985, 6127.464420129145, 6140.565599444434, 6161.075838624242, 6210.875782484415, 6231.937680623317, 5970.611755297916, 6128.295246046761, 6141.324454309933, 6160.075988601746, 6179.196704428396, 5945.732442040205, 6134.202553667995, 6168.753249869955, 6180.783672571439, 6195.19462469876, 5888.587798854211, 5934.376890426615, 6025.698715064142, 6068.362821903813, 6118.143459915526, 6162.322144564005, 6171.814845554641, 6186.938130618636, 6196.287426945959, 6208.161898143693, 5935.673537955454, 5992.340842507234, 6108.419700128481, 6114.877702446015, 6141.416565426277], "value": 5718.993620342813, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [494.2635473145013, 536.6779932040776, 581.206652918766, 593.3295376224867, 606.4935778624848, 38075.61924380755, 39165.0, 39170.41244381333, 39271.0, 39306.60693393065, 40614.59385406145, 40924.79537602308, 41257.793711031474, 41464.17071658565, 41475.0, 9356.298277629188, 9482.099200575969, 9611.086946740048, 9625.0, 9639.373440726366, 9485.193758530533, 9719.416834989888, 9738.415695058284, 9760.560774765117, 9791.11879930806, 9653.0, 9704.611815527405, 9757.804843903154, 9760.975097614719, 9775.266854985868, 9716.514174291293, 9746.90253097467, 9750.853737193947, 9764.365316254492, 9788.021197880225, 9597.376170548892, 9704.51477426133, 9715.951420242924, 9720.027997200294, 9744.464054477, 39802.0, 39993.60006399928, 40284.39573406401, 40449.79775101106, 40851.18297634031, 2324.418895276181, 3239.1254361322544, 3290.7531935104726, 3330.300636866261, 3367.074054634995, 26651.33371665724, 26860.194194174204, 26921.46157076867, 27084.729152708416, 27191.45617087667, 2725.9182224533297, 2778.1514530987697, 2810.4519618674367, 2837.886484540616, 2909.1418031680705, 290289.0, 301124.0, 320876.0, 352637.2368138169, 355380.0, 57492.42507574871, 60543.78912421727, 60573.09140362898, 60692.39307606956, 60705.39294607086, 66149.0, 69021.61956760837, 69551.0, 70420.64789676019, 70527.0, 17614.2073606688, 17677.20452579637, 17759.911200444174, 17805.554861128574, 17875.37436189727, 13532.052756306965, 13603.31983400826, 13645.863541364657, 13655.726885462234, 13670.726585468235, 19274.650774445934, 19412.22351105968, 19572.608547829248, 19657.606847863248, 19933.800661993486, 7909.960450197827, 7995.600219988977, 7995.640196191069, 8014.919850801417, 8067.636956336979, 962.5427921737119, 963.9252233759448, 994.7562847102506, 1010.8281592129356, 1134.5235001299475, 28794.136175914755, 30350.241243968634, 30832.69167308343, 30864.382712346076, 31018.68981310158, 1058.9364638121738, 1073.500822117719, 1084.7938891610568, 1094.2941802537478, 1121.607437396921, 12714.554990575285, 13501.59495215145, 14458.493952711604, 14585.343659535347, 14787.778183327258, 461466.0, 520078.0, 526237.0, 527355.3632231815, 528863.0, 59604.403955960755, 60465.395346046855, 61325.08012379818, 61359.69320153371, 61386.772264554456, 1466.6993266380366, 1480.9037412568364, 1482.5255918106318, 1489.8137732783402, 1498.1610298233024, 28470.43059138847, 28693.856530717214, 29426.411471770018, 29497.557536637396, 29507.557386639648, 53144.46855531395, 59486.0, 60077.0, 61457.692711536154, 61669.38330616636, 485419.0, 492574.0, 496320.0, 497526.0, 501831.0, 39662.0, 40927.0, 41043.97390065272, 42176.36735449032, 42903.356449653904, 44628.55371446374, 45313.0, 46414.76792616015, 48225.5177448221, 49679.0, 35400.0, 36165.27669446649, 36496.81751591225, 36994.07514812151, 37481.6251837489, 35500.467492988144, 35588.82205589059, 36748.63251367452, 36783.63216367802, 37139.0, 33263.83368083144, 36968.89093327204, 37747.81126094352, 38272.0, 38281.042973925876, 29714.405711886073, 32547.34905301928, 34933.12667183341, 35149.64850351464, 35273.47089793706, 23336.533269334857, 24457.633135503336, 24483.877580611985, 24677.136300229406, 24808.627870581593], "value": 19859.856341328672, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.7187935591208, 383.68346114455574, 398.5934346966096, 399.56447472255246, 413.14272883766193, 19244.23023079077, 19868.90065549674, 19912.20351185951, 20356.98215089247, 20391.796082039175, 16918.915405422988, 20269.0, 21139.894300528515, 21196.576068478622, 21381.251656192035, 5015.949840501585, 5512.062949298609, 5880.882382352351, 6091.78678746244, 6097.329293777703, 3439.7076248518906, 4371.256886329315, 6493.123428337185, 6625.801225963229, 6635.104260924762, 6597.076409302677, 6598.076269322299, 6600.042993765925, 6620.205575330967, 6629.8342541436605, 5718.228039214715, 5994.13085102662, 6049.304330002018, 6061.787837425713, 6069.848253793669, 5727.083666613322, 5771.307443106854, 5826.592138550303, 5847.239858818369, 5873.882522349529, 12870.0, 12873.420696068606, 13146.671333216746, 13205.405756740874, 13250.66873328165, 2195.4182141732294, 2214.7342318921833, 2235.798778109978, 2374.916877909282, 2795.65054368204, 9642.517874106337, 10092.495375231209, 10096.29325947184, 13437.66405839852, 13799.793003104962, 1414.8655877691604, 1765.5497848048794, 1779.4572655340116, 1812.2932056497975, 1840.6962851129547, 113891.86108138895, 115873.42063289629, 117648.41175794152, 123570.0, 124252.75747242503, 11522.76954460906, 11671.0, 11685.123615728855, 11687.415629218674, 11746.530138794524, 14812.555623331318, 14978.400863965537, 14987.775183372258, 15012.324445399983, 15016.699666006838, 8887.777805554784, 8957.731268061969, 8958.596863141043, 9023.864642030374, 9046.502442365732, 9025.593848276843, 9031.909680903238, 9063.909360906306, 9121.726348209564, 9136.771580710403, 4217.536071032184, 4381.255186618282, 4622.9688873002215, 4692.554207350297, 4742.905141897143, 1930.6042261336672, 1984.295575070858, 2014.919403223884, 2040.9285675001304, 2044.407121934652, 906.4153620914474, 915.4187091197138, 967.5549247346158, 1027.3168343051786, 1033.7467320506523, 19303.710444343345, 20247.08888100039, 20287.18851245963, 20306.289279875135, 20629.381118566165, 926.7358802741186, 934.0752654871628, 934.247930416008, 943.4386540008684, 961.7259081161835, 9526.0, 10839.620613278497, 10947.646256054828, 10988.780224395467, 11077.279976801483, 603507.0, 611566.0, 616587.0, 649620.0, 664443.0, 132162.0, 136239.0, 140939.0, 141573.29213353866, 145294.0, 997.9272282296561, 1037.2687255484866, 1038.6676263595728, 1058.7459009837582, 1074.666853275487, 18006.639867202844, 18091.638167236844, 18122.0, 18510.0, 18667.626647467245, 53328.0, 54178.91642167213, 55581.72209138928, 57138.57153571194, 57582.71208644097, 62916.0, 74388.62805685936, 80931.0, 83207.5839620798, 83543.0, 8081.797955051171, 8088.838223235384, 8172.877406839021, 8211.95894020526, 8335.666573337, 8367.832643347221, 11318.547258109913, 12471.812922806346, 12561.685957851127, 12826.102172847812, 10306.33008854422, 10958.397288149228, 11018.944905275423, 11102.722431938942, 11160.944195278971, 8609.65561377538, 10349.068583827491, 10409.0, 10682.89317106819, 10739.731506712395, 11290.717732056435, 11351.432428378714, 11473.024792892698, 11592.942035289769, 11628.6511404658, 7212.4951253411755, 7822.569758663328, 8196.672133114847, 8237.176282371714, 8466.0, 10558.419286939292, 10889.509972051435, 11073.5570577176, 11103.833442498531, 11318.94340528292], "value": 9498.105460749159, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1421.2822524625062, 1583.6515966487373, 1764.21492435866, 1858.8327050565438, 1918.8273055425009, 7731.845363092735, 8341.290990265834, 8520.40357174998, 8750.912490875096, 8762.780930476732, 8446.535440550768, 8474.618642161118, 8587.012493563243, 8604.440711353758, 8633.48199108054, 2401.903923843044, 2402.1232250228713, 2403.67550380699, 2423.7091549014144, 2432.7932125769244, 2386.1648423051965, 2407.7592240775957, 2414.4205390706193, 2420.1166574200465, 2429.2955043037587, 2398.496315773681, 2427.66012758214, 2436.597961336386, 2447.19242649926, 2448.6939132608422, 2407.4944261705064, 2410.4455975125734, 2422.2248880358293, 2426.502566973775, 2437.4515733959715, 2393.5571919195004, 2421.285720712394, 2424.248482970267, 2426.4904370082127, 2435.3059378077164, 8725.650973961034, 8738.519381434018, 8814.162654547808, 8815.942086949542, 8819.073997230262, 5284.5772338212855, 5356.009138309425, 5443.618946673734, 5451.618386712932, 5469.589780766419, 3417.5215469834243, 3424.708899743525, 3462.0306314232057, 3472.6874581287807, 3494.9650503494895, 1035.6323505155638, 1038.0812980512226, 1049.5801679328254, 1067.4075887882223, 1081.3674000709593, 11801.704957376049, 11872.40637968098, 11898.821517677241, 11919.284842909366, 11933.88066119345, 4728.787204575802, 4758.452777930513, 4759.6668233223345, 4786.856394308176, 4796.880077998078, 9962.103410692927, 10042.497875106215, 10064.295499314978, 10078.798424031478, 10103.646372376932, 5345.064613692588, 5397.298351214317, 5414.7834086636885, 5417.485338892799, 5418.83743487696, 4349.195398851222, 4741.502142275081, 4840.080384726891, 4864.197407427735, 4921.409430868319, 3456.0, 3975.522937247491, 4014.3376342903093, 4089.3661482470266, 4101.425800387948, 1367.9931600341934, 1370.1094288712218, 1377.7726675098697, 1390.5411214299256, 1393.8048673185763, 818.292177266672, 824.4105464592845, 860.5654144656908, 939.9295052871062, 951.6145960885852, 5808.128780682931, 5875.353711091777, 5930.673812940244, 5963.940360596339, 6022.638641681513, 911.4485736129546, 942.472215559289, 952.319091849331, 956.258899353007, 985.5121714751101, 3037.665856755755, 3159.573457583243, 3988.3419235826345, 3997.620226078518, 4020.8994775130855, 10391.168706503462, 10679.519421626046, 10728.624498142528, 10736.516856741466, 10785.191110666732, 10438.425886576159, 10515.737106572396, 10588.364698117983, 10601.0, 10666.679999600025, 7237.819054523679, 8283.25450709439, 8317.625706843206, 8400.789980250544, 8457.450265732708, 4904.0, 5706.343770466449, 5793.478586927196, 5824.621399609012, 5849.151872978479, 9038.096190381173, 9186.816263674555, 9333.486658233862, 9376.343655944018, 9508.904910951078, 9384.718458446258, 9512.286578506779, 9567.665131720356, 9628.951855240679, 9689.854652180082, 6747.932520674731, 7179.784606461605, 7226.927730722836, 7452.06849143857, 7470.514416562906, 8119.269265765874, 8174.141715119827, 8192.0, 8229.588520574067, 8236.958815205886, 7121.750738724327, 7286.234945330664, 7292.307230813064, 7355.926440735524, 7441.1070671519765, 6217.564770466205, 7173.569585824867, 7186.317299856505, 7220.535851793724, 7300.598467084362, 6945.791626251221, 6999.1950925644205, 7014.368706816411, 7081.221065682667, 7097.964510177416, 4801.327814106098, 4851.781669824937, 4881.780319885543, 4909.1654418748185, 4995.200767877122, 4320.697551171388, 4915.557599816034, 4940.654154209171, 4971.130052240916, 5038.798448062036], "value": 4571.400100944729, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [311.4533992842561, 316.61056900012983, 339.99830000849994, 343.1370104187966, 345.55596059064106, 6564.868702625945, 6685.398314151726, 6809.250982391939, 6872.656367181634, 6875.243723190451, 6190.3809619038075, 6712.697928593225, 6891.276415976313, 6897.379235868771, 6915.0318955346165, 5053.393592768854, 5089.541941225289, 5093.745312734358, 5100.974495127519, 5115.104856650071, 4292.484901811787, 5105.642605017651, 5106.055379754739, 5108.208227724708, 5115.386153661566, 4933.777979990891, 5022.623303252252, 5042.142835717918, 5043.747812609373, 5046.772895219724, 4911.187959287927, 5006.123928312567, 5036.9926014797, 5048.7223202723835, 5087.135187018216, 3637.7999210043436, 4327.459067616548, 4351.195028919628, 4354.368616550582, 4362.760048197348, 6567.376099270562, 6567.802965911031, 6572.277049524549, 6580.835479113012, 6608.008798680188, 5960.970195148997, 5962.761489540413, 5973.342932277446, 5978.940210597882, 5984.880302393928, 7396.149442814091, 7398.778036658909, 7406.0, 7418.443616728767, 7442.516236444614, 4505.5269196734525, 4719.174144524729, 4723.338732577442, 4856.125897338477, 4868.391451068616, 6287.4655654269445, 7721.884171737428, 7728.6522106505345, 7797.922020779776, 7807.219278072147, 4712.75112095295, 4759.881002974884, 4765.761711914391, 4769.165396055676, 4770.260609605517, 4013.0769922917893, 4035.3543433050572, 4090.897727556835, 4138.710290279651, 4863.2461968394955, 5814.476697097281, 5837.357890632027, 5864.648121112747, 5877.941220587825, 5878.794242201502, 5438.592105592096, 6491.772787952399, 6765.458763298924, 7411.6664750086375, 7458.179600243969, 1667.3080671521184, 1667.7415000674914, 1686.5867862373552, 1688.0462538665615, 1698.0490925081995, 999.6751055906859, 1054.0039662518845, 1054.1882750282393, 1072.538808312433, 1076.822324316479, 6706.899396509057, 6891.414229790424, 6949.166100067923, 6962.373386395248, 6964.547304425196, 7801.258880406353, 7801.81549166223, 7804.570748608768, 7804.960975195201, 7805.765827025199, 6865.519413640997, 6917.584944903322, 6924.169099707967, 6947.617881016593, 6972.0, 3966.2265858157384, 4029.7380670256925, 4045.815259759697, 4102.446169767103, 4105.97947010263, 8035.919640803634, 8098.595070246464, 8110.3511719062335, 8130.552819594979, 8144.226298501633, 8027.0, 8049.87925181111, 8051.194880512019, 8062.355011599058, 8062.919370806334, 6630.569013014138, 7948.887155798193, 8092.271695547429, 8098.028236611526, 8100.756977290691, 6538.117354157128, 7533.434992375484, 7759.224077592421, 7790.14308426084, 7809.804754881174, 7608.277213664693, 7638.15980242184, 7639.88540171909, 7650.540967541965, 7669.846603068019, 6667.49993750461, 8004.639791209526, 8074.232947869943, 8088.676452942052, 8104.351651867719, 4455.465344158722, 4476.858401107696, 4801.831935882367, 4812.831550895702, 4820.108279968217, 5912.497437717842, 7582.582957937367, 7597.696092156252, 7752.65113069902, 7774.222577742181, 7259.12890453149, 7279.381252593377, 7299.978003079679, 7311.8537629248185, 7314.0, 7340.522866013692, 7410.407167426485, 7437.181909989788, 7447.404207663266, 7465.029546158965, 7054.142223912621, 7244.058272424551, 7256.2018178001435, 7267.055282813412, 7274.563526188445, 7343.228960959236, 7426.517276377018, 7430.702771889064, 7443.0, 7471.850562988819, 6992.300769922967, 7157.498975071695, 7340.15588207363, 7348.926510735038, 7354.889676654748], "value": 5326.659843788061, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [689.1764341611773, 702.7751119641714, 739.9149097853746, 741.4365082537277, 764.8699721047418, 14793.186374749384, 14917.955743097988, 14964.251787410612, 14998.32507537162, 15024.549263522098, 13687.17876927385, 13701.588952331445, 15286.0, 15558.611034724137, 15620.2970866311, 769.2922511289627, 949.9762505937372, 962.1773383756893, 967.3808762392075, 971.7716336660915, 898.0884402331606, 971.8007808399296, 973.0512750068697, 973.2992245583204, 974.8586454964026, 1002.6490728245129, 1009.2884516415946, 1015.8425444056184, 1018.3024628129755, 1019.0777346501428, 945.8297506448837, 1013.513513513514, 1015.2132097624326, 1016.227666973102, 1017.1710056304089, 703.8662654095706, 734.3170851108467, 767.719782279464, 769.7690692792196, 771.8687823070092, 12820.487180512768, 13853.930730346385, 14809.333579988926, 14841.925790371088, 14887.702245955128, 12676.492940282378, 12915.806262906064, 12916.935415322958, 13083.542076027388, 13096.279704616243, 9076.591553380114, 10123.544440500122, 10282.228832837567, 10444.321119127233, 10601.522931468102, 3613.4218525035867, 3638.963610363889, 3665.1020499977676, 3747.9437808432895, 3749.850005999757, 66923.0, 81645.18354816435, 82220.9999999994, 82712.58643706804, 83629.58185209095, 9655.951720241354, 9742.269329800294, 9796.804063918824, 9842.065003824626, 10013.449260290608, 21729.782702173092, 22082.0, 22473.88763056174, 22844.543109137725, 22895.96968136438, 7654.961725191339, 7757.26306000929, 7882.5270483771155, 7948.801279967932, 8035.276825085771, 3454.0, 4095.6313931746286, 4097.89755256115, 4126.216018956388, 4159.563245859202, 3648.0515066083008, 3675.044488432973, 3693.4829123922673, 3714.925701485955, 3719.0330514065995, 660.1088530483809, 670.4167374384317, 677.7864972533636, 695.7738734911073, 703.0754557756534, 475.61712821178594, 607.4168797954013, 608.5587948737218, 632.0235236559527, 641.483605697418, 7431.3683336916965, 8202.097769245378, 8377.748667539861, 8379.287760540423, 8493.27807136388, 621.3817251834454, 622.8816524860353, 634.0837489827155, 645.1741770533724, 649.7076315657878, 3802.9429558556644, 3855.614438556178, 3908.3746600543777, 3926.4895563577124, 4023.5372932113182, 91085.08914910899, 92173.0, 93534.53232733793, 95322.52338738262, 95866.0413395871, 68415.0, 70146.64926675335, 75547.0, 76476.0, 79638.20361796423, 49482.0, 68162.65918670375, 68342.97485537721, 69020.3097969024, 69607.65196173987, 7650.6174691264105, 7815.324573698142, 7836.647350869111, 7947.046354437504, 8143.75568732939, 47629.28556071731, 49288.0, 49419.50580494149, 50228.74428139326, 50612.48162554982, 47457.28814067723, 66417.66791166013, 68679.65660171867, 70793.29206707867, 72153.0, 10160.796784064425, 10260.692179234635, 10311.123554497952, 10470.685879423629, 10787.838182427427, 10717.41054241993, 10769.62306319275, 10871.184661150604, 11054.944725276322, 11693.298402095901, 10245.180385569285, 10374.429406382722, 10424.478776061014, 10775.515101820594, 10786.696274857408, 10147.695569132939, 10275.7431064221, 10363.792724145324, 10624.415657138623, 10661.200409969128, 10118.732136930716, 10243.334183278062, 10381.740456488647, 10427.322224055411, 10529.736756581147, 8822.470651760916, 9007.594658240263, 9077.863832042656, 9113.817723645358, 9320.906790932004, 8712.607932642919, 8822.382433229612, 8894.644214231617, 8931.017588065437, 9020.639174432948], "value": 6886.694201316871, "unit": "score"}], "name": "dromaeo_css", "value": 7520.511650133418, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 119.0, 109.0, 117.0, 107.0, 169.0, 103.0, 166.0, 168.0, 166.0], "value": 133.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 123.0, 161.0, 202.0, 150.0, 139.0, 123.0, 153.0, 156.0, 123.0], "value": 147.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 200.0, 196.0, 200.0, 197.0, 197.0, 194.0, 202.0, 196.0, 197.0], "value": 197.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 89.0, 107.0, 180.0, 80.0, 59.0, 55.0, 59.0, 124.0, 54.0], "value": 87.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 91.0, 134.0, 77.0, 80.0, 82.0, 120.0, 81.0, 80.0, 124.0], "value": 94.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 128.0, 92.0, 85.0, 88.0, 84.0, 88.0, 86.0, 87.0, 86.0], "value": 94.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 98.0, 149.0, 130.0, 94.0, 111.0, 122.0, 94.0, 95.0, 121.0], "value": 110.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 93.0, 97.0, 91.0, 92.0, 89.0, 90.0, 91.0, 93.0, 91.0], "value": 91.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 65.0, 66.0, 68.0, 66.0, 65.0, 85.0, 63.0, 60.0], "value": 66.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 68.0, 76.0, 75.0, 72.0, 70.0, 68.0, 70.0, 70.0], "value": 70.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 103.0, 107.0, 106.0, 81.0, 80.0, 81.0, 83.0, 87.0, 83.0], "value": 90.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [129.0, 123.0, 146.0, 115.0, 149.0, 151.0, 143.0, 141.0, 153.0, 153.0], "value": 140.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 177.0, 176.0, 187.0, 164.0, 166.0, 165.0, 161.0, 171.0, 164.0], "value": 172.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 66.0, 58.0, 96.0, 98.0, 70.0, 59.0, 93.0, 82.0], "value": 75.7, "unit": "score"}], "name": "kraken", "value": 1573.1, "alertThreshold": 2.0}]} 16:08:00 INFO - Return code: 0 16:08:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 16:08:00 ERROR - # TBPL SUCCESS # 16:08:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:08:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:08:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:08:00 INFO - 2016-05-31 16:08:00.759 screenresolution[1695:10579] starting screenresolution argv=screenresolution get 16:08:00 INFO - 2016-05-31 16:08:00.762 screenresolution[1695:10579] Display 0: 1600x1200x32@60 16:08:00 INFO - 2016-05-31 16:08:00.768 screenresolution[1696:10582] starting screenresolution argv=screenresolution list 16:08:00 INFO - Available Modes on Display 0 16:08:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:08:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:08:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:08:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:08:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:08:01 INFO - Intel Iris: 16:08:01 INFO - Chipset Model: Intel Iris 16:08:01 INFO - Type: GPU 16:08:01 INFO - Bus: Built-In 16:08:01 INFO - VRAM (Dynamic, Max): 1536 MB 16:08:01 INFO - Vendor: Intel (0x8086) 16:08:01 INFO - Device ID: 0x0a2e 16:08:01 INFO - Revision ID: 0x0009 16:08:01 INFO - Displays: 16:08:01 INFO - Display: 16:08:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:08:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:08:01 INFO - Display Serial Number: 1600x1200 60 16:08:01 INFO - Main Display: Yes 16:08:01 INFO - Mirror: Off 16:08:01 INFO - Online: Yes 16:08:01 INFO - Rotation: Supported 16:08:01 INFO - Return code: 0 16:08:01 INFO - Running post-action listener: _resource_record_post_action 16:08:01 INFO - Running post-run listener: _resource_record_post_run 16:08:02 INFO - Total resource usage - Wall time: 1204s; CPU: 54.0%; Read bytes: 36734464; Write bytes: 653259264; Read time: 249; Write time: 1840 16:08:02 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 346065408; Write bytes: 345949696; Read time: 26739; Write time: 689 16:08:02 INFO - run-tests - Wall time: 1174s; CPU: 54.0%; Read bytes: 35780096; Write bytes: 297352192; Read time: 236; Write time: 1116 16:08:02 INFO - Running post-run listener: _upload_blobber_files 16:08:02 INFO - Blob upload gear active. 16:08:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:08:02 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 16:08:02 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'] 16:08:02 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 16:08:02 INFO - (blobuploader) - INFO - Open directory for files ... 16:08:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:08:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:08:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:08:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:08:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:08:04 INFO - (blobuploader) - INFO - Done attempting. 16:08:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:08:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:08:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:08:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:08:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:08:05 INFO - (blobuploader) - INFO - Done attempting. 16:08:05 INFO - (blobuploader) - INFO - Iteration through files over. 16:08:05 INFO - Return code: 0 16:08:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:08:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:08:05 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/64c08af65f3d01d42f0ffb88281c3e161b6285a3be3f4b6925e47a8f37bba9686eef05c2b159ab09ef01ac1b86fe63cd31598e815f1a37296c6dde888773b2ba", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e0c7d0faa15a0ed6cd30ed75f0e380293dec384344afb1f757e3f24b4b7d05e6af54a77558ae706277da761947975db8ef1dd184b7108f4e521a9ea9fc99b78a"} 16:08:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:08:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:08:05 INFO - Contents: 16:08:05 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/64c08af65f3d01d42f0ffb88281c3e161b6285a3be3f4b6925e47a8f37bba9686eef05c2b159ab09ef01ac1b86fe63cd31598e815f1a37296c6dde888773b2ba", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e0c7d0faa15a0ed6cd30ed75f0e380293dec384344afb1f757e3f24b4b7d05e6af54a77558ae706277da761947975db8ef1dd184b7108f4e521a9ea9fc99b78a"} 16:08:05 INFO - Running post-run listener: copy_logs_to_upload_dir 16:08:05 INFO - Copying logs to upload dir... 16:08:05 INFO - mkdir: /builds/slave/test/build/upload/logs 16:08:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.713909 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-05-31 16:08:05.096795) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-31 16:08:05.100741) ========= 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.yQkPQPJPKE/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.XBnOKfa7uS/Listeners TMPDIR=/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/64c08af65f3d01d42f0ffb88281c3e161b6285a3be3f4b6925e47a8f37bba9686eef05c2b159ab09ef01ac1b86fe63cd31598e815f1a37296c6dde888773b2ba", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e0c7d0faa15a0ed6cd30ed75f0e380293dec384344afb1f757e3f24b4b7d05e6af54a77558ae706277da761947975db8ef1dd184b7108f4e521a9ea9fc99b78a"} build_url:https://queue.taskcluster.net/v1/task/XAKk8H5wSjiTkjP_j5fo8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010668 build_url: 'https://queue.taskcluster.net/v1/task/XAKk8H5wSjiTkjP_j5fo8Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/64c08af65f3d01d42f0ffb88281c3e161b6285a3be3f4b6925e47a8f37bba9686eef05c2b159ab09ef01ac1b86fe63cd31598e815f1a37296c6dde888773b2ba", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/e0c7d0faa15a0ed6cd30ed75f0e380293dec384344afb1f757e3f24b4b7d05e6af54a77558ae706277da761947975db8ef1dd184b7108f4e521a9ea9fc99b78a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-31 16:08:05.129971) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 16:08:05.130286) ========= 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.yQkPQPJPKE/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.XBnOKfa7uS/Listeners TMPDIR=/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/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.004973 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-31 16:08:05.178891) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-31 16:08:05.179198) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-31 16:08:05.179509) ========= ========= Total master_lag: 0.16 =========