builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0153 starttime: 1461663928.28 results: success (0) buildid: 20160426004107 builduid: 38934b15869c4ff8bf70109d6f20fde7 revision: ca6ab5be342e2d7817b0015447872dab3ecd5ef1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-26 02:45:28.275655) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-26 02:45:28.276091) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-26 02:45:28.276391) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-26 02:45:28.298964) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 02:45:28.299358) ========= 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.9cQkNoj5Ga/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.rIEnxKBUDj/Listeners TMPDIR=/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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-04-26 02:45:28-- 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% 159M=0s 2016-04-26 02:45:28 (159 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.085271 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 02:45:28.404639) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 02:45:28.405008) ========= 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.9cQkNoj5Ga/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.rIEnxKBUDj/Listeners TMPDIR=/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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.019742 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-26 02:45:28.453982) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-26 02:45:28.457418) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ca6ab5be342e2d7817b0015447872dab3ecd5ef1 --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 ca6ab5be342e2d7817b0015447872dab3ecd5ef1 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.9cQkNoj5Ga/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.rIEnxKBUDj/Listeners TMPDIR=/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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-04-26 02:45:28,527 truncating revision to first 12 chars 2016-04-26 02:45:28,528 Setting DEBUG logging. 2016-04-26 02:45:28,528 attempt 1/10 2016-04-26 02:45:28,528 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/ca6ab5be342e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-26 02:45:30,364 unpacking tar archive at: mozilla-aurora-ca6ab5be342e/testing/mozharness/ program finished with exit code 0 elapsedTime=2.087497 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-04-26 02:45:30.563316) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-26 02:45:30.563640) ========= script_repo_revision: ca6ab5be342e2d7817b0015447872dab3ecd5ef1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-26 02:45:30.564028) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-26 02:45:30.564336) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-26 02:45:30.584599) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-04-26 02:45:30.584972) ========= /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.9cQkNoj5Ga/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.rIEnxKBUDj/Listeners TMPDIR=/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 02:45:30 INFO - MultiFileLogger online at 20160426 02:45:30 in /builds/slave/test 02:45:30 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 02:45:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:45:30 INFO - {'append_to_log': False, 02:45:30 INFO - 'base_work_dir': '/builds/slave/test', 02:45:30 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:45:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:45:30 INFO - 'branch': 'Mozilla-Aurora', 02:45:30 INFO - 'buildbot_json_path': 'buildprops.json', 02:45:30 INFO - 'config_files': ('talos/mac_config.py',), 02:45:30 INFO - 'default_actions': ('clobber', 02:45:30 INFO - 'read-buildbot-config', 02:45:30 INFO - 'download-and-extract', 02:45:30 INFO - 'populate-webroot', 02:45:30 INFO - 'create-virtualenv', 02:45:30 INFO - 'install', 02:45:30 INFO - 'run-tests'), 02:45:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:45:30 INFO - 'download_minidump_stackwalk': True, 02:45:30 INFO - 'download_symbols': 'ondemand', 02:45:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:45:30 INFO - 'tooltool.py': '/tools/tooltool.py', 02:45:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:45:30 INFO - '/tools/misc-python/virtualenv.py')}, 02:45:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:45:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:45:30 INFO - 'installer_path': 'installer.exe', 02:45:30 INFO - 'log_level': 'info', 02:45:30 INFO - 'log_name': 'talos', 02:45:30 INFO - 'log_to_console': True, 02:45:30 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:45:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:45:30 INFO - 'opt_config_files': (), 02:45:30 INFO - 'pip_index': False, 02:45:30 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:45:30 INFO - 'cmd': ('bash', 02:45:30 INFO - '-c', 02:45:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:45:30 INFO - 'enabled': True, 02:45:30 INFO - 'halt_on_failure': False, 02:45:30 INFO - 'name': 'check_screen_resolution'},), 02:45:30 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:45:30 INFO - 'cmd': ('bash', 02:45:30 INFO - '-c', 02:45:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:45:30 INFO - 'enabled': True, 02:45:30 INFO - 'halt_on_failure': False, 02:45:30 INFO - 'name': 'check_screen_resolution'},), 02:45:30 INFO - 'run_cmd_checks_enabled': True, 02:45:30 INFO - 'sps_profile': False, 02:45:30 INFO - 'sps_profile_interval': 0, 02:45:30 INFO - 'suite': 'dromaeojs-e10s', 02:45:30 INFO - 'system_bits': '32', 02:45:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:45:30 INFO - 'title': 't-yosemite-r7-0153', 02:45:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:45:30 INFO - 'use_talos_json': True, 02:45:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:45:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:45:30 INFO - 'work_dir': 'build'} 02:45:30 INFO - ##### 02:45:30 INFO - ##### Running clobber step. 02:45:30 INFO - ##### 02:45:30 INFO - Running pre-action listener: _resource_record_pre_action 02:45:30 INFO - Running main action method: clobber 02:45:30 INFO - rmtree: /builds/slave/test/build 02:45:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:45:31 INFO - Running post-action listener: _resource_record_post_action 02:45:31 INFO - ##### 02:45:31 INFO - ##### Running read-buildbot-config step. 02:45:31 INFO - ##### 02:45:31 INFO - Running pre-action listener: _resource_record_pre_action 02:45:31 INFO - Running main action method: read_buildbot_config 02:45:31 INFO - Using buildbot properties: 02:45:31 INFO - { 02:45:31 INFO - "project": "", 02:45:31 INFO - "product": "firefox", 02:45:31 INFO - "script_repo_revision": "production", 02:45:31 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 02:45:31 INFO - "repository": "", 02:45:31 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 02:45:31 INFO - "buildid": "20160426004107", 02:45:31 INFO - "stage_platform": "macosx64", 02:45:31 INFO - "basedir": "/builds/slave/test", 02:45:31 INFO - "nightly_build": "True", 02:45:31 INFO - "buildnumber": 259, 02:45:31 INFO - "slavename": "t-yosemite-r7-0153", 02:45:31 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 02:45:31 INFO - "platform": "yosemite_r7", 02:45:31 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:45:31 INFO - "branch": "mozilla-aurora", 02:45:31 INFO - "repo_path": "releases/mozilla-aurora", 02:45:31 INFO - "revision": "ca6ab5be342e2d7817b0015447872dab3ecd5ef1", 02:45:31 INFO - "pgo_build": "False", 02:45:31 INFO - "builduid": "38934b15869c4ff8bf70109d6f20fde7", 02:45:31 INFO - "slavebuilddir": "test" 02:45:31 INFO - } 02:45:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 02:45:31 INFO - Running post-action listener: _resource_record_post_action 02:45:31 INFO - ##### 02:45:31 INFO - ##### Running download-and-extract step. 02:45:31 INFO - ##### 02:45:31 INFO - Running pre-action listener: _resource_record_pre_action 02:45:31 INFO - Running main action method: download_and_extract 02:45:31 INFO - mkdir: /builds/slave/test/build/tests 02:45:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:45:31 INFO - https://queue.taskcluster.net/v1/task/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 02:45:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:45:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:45:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HOEL3zTzTre5xQ4eikKsFA/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 02:45:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HOEL3zTzTre5xQ4eikKsFA/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 02:45:33 INFO - Downloaded 1199 bytes. 02:45:33 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:45:33 INFO - Using the following test package requirements: 02:45:33 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:45:33 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:45:33 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 02:45:33 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:45:33 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 02:45:33 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:45:33 INFO - u'jsshell-mac.zip'], 02:45:33 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:45:33 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 02:45:33 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:45:33 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:45:33 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 02:45:33 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:45:33 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 02:45:33 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:45:33 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 02:45:33 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:45:33 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 02:45:33 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:45: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')]} 02:45:33 INFO - https://queue.taskcluster.net/v1/task/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:45:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:45:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:45:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HOEL3zTzTre5xQ4eikKsFA/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 02:45:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HOEL3zTzTre5xQ4eikKsFA/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 02:45:35 INFO - Downloaded 18109665 bytes. 02:45:35 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'] 02:45:35 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 02:45:38 INFO - Return code: 0 02:45:38 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 02:45:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:45:38 INFO - https://queue.taskcluster.net/v1/task/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:45:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:45:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:45:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HOEL3zTzTre5xQ4eikKsFA/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 02:45:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HOEL3zTzTre5xQ4eikKsFA/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 02:45:39 INFO - Downloaded 18109665 bytes. 02:45:39 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'] 02:45:39 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 02:45:42 INFO - Return code: 0 02:45:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:45:42 INFO - https://queue.taskcluster.net/v1/task/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 02:45:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:45:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:45:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HOEL3zTzTre5xQ4eikKsFA/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 02:45:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HOEL3zTzTre5xQ4eikKsFA/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 02:45:44 INFO - Downloaded 10840145 bytes. 02:45:44 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'] 02:45:44 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 02:45:44 INFO - Return code: 0 02:45:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:45:44 INFO - https://queue.taskcluster.net/v1/task/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 02:45:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:45:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:45:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 02:45:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/HOEL3zTzTre5xQ4eikKsFA/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 02:45:50 INFO - Downloaded 114216509 bytes. 02:45:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:45:50 INFO - mkdir: /builds/slave/test/properties 02:45:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:45:50 INFO - Writing to file /builds/slave/test/properties/build_url 02:45:50 INFO - Contents: 02:45:50 INFO - build_url:https://queue.taskcluster.net/v1/task/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:45:51 INFO - Running post-action listener: _resource_record_post_action 02:45:51 INFO - Running post-action listener: set_extra_try_arguments 02:45:51 INFO - ##### 02:45:51 INFO - ##### Running populate-webroot step. 02:45:51 INFO - ##### 02:45:51 INFO - Running pre-action listener: _resource_record_pre_action 02:45:51 INFO - Running main action method: populate_webroot 02:45:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:45:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:45:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:45:51 INFO - '--tppagecycles', 02:45:51 INFO - '7'], 02:45:51 INFO - 'tests': ['tsvgm']}}, 02:45:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:45:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:45:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:45:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:45:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:45:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:45:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:45:51 INFO - 'talos_options': ['--disable-e10s'], 02:45:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:45:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:45:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:45:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:45:51 INFO - 'talos_options': ['--disable-e10s'], 02:45:51 INFO - 'tests': ['damp', 'tps']}, 02:45:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:45:51 INFO - 'tests': ['damp', 'tps']}, 02:45:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:45:51 INFO - 'tests': ['dromaeo_dom']}, 02:45:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:45:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:45:51 INFO - 'tests': ['a11yr', 02:45:51 INFO - 'ts_paint', 02:45:51 INFO - 'tpaint', 02:45:51 INFO - 'sessionrestore', 02:45:51 INFO - 'sessionrestore_no_auto_restore', 02:45:51 INFO - 'tabpaint']}, 02:45:51 INFO - 'other-e10s': {'tests': ['a11yr', 02:45:51 INFO - 'ts_paint', 02:45:51 INFO - 'tpaint', 02:45:51 INFO - 'sessionrestore', 02:45:51 INFO - 'sessionrestore_no_auto_restore', 02:45:51 INFO - 'tabpaint']}, 02:45:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:45:51 INFO - 'ts_paint', 02:45:51 INFO - 'tpaint', 02:45:51 INFO - 'sessionrestore', 02:45:51 INFO - 'sessionrestore_no_auto_restore', 02:45:51 INFO - 'tabpaint']}, 02:45:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:45:51 INFO - 'ts_paint', 02:45:51 INFO - 'tpaint', 02:45:51 INFO - 'sessionrestore', 02:45:51 INFO - 'sessionrestore_no_auto_restore', 02:45:51 INFO - 'tabpaint']}, 02:45:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:45:51 INFO - 'tests': ['a11yr', 02:45:51 INFO - 'ts_paint', 02:45:51 INFO - 'tpaint', 02:45:51 INFO - 'sessionrestore', 02:45:51 INFO - 'sessionrestore_no_auto_restore', 02:45:51 INFO - 'tabpaint']}, 02:45:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:45:51 INFO - 'tests': ['a11yr', 02:45:51 INFO - 'ts_paint', 02:45:51 INFO - 'tpaint', 02:45:51 INFO - 'sessionrestore', 02:45:51 INFO - 'sessionrestore_no_auto_restore', 02:45:51 INFO - 'tabpaint']}, 02:45:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:45:51 INFO - 'tests': ['tsvgx', 02:45:51 INFO - 'tsvgr_opacity', 02:45:51 INFO - 'tart', 02:45:51 INFO - 'tscrollx', 02:45:51 INFO - 'cart']}, 02:45:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:45:51 INFO - 'tsvgr_opacity', 02:45:51 INFO - 'tart', 02:45:51 INFO - 'tscrollx', 02:45:51 INFO - 'cart']}, 02:45:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:45:51 INFO - 'talos_options': ['--disable-e10s'], 02:45:51 INFO - 'tests': ['tp5o']}, 02:45:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:45:51 INFO - 'tests': ['tp5o']}, 02:45:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:45:51 INFO - 'talos_options': ['--disable-e10s', 02:45:51 INFO - '--xperf_path', 02:45:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:45:51 INFO - 'tests': ['tp5n']}, 02:45:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:45:51 INFO - 'talos_options': ['--xperf_path', 02:45:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:45:51 INFO - 'tests': ['tp5n']}}, 02:45:51 INFO - 'talos.zip': {'path': '', 02:45:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:45:51 INFO - Running post-action listener: _resource_record_post_action 02:45:51 INFO - ##### 02:45:51 INFO - ##### Running create-virtualenv step. 02:45:51 INFO - ##### 02:45:51 INFO - Running pre-action listener: _resource_record_pre_action 02:45:51 INFO - Running main action method: create_virtualenv 02:45:51 INFO - Creating virtualenv /builds/slave/test/build/venv 02:45:51 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:45:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:45:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:45:51 INFO - Using real prefix '/tools/python27' 02:45:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:45:52 INFO - Installing distribute.............................................................................................................................................................................................done. 02:45:55 INFO - Installing pip.................done. 02:45:55 INFO - Return code: 0 02:45:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:45:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:45:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:45:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:45:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:45:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45:55 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 0x10760da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10761d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa270d67b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074ace00>, '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 0x107315db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074a7b20>, '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.9cQkNoj5Ga/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.rIEnxKBUDj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:45:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:45:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:45:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9cQkNoj5Ga/Render', 02:45:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:45:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:45:55 INFO - 'HOME': '/Users/cltbld', 02:45:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:45:55 INFO - 'LOGNAME': 'cltbld', 02:45:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:55 INFO - 'MOZ_NO_REMOTE': '1', 02:45:55 INFO - 'NO_EM_RESTART': '1', 02:45:55 INFO - 'PAGER': '/bin/cat', 02:45:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:45:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:55 INFO - 'PWD': '/builds/slave/test', 02:45:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:45:55 INFO - 'SHELL': '/bin/bash', 02:45:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rIEnxKBUDj/Listeners', 02:45:55 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 02:45:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:45:55 INFO - 'USER': 'cltbld', 02:45:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:45:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:45:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:55 INFO - 'XPC_FLAGS': '0x0', 02:45:55 INFO - 'XPC_SERVICE_NAME': '0', 02:45:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:45:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:45:55 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 02:45:55 INFO - Cleaning up... 02:45:55 INFO - Return code: 0 02:45:55 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 02:45:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:45:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:45:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:45:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:45:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45:55 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 0x10760da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10761d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa270d67b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074ace00>, '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 0x107315db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074a7b20>, '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.9cQkNoj5Ga/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.rIEnxKBUDj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:45:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 02:45:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 02:45:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9cQkNoj5Ga/Render', 02:45:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:45:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:45:55 INFO - 'HOME': '/Users/cltbld', 02:45:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:45:55 INFO - 'LOGNAME': 'cltbld', 02:45:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:55 INFO - 'MOZ_NO_REMOTE': '1', 02:45:55 INFO - 'NO_EM_RESTART': '1', 02:45:55 INFO - 'PAGER': '/bin/cat', 02:45:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:45:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:55 INFO - 'PWD': '/builds/slave/test', 02:45:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:45:55 INFO - 'SHELL': '/bin/bash', 02:45:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rIEnxKBUDj/Listeners', 02:45:55 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 02:45:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:45:55 INFO - 'USER': 'cltbld', 02:45:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:45:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:45:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:55 INFO - 'XPC_FLAGS': '0x0', 02:45:55 INFO - 'XPC_SERVICE_NAME': '0', 02:45:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:45:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:45:56 INFO - Downloading/unpacking psutil>=0.7.1 02:45:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:45:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:45:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:45:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:45:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:45:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:45:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:45:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:45:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:45:59 INFO - Installing collected packages: psutil 02:45:59 INFO - Running setup.py install for psutil 02:45:59 INFO - building 'psutil._psutil_osx' extension 02:45:59 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 02:45:59 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 02:45:59 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 02:45:59 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 02:45:59 INFO - building 'psutil._psutil_posix' extension 02:45:59 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 02:45:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:45:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:45:59 INFO - ^ 02:45:59 INFO - 1 warning generated. 02:45:59 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 02:45:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:45:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:45:59 INFO - Successfully installed psutil 02:45:59 INFO - Cleaning up... 02:45:59 INFO - Return code: 0 02:45:59 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 02:45:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:45:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:45:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:45:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:45:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:45:59 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 0x10760da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10761d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa270d67b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074ace00>, '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 0x107315db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074a7b20>, '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.9cQkNoj5Ga/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.rIEnxKBUDj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:45:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 02:45:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 02:45:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9cQkNoj5Ga/Render', 02:45:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:45:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:45:59 INFO - 'HOME': '/Users/cltbld', 02:45:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:45:59 INFO - 'LOGNAME': 'cltbld', 02:45:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:45:59 INFO - 'MOZ_NO_REMOTE': '1', 02:45:59 INFO - 'NO_EM_RESTART': '1', 02:45:59 INFO - 'PAGER': '/bin/cat', 02:45:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:45:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:45:59 INFO - 'PWD': '/builds/slave/test', 02:45:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:45:59 INFO - 'SHELL': '/bin/bash', 02:45:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rIEnxKBUDj/Listeners', 02:45:59 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 02:45:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:45:59 INFO - 'USER': 'cltbld', 02:45:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:45:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:45:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:45:59 INFO - 'XPC_FLAGS': '0x0', 02:45:59 INFO - 'XPC_SERVICE_NAME': '0', 02:45:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:46:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:46:00 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:46:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:46:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:46:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:46:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:46:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:46:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:46:02 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:46:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:46:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 02:46:02 INFO - Installing collected packages: mozsystemmonitor 02:46:02 INFO - Running setup.py install for mozsystemmonitor 02:46:02 INFO - Successfully installed mozsystemmonitor 02:46:02 INFO - Cleaning up... 02:46:02 INFO - Return code: 0 02:46:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:46:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:46:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:46:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:46:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:46:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:46:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:46:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10760da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10761d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa270d67b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074ace00>, '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 0x107315db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074a7b20>, '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.9cQkNoj5Ga/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.rIEnxKBUDj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:46:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:46:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:46:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9cQkNoj5Ga/Render', 02:46:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:46:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:46:02 INFO - 'HOME': '/Users/cltbld', 02:46:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:46:02 INFO - 'LOGNAME': 'cltbld', 02:46:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:46:02 INFO - 'MOZ_NO_REMOTE': '1', 02:46:02 INFO - 'NO_EM_RESTART': '1', 02:46:02 INFO - 'PAGER': '/bin/cat', 02:46:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:46:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:46:02 INFO - 'PWD': '/builds/slave/test', 02:46:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:46:02 INFO - 'SHELL': '/bin/bash', 02:46:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rIEnxKBUDj/Listeners', 02:46:02 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 02:46:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:46:02 INFO - 'USER': 'cltbld', 02:46:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:46:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:46:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:46:02 INFO - 'XPC_FLAGS': '0x0', 02:46:02 INFO - 'XPC_SERVICE_NAME': '0', 02:46:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:46:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:46:03 INFO - Downloading/unpacking blobuploader==1.2.4 02:46:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:46:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:46:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:46:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:46:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:46:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:46:05 INFO - Downloading blobuploader-1.2.4.tar.gz 02:46:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:46:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:46:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:46:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:46:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:46:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:46:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:46:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:46:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:46:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:46:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:46:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:46:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:46:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:46:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:46:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:46:06 INFO - Downloading docopt-0.6.1.tar.gz 02:46:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:46:06 INFO - Installing collected packages: blobuploader, requests, docopt 02:46:06 INFO - Running setup.py install for blobuploader 02:46:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:46:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:46:07 INFO - Running setup.py install for requests 02:46:07 INFO - Running setup.py install for docopt 02:46:07 INFO - Successfully installed blobuploader requests docopt 02:46:07 INFO - Cleaning up... 02:46:07 INFO - Return code: 0 02:46:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:46:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:46:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:46:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:46:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:46:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:46:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:46:07 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 0x10760da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10761d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa270d67b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074ace00>, '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 0x107315db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074a7b20>, '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.9cQkNoj5Ga/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.rIEnxKBUDj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:46:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:46:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:46:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9cQkNoj5Ga/Render', 02:46:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:46:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:46:07 INFO - 'HOME': '/Users/cltbld', 02:46:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:46:07 INFO - 'LOGNAME': 'cltbld', 02:46:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:46:07 INFO - 'MOZ_NO_REMOTE': '1', 02:46:07 INFO - 'NO_EM_RESTART': '1', 02:46:07 INFO - 'PAGER': '/bin/cat', 02:46:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:46:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:46:07 INFO - 'PWD': '/builds/slave/test', 02:46:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:46:07 INFO - 'SHELL': '/bin/bash', 02:46:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rIEnxKBUDj/Listeners', 02:46:07 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 02:46:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:46:07 INFO - 'USER': 'cltbld', 02:46:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:46:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:46:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:46:07 INFO - 'XPC_FLAGS': '0x0', 02:46:07 INFO - 'XPC_SERVICE_NAME': '0', 02:46:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:46:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:46:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:46:08 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-dz4Lyi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:46:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:46:08 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-NfUhk6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:46:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:46:08 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-ybsprP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:46:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:46:08 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-eG095l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:46:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:46:08 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-15XLDj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:46:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:46:08 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-0LhhxP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:46:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:46:08 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-ba70Gg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:46:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:46:08 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-UmiOVf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:46:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:46:08 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-AHdmr_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:46:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:46:08 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-snENW0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:46:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:46:09 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-mHmubP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:46:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:46:09 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-8e5ZRu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:46:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:46:09 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-Hp7TS9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:46:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:46:09 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-nbE6v4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:46:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:46:09 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-Dz4ZkV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:46:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:46:09 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-p_p22R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:46:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:46:09 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-hauM93-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:46:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:46:09 INFO - Running setup.py install for manifestparser 02:46:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:46:09 INFO - Running setup.py install for mozcrash 02:46:10 INFO - Running setup.py install for mozdebug 02:46:10 INFO - Running setup.py install for mozdevice 02:46:10 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:46:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:46:10 INFO - Running setup.py install for mozfile 02:46:10 INFO - Running setup.py install for mozhttpd 02:46:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:46:10 INFO - Running setup.py install for mozinfo 02:46:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:46:10 INFO - Running setup.py install for mozInstall 02:46:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:46:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:46:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:46:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:46:10 INFO - Running setup.py install for mozleak 02:46:11 INFO - Running setup.py install for mozlog 02:46:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:46:11 INFO - Running setup.py install for moznetwork 02:46:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:46:11 INFO - Running setup.py install for mozprocess 02:46:11 INFO - Running setup.py install for mozprofile 02:46:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:46:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:46:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:46:11 INFO - Running setup.py install for mozrunner 02:46:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:46:11 INFO - Running setup.py install for mozscreenshot 02:46:12 INFO - Running setup.py install for moztest 02:46:12 INFO - Running setup.py install for mozversion 02:46:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:46:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:46:12 INFO - Cleaning up... 02:46:12 INFO - Return code: 0 02:46:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:46:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:46:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:46:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:46:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:46:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:46:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:46:12 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 0x10760da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10761d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa270d67b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074ace00>, '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 0x107315db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074a7b20>, '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.9cQkNoj5Ga/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.rIEnxKBUDj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:46:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:46:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:46:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9cQkNoj5Ga/Render', 02:46:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:46:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:46:12 INFO - 'HOME': '/Users/cltbld', 02:46:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:46:12 INFO - 'LOGNAME': 'cltbld', 02:46:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:46:12 INFO - 'MOZ_NO_REMOTE': '1', 02:46:12 INFO - 'NO_EM_RESTART': '1', 02:46:12 INFO - 'PAGER': '/bin/cat', 02:46:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:46:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:46:12 INFO - 'PWD': '/builds/slave/test', 02:46:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:46:12 INFO - 'SHELL': '/bin/bash', 02:46:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rIEnxKBUDj/Listeners', 02:46:12 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 02:46:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:46:12 INFO - 'USER': 'cltbld', 02:46:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:46:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:46:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:46:12 INFO - 'XPC_FLAGS': '0x0', 02:46:12 INFO - 'XPC_SERVICE_NAME': '0', 02:46:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:46:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:46:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:46:12 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-YWeVwv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:46:12 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:46:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:46:12 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-RR8CuB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:46:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:46:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:46:12 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-1FNNxk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:46:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:46:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:46:12 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-1Qs7jw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:46:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:46:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:46:13 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-31tD2h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:46:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:46:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:46:13 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-BlbW9I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:46:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:46:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:46:13 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-EdKE71-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:46:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 02:46:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:46:13 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-hidPUW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:46:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:46:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:46:13 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-rf3S9B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:46:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 02:46:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:46:13 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-Zz7vsY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:46:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:46:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:46:13 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-WfPKO0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:46:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 02:46:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:46:13 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-p17VBI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:46:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:46:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:46:13 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-2lBcXT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:46:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:46:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:46:13 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-aEfsr5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:46:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:46:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:46:14 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-bj2AzI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:46:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:46:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:46:14 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-7geddt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:46:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:46:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:46:14 INFO - Running setup.py (path:/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/pip-0NIl7W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:46:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 02:46:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:46:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:46:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:46:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:46:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:46:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:46:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:46:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:46:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:46:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:46:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:46:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:46:16 INFO - Downloading blessings-1.6.tar.gz 02:46:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:46:16 INFO - Installing collected packages: blessings 02:46:16 INFO - Running setup.py install for blessings 02:46:17 INFO - Successfully installed blessings 02:46:17 INFO - Cleaning up... 02:46:17 INFO - Return code: 0 02:46:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:46:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:46:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:46:17 INFO - Reading from file tmpfile_stdout 02:46:17 INFO - Current package versions: 02:46:17 INFO - blessings == 1.6 02:46:17 INFO - blobuploader == 1.2.4 02:46:17 INFO - docopt == 0.6.1 02:46:17 INFO - manifestparser == 1.1 02:46:17 INFO - mozInstall == 1.12 02:46:17 INFO - mozcrash == 0.17 02:46:17 INFO - mozdebug == 0.1 02:46:17 INFO - mozdevice == 0.48 02:46:17 INFO - mozfile == 1.2 02:46:17 INFO - mozhttpd == 0.7 02:46:17 INFO - mozinfo == 0.9 02:46:17 INFO - mozleak == 0.1 02:46:17 INFO - mozlog == 3.1 02:46:17 INFO - moznetwork == 0.27 02:46:17 INFO - mozprocess == 0.22 02:46:17 INFO - mozprofile == 0.28 02:46:17 INFO - mozrunner == 6.11 02:46:17 INFO - mozscreenshot == 0.1 02:46:17 INFO - mozsystemmonitor == 0.0 02:46:17 INFO - moztest == 0.7 02:46:17 INFO - mozversion == 1.4 02:46:17 INFO - psutil == 3.1.1 02:46:17 INFO - requests == 1.2.3 02:46:17 INFO - wsgiref == 0.1.2 02:46:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:46: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')]} 02:46:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:46:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:46: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')]} 02:46:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:46:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:46:17 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 0x10760da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10761d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa270d67b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074ace00>, '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 0x107315db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074a7b20>, '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.9cQkNoj5Ga/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.rIEnxKBUDj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:46:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 02:46:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:46:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9cQkNoj5Ga/Render', 02:46:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:46:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:46:17 INFO - 'HOME': '/Users/cltbld', 02:46:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:46:17 INFO - 'LOGNAME': 'cltbld', 02:46:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:46:17 INFO - 'MOZ_NO_REMOTE': '1', 02:46:17 INFO - 'NO_EM_RESTART': '1', 02:46:17 INFO - 'PAGER': '/bin/cat', 02:46:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:46:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:46:17 INFO - 'PWD': '/builds/slave/test', 02:46:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:46:17 INFO - 'SHELL': '/bin/bash', 02:46:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rIEnxKBUDj/Listeners', 02:46:17 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 02:46:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:46:17 INFO - 'USER': 'cltbld', 02:46:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:46:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:46:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:46:17 INFO - 'XPC_FLAGS': '0x0', 02:46:17 INFO - 'XPC_SERVICE_NAME': '0', 02:46:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:46:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:46:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:46:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 02:46:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 02:46:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:46:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 02:46:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 02:46:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 02:46:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 02:46:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 02:46:17 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:46:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:46:17 INFO - Cleaning up... 02:46:17 INFO - Return code: 0 02:46:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:46: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')]} 02:46:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:46:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:46: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')]} 02:46:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:46:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:46:17 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 0x10760da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10761d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa270d67b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074ace00>, '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 0x107315db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074a7b20>, '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.9cQkNoj5Ga/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.rIEnxKBUDj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:46:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:46:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:46:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9cQkNoj5Ga/Render', 02:46:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:46:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:46:17 INFO - 'HOME': '/Users/cltbld', 02:46:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:46:17 INFO - 'LOGNAME': 'cltbld', 02:46:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:46:17 INFO - 'MOZ_NO_REMOTE': '1', 02:46:17 INFO - 'NO_EM_RESTART': '1', 02:46:17 INFO - 'PAGER': '/bin/cat', 02:46:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:46:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:46:17 INFO - 'PWD': '/builds/slave/test', 02:46:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:46:17 INFO - 'SHELL': '/bin/bash', 02:46:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rIEnxKBUDj/Listeners', 02:46:17 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 02:46:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:46:17 INFO - 'USER': 'cltbld', 02:46:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:46:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:46:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:46:17 INFO - 'XPC_FLAGS': '0x0', 02:46:17 INFO - 'XPC_SERVICE_NAME': '0', 02:46:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:46:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:46:17 INFO - Downloading/unpacking jsonschema 02:46:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:46:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:46:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:46:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:46:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:46:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:46:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:46:20 INFO - Downloading/unpacking functools32 (from jsonschema) 02:46:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:46:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:46:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:46:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:46:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:46:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:46:20 INFO - Downloading functools32-3.2.3-2.tar.gz 02:46:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:46:20 INFO - warning: no files found matching '*.txt' 02:46:20 INFO - no previously-included directories found matching 'build' 02:46:20 INFO - no previously-included directories found matching 'dist' 02:46:20 INFO - no previously-included directories found matching '.git*' 02:46:20 INFO - Installing collected packages: jsonschema, functools32 02:46:20 INFO - Running setup.py install for functools32 02:46:21 INFO - warning: no files found matching '*.txt' 02:46:21 INFO - no previously-included directories found matching 'build' 02:46:21 INFO - no previously-included directories found matching 'dist' 02:46:21 INFO - no previously-included directories found matching '.git*' 02:46:21 INFO - Successfully installed jsonschema functools32 02:46:21 INFO - Cleaning up... 02:46:21 INFO - Return code: 0 02:46:21 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:46:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:46:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:46:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:46:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:46:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:46:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:46:21 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 0x10760da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10761d420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa270d67b60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1074ace00>, '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 0x107315db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074a7b20>, '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.9cQkNoj5Ga/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.rIEnxKBUDj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:46:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 02:46:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 02:46:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9cQkNoj5Ga/Render', 02:46:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:46:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:46:21 INFO - 'HOME': '/Users/cltbld', 02:46:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:46:21 INFO - 'LOGNAME': 'cltbld', 02:46:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:46:21 INFO - 'MOZ_NO_REMOTE': '1', 02:46:21 INFO - 'NO_EM_RESTART': '1', 02:46:21 INFO - 'PAGER': '/bin/cat', 02:46:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:46:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:46:21 INFO - 'PWD': '/builds/slave/test', 02:46:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:46:21 INFO - 'SHELL': '/bin/bash', 02:46:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rIEnxKBUDj/Listeners', 02:46:21 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 02:46:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:46:21 INFO - 'USER': 'cltbld', 02:46:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:46:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:46:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:46:21 INFO - 'XPC_FLAGS': '0x0', 02:46:21 INFO - 'XPC_SERVICE_NAME': '0', 02:46:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:46:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:46:21 INFO - Downloading/unpacking flake8 02:46:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:46:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:46:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:46:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:46:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:46:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:46:23 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:46:23 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:46:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:46:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:46:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:46:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:46:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:46:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:46:24 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:46:24 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:46:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:46:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:46:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:46:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:46:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:46:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:46:24 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:46:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:46:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:46:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:46:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:46:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:46:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:46:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:46:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:46:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:46:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:46:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:46:24 INFO - no previously-included directories found matching 'docs/_build' 02:46:25 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:46:25 INFO - Running setup.py install for pep8 02:46:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:46:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:46:25 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:46:25 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:46:25 INFO - no previously-included directories found matching 'docs/_build' 02:46:25 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:46:25 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:46:25 INFO - Cleaning up... 02:46:25 INFO - Return code: 0 02:46:25 INFO - Running post-action listener: _resource_record_post_action 02:46:25 INFO - Running post-action listener: _start_resource_monitoring 02:46:25 INFO - Starting resource monitoring. 02:46:25 INFO - ##### 02:46:25 INFO - ##### Running install step. 02:46:25 INFO - ##### 02:46:25 INFO - Running pre-action listener: _resource_record_pre_action 02:46:25 INFO - Running main action method: install 02:46:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:46:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:46:25 INFO - Reading from file tmpfile_stdout 02:46:25 INFO - Detecting whether we're running mozinstall >=1.0... 02:46:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:46:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:46:25 INFO - Reading from file tmpfile_stdout 02:46:25 INFO - Output received: 02:46:25 INFO - Usage: mozinstall [options] installer 02:46:25 INFO - Options: 02:46:25 INFO - -h, --help show this help message and exit 02:46:25 INFO - -d DEST, --destination=DEST 02:46:25 INFO - Directory to install application into. [default: 02:46:25 INFO - "/builds/slave/test"] 02:46:25 INFO - --app=APP Application being installed. [default: firefox] 02:46:25 INFO - mkdir: /builds/slave/test/build/application 02:46:25 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'] 02:46:25 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 02:46:57 INFO - Reading from file tmpfile_stdout 02:46:57 INFO - Output received: 02:46:57 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:46:57 INFO - Running post-action listener: _resource_record_post_action 02:46:57 INFO - ##### 02:46:57 INFO - ##### Running run-tests step. 02:46:57 INFO - ##### 02:46:57 INFO - Running pre-action listener: _resource_record_pre_action 02:46:57 INFO - Running main action method: run_tests 02:46:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:46:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:46:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:46:57 INFO - 2016-04-26 02:46:57.660 screenresolution[2029:15467] starting screenresolution argv=screenresolution get 02:46:57 INFO - 2016-04-26 02:46:57.662 screenresolution[2029:15467] Display 0: 1600x1200x32@60 02:46:57 INFO - 2016-04-26 02:46:57.668 screenresolution[2030:15469] starting screenresolution argv=screenresolution list 02:46:57 INFO - Available Modes on Display 0 02:46:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:46:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:46:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:46:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:46:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:46:57 INFO - Intel Iris: 02:46:57 INFO - Chipset Model: Intel Iris 02:46:57 INFO - Type: GPU 02:46:57 INFO - Bus: Built-In 02:46:57 INFO - VRAM (Dynamic, Max): 1536 MB 02:46:57 INFO - Vendor: Intel (0x8086) 02:46:57 INFO - Device ID: 0x0a2e 02:46:57 INFO - Revision ID: 0x0009 02:46:57 INFO - Displays: 02:46:57 INFO - Display: 02:46:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:46:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:46:57 INFO - Display Serial Number: 1600x1200 60 02:46:57 INFO - Main Display: Yes 02:46:57 INFO - Mirror: Off 02:46:57 INFO - Online: Yes 02:46:57 INFO - Rotation: Supported 02:46:57 INFO - Return code: 0 02:46:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:46:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:46:57 INFO - Python 2.7.3 02:46:57 INFO - Return code: 0 02:46:57 INFO - grabbing minidump binary from tooltool 02:46:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:46:57 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 0x1074ace00>, '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 0x107315db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1074a7b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:46:57 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 02:46:57 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 02:46:57 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:46:57 INFO - Return code: 0 02:46:57 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:46:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:46:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:46:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:46:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:46:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:46:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:46:59 INFO - Return code: 0 02:46:59 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/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0153', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 02:46:59 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/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0153 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 02:46:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9cQkNoj5Ga/Render', 02:46:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:46:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:46:59 INFO - 'HOME': '/Users/cltbld', 02:46:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:46:59 INFO - 'LOGNAME': 'cltbld', 02:46:59 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:46:59 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:46:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:46:59 INFO - 'MOZ_NO_REMOTE': '1', 02:46:59 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:46:59 INFO - 'NO_EM_RESTART': '1', 02:46:59 INFO - 'PAGER': '/bin/cat', 02:46:59 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:46:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:46:59 INFO - 'PWD': '/builds/slave/test', 02:46:59 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:46:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:46:59 INFO - 'SHELL': '/bin/bash', 02:46:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rIEnxKBUDj/Listeners', 02:46:59 INFO - 'TMPDIR': '/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/', 02:46:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:46:59 INFO - 'USER': 'cltbld', 02:46:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:46:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:46:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:46:59 INFO - 'XPC_FLAGS': '0x0', 02:46:59 INFO - 'XPC_SERVICE_NAME': '0', 02:46:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:46:59 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/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0153', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 02:46:59 INFO - mozversion application_buildid: 20160426004107 02:46:59 INFO - mozversion application_changeset: ca6ab5be342e2d7817b0015447872dab3ecd5ef1 02:46:59 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:46:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:46:59 INFO - mozversion application_name: Firefox 02:46:59 INFO - mozversion application_remotingname: firefox-dev 02:46:59 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:46:59 INFO - mozversion application_vendor: Mozilla 02:46:59 INFO - mozversion application_version: 48.0a2 02:46:59 INFO - mozversion platform_buildid: 20160426004107 02:46:59 INFO - mozversion platform_changeset: ca6ab5be342e2d7817b0015447872dab3ecd5ef1 02:46:59 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:46:59 INFO - mozversion platform_version: 48.0a2 02:46:59 INFO - using testdate: 1461664019 02:46:59 INFO - actual date: 1461664019 02:46:59 INFO - starting webserver on 'localhost:51515' 02:46:59 INFO - SUITE-START | Running 2 tests 02:46:59 INFO - TEST-START | dromaeo_css 02:46:59 INFO - operating with platform_type : mac_ 02:46:59 INFO - Initialising browser for dromaeo_css test... 02:46:59 INFO - TEST-INFO | started process 2048 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/tmp3U7GMS/profile http://localhost:51515/getInfo.html) 02:47:02 INFO - PROCESS | 2048 | __metrics Screen width/height:1600/1200 02:47:02 INFO - PROCESS | 2048 | colorDepth:24 02:47:02 INFO - PROCESS | 2048 | Browser inner width/height: 1024/689 02:47:02 INFO - PROCESS | 2048 | __metrics 02:47:02 ERROR - PROCESS | 2048 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 02:47:03 INFO - TEST-INFO | 2048: exit 0 02:47:03 INFO - Browser initialized. 02:47:03 INFO - Running cycle 1/1 for dromaeo_css test... 02:47:03 INFO - TEST-INFO | started process 2052 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/tmp3U7GMS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:47:15 INFO - PROCESS | 2052 | RSS: Main: 221790208 02:47:15 INFO - PROCESS | 2052 | 02:48:04 INFO - PROCESS | 2052 | 1461664084933 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 02:50:15 INFO - PROCESS | 2052 | Cycle 1(1): loaded http://localhost:51515/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51515/tests/dromaeo/cssquery-ext.html) 02:50:17 INFO - PROCESS | 2052 | RSS: Main: 232169472 02:50:17 INFO - PROCESS | 2052 | 02:53:19 INFO - PROCESS | 2052 | Cycle 1(1): loaded http://localhost:51515/tests/dromaeo/cssquery-ext.html (next: http://localhost:51515/tests/dromaeo/cssquery-jquery.html) 02:53:20 INFO - PROCESS | 2052 | RSS: Main: 243707904 02:53:20 INFO - PROCESS | 2052 | 02:56:21 INFO - PROCESS | 2052 | Cycle 1(1): loaded http://localhost:51515/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51515/tests/dromaeo/cssquery-mootools.html) 02:56:22 INFO - PROCESS | 2052 | RSS: Main: 245633024 02:56:22 INFO - PROCESS | 2052 | 02:59:24 INFO - PROCESS | 2052 | Cycle 1(1): loaded http://localhost:51515/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51515/tests/dromaeo/cssquery-prototype.html) 02:59:24 INFO - PROCESS | 2052 | RSS: Main: 247468032 02:59:24 INFO - PROCESS | 2052 | 03:02:27 INFO - PROCESS | 2052 | Cycle 1(1): loaded http://localhost:51515/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51515/tests/dromaeo/cssquery-yui.html) 03:02:28 INFO - PROCESS | 2052 | RSS: Main: 250318848 03:02:28 INFO - PROCESS | 2052 | 03:05:30 INFO - PROCESS | 2052 | Cycle 1(1): loaded http://localhost:51515/tests/dromaeo/cssquery-yui.html (next: http://localhost:51515/tests/dromaeo/cssquery-dojo.html) 03:05:30 INFO - PROCESS | 2052 | RSS: Main: 251584512 03:05:30 INFO - PROCESS | 2052 | 03:05:30 INFO - PROCESS | 2052 | __start_tp_report 03:05:30 INFO - PROCESS | 2052 | _x_x_mozilla_page_load 03:05:30 INFO - PROCESS | 2052 | _x_x_mozilla_page_load_details 03:05:30 INFO - PROCESS | 2052 | |i|pagename|runs| 03:05:30 INFO - PROCESS | 2052 | |0;dojo.html;3396.779209351392;3440.6731360520744;3450.8964731058077;3495.388307046266;3507.0355652195644;5813.040848260032;5832.096025116109;5845.094010428385;5845.3570107288215;5860.413958604137;5833.154192642064;5845.824625261249;5850.795222167214;5889.793857214988;5916.408359164081;5247.501487358704;5324.65389749667;5324.92012619811;5336.546393556552;5339.759710813023;5230.320058392404;5324.094903866333;5343.332083489564;5438.075527160372;5455.563554915617;5309.41596424393;5315.893682126355;5323;5326.414094449608;5331.306930099082;5274.129768588197;5308.124159513675;5309.36287645485;5335.973320133413;5418.241446197536;5152.92270615941;5253.133233016548;5299.019681358961;5317.574594032507;5352.2506849041165;5757.453041961007;5800.651960882361;5830.32951210612;5847.678377689225;5880.206172166745;6121.816345509642;6131.927227014014;6297.464715499188;6298.590591611531;6313;6285.371462853724;6286.214223222098;6320.873582528369;6324;6327.082573026932;5885.323187833411;5930.881382372371;5940.2871655401195;5985.22192115031;5994.613043630378;6220.440160385587;6297.685115744194;6312.810615681537;6323.747050117944;6371.936280637135;6228.252609686867;6253.6873156342;6257.468115210167;6266.843328916722;6286.057091436322;6291.182146320949;6308.400701933309;6322.051692246107;6330.05049242608;6344.809655710336;5206.635535512553;5927.1405646181065;6110.467630727307;6201.224846894138;6306.810795676137;6246.6876656167005;6284;6291.874162516816;6293.685315734195;6315.305316415191;4067.9796601016806;4869.853904382803;4998;5001.799928002839;5004.949950500449;4427.048184640318;4446.377507149002;4498.280275155959;4506.639468842484;4560.247559152703;5767.653940763567;5856.795012174553;5863.67749773758;5893.410658934158;5945.16767652529;6165.475934545614;6220.191375121296;6260.436560709467;6287.49700023997;6287.874242515124;5683.318001839723;5825.24271844666;5846.298444186725;5902.793402230987;5955.3151387590115;5448.945510544923;5458.153986132156;5483.424240454695;5486.972565137149;5495.0383682855345;6332.11350410952;6479.773207937699;6497.707603157964;6500.992346186349;6515.315891831384;6235.563510554218;6254.687265636609;6277.717502712298;6292.968535157295;6297.685115744286;6471.546991710535;6482.22213334384;6485.319041500575;6496.577722448026;6505.154329937078;6104.938950610437;6164.845878852885;6236.75052997875;6291.370862913672;6295.622262664255;6268.592541484789;6307.747690092345;6328.303886572566;6335.841603959938;6340.619562826245;6177.4749146321265;6199.969000154971;6212.502999759918;6213.65824879636;6242.906356404748;6049.0926361046195;6128.136482154648;6144.15675297418;6191.938080619136;6198.969005154945;4454.554544545649;5934.495567876779;5972.25346831646;6067.453929146398;6117.082437634391;6182.1345011699295;6206.06908963659;6263.592866463665;6274.964630836043;6281.96859015702;5746.798862039808;6006.309274433496;6138.633639632548;6148.938510614837;6184.350643182581 03:05:30 INFO - PROCESS | 2052 | |1;ext.html;501.1680610187088;535.4698848140341;563.2846285217773;577.4341145677239;606.3269770554683;29332.853335733318;40708.592914070854;40739;40772;40876.59123408765;33382.833085834594;41709.582904171024;42323.36514952278;42599.57400425995;42929;9376.765580860465;9569.712908612753;9615.13463788259;9632;9644.903550964471;8398.748037558853;9608.711738647851;9820.36167649104;9852.310338276324;9854.113129818315;9774.462404567746;9797.26520510961;9868.111869931705;9878.357906736075;9894.703158905244;9761.755956101084;9826.312158148932;9828.901710982906;9885.851712224323;9917.652882149127;9625.51872406377;9719.854202186973;9727.513624318755;9749.951250243774;9752.707418777449;35632;40012.59987400118;40063.59936400628;40639;40817.79591102055;3176.9364612707845;3252.70725634694;3328.118048717092;3339.34882697874;3700.870469533553;26339.604905926426;27060.05289814847;27801.443971120465;30091.94678186253;30208;2770.7644850187758;2779.7498225159834;2787.3171073087224;2842.161562339115;2875.6405449318836;476843;483377;488143;488608;492281;35764.64235357613;72421.6378918102;73776;73894;74036.51926961538;37746.43380349297;37769.62230377716;38040;38075.428868566996;38154.04614884595;15069;15482.993605415611;15488;15505.689886202214;15591.688166236612;12848.48606055766;13125.868741312655;13131;13206.207627542186;13227.603171904859;8376.790580235544;8382.37132215074;8389.286910612665;8457.858189144386;8608.569571521524;3533.258015816656;3549.414346632777;3567.1795487038125;3578.534790477221;3581.71346292296;960.6013504395597;984.9556769945369;1006.5722068121165;1047.863777708893;1078.4823284823328;22116.668249976265;26271;26503.469930601666;26603.46793064128;26690;1080.7676349584876;1081.4646749858714;1107.9556817727362;1154.9133814963743;1176.3765204441659;11477.942610286895;11586.13104017202;14261;14846.257687115602;14918.850811491746;663181;665840.6707966429;670198;677630;680019;62874.37125628685;63415;63694;63835;64457.03314450287;1490.1655073159066;1514.1142431677592;1519.0202319503858;1519.4425713643604;1528.6407694191855;25205.243842684748;25847.483050339262;25930.61104083478;25976.22071337863;25977.480450391264;55232.44767552434;55349;55362.44637553573;55762.72118639381;55824;562797;565295;567215;567451;567771;28290.43419131647;28511.28721781972;41485.58514414817;42903.785481073646;43621.78189109161;45535.77232113818;45955.08089838251;46016;46357.76821115873;46384;37454.62545374512;37555.436668450544;37930;38006.049848754;38580.228395432496;36686.816565917;37284.81357593195;37519.62480375161;37571.62428375681;38205.80897095497;38121.618783811806;38379.2324153521;38436.23127537489;39206;39695.80152099221;33223.66776332302;34407.967760967214;34424.655753442144;34905.08022058811;35139.472907906915;25659.87170064138;25743;25750.8712456444;25842.741572584033;26198.73801261963 03:05:30 INFO - PROCESS | 2052 | |2;jquery.html;346.7797948302827;395.8357281728083;400.8256408462318;403.18154147081424;411.07713183902143;15391.922565420411;15472.303746331414;21394.25120120792;21577.4605634859;21869.79716115613;21227.787722122775;21257.787422125773;21668.783312166914;22406.88796556019;22662.546749065008;4834.444038935515;5187.533122019017;5794.565407594437;5930.970345148268;6020.5785595008365;5710.857228569298;6440.516961227927;7572.924270757305;7577.81055473612;7592;7415.1843297237265;7600.7339743109005;7936.404769642271;7985.6406461709075;8014.438989270753;7565.924340756577;7779.72770953014;7812.765617031499;7920.564368959705;7937.642806073741;7333.486655934087;7613.08642962848;7635.923640763633;7657.349125324354;7658.923410765877;28971.85514072416;29067.982620608178;29593.852030739923;30301;30813;2102.852800303979;2315.8494697844585;2329.94175145621;2500.7374225706403;2525.9643546145994;15526.689466210726;15565.610859728484;15882.682346353124;16253.349866005346;16568.254428550743;1735.6355165415277;1876.8310852023383;1889.6126294109813;1905.4950438133767;1918.6066856294426;142412;149249;151587.242063789;154616;154751.99999999886;17758.644827103384;22259.666105008437;22301.21945731924;22389.888050559643;22527.098916043502;14814.407423703147;15578.766318505232;15664.138472384127;38104.809475953;38233.617663823;8751.168638979287;8961.551922403854;9110.043445438267;9146.954265228764;9190.126937940884;9052.27581793455;9054;9149.35954483193;9174.357794954289;9230.58462369195;4783.569478746929;4798.5441383068555;4819.662623616383;4858.684185527929;4891.755412229445;2006.5184355754807;2016.9798302017086;2050.4258807533915;2064.8244899183737;2088.9895550522456;841.5329492131804;882.5013867165031;910.6357457017114;946.4605175050156;971.455984648599;13645.727085458235;18044.729329060076;18131.72802407965;18806.529836753918;19285;935.8876934767871;945.9290553208536;966.4394651102484;971.6744890461774;980.7548112971757;10097.24270679702;10384.584616615402;10395.272330936761;10406.635767748092;10428.582856685647;563089;565889;602345;621358;624868;116572;138781;140622;142286.28856855858;146334.26832865766;1013.8580598716333;1023.8617786598862;1062.3254233561745;1082.99458502707;1086.7989421956963;16571.83428165703;16574.254158562653;16611.83388166151;16923.492295231164;17296.394626188525;38837.02907427337;50146.7492662549;53466.592744654765;54121.72939135279;54989.175162371794;74839.62580187064;78321.60839195768;78952.21047789678;79602;80108.59945700235;5580;5668.064769313017;8332.625031873458;8348.7912802178;8417.747467575982;10392;11836.70408239801;12640.6839829005;12729.108962372915;12885.162464439782;9954.20366370703;10187.08316251541;10230.795384092426;10369.014943580349;10376.740581485523;8762.649494020168;10302.278840481094;10684.145268378355;10685;11287.604933827604;10133.03736145065;10326.632287823582;10368.688939331832;10475.528601213116;10477.214208934512;8282.544460054754;8287.41988060854;8334;8415.621297041771;8656.91343086561;7821.843563128592;9798.80402391934;10342.172626190037;10497.422641754776;10714.464276786286 03:05:30 INFO - PROCESS | 2052 | |3;mootools.html;1553.5028790786946;1563.8592526672596;1587.111217718078;1902.391234804862;1916.6645836978525;8018.599070046504;8439.535825529594;8709.738707838767;8995.370324077317;9005.774855628604;8672.262857657106;8702.52136132514;8809.163129502687;8814.647414103445;8826.984896736878;2456.128074533546;2477.7770000699934;2478.913238036669;2483.677121974141;2487.9253622391357;2449.0571130114945;2455.3370589940705;2482.3173627252468;2492.003198720517;2495.7878580320694;2452.3869032741813;2478.4423504711476;2510.0085466240826;2514.497100579882;2514.6228065790187;2463.4210960424375;2466.1861585676684;2476.1457297232428;2482.6276058591175;2483.925482235536;2454.9386265343333;2458.397692565315;2476.418041760185;2477.6035834266613;2484.9751502484924;9018.954905225432;9031.187193152648;9031.729048128567;9047.004829468753;9083.364164508486;5320.17537281722;5472.069748142785;5505.421930697259;5531;5535.99999999996;3578.4453409721536;3638.854445822164;3651.1967367179177;3732.5520937487404;3738.8504459821575;1035.244271681669;1051.2168434516334;1059.422614675004;1061.3101484035362;1070.314998401024;11659.183857130005;11865.940670296679;11919.523219071227;11922.344271065174;11947.641570752892;4132.628063474242;4826.420829500483;4873.80504779812;4955.677880937727;4963.8014479421145;10197.490125493696;10201.948990255001;10209.38743675382;10213.285070045025;10242.48787560619;5422.593305502103;5476.890462190813;5491.835244942658;5511.255980442669;5518.834434966958;4455.977720111444;4846.903061938741;4926.408830940239;4958.479359667255;5007.223880298559;4071;4135.801243222856;4180.681167003003;4201.033762234703;4207.810648520763;1348.5954213736036;1372.876441120304;1385.9376328065262;1397.4829313154198;1398;672.7376323233942;810.9351251899834;882.6116508736136;903.4534106865383;927.9072092790667;5422.295101636762;5708.71456427177;5741.971290143522;5823.330317013513;5926.703664816742;446.484310621233;455.24883941496137;461.67451946377724;468.414481897628;473.7844280852204;3894.6494815466176;3931.92136157281;3933.704972127102;3955.9011024724036;3966.186931678999;10463.006014428616;10996.450177491093;10996.670099897015;11041.613543525938;11070.169737269736;10763.730906727395;10801.297915635458;10912.56349746017;10934.945325273322;10945.671629850958;7704.460687751915;8270.048944371474;8285.25432711059;8332.291755200755;8521;5486.957478079152;5521.861953451196;5609.943900561106;5621.4659607337235;5765.827025189251;8338.708145214889;9994.950025249827;10282.845757313497;10288.897111028793;10357.22320825926;9766.414015159113;10120.79758404813;10319.380837149794;10319.638812641822;10365.792684146425;6875.5874647521305;7352.264773522605;7570.432217583593;7623.199564045917;7694.07671079474;8351.29014033814;8400.369972251983;8424.747257582283;8528.74413767588;8547.572621368783;7430.517016394133;7609.467337286337;7656.961715191388;7663.425243106678;7710.807229819299;7281.854362912818;7350.52221605594;7383.2985866342615;7458.776236712907;7493.4379921505015;6844.452443804584;6859.657017149023;6924.619145947021;6934.826629334105;6961.408280296231;4067.613576710208;4646.721196728207;4662.393888794435;4781.521847815191;4823.758812059453;4531.773411329486;4714.080754252887;4754.358161648272;4806.903861922812;4936.827211047596 03:05:30 INFO - PROCESS | 2052 | |4;prototype.html;303.29181361520847;321.2835377109047;324.60722525743836;328.8785242323676;331.3786650031191;4760.619150467963;5487.094629386152;5538.307711536058;5555.861103472415;5628.831135065944;4839.540243676854;5309.964556911404;5486.588505862056;5501.339839219289;5522.294075902809;3399.9660003399895;4023.6781057515327;4035.515738111431;4123.670106391496;4237.6692818949605;3542.3269578780078;3928.489296391465;4150.979245103786;4189.832406703728;4207.810648520824;3365.478350855621;3470.9305813883834;4010.979945100285;4013.6387725104737;4251.128518653684;3671.430928206132;3989.920201595981;4253.319468884979;4270.466191726035;4272.914541709149;3526.2594855080465;3698.8335524901445;3714.53568303962;3725.403935370328;3736.906577335561;4400.904746925499;4530.912580980574;4638.721676699409;4773.355596994396;5372.274742909696;3757.28611563802;4672.061094645718;4867;4876.244182151772;4944.8269310574315;5365.409804921455;5830.530834153672;5841.299044114691;5868.97065514674;5887.0858924832055;4116.0327323079055;4116.547179810218;4119.878262694715;4133.945843809839;4137.731047481934;5745.023346031185;5896.439838215363;6215.782447614311;6233.563650544464;6245.500359971237;3503.859845606198;3630.945535816965;3747.6065013173225;3835.9642896417813;4192.685548583869;3339.5157702133056;3721.5906250312446;4264.147170565837;4268.317069268902;4274.957250427518;4499.662525310615;4513.323001549794;4897.975510122426;4899.608031357483;4901.289313049588;5149.9742501288;5367.9264147170725;5904.7342869570975;5926.288845338587;5929.7547515022325;1464.406915199346;1562.1486289971765;1562.7577725452572;1563.3590228006462;1564.3664315952058;935.6023689931752;954.9618015279311;983.6655537117272;1034.4827586206898;1057.117307048623;4622.791974361162;4980.8754781130765;5314.7608357624;5460.31746031746;5543.390227075019;5608;6085.117657939574;6129.724162412702;6181.381861813783;6217.191765070512;5084.313617661643;5278.885457580046;5476.91784623231;5508.402563256691;5531.976584331912;3437.4843773434186;3438.5014172945284;3474.2009337851932;3480.6251530645254;3484.1289677580603;6645.102911107036;6844.726210951506;6885.070515480446;6908.378245957788;6942.20164681058;5104.668196567285;6258.937410625836;6704.865902681919;6797.286284940109;6816.556923799937;5660.349059858169;6213.471854892385;6756.662166891636;6947.305269473012;6955.408790252885;4674.883127921829;5147.279380886754;5352.785888564414;5734.942650573441;6145.692715364304;5584.357798853021;5955.553333499918;6063.909041364295;6071.939280607314;6119.663418512025;5230.189320655304;5459.672419654833;6347.428731414195;6399.712012959521;6406.615603063831;4010.2981978153125;4040.6665800286423;4054.655354294918;4074.4703188585295;4078.8164532596697;5078.5175408336145;5394.973025134849;5645.802396916089;6119.062849832017;6139.57023008403;4869.9026019480125;5686.83419898928;5968;5991.281046274475;6001.4298641628975;5496.945030549643;5854.502367298827;5917.526597872246;5982.7008649568215;5998.760049597967;4314.288142456585;5223.9477605223465;5476.479734425223;5825.70871456434;5827.358990510956;5968.432998865274;5976.280792239608;5987.550933679904;6002.2893475359315;6067.757289708362;5324.946750532445;5749.13762935563;5908.704564771659;5937.465628093492;5986.2217911671205 03:05:30 INFO - PROCESS | 2052 | |5;yui.html;650.8600650860064;718.3929579505316;737.5685224143874;747.5776186454652;753.0097921233578;14410.423583056665;14782.778258326134;14865.10809351437;14930.850691493082;16055.277512511935;12399.814002789943;12515.749685006294;12533.435995380207;12551.05867059972;12576.371181440914;722.1478655186877;910.2354022620997;910.9863352049724;917.7017469322464;918.3571499950028;878.3807415771865;881.5812489067673;883.2801266967401;886.2776836877956;889.3552174673375;962.956666949989;973.9561719722594;974.1476208317722;974.3131092579749;977.3647129365897;957.1481381570408;961.8845738511353;963.8168747937905;964.137097297918;964.6768332608584;688.9827754306183;713.4791602130422;713.7751608243386;718.5724493926106;720.3049057659332;15336.079835209923;15501.224938753017;15527.378904843792;15538;15596.908216424854;10774.838377424345;12268.202566833217;12366.381680915918;12513.74972500545;12538.373081345897;9607.423554586678;10088.69733907984;10108.797824043551;10131.290809643328;10148.238882083873;3379.8141102239365;3415.4023045966856;3465.5841299044278;3504.737144714157;3600.4419315006216;84660.15339846585;85638.57180714118;85703.14296857014;86321.56839215827;88830.55584722036;9141.360104792733;9172.312076594282;9281.350305478552;9521.28590355726;9565.856512152322;21202.363929082152;21294.467638309165;21324.78675213259;21353.893230533748;21462.678059829115;7174.928250717426;7272.8181795454475;7273.9636301818155;7314.08573928259;7335.1197856257595;4017.598240175959;4078.2455245779624;4136.937945930814;4156.875293741193;4231.61915427613;3517.4547945068557;3602.2795440911923;3605.188832512664;3614.512040874501;3616.6383361663625;650.648649729141;661.2858113237692;661.4873473058418;663.7709990053423;666.9533132680763;426.7844738407085;570.5606682854179;582.9766809327664;584.514852672281;585.8857522783017;6518.348165183444;7812.648430820513;7823.804404889809;8033.794930760433;8167.673293068329;470.8987567672967;578.9247397838254;580.9651420914759;594.3610618584952;594.9405059494103;3439.4496880499;3662.560492740888;3771.9622803771613;3859;4030.8589199378466;88596;91508.6273705895;99442;100185;101907.98092019133;61510.38489615136;80517.19482805215;82830;84404.57797710971;84794.30411391737;67369.66315168393;68225;68334.63330733357;68749.65625171842;70429.64785176041;6572.342765723581;6892.483063770337;7501.062367204099;7533.472656913963;7547.207543207884;44753.10493790041;44907;45484;47045;47067.29399059086;62582.374176257654;64613;66821;67534;67628.98556521558;10191.286609937233;10193.898061019592;10423.21825863048;10439.94780026095;10532.104771094237;11096.944515277695;11129.666110016713;11270.211085224284;11308.660740177807;12003.219790713576;9812.705618831447;9893.802123957625;9912.801743964936;9970.40177589347;10330.431826249629;10137.645182418875;10183.965840615992;10265.2301077418;10487.842682359618;10556.841647375142;9692.806143877224;9858.55636496345;9930.304878658424;10244.692659220234;10525.210609204187;8666.956665216885;8949.59726812282;9006.459612423276;9042.864357034518;9065.365424420228;8441.15588441151;8681.826363472821;8725.040245572854;8756.912430875609;8978.967418746928 03:05:30 INFO - PROCESS | 2052 | __end_tp_report 03:05:30 INFO - PROCESS | 2052 | __start_cc_report 03:05:30 INFO - PROCESS | 2052 | _x_x_mozilla_cycle_collect,417 03:05:30 INFO - PROCESS | 2052 | __end_cc_report 03:05:30 INFO - PROCESS | 2052 | __startTimestamp1461665130712__endTimestamp 03:05:30 INFO - PROCESS | 2052 | 03:05:30 INFO - PROCESS | 2052 | ------- Summary: start ------- 03:05:30 INFO - PROCESS | 2052 | Number of tests: 6 03:05:30 INFO - PROCESS | 2052 | 03:05:30 INFO - PROCESS | 2052 | [#0] dojo.html Cycles:165 Average:5818.34 Median:6049.09 stddev:654.66 (10.8%) stddev-sans-first:628.50 03:05:30 INFO - PROCESS | 2052 | Values: 3396.8 3440.7 3450.9 3495.4 3507.0 5813.0 5832.1 5845.1 5845.4 5860.4 5833.2 5845.8 5850.8 5889.8 5916.4 5247.5 5324.7 5324.9 5336.5 5339.8 5230.3 5324.1 5343.3 5438.1 5455.6 5309.4 5315.9 5323.0 5326.4 5331.3 5274.1 5308.1 5309.4 5336.0 5418.2 5152.9 5253.1 5299.0 5317.6 5352.3 5757.5 5800.7 5830.3 5847.7 5880.2 6121.8 6131.9 6297.5 6298.6 6313.0 6285.4 6286.2 6320.9 6324.0 6327.1 5885.3 5930.9 5940.3 5985.2 5994.6 6220.4 6297.7 6312.8 6323.7 6371.9 6228.3 6253.7 6257.5 6266.8 6286.1 6291.2 6308.4 6322.1 6330.1 6344.8 5206.6 5927.1 6110.5 6201.2 6306.8 6246.7 6284.0 6291.9 6293.7 6315.3 4068.0 4869.9 4998.0 5001.8 5004.9 4427.0 4446.4 4498.3 4506.6 4560.2 5767.7 5856.8 5863.7 5893.4 5945.2 6165.5 6220.2 6260.4 6287.5 6287.9 5683.3 5825.2 5846.3 5902.8 5955.3 5448.9 5458.2 5483.4 5487.0 5495.0 6332.1 6479.8 6497.7 6501.0 6515.3 6235.6 6254.7 6277.7 6293.0 6297.7 6471.5 6482.2 6485.3 6496.6 6505.2 6104.9 6164.8 6236.8 6291.4 6295.6 6268.6 6307.7 6328.3 6335.8 6340.6 6177.5 6200.0 6212.5 6213.7 6242.9 6049.1 6128.1 6144.2 6191.9 6199.0 4454.6 5934.5 5972.3 6067.5 6117.1 6182.1 6206.1 6263.6 6275.0 6282.0 5746.8 6006.3 6138.6 6148.9 6184.4 03:05:30 INFO - PROCESS | 2052 | 03:05:30 INFO - PROCESS | 2052 | [#1] ext.html Cycles:180 Average:69857.49 Median:38508.23 stddev:155222.87 (403.1%) stddev-sans-first:155570.96 03:05:30 INFO - PROCESS | 2052 | Values: 501.2 535.5 563.3 577.4 606.3 29332.9 40708.6 40739.0 40772.0 40876.6 33382.8 41709.6 42323.4 42599.6 42929.0 9376.8 9569.7 9615.1 9632.0 9644.9 8398.7 9608.7 9820.4 9852.3 9854.1 9774.5 9797.3 9868.1 9878.4 9894.7 9761.8 9826.3 9828.9 9885.9 9917.7 9625.5 9719.9 9727.5 9750.0 9752.7 35632.0 40012.6 40063.6 40639.0 40817.8 3176.9 3252.7 3328.1 3339.3 3700.9 26339.6 27060.1 27801.4 30091.9 30208.0 2770.8 2779.7 2787.3 2842.2 2875.6 476843.0 483377.0 488143.0 488608.0 492281.0 35764.6 72421.6 73776.0 73894.0 74036.5 37746.4 37769.6 38040.0 38075.4 38154.0 15069.0 15483.0 15488.0 15505.7 15591.7 12848.5 13125.9 13131.0 13206.2 13227.6 8376.8 8382.4 8389.3 8457.9 8608.6 3533.3 3549.4 3567.2 3578.5 3581.7 960.6 985.0 1006.6 1047.9 1078.5 22116.7 26271.0 26503.5 26603.5 26690.0 1080.8 1081.5 1108.0 1154.9 1176.4 11477.9 11586.1 14261.0 14846.3 14918.9 663181.0 665840.7 670198.0 677630.0 680019.0 62874.4 63415.0 63694.0 63835.0 64457.0 1490.2 1514.1 1519.0 1519.4 1528.6 25205.2 25847.5 25930.6 25976.2 25977.5 55232.4 55349.0 55362.4 55762.7 55824.0 562797.0 565295.0 567215.0 567451.0 567771.0 28290.4 28511.3 41485.6 42903.8 43621.8 45535.8 45955.1 46016.0 46357.8 46384.0 37454.6 37555.4 37930.0 38006.0 38580.2 36686.8 37284.8 37519.6 37571.6 38205.8 38121.6 38379.2 38436.2 39206.0 39695.8 33223.7 34408.0 34424.7 34905.1 35139.5 25659.9 25743.0 25750.9 25842.7 26198.7 03:05:30 INFO - PROCESS | 2052 | 03:05:30 INFO - PROCESS | 2052 | [#2] jquery.html Cycles:180 Average:36827.06 Median:29330.92 stddev:100528.19 (342.7%) stddev-sans-first:100772.89 03:05:30 INFO - PROCESS | 2052 | Values: 346.8 395.8 400.8 403.2 411.1 15391.9 15472.3 21394.3 21577.5 21869.8 21227.8 21257.8 21668.8 22406.9 22662.5 4834.4 5187.5 5794.6 5931.0 6020.6 5710.9 6440.5 7572.9 7577.8 7592.0 7415.2 7600.7 7936.4 7985.6 8014.4 7565.9 7779.7 7812.8 7920.6 7937.6 7333.5 7613.1 7635.9 7657.3 7658.9 28971.9 29068.0 29593.9 30301.0 30813.0 2102.9 2315.8 2329.9 2500.7 2526.0 15526.7 15565.6 15882.7 16253.3 16568.3 1735.6 1876.8 1889.6 1905.5 1918.6 142412.0 149249.0 151587.2 154616.0 154752.0 17758.6 22259.7 22301.2 22389.9 22527.1 14814.4 15578.8 15664.1 38104.8 38233.6 8751.2 8961.6 9110.0 9147.0 9190.1 9052.3 9054.0 9149.4 9174.4 9230.6 4783.6 4798.5 4819.7 4858.7 4891.8 2006.5 2017.0 2050.4 2064.8 2089.0 841.5 882.5 910.6 946.5 971.5 13645.7 18044.7 18131.7 18806.5 19285.0 935.9 945.9 966.4 971.7 980.8 10097.2 10384.6 10395.3 10406.6 10428.6 563089.0 565889.0 602345.0 621358.0 624868.0 116572.0 138781.0 140622.0 142286.3 146334.3 1013.9 1023.9 1062.3 1083.0 1086.8 16571.8 16574.3 16611.8 16923.5 17296.4 38837.0 50146.7 53466.6 54121.7 54989.2 74839.6 78321.6 78952.2 79602.0 80108.6 5580.0 5668.1 8332.6 8348.8 8417.7 10392.0 11836.7 12640.7 12729.1 12885.2 9954.2 10187.1 10230.8 10369.0 10376.7 8762.6 10302.3 10684.1 10685.0 11287.6 10133.0 10326.6 10368.7 10475.5 10477.2 8282.5 8287.4 8334.0 8415.6 8656.9 7821.8 9798.8 10342.2 10497.4 10714.5 03:05:30 INFO - PROCESS | 2052 | 03:05:30 INFO - PROCESS | 2052 | [#3] mootools.html Cycles:180 Average:5754.33 Median:2565.40 stddev:3247.39 (126.6%) stddev-sans-first:3241.16 03:05:30 INFO - PROCESS | 2052 | Values: 1553.5 1563.9 1587.1 1902.4 1916.7 8018.6 8439.5 8709.7 8995.4 9005.8 8672.3 8702.5 8809.2 8814.6 8827.0 2456.1 2477.8 2478.9 2483.7 2487.9 2449.1 2455.3 2482.3 2492.0 2495.8 2452.4 2478.4 2510.0 2514.5 2514.6 2463.4 2466.2 2476.1 2482.6 2483.9 2454.9 2458.4 2476.4 2477.6 2485.0 9019.0 9031.2 9031.7 9047.0 9083.4 5320.2 5472.1 5505.4 5531.0 5536.0 3578.4 3638.9 3651.2 3732.6 3738.9 1035.2 1051.2 1059.4 1061.3 1070.3 11659.2 11865.9 11919.5 11922.3 11947.6 4132.6 4826.4 4873.8 4955.7 4963.8 10197.5 10201.9 10209.4 10213.3 10242.5 5422.6 5476.9 5491.8 5511.3 5518.8 4456.0 4846.9 4926.4 4958.5 5007.2 4071.0 4135.8 4180.7 4201.0 4207.8 1348.6 1372.9 1385.9 1397.5 1398.0 672.7 810.9 882.6 903.5 927.9 5422.3 5708.7 5742.0 5823.3 5926.7 446.5 455.2 461.7 468.4 473.8 3894.6 3931.9 3933.7 3955.9 3966.2 10463.0 10996.5 10996.7 11041.6 11070.2 10763.7 10801.3 10912.6 10934.9 10945.7 7704.5 8270.0 8285.3 8332.3 8521.0 5487.0 5521.9 5609.9 5621.5 5765.8 8338.7 9995.0 10282.8 10288.9 10357.2 9766.4 10120.8 10319.4 10319.6 10365.8 6875.6 7352.3 7570.4 7623.2 7694.1 8351.3 8400.4 8424.7 8528.7 8547.6 7430.5 7609.5 7657.0 7663.4 7710.8 7281.9 7350.5 7383.3 7458.8 7493.4 6844.5 6859.7 6924.6 6934.8 6961.4 4067.6 4646.7 4662.4 4781.5 4823.8 4531.8 4714.1 4754.4 4806.9 4936.8 03:05:30 INFO - PROCESS | 2052 | 03:05:30 INFO - PROCESS | 2052 | [#4] prototype.html Cycles:180 Average:4780.82 Median:5294.43 stddev:1504.28 (28.4%) stddev-sans-first:1470.48 03:05:30 INFO - PROCESS | 2052 | Values: 303.3 321.3 324.6 328.9 331.4 4760.6 5487.1 5538.3 5555.9 5628.8 4839.5 5310.0 5486.6 5501.3 5522.3 3400.0 4023.7 4035.5 4123.7 4237.7 3542.3 3928.5 4151.0 4189.8 4207.8 3365.5 3470.9 4011.0 4013.6 4251.1 3671.4 3989.9 4253.3 4270.5 4272.9 3526.3 3698.8 3714.5 3725.4 3736.9 4400.9 4530.9 4638.7 4773.4 5372.3 3757.3 4672.1 4867.0 4876.2 4944.8 5365.4 5830.5 5841.3 5869.0 5887.1 4116.0 4116.5 4119.9 4133.9 4137.7 5745.0 5896.4 6215.8 6233.6 6245.5 3503.9 3630.9 3747.6 3836.0 4192.7 3339.5 3721.6 4264.1 4268.3 4275.0 4499.7 4513.3 4898.0 4899.6 4901.3 5150.0 5367.9 5904.7 5926.3 5929.8 1464.4 1562.1 1562.8 1563.4 1564.4 935.6 955.0 983.7 1034.5 1057.1 4622.8 4980.9 5314.8 5460.3 5543.4 5608.0 6085.1 6129.7 6181.4 6217.2 5084.3 5278.9 5476.9 5508.4 5532.0 3437.5 3438.5 3474.2 3480.6 3484.1 6645.1 6844.7 6885.1 6908.4 6942.2 5104.7 6258.9 6704.9 6797.3 6816.6 5660.3 6213.5 6756.7 6947.3 6955.4 4674.9 5147.3 5352.8 5734.9 6145.7 5584.4 5955.6 6063.9 6071.9 6119.7 5230.2 5459.7 6347.4 6399.7 6406.6 4010.3 4040.7 4054.7 4074.5 4078.8 5078.5 5395.0 5645.8 6119.1 6139.6 4869.9 5686.8 5968.0 5991.3 6001.4 5496.9 5854.5 5917.5 5982.7 5998.8 4314.3 5223.9 5476.5 5825.7 5827.4 5968.4 5976.3 5987.6 6002.3 6067.8 5324.9 5749.1 5908.7 5937.5 5986.2 03:05:30 INFO - PROCESS | 2052 | 03:05:30 INFO - PROCESS | 2052 | [#5] yui.html Cycles:180 Average:18089.33 Median:3616.81 stddev:26288.99 (726.9%) stddev-sans-first:26330.13 03:05:30 INFO - PROCESS | 2052 | Values: 650.9 718.4 737.6 747.6 753.0 14410.4 14782.8 14865.1 14930.9 16055.3 12399.8 12515.7 12533.4 12551.1 12576.4 722.1 910.2 911.0 917.7 918.4 878.4 881.6 883.3 886.3 889.4 963.0 974.0 974.1 974.3 977.4 957.1 961.9 963.8 964.1 964.7 689.0 713.5 713.8 718.6 720.3 15336.1 15501.2 15527.4 15538.0 15596.9 10774.8 12268.2 12366.4 12513.7 12538.4 9607.4 10088.7 10108.8 10131.3 10148.2 3379.8 3415.4 3465.6 3504.7 3600.4 84660.2 85638.6 85703.1 86321.6 88830.6 9141.4 9172.3 9281.4 9521.3 9565.9 21202.4 21294.5 21324.8 21353.9 21462.7 7174.9 7272.8 7274.0 7314.1 7335.1 4017.6 4078.2 4136.9 4156.9 4231.6 3517.5 3602.3 3605.2 3614.5 3616.6 650.6 661.3 661.5 663.8 667.0 426.8 570.6 583.0 584.5 585.9 6518.3 7812.6 7823.8 8033.8 8167.7 470.9 578.9 581.0 594.4 594.9 3439.4 3662.6 3772.0 3859.0 4030.9 88596.0 91508.6 99442.0 100185.0 101908.0 61510.4 80517.2 82830.0 84404.6 84794.3 67369.7 68225.0 68334.6 68749.7 70429.6 6572.3 6892.5 7501.1 7533.5 7547.2 44753.1 44907.0 45484.0 47045.0 47067.3 62582.4 64613.0 66821.0 67534.0 67629.0 10191.3 10193.9 10423.2 10439.9 10532.1 11096.9 11129.7 11270.2 11308.7 12003.2 9812.7 9893.8 9912.8 9970.4 10330.4 10137.6 10184.0 10265.2 10487.8 10556.8 9692.8 9858.6 9930.3 10244.7 10525.2 8667.0 8949.6 9006.5 9042.9 9065.4 8441.2 8681.8 8725.0 8756.9 8979.0 03:05:30 INFO - PROCESS | 2052 | -------- Summary: end -------- 03:05:30 INFO - PROCESS | 2052 | 03:05:31 INFO - TEST-INFO | 2052: exit 0 03:05:31 INFO - TEST-OK | dromaeo_css | took 1111974ms 03:05:31 INFO - TEST-START | kraken 03:05:31 INFO - operating with platform_type : mac_ 03:05:31 INFO - Initialising browser for kraken test... 03:05:31 INFO - TEST-INFO | started process 2057 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/tmpnkFrDc/profile http://localhost:51515/getInfo.html) 03:05:34 INFO - PROCESS | 2057 | __metrics Screen width/height:1600/1200 03:05:34 INFO - PROCESS | 2057 | colorDepth:24 03:05:34 INFO - PROCESS | 2057 | Browser inner width/height: 1024/689 03:05:34 INFO - PROCESS | 2057 | __metrics 03:05:34 ERROR - PROCESS | 2057 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 03:05:34 INFO - TEST-INFO | 2057: exit 0 03:05:34 INFO - Browser initialized. 03:05:34 INFO - Running cycle 1/1 for kraken test... 03:05:34 INFO - TEST-INFO | started process 2061 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/T/tmpnkFrDc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:05:46 INFO - PROCESS | 2061 | RSS: Main: 222113792 03:05:46 INFO - PROCESS | 2061 | 03:05:49 INFO - PROCESS | 2061 | Cycle 1(1): loaded http://localhost:51515/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51515/tests/kraken/driver.html?testName=audio-beat-detection) 03:05:50 INFO - PROCESS | 2061 | RSS: Main: 233754624 03:05:50 INFO - PROCESS | 2061 | 03:05:53 INFO - PROCESS | 2061 | Cycle 1(1): loaded http://localhost:51515/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51515/tests/kraken/driver.html?testName=audio-dft) 03:05:54 INFO - PROCESS | 2061 | RSS: Main: 243081216 03:05:54 INFO - PROCESS | 2061 | 03:05:57 INFO - PROCESS | 2061 | Cycle 1(1): loaded http://localhost:51515/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51515/tests/kraken/driver.html?testName=audio-fft) 03:05:58 INFO - PROCESS | 2061 | RSS: Main: 245145600 03:05:58 INFO - PROCESS | 2061 | 03:06:00 INFO - PROCESS | 2061 | Cycle 1(1): loaded http://localhost:51515/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51515/tests/kraken/driver.html?testName=audio-oscillator) 03:06:01 INFO - PROCESS | 2061 | RSS: Main: 246063104 03:06:01 INFO - PROCESS | 2061 | 03:06:03 INFO - PROCESS | 2061 | Cycle 1(1): loaded http://localhost:51515/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51515/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:06:03 INFO - PROCESS | 2061 | RSS: Main: 248274944 03:06:03 INFO - PROCESS | 2061 | 03:06:06 INFO - PROCESS | 2061 | Cycle 1(1): loaded http://localhost:51515/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51515/tests/kraken/driver.html?testName=imaging-darkroom) 03:06:07 INFO - PROCESS | 2061 | RSS: Main: 247877632 03:06:07 INFO - PROCESS | 2061 | 03:06:10 INFO - PROCESS | 2061 | Cycle 1(1): loaded http://localhost:51515/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51515/tests/kraken/driver.html?testName=imaging-desaturate) 03:06:11 INFO - PROCESS | 2061 | RSS: Main: 248123392 03:06:11 INFO - PROCESS | 2061 | 03:06:14 INFO - PROCESS | 2061 | Cycle 1(1): loaded http://localhost:51515/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51515/tests/kraken/driver.html?testName=json-parse-financial) 03:06:14 INFO - PROCESS | 2061 | RSS: Main: 248332288 03:06:14 INFO - PROCESS | 2061 | 03:06:16 INFO - PROCESS | 2061 | Cycle 1(1): loaded http://localhost:51515/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51515/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:06:17 INFO - PROCESS | 2061 | RSS: Main: 247971840 03:06:17 INFO - PROCESS | 2061 | 03:06:19 INFO - PROCESS | 2061 | Cycle 1(1): loaded http://localhost:51515/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51515/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:06:19 INFO - PROCESS | 2061 | RSS: Main: 247988224 03:06:19 INFO - PROCESS | 2061 | 03:06:21 INFO - PROCESS | 2061 | Cycle 1(1): loaded http://localhost:51515/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51515/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:06:22 INFO - PROCESS | 2061 | RSS: Main: 248229888 03:06:22 INFO - PROCESS | 2061 | 03:06:25 INFO - PROCESS | 2061 | Cycle 1(1): loaded http://localhost:51515/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51515/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:06:26 INFO - PROCESS | 2061 | RSS: Main: 248250368 03:06:26 INFO - PROCESS | 2061 | 03:06:29 INFO - PROCESS | 2061 | Cycle 1(1): loaded http://localhost:51515/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51515/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:06:29 INFO - PROCESS | 2061 | RSS: Main: 248217600 03:06:29 INFO - PROCESS | 2061 | 03:06:31 INFO - PROCESS | 2061 | Cycle 1(1): loaded http://localhost:51515/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51515/tests/kraken/driver.html?testName=ai-astar) 03:06:31 INFO - PROCESS | 2061 | RSS: Main: 249929728 03:06:31 INFO - PROCESS | 2061 | 03:06:31 INFO - PROCESS | 2061 | __start_tp_report 03:06:31 INFO - PROCESS | 2061 | _x_x_mozilla_page_load 03:06:31 INFO - PROCESS | 2061 | _x_x_mozilla_page_load_details 03:06:31 INFO - PROCESS | 2061 | |i|pagename|runs| 03:06:31 INFO - PROCESS | 2061 | |0;ai-astar;123;160;115;116;120;165;169;148;115;117 03:06:31 INFO - PROCESS | 2061 | |1;audio-beat-detection;148;135;192;194;111;176;155;184;152;124 03:06:31 INFO - PROCESS | 2061 | |2;audio-dft;194;199;198;226;286;242;203;219;273;210 03:06:31 INFO - PROCESS | 2061 | |3;audio-fft;68;105;109;186;57;60;58;63;127;73 03:06:31 INFO - PROCESS | 2061 | |4;audio-oscillator;63;104;129;118;114;122;159;87;79;117 03:06:31 INFO - PROCESS | 2061 | |5;imaging-gaussian-blur;91;88;89;87;90;86;90;88;90;87 03:06:31 INFO - PROCESS | 2061 | |6;imaging-darkroom;100;95;96;105;96;99;150;151;104;150 03:06:31 INFO - PROCESS | 2061 | |7;imaging-desaturate;148;87;148;147;121;90;91;92;93;96 03:06:31 INFO - PROCESS | 2061 | |8;json-parse-financial;63;66;67;65;68;67;67;84;65;61 03:06:31 INFO - PROCESS | 2061 | |9;json-stringify-tinderbox;77;103;60;86;111;63;94;97;102;90 03:06:31 INFO - PROCESS | 2061 | |10;stanford-crypto-aes;85;83;84;81;83;83;82;85;85;85 03:06:31 INFO - PROCESS | 2061 | |11;stanford-crypto-ccm;161;156;130;139;139;119;152;156;194;151 03:06:31 INFO - PROCESS | 2061 | |12;stanford-crypto-pbkdf2;174;172;171;169;175;173;172;175;173;173 03:06:31 INFO - PROCESS | 2061 | |13;stanford-crypto-sha256-iterative;71;71;70;70;70;65;86;100;96;99 03:06:31 INFO - PROCESS | 2061 | __end_tp_report 03:06:31 INFO - PROCESS | 2061 | __start_cc_report 03:06:31 INFO - PROCESS | 2061 | _x_x_mozilla_cycle_collect,1086 03:06:31 INFO - PROCESS | 2061 | __end_cc_report 03:06:31 INFO - PROCESS | 2061 | __startTimestamp1461665191788__endTimestamp 03:06:31 INFO - PROCESS | 2061 | 03:06:31 INFO - PROCESS | 2061 | ------- Summary: start ------- 03:06:31 INFO - PROCESS | 2061 | Number of tests: 14 03:06:31 INFO - PROCESS | 2061 | 03:06:31 INFO - PROCESS | 2061 | [#0] ai-astar Cycles:10 Average:134.80 Median:135.50 stddev:22.86 (16.9%) stddev-sans-first:23.85 03:06:31 INFO - PROCESS | 2061 | Values: 123.0 160.0 115.0 116.0 120.0 165.0 169.0 148.0 115.0 117.0 03:06:31 INFO - PROCESS | 2061 | 03:06:31 INFO - PROCESS | 2061 | [#1] audio-beat-detection Cycles:10 Average:157.10 Median:165.50 stddev:28.83 (17.4%) stddev-sans-first:30.40 03:06:31 INFO - PROCESS | 2061 | Values: 148.0 135.0 192.0 194.0 111.0 176.0 155.0 184.0 152.0 124.0 03:06:31 INFO - PROCESS | 2061 | 03:06:31 INFO - PROCESS | 2061 | [#2] audio-dft Cycles:10 Average:225.00 Median:222.50 stddev:32.36 (14.5%) stddev-sans-first:32.32 03:06:31 INFO - PROCESS | 2061 | Values: 194.0 199.0 198.0 226.0 286.0 242.0 203.0 219.0 273.0 210.0 03:06:31 INFO - PROCESS | 2061 | 03:06:31 INFO - PROCESS | 2061 | [#3] audio-fft Cycles:10 Average:90.60 Median:59.00 stddev:41.74 (70.8%) stddev-sans-first:43.47 03:06:31 INFO - PROCESS | 2061 | Values: 68.0 105.0 109.0 186.0 57.0 60.0 58.0 63.0 127.0 73.0 03:06:31 INFO - PROCESS | 2061 | 03:06:31 INFO - PROCESS | 2061 | [#4] audio-oscillator Cycles:10 Average:109.20 Median:144.00 stddev:27.41 (19.0%) stddev-sans-first:23.43 03:06:31 INFO - PROCESS | 2061 | Values: 63.0 104.0 129.0 118.0 114.0 122.0 159.0 87.0 79.0 117.0 03:06:31 INFO - PROCESS | 2061 | 03:06:31 INFO - PROCESS | 2061 | [#5] imaging-gaussian-blur Cycles:10 Average:88.60 Median:89.50 stddev:1.65 (1.8%) stddev-sans-first:1.50 03:06:31 INFO - PROCESS | 2061 | Values: 91.0 88.0 89.0 87.0 90.0 86.0 90.0 88.0 90.0 87.0 03:06:31 INFO - PROCESS | 2061 | 03:06:31 INFO - PROCESS | 2061 | [#6] imaging-darkroom Cycles:10 Average:114.60 Median:123.00 stddev:24.87 (20.2%) stddev-sans-first:25.82 03:06:31 INFO - PROCESS | 2061 | Values: 100.0 95.0 96.0 105.0 96.0 99.0 150.0 151.0 104.0 150.0 03:06:31 INFO - PROCESS | 2061 | 03:06:31 INFO - PROCESS | 2061 | [#7] imaging-desaturate Cycles:10 Average:111.30 Median:90.50 stddev:26.79 (29.6%) stddev-sans-first:24.91 03:06:31 INFO - PROCESS | 2061 | Values: 148.0 87.0 148.0 147.0 121.0 90.0 91.0 92.0 93.0 96.0 03:06:31 INFO - PROCESS | 2061 | 03:06:31 INFO - PROCESS | 2061 | [#8] json-parse-financial Cycles:10 Average:67.30 Median:67.00 stddev:6.24 (9.3%) stddev-sans-first:6.42 03:06:31 INFO - PROCESS | 2061 | Values: 63.0 66.0 67.0 65.0 68.0 67.0 67.0 84.0 65.0 61.0 03:06:31 INFO - PROCESS | 2061 | 03:06:31 INFO - PROCESS | 2061 | [#9] json-stringify-tinderbox Cycles:10 Average:88.30 Median:81.50 stddev:17.01 (20.9%) stddev-sans-first:17.54 03:06:31 INFO - PROCESS | 2061 | Values: 77.0 103.0 60.0 86.0 111.0 63.0 94.0 97.0 102.0 90.0 03:06:31 INFO - PROCESS | 2061 | 03:06:31 INFO - PROCESS | 2061 | [#10] stanford-crypto-aes Cycles:10 Average:83.60 Median:84.50 stddev:1.43 (1.7%) stddev-sans-first:1.42 03:06:31 INFO - PROCESS | 2061 | Values: 85.0 83.0 84.0 81.0 83.0 83.0 82.0 85.0 85.0 85.0 03:06:31 INFO - PROCESS | 2061 | 03:06:31 INFO - PROCESS | 2061 | [#11] stanford-crypto-ccm Cycles:10 Average:149.70 Median:154.00 stddev:20.37 (13.2%) stddev-sans-first:21.20 03:06:31 INFO - PROCESS | 2061 | Values: 161.0 156.0 130.0 139.0 139.0 119.0 152.0 156.0 194.0 151.0 03:06:31 INFO - PROCESS | 2061 | 03:06:31 INFO - PROCESS | 2061 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.70 Median:173.00 stddev:1.83 (1.1%) stddev-sans-first:1.88 03:06:31 INFO - PROCESS | 2061 | Values: 174.0 172.0 171.0 169.0 175.0 173.0 172.0 175.0 173.0 173.0 03:06:31 INFO - PROCESS | 2061 | 03:06:31 INFO - PROCESS | 2061 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:79.80 Median:71.00 stddev:13.90 (19.6%) stddev-sans-first:14.38 03:06:31 INFO - PROCESS | 2061 | Values: 71.0 71.0 70.0 70.0 70.0 65.0 86.0 100.0 96.0 99.0 03:06:31 INFO - PROCESS | 2061 | -------- Summary: end -------- 03:06:31 INFO - PROCESS | 2061 | 03:06:32 INFO - TEST-INFO | 2061: exit 0 03:06:32 INFO - TEST-OK | kraken | took 61307ms 03:06:32 INFO - SUITE-END | took 1173s 03:06:32 INFO - Completed test suite (00:19:33) 03:06:32 INFO - javascript benchmark 03:06:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3396.779209351392, 3440.6731360520744, 3450.8964731058077, 3495.388307046266, 3507.0355652195644, 5813.040848260032, 5832.096025116109, 5845.094010428385, 5845.3570107288215, 5860.413958604137, 5833.154192642064, 5845.824625261249, 5850.795222167214, 5889.793857214988, 5916.408359164081, 5247.501487358704, 5324.65389749667, 5324.92012619811, 5336.546393556552, 5339.759710813023, 5230.320058392404, 5324.094903866333, 5343.332083489564, 5438.075527160372, 5455.563554915617, 5309.41596424393, 5315.893682126355, 5323.0, 5326.414094449608, 5331.306930099082, 5274.129768588197, 5308.124159513675, 5309.36287645485, 5335.973320133413, 5418.241446197536, 5152.92270615941, 5253.133233016548, 5299.019681358961, 5317.574594032507, 5352.2506849041165, 5757.453041961007, 5800.651960882361, 5830.32951210612, 5847.678377689225, 5880.206172166745, 6121.816345509642, 6131.927227014014, 6297.464715499188, 6298.590591611531, 6313.0, 6285.371462853724, 6286.214223222098, 6320.873582528369, 6324.0, 6327.082573026932, 5885.323187833411, 5930.881382372371, 5940.2871655401195, 5985.22192115031, 5994.613043630378, 6220.440160385587, 6297.685115744194, 6312.810615681537, 6323.747050117944, 6371.936280637135, 6228.252609686867, 6253.6873156342, 6257.468115210167, 6266.843328916722, 6286.057091436322, 6291.182146320949, 6308.400701933309, 6322.051692246107, 6330.05049242608, 6344.809655710336, 5206.635535512553, 5927.1405646181065, 6110.467630727307, 6201.224846894138, 6306.810795676137, 6246.6876656167005, 6284.0, 6291.874162516816, 6293.685315734195, 6315.305316415191, 4067.9796601016806, 4869.853904382803, 4998.0, 5001.799928002839, 5004.949950500449, 4427.048184640318, 4446.377507149002, 4498.280275155959, 4506.639468842484, 4560.247559152703, 5767.653940763567, 5856.795012174553, 5863.67749773758, 5893.410658934158, 5945.16767652529, 6165.475934545614, 6220.191375121296, 6260.436560709467, 6287.49700023997, 6287.874242515124, 5683.318001839723, 5825.24271844666, 5846.298444186725, 5902.793402230987, 5955.3151387590115, 5448.945510544923, 5458.153986132156, 5483.424240454695, 5486.972565137149, 5495.0383682855345, 6332.11350410952, 6479.773207937699, 6497.707603157964, 6500.992346186349, 6515.315891831384, 6235.563510554218, 6254.687265636609, 6277.717502712298, 6292.968535157295, 6297.685115744286, 6471.546991710535, 6482.22213334384, 6485.319041500575, 6496.577722448026, 6505.154329937078, 6104.938950610437, 6164.845878852885, 6236.75052997875, 6291.370862913672, 6295.622262664255, 6268.592541484789, 6307.747690092345, 6328.303886572566, 6335.841603959938, 6340.619562826245, 6177.4749146321265, 6199.969000154971, 6212.502999759918, 6213.65824879636, 6242.906356404748, 6049.0926361046195, 6128.136482154648, 6144.15675297418, 6191.938080619136, 6198.969005154945, 4454.554544545649, 5934.495567876779, 5972.25346831646, 6067.453929146398, 6117.082437634391, 6182.1345011699295, 6206.06908963659, 6263.592866463665, 6274.964630836043, 6281.96859015702, 5746.798862039808, 6006.309274433496, 6138.633639632548, 6148.938510614837, 6184.350643182581], "value": 5777.244257703761, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [501.1680610187088, 535.4698848140341, 563.2846285217773, 577.4341145677239, 606.3269770554683, 29332.853335733318, 40708.592914070854, 40739.0, 40772.0, 40876.59123408765, 33382.833085834594, 41709.582904171024, 42323.36514952278, 42599.57400425995, 42929.0, 9376.765580860465, 9569.712908612753, 9615.13463788259, 9632.0, 9644.903550964471, 8398.748037558853, 9608.711738647851, 9820.36167649104, 9852.310338276324, 9854.113129818315, 9774.462404567746, 9797.26520510961, 9868.111869931705, 9878.357906736075, 9894.703158905244, 9761.755956101084, 9826.312158148932, 9828.901710982906, 9885.851712224323, 9917.652882149127, 9625.51872406377, 9719.854202186973, 9727.513624318755, 9749.951250243774, 9752.707418777449, 35632.0, 40012.59987400118, 40063.59936400628, 40639.0, 40817.79591102055, 3176.9364612707845, 3252.70725634694, 3328.118048717092, 3339.34882697874, 3700.870469533553, 26339.604905926426, 27060.05289814847, 27801.443971120465, 30091.94678186253, 30208.0, 2770.7644850187758, 2779.7498225159834, 2787.3171073087224, 2842.161562339115, 2875.6405449318836, 476843.0, 483377.0, 488143.0, 488608.0, 492281.0, 35764.64235357613, 72421.6378918102, 73776.0, 73894.0, 74036.51926961538, 37746.43380349297, 37769.62230377716, 38040.0, 38075.428868566996, 38154.04614884595, 15069.0, 15482.993605415611, 15488.0, 15505.689886202214, 15591.688166236612, 12848.48606055766, 13125.868741312655, 13131.0, 13206.207627542186, 13227.603171904859, 8376.790580235544, 8382.37132215074, 8389.286910612665, 8457.858189144386, 8608.569571521524, 3533.258015816656, 3549.414346632777, 3567.1795487038125, 3578.534790477221, 3581.71346292296, 960.6013504395597, 984.9556769945369, 1006.5722068121165, 1047.863777708893, 1078.4823284823328, 22116.668249976265, 26271.0, 26503.469930601666, 26603.46793064128, 26690.0, 1080.7676349584876, 1081.4646749858714, 1107.9556817727362, 1154.9133814963743, 1176.3765204441659, 11477.942610286895, 11586.13104017202, 14261.0, 14846.257687115602, 14918.850811491746, 663181.0, 665840.6707966429, 670198.0, 677630.0, 680019.0, 62874.37125628685, 63415.0, 63694.0, 63835.0, 64457.03314450287, 1490.1655073159066, 1514.1142431677592, 1519.0202319503858, 1519.4425713643604, 1528.6407694191855, 25205.243842684748, 25847.483050339262, 25930.61104083478, 25976.22071337863, 25977.480450391264, 55232.44767552434, 55349.0, 55362.44637553573, 55762.72118639381, 55824.0, 562797.0, 565295.0, 567215.0, 567451.0, 567771.0, 28290.43419131647, 28511.28721781972, 41485.58514414817, 42903.785481073646, 43621.78189109161, 45535.77232113818, 45955.08089838251, 46016.0, 46357.76821115873, 46384.0, 37454.62545374512, 37555.436668450544, 37930.0, 38006.049848754, 38580.228395432496, 36686.816565917, 37284.81357593195, 37519.62480375161, 37571.62428375681, 38205.80897095497, 38121.618783811806, 38379.2324153521, 38436.23127537489, 39206.0, 39695.80152099221, 33223.66776332302, 34407.967760967214, 34424.655753442144, 34905.08022058811, 35139.472907906915, 25659.87170064138, 25743.0, 25750.8712456444, 25842.741572584033, 26198.73801261963], "value": 18956.197472999924, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.7797948302827, 395.8357281728083, 400.8256408462318, 403.18154147081424, 411.07713183902143, 15391.922565420411, 15472.303746331414, 21394.25120120792, 21577.4605634859, 21869.79716115613, 21227.787722122775, 21257.787422125773, 21668.783312166914, 22406.88796556019, 22662.546749065008, 4834.444038935515, 5187.533122019017, 5794.565407594437, 5930.970345148268, 6020.5785595008365, 5710.857228569298, 6440.516961227927, 7572.924270757305, 7577.81055473612, 7592.0, 7415.1843297237265, 7600.7339743109005, 7936.404769642271, 7985.6406461709075, 8014.438989270753, 7565.924340756577, 7779.72770953014, 7812.765617031499, 7920.564368959705, 7937.642806073741, 7333.486655934087, 7613.08642962848, 7635.923640763633, 7657.349125324354, 7658.923410765877, 28971.85514072416, 29067.982620608178, 29593.852030739923, 30301.0, 30813.0, 2102.852800303979, 2315.8494697844585, 2329.94175145621, 2500.7374225706403, 2525.9643546145994, 15526.689466210726, 15565.610859728484, 15882.682346353124, 16253.349866005346, 16568.254428550743, 1735.6355165415277, 1876.8310852023383, 1889.6126294109813, 1905.4950438133767, 1918.6066856294426, 142412.0, 149249.0, 151587.242063789, 154616.0, 154751.99999999886, 17758.644827103384, 22259.666105008437, 22301.21945731924, 22389.888050559643, 22527.098916043502, 14814.407423703147, 15578.766318505232, 15664.138472384127, 38104.809475953, 38233.617663823, 8751.168638979287, 8961.551922403854, 9110.043445438267, 9146.954265228764, 9190.126937940884, 9052.27581793455, 9054.0, 9149.35954483193, 9174.357794954289, 9230.58462369195, 4783.569478746929, 4798.5441383068555, 4819.662623616383, 4858.684185527929, 4891.755412229445, 2006.5184355754807, 2016.9798302017086, 2050.4258807533915, 2064.8244899183737, 2088.9895550522456, 841.5329492131804, 882.5013867165031, 910.6357457017114, 946.4605175050156, 971.455984648599, 13645.727085458235, 18044.729329060076, 18131.72802407965, 18806.529836753918, 19285.0, 935.8876934767871, 945.9290553208536, 966.4394651102484, 971.6744890461774, 980.7548112971757, 10097.24270679702, 10384.584616615402, 10395.272330936761, 10406.635767748092, 10428.582856685647, 563089.0, 565889.0, 602345.0, 621358.0, 624868.0, 116572.0, 138781.0, 140622.0, 142286.28856855858, 146334.26832865766, 1013.8580598716333, 1023.8617786598862, 1062.3254233561745, 1082.99458502707, 1086.7989421956963, 16571.83428165703, 16574.254158562653, 16611.83388166151, 16923.492295231164, 17296.394626188525, 38837.02907427337, 50146.7492662549, 53466.592744654765, 54121.72939135279, 54989.175162371794, 74839.62580187064, 78321.60839195768, 78952.21047789678, 79602.0, 80108.59945700235, 5580.0, 5668.064769313017, 8332.625031873458, 8348.7912802178, 8417.747467575982, 10392.0, 11836.70408239801, 12640.6839829005, 12729.108962372915, 12885.162464439782, 9954.20366370703, 10187.08316251541, 10230.795384092426, 10369.014943580349, 10376.740581485523, 8762.649494020168, 10302.278840481094, 10684.145268378355, 10685.0, 11287.604933827604, 10133.03736145065, 10326.632287823582, 10368.688939331832, 10475.528601213116, 10477.214208934512, 8282.544460054754, 8287.41988060854, 8334.0, 8415.621297041771, 8656.91343086561, 7821.843563128592, 9798.80402391934, 10342.172626190037, 10497.422641754776, 10714.464276786286], "value": 10294.438987277781, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1553.5028790786946, 1563.8592526672596, 1587.111217718078, 1902.391234804862, 1916.6645836978525, 8018.599070046504, 8439.535825529594, 8709.738707838767, 8995.370324077317, 9005.774855628604, 8672.262857657106, 8702.52136132514, 8809.163129502687, 8814.647414103445, 8826.984896736878, 2456.128074533546, 2477.7770000699934, 2478.913238036669, 2483.677121974141, 2487.9253622391357, 2449.0571130114945, 2455.3370589940705, 2482.3173627252468, 2492.003198720517, 2495.7878580320694, 2452.3869032741813, 2478.4423504711476, 2510.0085466240826, 2514.497100579882, 2514.6228065790187, 2463.4210960424375, 2466.1861585676684, 2476.1457297232428, 2482.6276058591175, 2483.925482235536, 2454.9386265343333, 2458.397692565315, 2476.418041760185, 2477.6035834266613, 2484.9751502484924, 9018.954905225432, 9031.187193152648, 9031.729048128567, 9047.004829468753, 9083.364164508486, 5320.17537281722, 5472.069748142785, 5505.421930697259, 5531.0, 5535.99999999996, 3578.4453409721536, 3638.854445822164, 3651.1967367179177, 3732.5520937487404, 3738.8504459821575, 1035.244271681669, 1051.2168434516334, 1059.422614675004, 1061.3101484035362, 1070.314998401024, 11659.183857130005, 11865.940670296679, 11919.523219071227, 11922.344271065174, 11947.641570752892, 4132.628063474242, 4826.420829500483, 4873.80504779812, 4955.677880937727, 4963.8014479421145, 10197.490125493696, 10201.948990255001, 10209.38743675382, 10213.285070045025, 10242.48787560619, 5422.593305502103, 5476.890462190813, 5491.835244942658, 5511.255980442669, 5518.834434966958, 4455.977720111444, 4846.903061938741, 4926.408830940239, 4958.479359667255, 5007.223880298559, 4071.0, 4135.801243222856, 4180.681167003003, 4201.033762234703, 4207.810648520763, 1348.5954213736036, 1372.876441120304, 1385.9376328065262, 1397.4829313154198, 1398.0, 672.7376323233942, 810.9351251899834, 882.6116508736136, 903.4534106865383, 927.9072092790667, 5422.295101636762, 5708.71456427177, 5741.971290143522, 5823.330317013513, 5926.703664816742, 446.484310621233, 455.24883941496137, 461.67451946377724, 468.414481897628, 473.7844280852204, 3894.6494815466176, 3931.92136157281, 3933.704972127102, 3955.9011024724036, 3966.186931678999, 10463.006014428616, 10996.450177491093, 10996.670099897015, 11041.613543525938, 11070.169737269736, 10763.730906727395, 10801.297915635458, 10912.56349746017, 10934.945325273322, 10945.671629850958, 7704.460687751915, 8270.048944371474, 8285.25432711059, 8332.291755200755, 8521.0, 5486.957478079152, 5521.861953451196, 5609.943900561106, 5621.4659607337235, 5765.827025189251, 8338.708145214889, 9994.950025249827, 10282.845757313497, 10288.897111028793, 10357.22320825926, 9766.414015159113, 10120.79758404813, 10319.380837149794, 10319.638812641822, 10365.792684146425, 6875.5874647521305, 7352.264773522605, 7570.432217583593, 7623.199564045917, 7694.07671079474, 8351.29014033814, 8400.369972251983, 8424.747257582283, 8528.74413767588, 8547.572621368783, 7430.517016394133, 7609.467337286337, 7656.961715191388, 7663.425243106678, 7710.807229819299, 7281.854362912818, 7350.52221605594, 7383.2985866342615, 7458.776236712907, 7493.4379921505015, 6844.452443804584, 6859.657017149023, 6924.619145947021, 6934.826629334105, 6961.408280296231, 4067.613576710208, 4646.721196728207, 4662.393888794435, 4781.521847815191, 4823.758812059453, 4531.773411329486, 4714.080754252887, 4754.358161648272, 4806.903861922812, 4936.827211047596], "value": 4557.418909775707, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.29181361520847, 321.2835377109047, 324.60722525743836, 328.8785242323676, 331.3786650031191, 4760.619150467963, 5487.094629386152, 5538.307711536058, 5555.861103472415, 5628.831135065944, 4839.540243676854, 5309.964556911404, 5486.588505862056, 5501.339839219289, 5522.294075902809, 3399.9660003399895, 4023.6781057515327, 4035.515738111431, 4123.670106391496, 4237.6692818949605, 3542.3269578780078, 3928.489296391465, 4150.979245103786, 4189.832406703728, 4207.810648520824, 3365.478350855621, 3470.9305813883834, 4010.979945100285, 4013.6387725104737, 4251.128518653684, 3671.430928206132, 3989.920201595981, 4253.319468884979, 4270.466191726035, 4272.914541709149, 3526.2594855080465, 3698.8335524901445, 3714.53568303962, 3725.403935370328, 3736.906577335561, 4400.904746925499, 4530.912580980574, 4638.721676699409, 4773.355596994396, 5372.274742909696, 3757.28611563802, 4672.061094645718, 4867.0, 4876.244182151772, 4944.8269310574315, 5365.409804921455, 5830.530834153672, 5841.299044114691, 5868.97065514674, 5887.0858924832055, 4116.0327323079055, 4116.547179810218, 4119.878262694715, 4133.945843809839, 4137.731047481934, 5745.023346031185, 5896.439838215363, 6215.782447614311, 6233.563650544464, 6245.500359971237, 3503.859845606198, 3630.945535816965, 3747.6065013173225, 3835.9642896417813, 4192.685548583869, 3339.5157702133056, 3721.5906250312446, 4264.147170565837, 4268.317069268902, 4274.957250427518, 4499.662525310615, 4513.323001549794, 4897.975510122426, 4899.608031357483, 4901.289313049588, 5149.9742501288, 5367.9264147170725, 5904.7342869570975, 5926.288845338587, 5929.7547515022325, 1464.406915199346, 1562.1486289971765, 1562.7577725452572, 1563.3590228006462, 1564.3664315952058, 935.6023689931752, 954.9618015279311, 983.6655537117272, 1034.4827586206898, 1057.117307048623, 4622.791974361162, 4980.8754781130765, 5314.7608357624, 5460.31746031746, 5543.390227075019, 5608.0, 6085.117657939574, 6129.724162412702, 6181.381861813783, 6217.191765070512, 5084.313617661643, 5278.885457580046, 5476.91784623231, 5508.402563256691, 5531.976584331912, 3437.4843773434186, 3438.5014172945284, 3474.2009337851932, 3480.6251530645254, 3484.1289677580603, 6645.102911107036, 6844.726210951506, 6885.070515480446, 6908.378245957788, 6942.20164681058, 5104.668196567285, 6258.937410625836, 6704.865902681919, 6797.286284940109, 6816.556923799937, 5660.349059858169, 6213.471854892385, 6756.662166891636, 6947.305269473012, 6955.408790252885, 4674.883127921829, 5147.279380886754, 5352.785888564414, 5734.942650573441, 6145.692715364304, 5584.357798853021, 5955.553333499918, 6063.909041364295, 6071.939280607314, 6119.663418512025, 5230.189320655304, 5459.672419654833, 6347.428731414195, 6399.712012959521, 6406.615603063831, 4010.2981978153125, 4040.6665800286423, 4054.655354294918, 4074.4703188585295, 4078.8164532596697, 5078.5175408336145, 5394.973025134849, 5645.802396916089, 6119.062849832017, 6139.57023008403, 4869.9026019480125, 5686.83419898928, 5968.0, 5991.281046274475, 6001.4298641628975, 5496.945030549643, 5854.502367298827, 5917.526597872246, 5982.7008649568215, 5998.760049597967, 4314.288142456585, 5223.9477605223465, 5476.479734425223, 5825.70871456434, 5827.358990510956, 5968.432998865274, 5976.280792239608, 5987.550933679904, 6002.2893475359315, 6067.757289708362, 5324.946750532445, 5749.13762935563, 5908.704564771659, 5937.465628093492, 5986.2217911671205], "value": 4318.6100053500995, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [650.8600650860064, 718.3929579505316, 737.5685224143874, 747.5776186454652, 753.0097921233578, 14410.423583056665, 14782.778258326134, 14865.10809351437, 14930.850691493082, 16055.277512511935, 12399.814002789943, 12515.749685006294, 12533.435995380207, 12551.05867059972, 12576.371181440914, 722.1478655186877, 910.2354022620997, 910.9863352049724, 917.7017469322464, 918.3571499950028, 878.3807415771865, 881.5812489067673, 883.2801266967401, 886.2776836877956, 889.3552174673375, 962.956666949989, 973.9561719722594, 974.1476208317722, 974.3131092579749, 977.3647129365897, 957.1481381570408, 961.8845738511353, 963.8168747937905, 964.137097297918, 964.6768332608584, 688.9827754306183, 713.4791602130422, 713.7751608243386, 718.5724493926106, 720.3049057659332, 15336.079835209923, 15501.224938753017, 15527.378904843792, 15538.0, 15596.908216424854, 10774.838377424345, 12268.202566833217, 12366.381680915918, 12513.74972500545, 12538.373081345897, 9607.423554586678, 10088.69733907984, 10108.797824043551, 10131.290809643328, 10148.238882083873, 3379.8141102239365, 3415.4023045966856, 3465.5841299044278, 3504.737144714157, 3600.4419315006216, 84660.15339846585, 85638.57180714118, 85703.14296857014, 86321.56839215827, 88830.55584722036, 9141.360104792733, 9172.312076594282, 9281.350305478552, 9521.28590355726, 9565.856512152322, 21202.363929082152, 21294.467638309165, 21324.78675213259, 21353.893230533748, 21462.678059829115, 7174.928250717426, 7272.8181795454475, 7273.9636301818155, 7314.08573928259, 7335.1197856257595, 4017.598240175959, 4078.2455245779624, 4136.937945930814, 4156.875293741193, 4231.61915427613, 3517.4547945068557, 3602.2795440911923, 3605.188832512664, 3614.512040874501, 3616.6383361663625, 650.648649729141, 661.2858113237692, 661.4873473058418, 663.7709990053423, 666.9533132680763, 426.7844738407085, 570.5606682854179, 582.9766809327664, 584.514852672281, 585.8857522783017, 6518.348165183444, 7812.648430820513, 7823.804404889809, 8033.794930760433, 8167.673293068329, 470.8987567672967, 578.9247397838254, 580.9651420914759, 594.3610618584952, 594.9405059494103, 3439.4496880499, 3662.560492740888, 3771.9622803771613, 3859.0, 4030.8589199378466, 88596.0, 91508.6273705895, 99442.0, 100185.0, 101907.98092019133, 61510.38489615136, 80517.19482805215, 82830.0, 84404.57797710971, 84794.30411391737, 67369.66315168393, 68225.0, 68334.63330733357, 68749.65625171842, 70429.64785176041, 6572.342765723581, 6892.483063770337, 7501.062367204099, 7533.472656913963, 7547.207543207884, 44753.10493790041, 44907.0, 45484.0, 47045.0, 47067.29399059086, 62582.374176257654, 64613.0, 66821.0, 67534.0, 67628.98556521558, 10191.286609937233, 10193.898061019592, 10423.21825863048, 10439.94780026095, 10532.104771094237, 11096.944515277695, 11129.666110016713, 11270.211085224284, 11308.660740177807, 12003.219790713576, 9812.705618831447, 9893.802123957625, 9912.801743964936, 9970.40177589347, 10330.431826249629, 10137.645182418875, 10183.965840615992, 10265.2301077418, 10487.842682359618, 10556.841647375142, 9692.806143877224, 9858.55636496345, 9930.304878658424, 10244.692659220234, 10525.210609204187, 8666.956665216885, 8949.59726812282, 9006.459612423276, 9042.864357034518, 9065.365424420228, 8441.15588441151, 8681.826363472821, 8725.040245572854, 8756.912430875609, 8978.967418746928], "value": 6697.66341493965, "unit": "score"}], "name": "dromaeo_css", "value": 7278.088026315204, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 160.0, 115.0, 116.0, 120.0, 165.0, 169.0, 148.0, 115.0, 117.0], "value": 134.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 135.0, 192.0, 194.0, 111.0, 176.0, 155.0, 184.0, 152.0, 124.0], "value": 157.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 199.0, 198.0, 226.0, 286.0, 242.0, 203.0, 219.0, 273.0, 210.0], "value": 225.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 105.0, 109.0, 186.0, 57.0, 60.0, 58.0, 63.0, 127.0, 73.0], "value": 90.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 104.0, 129.0, 118.0, 114.0, 122.0, 159.0, 87.0, 79.0, 117.0], "value": 109.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 88.0, 89.0, 87.0, 90.0, 86.0, 90.0, 88.0, 90.0, 87.0], "value": 88.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 95.0, 96.0, 105.0, 96.0, 99.0, 150.0, 151.0, 104.0, 150.0], "value": 114.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 87.0, 148.0, 147.0, 121.0, 90.0, 91.0, 92.0, 93.0, 96.0], "value": 111.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 66.0, 67.0, 65.0, 68.0, 67.0, 67.0, 84.0, 65.0, 61.0], "value": 67.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 103.0, 60.0, 86.0, 111.0, 63.0, 94.0, 97.0, 102.0, 90.0], "value": 88.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 83.0, 84.0, 81.0, 83.0, 83.0, 82.0, 85.0, 85.0, 85.0], "value": 83.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 156.0, 130.0, 139.0, 139.0, 119.0, 152.0, 156.0, 194.0, 151.0], "value": 149.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 172.0, 171.0, 169.0, 175.0, 173.0, 172.0, 175.0, 173.0, 173.0], "value": 172.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 71.0, 70.0, 70.0, 70.0, 65.0, 86.0, 100.0, 96.0, 99.0], "value": 79.8, "unit": "score"}], "name": "kraken", "value": 1672.6, "alertThreshold": 2.0}]} 03:06:32 INFO - Return code: 0 03:06:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 03:06:33 ERROR - # TBPL SUCCESS # 03:06:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:06:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:06:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:06:33 INFO - 2016-04-26 03:06:33.049 screenresolution[2066:18084] starting screenresolution argv=screenresolution get 03:06:33 INFO - 2016-04-26 03:06:33.051 screenresolution[2066:18084] Display 0: 1600x1200x32@60 03:06:33 INFO - 2016-04-26 03:06:33.057 screenresolution[2067:18087] starting screenresolution argv=screenresolution list 03:06:33 INFO - Available Modes on Display 0 03:06:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:06:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:06:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:06:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:06:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:06:33 INFO - Intel Iris: 03:06:33 INFO - Chipset Model: Intel Iris 03:06:33 INFO - Type: GPU 03:06:33 INFO - Bus: Built-In 03:06:33 INFO - VRAM (Dynamic, Max): 1536 MB 03:06:33 INFO - Vendor: Intel (0x8086) 03:06:33 INFO - Device ID: 0x0a2e 03:06:33 INFO - Revision ID: 0x0009 03:06:33 INFO - Displays: 03:06:33 INFO - Display: 03:06:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:06:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:06:33 INFO - Display Serial Number: 1600x1200 60 03:06:33 INFO - Main Display: Yes 03:06:33 INFO - Mirror: Off 03:06:33 INFO - Online: Yes 03:06:33 INFO - Rotation: Supported 03:06:33 INFO - Return code: 0 03:06:33 INFO - Running post-action listener: _resource_record_post_action 03:06:33 INFO - Running post-run listener: _resource_record_post_run 03:06:34 INFO - Total resource usage - Wall time: 1207s; CPU: 54.0%; Read bytes: 1667584; Write bytes: 654432256; Read time: 15; Write time: 1809 03:06:34 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 350738944; Write bytes: 346543616; Read time: 27608; Write time: 680 03:06:34 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 1667584; Write bytes: 297099776; Read time: 15; Write time: 1099 03:06:34 INFO - Running post-run listener: _upload_blobber_files 03:06:34 INFO - Blob upload gear active. 03:06:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:06:34 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 03:06:34 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'] 03:06:34 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 03:06:34 INFO - (blobuploader) - INFO - Open directory for files ... 03:06:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 03:06:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:06:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:06:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 03:06:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:06:35 INFO - (blobuploader) - INFO - Done attempting. 03:06:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 03:06:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:06:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:06:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 03:06:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:06:36 INFO - (blobuploader) - INFO - Done attempting. 03:06:36 INFO - (blobuploader) - INFO - Iteration through files over. 03:06:36 INFO - Return code: 0 03:06:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:06:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:06:36 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8f9fc853dac6a32dca54cb8060e0549bcf15387ae77f208968d56900c26183d22a2742111d447cb0e1a1abcfc160fb7d66ba9369ecad72352d67f2f9c68a6751", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/228a744d764aafa7745778bd95ebcccd1ecac102369bb211ec431f4e90a1ec7c9860f14e96a31091a58bff4bde00a93ef3c444159cd916befb513559de229401"} 03:06:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:06:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:06:36 INFO - Contents: 03:06:36 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8f9fc853dac6a32dca54cb8060e0549bcf15387ae77f208968d56900c26183d22a2742111d447cb0e1a1abcfc160fb7d66ba9369ecad72352d67f2f9c68a6751", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/228a744d764aafa7745778bd95ebcccd1ecac102369bb211ec431f4e90a1ec7c9860f14e96a31091a58bff4bde00a93ef3c444159cd916befb513559de229401"} 03:06:36 INFO - Running post-run listener: copy_logs_to_upload_dir 03:06:36 INFO - Copying logs to upload dir... 03:06:36 INFO - mkdir: /builds/slave/test/build/upload/logs 03:06:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.910660 ========= master_lag: 7.21 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-04-26 03:06:43.710471) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 7 secs) (at 2016-04-26 03:06:43.716347) ========= 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.9cQkNoj5Ga/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.rIEnxKBUDj/Listeners TMPDIR=/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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/8f9fc853dac6a32dca54cb8060e0549bcf15387ae77f208968d56900c26183d22a2742111d447cb0e1a1abcfc160fb7d66ba9369ecad72352d67f2f9c68a6751", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/228a744d764aafa7745778bd95ebcccd1ecac102369bb211ec431f4e90a1ec7c9860f14e96a31091a58bff4bde00a93ef3c444159cd916befb513559de229401"} build_url:https://queue.taskcluster.net/v1/task/HOEL3zTzTre5xQ4eikKsFA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011225 build_url: 'https://queue.taskcluster.net/v1/task/HOEL3zTzTre5xQ4eikKsFA/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/8f9fc853dac6a32dca54cb8060e0549bcf15387ae77f208968d56900c26183d22a2742111d447cb0e1a1abcfc160fb7d66ba9369ecad72352d67f2f9c68a6751", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/228a744d764aafa7745778bd95ebcccd1ecac102369bb211ec431f4e90a1ec7c9860f14e96a31091a58bff4bde00a93ef3c444159cd916befb513559de229401"}' ========= master_lag: 7.25 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 7 secs) (at 2016-04-26 03:06:50.976238) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 15 secs) (at 2016-04-26 03:06:50.976538) ========= 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.9cQkNoj5Ga/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.rIEnxKBUDj/Listeners TMPDIR=/var/folders/15/kcx20ryj2rs9kb6sgv5k9lrm00000w/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.005404 ========= master_lag: 15.84 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 15 secs) (at 2016-04-26 03:07:06.819931) ========= ========= Started reboot skipped (results: 3, elapsed: 18 secs) (at 2016-04-26 03:07:06.824270) ========= ========= Finished reboot skipped (results: 3, elapsed: 18 secs) (at 2016-04-26 03:07:24.872966) ========= ========= Total master_lag: 30.37 =========