builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0339 starttime: 1464619797.79 results: success (0) buildid: 20160530063837 builduid: 3b0a3caa272d495abd2812a5b4932f33 revision: 433520b687b76bc6332bf080db94b01ebf4ef881 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 07:49:57.787067) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 07:49:57.787569) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 07:49:57.788008) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 07:49:57.872846) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 07:49:57.873316) ========= 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.ucXvfOF5GD/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.KvjkzzEjiq/Listeners TMPDIR=/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-30 07:49:57-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.6M=0.001s 2016-05-30 07:49:58 (18.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.593505 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 07:49:58.490397) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 07:49:58.490742) ========= 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.ucXvfOF5GD/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.KvjkzzEjiq/Listeners TMPDIR=/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/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.025481 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 07:49:58.541244) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-30 07:49:58.541893) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 433520b687b76bc6332bf080db94b01ebf4ef881 --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 433520b687b76bc6332bf080db94b01ebf4ef881 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ucXvfOF5GD/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.KvjkzzEjiq/Listeners TMPDIR=/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-30 07:49:58,595 truncating revision to first 12 chars 2016-05-30 07:49:58,595 Setting DEBUG logging. 2016-05-30 07:49:58,595 attempt 1/10 2016-05-30 07:49:58,595 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/433520b687b7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-30 07:50:00,732 unpacking tar archive at: mozilla-aurora-433520b687b7/testing/mozharness/ program finished with exit code 0 elapsedTime=2.397289 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-05-30 07:50:00.961390) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 07:50:00.961767) ========= script_repo_revision: 433520b687b76bc6332bf080db94b01ebf4ef881 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 07:50:00.962185) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 07:50:00.962587) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 07:50:00.978967) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 36 secs) (at 2016-05-30 07:50:00.979346) ========= /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.ucXvfOF5GD/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.KvjkzzEjiq/Listeners TMPDIR=/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/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 07:50:01 INFO - MultiFileLogger online at 20160530 07:50:01 in /builds/slave/test 07:50:01 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 07:50:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:50:01 INFO - {'append_to_log': False, 07:50:01 INFO - 'base_work_dir': '/builds/slave/test', 07:50:01 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 07:50:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:50:01 INFO - 'branch': 'Mozilla-Aurora', 07:50:01 INFO - 'buildbot_json_path': 'buildprops.json', 07:50:01 INFO - 'config_files': ('talos/mac_config.py',), 07:50:01 INFO - 'default_actions': ('clobber', 07:50:01 INFO - 'read-buildbot-config', 07:50:01 INFO - 'download-and-extract', 07:50:01 INFO - 'populate-webroot', 07:50:01 INFO - 'create-virtualenv', 07:50:01 INFO - 'install', 07:50:01 INFO - 'run-tests'), 07:50:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:50:01 INFO - 'download_minidump_stackwalk': True, 07:50:01 INFO - 'download_symbols': 'ondemand', 07:50:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:50:01 INFO - 'tooltool.py': '/tools/tooltool.py', 07:50:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:50:01 INFO - '/tools/misc-python/virtualenv.py')}, 07:50:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:50:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:50:01 INFO - 'installer_path': 'installer.exe', 07:50:01 INFO - 'log_level': 'info', 07:50:01 INFO - 'log_name': 'talos', 07:50:01 INFO - 'log_to_console': True, 07:50:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:50:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:50:01 INFO - 'opt_config_files': (), 07:50:01 INFO - 'pip_index': False, 07:50:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:50:01 INFO - 'cmd': ('bash', 07:50:01 INFO - '-c', 07:50:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:50:01 INFO - 'enabled': True, 07:50:01 INFO - 'halt_on_failure': False, 07:50:01 INFO - 'name': 'check_screen_resolution'},), 07:50:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:50:01 INFO - 'cmd': ('bash', 07:50:01 INFO - '-c', 07:50:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:50:01 INFO - 'enabled': True, 07:50:01 INFO - 'halt_on_failure': False, 07:50:01 INFO - 'name': 'check_screen_resolution'},), 07:50:01 INFO - 'run_cmd_checks_enabled': True, 07:50:01 INFO - 'sps_profile': False, 07:50:01 INFO - 'sps_profile_interval': 0, 07:50:01 INFO - 'suite': 'dromaeojs-e10s', 07:50:01 INFO - 'system_bits': '32', 07:50:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:50:01 INFO - 'title': 't-yosemite-r7-0339', 07:50:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:50:01 INFO - 'use_talos_json': True, 07:50:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:50:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:50:01 INFO - 'work_dir': 'build'} 07:50:01 INFO - ##### 07:50:01 INFO - ##### Running clobber step. 07:50:01 INFO - ##### 07:50:01 INFO - Running pre-action listener: _resource_record_pre_action 07:50:01 INFO - Running main action method: clobber 07:50:01 INFO - rmtree: /builds/slave/test/build 07:50:01 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:50:02 INFO - Running post-action listener: _resource_record_post_action 07:50:02 INFO - ##### 07:50:02 INFO - ##### Running read-buildbot-config step. 07:50:02 INFO - ##### 07:50:02 INFO - Running pre-action listener: _resource_record_pre_action 07:50:02 INFO - Running main action method: read_buildbot_config 07:50:02 INFO - Using buildbot properties: 07:50:02 INFO - { 07:50:02 INFO - "project": "", 07:50:02 INFO - "product": "firefox", 07:50:02 INFO - "script_repo_revision": "production", 07:50:02 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 07:50:02 INFO - "repository": "", 07:50:02 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 07:50:02 INFO - "buildid": "20160530063837", 07:50:02 INFO - "pgo_build": "False", 07:50:02 INFO - "basedir": "/builds/slave/test", 07:50:02 INFO - "buildnumber": 20, 07:50:02 INFO - "slavename": "t-yosemite-r7-0339", 07:50:02 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 07:50:02 INFO - "platform": "yosemite_r7", 07:50:02 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 07:50:02 INFO - "branch": "mozilla-aurora", 07:50:02 INFO - "repo_path": "releases/mozilla-aurora", 07:50:02 INFO - "revision": "433520b687b76bc6332bf080db94b01ebf4ef881", 07:50:02 INFO - "stage_platform": "macosx64", 07:50:02 INFO - "builduid": "3b0a3caa272d495abd2812a5b4932f33", 07:50:02 INFO - "slavebuilddir": "test" 07:50:02 INFO - } 07:50:02 INFO - Found installer url https://queue.taskcluster.net/v1/task/cn0lKUxATnuJLEicI6z55Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 07:50:02 INFO - Running post-action listener: _resource_record_post_action 07:50:02 INFO - ##### 07:50:02 INFO - ##### Running download-and-extract step. 07:50:02 INFO - ##### 07:50:02 INFO - Running pre-action listener: _resource_record_pre_action 07:50:02 INFO - Running main action method: download_and_extract 07:50:02 INFO - mkdir: /builds/slave/test/build/tests 07:50: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')]} 07:50:02 INFO - https://queue.taskcluster.net/v1/task/cn0lKUxATnuJLEicI6z55Q/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 07:50:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cn0lKUxATnuJLEicI6z55Q/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 07:50:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cn0lKUxATnuJLEicI6z55Q/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 07:50:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cn0lKUxATnuJLEicI6z55Q/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 07:50:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cn0lKUxATnuJLEicI6z55Q/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 07:50:28 INFO - Downloaded 1199 bytes. 07:50:28 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 07:50:28 INFO - Using the following test package requirements: 07:50:28 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 07:50:28 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 07:50:28 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 07:50:28 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 07:50:28 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 07:50:28 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 07:50:28 INFO - u'jsshell-mac.zip'], 07:50:28 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 07:50:28 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 07:50:28 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 07:50:28 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 07:50:28 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 07:50:28 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 07:50:28 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 07:50:28 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 07:50:28 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 07:50:28 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 07:50:28 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 07:50:28 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 07:50:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:50:28 INFO - https://queue.taskcluster.net/v1/task/cn0lKUxATnuJLEicI6z55Q/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:50:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cn0lKUxATnuJLEicI6z55Q/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 07:50:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cn0lKUxATnuJLEicI6z55Q/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 07:50:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cn0lKUxATnuJLEicI6z55Q/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 07:50:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cn0lKUxATnuJLEicI6z55Q/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 07:50:29 INFO - Downloaded 18425500 bytes. 07:50:29 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'] 07:50:29 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 07:50:32 INFO - Return code: 0 07:50:32 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 07:50:32 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')]} 07:50:32 INFO - https://queue.taskcluster.net/v1/task/cn0lKUxATnuJLEicI6z55Q/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 07:50:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cn0lKUxATnuJLEicI6z55Q/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 07:50:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cn0lKUxATnuJLEicI6z55Q/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 07:50:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cn0lKUxATnuJLEicI6z55Q/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 07:50:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cn0lKUxATnuJLEicI6z55Q/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 07:50:34 INFO - Downloaded 18425500 bytes. 07:50:34 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'] 07:50:34 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 07:50:38 INFO - Return code: 0 07:50: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')]} 07:50:38 INFO - https://queue.taskcluster.net/v1/task/cn0lKUxATnuJLEicI6z55Q/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 07:50:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cn0lKUxATnuJLEicI6z55Q/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 07:50:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cn0lKUxATnuJLEicI6z55Q/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 07:50:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cn0lKUxATnuJLEicI6z55Q/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 07:50:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cn0lKUxATnuJLEicI6z55Q/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 07:50:40 INFO - Downloaded 10840145 bytes. 07:50:40 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'] 07:50:40 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 07:50:40 INFO - Return code: 0 07:50:40 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')]} 07:50:40 INFO - https://queue.taskcluster.net/v1/task/cn0lKUxATnuJLEicI6z55Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 07:50:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cn0lKUxATnuJLEicI6z55Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 07:50:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cn0lKUxATnuJLEicI6z55Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 07:50:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cn0lKUxATnuJLEicI6z55Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 07:50:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/cn0lKUxATnuJLEicI6z55Q/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 07:50:49 INFO - Downloaded 114140474 bytes. 07:50:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cn0lKUxATnuJLEicI6z55Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 07:50:49 INFO - mkdir: /builds/slave/test/properties 07:50:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:50:49 INFO - Writing to file /builds/slave/test/properties/build_url 07:50:49 INFO - Contents: 07:50:49 INFO - build_url:https://queue.taskcluster.net/v1/task/cn0lKUxATnuJLEicI6z55Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 07:50:50 INFO - Running post-action listener: _resource_record_post_action 07:50:50 INFO - Running post-action listener: set_extra_try_arguments 07:50:50 INFO - ##### 07:50:50 INFO - ##### Running populate-webroot step. 07:50:50 INFO - ##### 07:50:50 INFO - Running pre-action listener: _resource_record_pre_action 07:50:50 INFO - Running main action method: populate_webroot 07:50:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:50:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:50:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:50:50 INFO - '--tppagecycles', 07:50:50 INFO - '7'], 07:50:50 INFO - 'tests': ['tsvgm']}}, 07:50:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:50:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:50:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:50:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:50:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:50:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:50:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:50:50 INFO - 'talos_options': ['--disable-e10s'], 07:50:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:50:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:50:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:50:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:50:50 INFO - 'talos_options': ['--disable-e10s'], 07:50:50 INFO - 'tests': ['damp', 'tps']}, 07:50:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:50:50 INFO - 'tests': ['damp', 'tps']}, 07:50:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:50:50 INFO - 'tests': ['dromaeo_dom']}, 07:50:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:50:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:50:50 INFO - 'tests': ['a11yr', 07:50:50 INFO - 'ts_paint', 07:50:50 INFO - 'tpaint', 07:50:50 INFO - 'sessionrestore', 07:50:50 INFO - 'sessionrestore_no_auto_restore', 07:50:50 INFO - 'tabpaint']}, 07:50:50 INFO - 'other-e10s': {'tests': ['a11yr', 07:50:50 INFO - 'ts_paint', 07:50:50 INFO - 'tpaint', 07:50:50 INFO - 'sessionrestore', 07:50:50 INFO - 'sessionrestore_no_auto_restore', 07:50:50 INFO - 'tabpaint']}, 07:50:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:50:50 INFO - 'ts_paint', 07:50:50 INFO - 'tpaint', 07:50:50 INFO - 'sessionrestore', 07:50:50 INFO - 'sessionrestore_no_auto_restore', 07:50:50 INFO - 'tabpaint']}, 07:50:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:50:50 INFO - 'ts_paint', 07:50:50 INFO - 'tpaint', 07:50:50 INFO - 'sessionrestore', 07:50:50 INFO - 'sessionrestore_no_auto_restore', 07:50:50 INFO - 'tabpaint']}, 07:50:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:50:50 INFO - 'tests': ['a11yr', 07:50:50 INFO - 'ts_paint', 07:50:50 INFO - 'tpaint', 07:50:50 INFO - 'sessionrestore', 07:50:50 INFO - 'sessionrestore_no_auto_restore', 07:50:50 INFO - 'tabpaint']}, 07:50:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:50:50 INFO - 'tests': ['a11yr', 07:50:50 INFO - 'ts_paint', 07:50:50 INFO - 'tpaint', 07:50:50 INFO - 'sessionrestore', 07:50:50 INFO - 'sessionrestore_no_auto_restore', 07:50:50 INFO - 'tabpaint']}, 07:50:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:50:50 INFO - 'tests': ['tsvgx', 07:50:50 INFO - 'tsvgr_opacity', 07:50:50 INFO - 'tart', 07:50:50 INFO - 'tscrollx', 07:50:50 INFO - 'cart']}, 07:50:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:50:50 INFO - 'tsvgr_opacity', 07:50:50 INFO - 'tart', 07:50:50 INFO - 'tscrollx', 07:50:50 INFO - 'cart']}, 07:50:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:50:50 INFO - 'talos_options': ['--disable-e10s'], 07:50:50 INFO - 'tests': ['tp5o']}, 07:50:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:50:50 INFO - 'tests': ['tp5o']}, 07:50:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:50:50 INFO - 'talos_options': ['--disable-e10s', 07:50:50 INFO - '--xperf_path', 07:50:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:50:50 INFO - 'tests': ['tp5n']}, 07:50:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:50:50 INFO - 'talos_options': ['--xperf_path', 07:50:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:50:50 INFO - 'tests': ['tp5n']}}, 07:50:50 INFO - 'talos.zip': {'path': '', 07:50:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:50:50 INFO - Running post-action listener: _resource_record_post_action 07:50:50 INFO - ##### 07:50:50 INFO - ##### Running create-virtualenv step. 07:50:50 INFO - ##### 07:50:50 INFO - Running pre-action listener: _resource_record_pre_action 07:50:50 INFO - Running main action method: create_virtualenv 07:50:50 INFO - Creating virtualenv /builds/slave/test/build/venv 07:50:50 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 07:50:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:50:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:50:50 INFO - Using real prefix '/tools/python27' 07:50:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:50:51 INFO - Installing distribute.............................................................................................................................................................................................done. 07:50:53 INFO - Installing pip.................done. 07:50:53 INFO - Return code: 0 07:50:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:50:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:50:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:50:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:50:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:50:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:53 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 0x10f3d2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe9849cb50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f271e00>, '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 0x10f0dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f26cb20>, '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.ucXvfOF5GD/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.KvjkzzEjiq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/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 07:50:53 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 07:50:53 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 07:50:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ucXvfOF5GD/Render', 07:50:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:50:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:50:53 INFO - 'HOME': '/Users/cltbld', 07:50:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:50:53 INFO - 'LOGNAME': 'cltbld', 07:50:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:50:53 INFO - 'MOZ_NO_REMOTE': '1', 07:50:53 INFO - 'NO_EM_RESTART': '1', 07:50:53 INFO - 'PAGER': '/bin/cat', 07:50:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:50:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:50:53 INFO - 'PWD': '/builds/slave/test', 07:50:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:50:53 INFO - 'SHELL': '/bin/bash', 07:50:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KvjkzzEjiq/Listeners', 07:50:53 INFO - 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', 07:50:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:50:53 INFO - 'USER': 'cltbld', 07:50:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:50:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:50:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:50:53 INFO - 'XPC_FLAGS': '0x0', 07:50:53 INFO - 'XPC_SERVICE_NAME': '0', 07:50:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:50:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:50:54 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 07:50:54 INFO - Cleaning up... 07:50:54 INFO - Return code: 0 07:50:54 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 07:50:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:50:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:50:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:50:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:50:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:54 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 0x10f3d2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe9849cb50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f271e00>, '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 0x10f0dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f26cb20>, '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.ucXvfOF5GD/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.KvjkzzEjiq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/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 07:50:54 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 07:50:54 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 07:50:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ucXvfOF5GD/Render', 07:50:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:50:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:50:54 INFO - 'HOME': '/Users/cltbld', 07:50:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:50:54 INFO - 'LOGNAME': 'cltbld', 07:50:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:50:54 INFO - 'MOZ_NO_REMOTE': '1', 07:50:54 INFO - 'NO_EM_RESTART': '1', 07:50:54 INFO - 'PAGER': '/bin/cat', 07:50:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:50:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:50:54 INFO - 'PWD': '/builds/slave/test', 07:50:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:50:54 INFO - 'SHELL': '/bin/bash', 07:50:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KvjkzzEjiq/Listeners', 07:50:54 INFO - 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', 07:50:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:50:54 INFO - 'USER': 'cltbld', 07:50:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:50:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:50:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:50:54 INFO - 'XPC_FLAGS': '0x0', 07:50:54 INFO - 'XPC_SERVICE_NAME': '0', 07:50:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:50:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:50:54 INFO - Downloading/unpacking psutil>=0.7.1 07:50:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:50:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:50:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:50:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:50:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:50:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:50:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:50:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:50:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:50:57 INFO - Installing collected packages: psutil 07:50:57 INFO - Running setup.py install for psutil 07:50:57 INFO - building 'psutil._psutil_osx' extension 07:50:57 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 07:50:58 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 07:50:58 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 07:50:58 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 07:50:58 INFO - building 'psutil._psutil_posix' extension 07:50:58 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 07:50:58 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:50:58 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:50:58 INFO - ^ 07:50:58 INFO - 1 warning generated. 07:50:58 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 07:50:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:50:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:50:58 INFO - Successfully installed psutil 07:50:58 INFO - Cleaning up... 07:50:58 INFO - Return code: 0 07:50:58 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 07:50:58 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')]} 07:50:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:50:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:58 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')]} 07:50:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:50:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:50:58 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 0x10f3d2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe9849cb50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f271e00>, '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 0x10f0dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f26cb20>, '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.ucXvfOF5GD/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.KvjkzzEjiq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/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 07:50:58 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 07:50:58 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 07:50:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ucXvfOF5GD/Render', 07:50:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:50:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:50:58 INFO - 'HOME': '/Users/cltbld', 07:50:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:50:58 INFO - 'LOGNAME': 'cltbld', 07:50:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:50:58 INFO - 'MOZ_NO_REMOTE': '1', 07:50:58 INFO - 'NO_EM_RESTART': '1', 07:50:58 INFO - 'PAGER': '/bin/cat', 07:50:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:50:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:50:58 INFO - 'PWD': '/builds/slave/test', 07:50:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:50:58 INFO - 'SHELL': '/bin/bash', 07:50:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KvjkzzEjiq/Listeners', 07:50:58 INFO - 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', 07:50:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:50:58 INFO - 'USER': 'cltbld', 07:50:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:50:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:50:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:50:58 INFO - 'XPC_FLAGS': '0x0', 07:50:58 INFO - 'XPC_SERVICE_NAME': '0', 07:50:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:50:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:50:58 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 07:50:58 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 07:50:58 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 07:50:58 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 07:50:58 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 07:50:58 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 07:50:58 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 07:51:01 INFO - Downloading mozsystemmonitor-0.0.tar.gz 07:51:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:51:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 07:51:01 INFO - Installing collected packages: mozsystemmonitor 07:51:01 INFO - Running setup.py install for mozsystemmonitor 07:51:01 INFO - Successfully installed mozsystemmonitor 07:51:01 INFO - Cleaning up... 07:51:01 INFO - Return code: 0 07:51:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:51:01 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')]} 07:51:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:51:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:01 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')]} 07:51:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:51:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:01 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 0x10f3d2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe9849cb50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f271e00>, '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 0x10f0dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f26cb20>, '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.ucXvfOF5GD/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.KvjkzzEjiq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/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 07:51:01 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 07:51:01 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 07:51:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ucXvfOF5GD/Render', 07:51:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:51:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:51:01 INFO - 'HOME': '/Users/cltbld', 07:51:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:51:01 INFO - 'LOGNAME': 'cltbld', 07:51:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:51:01 INFO - 'MOZ_NO_REMOTE': '1', 07:51:01 INFO - 'NO_EM_RESTART': '1', 07:51:01 INFO - 'PAGER': '/bin/cat', 07:51:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:51:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:51:01 INFO - 'PWD': '/builds/slave/test', 07:51:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:51:01 INFO - 'SHELL': '/bin/bash', 07:51:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KvjkzzEjiq/Listeners', 07:51:01 INFO - 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', 07:51:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:51:01 INFO - 'USER': 'cltbld', 07:51:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:51:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:51:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:51:01 INFO - 'XPC_FLAGS': '0x0', 07:51:01 INFO - 'XPC_SERVICE_NAME': '0', 07:51:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:51:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:51:01 INFO - Downloading/unpacking blobuploader==1.2.4 07:51:01 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 07:51:01 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 07:51:01 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 07:51:01 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 07:51:01 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 07:51:01 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 07:51:04 INFO - Downloading blobuploader-1.2.4.tar.gz 07:51:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:51:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:51:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:51:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:51:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:51:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:51:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:51:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:51:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:51:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:51: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 07:51: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 07:51: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 07:51: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 07:51: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 07:51: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 07:51:05 INFO - Downloading docopt-0.6.1.tar.gz 07:51:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:51:05 INFO - Installing collected packages: blobuploader, requests, docopt 07:51:05 INFO - Running setup.py install for blobuploader 07:51:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:51:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:51:05 INFO - Running setup.py install for requests 07:51:06 INFO - Running setup.py install for docopt 07:51:06 INFO - Successfully installed blobuploader requests docopt 07:51:06 INFO - Cleaning up... 07:51:06 INFO - Return code: 0 07:51:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:51:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:51:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:51:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:51:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:51:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:06 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 0x10f3d2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe9849cb50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f271e00>, '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 0x10f0dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f26cb20>, '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.ucXvfOF5GD/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.KvjkzzEjiq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/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 07:51:06 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 07:51:06 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 07:51:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ucXvfOF5GD/Render', 07:51:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:51:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:51:06 INFO - 'HOME': '/Users/cltbld', 07:51:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:51:06 INFO - 'LOGNAME': 'cltbld', 07:51:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:51:06 INFO - 'MOZ_NO_REMOTE': '1', 07:51:06 INFO - 'NO_EM_RESTART': '1', 07:51:06 INFO - 'PAGER': '/bin/cat', 07:51:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:51:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:51:06 INFO - 'PWD': '/builds/slave/test', 07:51:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:51:06 INFO - 'SHELL': '/bin/bash', 07:51:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KvjkzzEjiq/Listeners', 07:51:06 INFO - 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', 07:51:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:51:06 INFO - 'USER': 'cltbld', 07:51:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:51:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:51:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:51:06 INFO - 'XPC_FLAGS': '0x0', 07:51:06 INFO - 'XPC_SERVICE_NAME': '0', 07:51:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:51:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:51:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:51:06 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-c4pdt1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:51:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:51:06 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-PHcnHm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:51:07 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-nM5ZyO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:51:07 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-6nbx4L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:51:07 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-fCpCku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:51:07 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-H8YdWE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:51:07 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-oUkQc9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:51:07 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-4ZB8TY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:51:07 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-YsBZL9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:51:07 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-P3YEXk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:51:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:51:07 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-9FoomM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:51:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:51:08 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-yqEJMo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:51:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:51:08 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-SOY9hz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:51:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:51:08 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-S646_F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:51:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:51:08 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-wJvsJy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:51:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:51:08 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-Ef6cw2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:51:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:51:08 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-IfPyon-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:51:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:51:08 INFO - Running setup.py install for manifestparser 07:51:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:51:08 INFO - Running setup.py install for mozcrash 07:51:08 INFO - Running setup.py install for mozdebug 07:51:09 INFO - Running setup.py install for mozdevice 07:51:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:51:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:51:09 INFO - Running setup.py install for mozfile 07:51:09 INFO - Running setup.py install for mozhttpd 07:51:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:51:09 INFO - Running setup.py install for mozinfo 07:51:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:51:09 INFO - Running setup.py install for mozInstall 07:51:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:51:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:51:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:51:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:51:09 INFO - Running setup.py install for mozleak 07:51:09 INFO - Running setup.py install for mozlog 07:51:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:51:10 INFO - Running setup.py install for moznetwork 07:51:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:51:10 INFO - Running setup.py install for mozprocess 07:51:10 INFO - Running setup.py install for mozprofile 07:51:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:51:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:51:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:51:10 INFO - Running setup.py install for mozrunner 07:51:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:51:10 INFO - Running setup.py install for mozscreenshot 07:51:10 INFO - Running setup.py install for moztest 07:51:11 INFO - Running setup.py install for mozversion 07:51:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:51:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:51:11 INFO - Cleaning up... 07:51:11 INFO - Return code: 0 07:51:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:51:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:51:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:51:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:51:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:51:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:11 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 0x10f3d2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe9849cb50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f271e00>, '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 0x10f0dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f26cb20>, '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.ucXvfOF5GD/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.KvjkzzEjiq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/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 07:51:11 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 07:51:11 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 07:51:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ucXvfOF5GD/Render', 07:51:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:51:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:51:11 INFO - 'HOME': '/Users/cltbld', 07:51:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:51:11 INFO - 'LOGNAME': 'cltbld', 07:51:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:51:11 INFO - 'MOZ_NO_REMOTE': '1', 07:51:11 INFO - 'NO_EM_RESTART': '1', 07:51:11 INFO - 'PAGER': '/bin/cat', 07:51:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:51:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:51:11 INFO - 'PWD': '/builds/slave/test', 07:51:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:51:11 INFO - 'SHELL': '/bin/bash', 07:51:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KvjkzzEjiq/Listeners', 07:51:11 INFO - 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', 07:51:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:51:11 INFO - 'USER': 'cltbld', 07:51:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:51:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:51:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:51:11 INFO - 'XPC_FLAGS': '0x0', 07:51:11 INFO - 'XPC_SERVICE_NAME': '0', 07:51:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:51:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:51:11 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-g0MZlV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:51:11 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)) 07:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:51:11 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-_O3A5V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:51:11 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)) 07:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:51:11 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-5B6t8o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:51:11 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)) 07:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:51:11 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-fou4WQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:51:11 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)) 07:51:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:51:11 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-ZpTxwB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:51:12 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)) 07:51:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:51:12 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-fTpF4L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:51:12 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)) 07:51:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:51:12 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-vuuxIy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:51:12 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)) 07:51:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:51:12 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-sV1Nud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:51:12 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)) 07:51:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:51:12 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-wo5B5D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:51:12 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)) 07:51:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:51:12 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-jmK08C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:51:12 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)) 07:51:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:51:12 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-oaRYyt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:51:12 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)) 07:51:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:51:12 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-6N39gw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:51:12 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)) 07:51:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:51:12 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-7xcZSA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:51:12 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)) 07:51:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:51:12 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-4QqPMy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:51:12 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)) 07:51:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:51:12 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-xJTJm_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:51:13 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)) 07:51:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:51:13 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-agTAxy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:51:13 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)) 07:51:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:51:13 INFO - Running setup.py (path:/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/pip-taiGYR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:51:13 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)) 07:51:13 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)) 07:51:13 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)) 07:51:13 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)) 07:51:13 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)) 07:51:13 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)) 07:51:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:51:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:51:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:51:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:51:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:51:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:51:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:51:15 INFO - Downloading blessings-1.6.tar.gz 07:51:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:51:15 INFO - Installing collected packages: blessings 07:51:15 INFO - Running setup.py install for blessings 07:51:16 INFO - Successfully installed blessings 07:51:16 INFO - Cleaning up... 07:51:16 INFO - Return code: 0 07:51:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:51:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:51:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:51:16 INFO - Reading from file tmpfile_stdout 07:51:16 INFO - Current package versions: 07:51:16 INFO - blessings == 1.6 07:51:16 INFO - blobuploader == 1.2.4 07:51:16 INFO - docopt == 0.6.1 07:51:16 INFO - manifestparser == 1.1 07:51:16 INFO - mozInstall == 1.12 07:51:16 INFO - mozcrash == 0.17 07:51:16 INFO - mozdebug == 0.1 07:51:16 INFO - mozdevice == 0.48 07:51:16 INFO - mozfile == 1.2 07:51:16 INFO - mozhttpd == 0.7 07:51:16 INFO - mozinfo == 0.9 07:51:16 INFO - mozleak == 0.1 07:51:16 INFO - mozlog == 3.1 07:51:16 INFO - moznetwork == 0.27 07:51:16 INFO - mozprocess == 0.22 07:51:16 INFO - mozprofile == 0.28 07:51:16 INFO - mozrunner == 6.11 07:51:16 INFO - mozscreenshot == 0.1 07:51:16 INFO - mozsystemmonitor == 0.0 07:51:16 INFO - moztest == 0.7 07:51:16 INFO - mozversion == 1.4 07:51:16 INFO - psutil == 3.1.1 07:51:16 INFO - requests == 1.2.3 07:51:16 INFO - wsgiref == 0.1.2 07:51:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:51:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:51:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:51:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:51:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:51:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:16 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 0x10f3d2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe9849cb50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f271e00>, '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 0x10f0dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f26cb20>, '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.ucXvfOF5GD/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.KvjkzzEjiq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/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 07:51:16 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 07:51:16 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 07:51:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ucXvfOF5GD/Render', 07:51:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:51:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:51:16 INFO - 'HOME': '/Users/cltbld', 07:51:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:51:16 INFO - 'LOGNAME': 'cltbld', 07:51:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:51:16 INFO - 'MOZ_NO_REMOTE': '1', 07:51:16 INFO - 'NO_EM_RESTART': '1', 07:51:16 INFO - 'PAGER': '/bin/cat', 07:51:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:51:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:51:16 INFO - 'PWD': '/builds/slave/test', 07:51:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:51:16 INFO - 'SHELL': '/bin/bash', 07:51:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KvjkzzEjiq/Listeners', 07:51:16 INFO - 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', 07:51:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:51:16 INFO - 'USER': 'cltbld', 07:51:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:51:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:51:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:51:16 INFO - 'XPC_FLAGS': '0x0', 07:51:16 INFO - 'XPC_SERVICE_NAME': '0', 07:51:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:51:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:51:16 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)) 07:51:16 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)) 07:51:16 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)) 07:51:16 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)) 07:51:16 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)) 07:51:16 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)) 07:51:16 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)) 07:51:16 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)) 07:51:16 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)) 07:51:16 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)) 07:51:16 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)) 07:51:16 INFO - Cleaning up... 07:51:16 INFO - Return code: 0 07:51:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:51:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:51:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:51:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:51:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:51:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:16 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 0x10f3d2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe9849cb50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f271e00>, '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 0x10f0dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f26cb20>, '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.ucXvfOF5GD/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.KvjkzzEjiq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/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 07:51:16 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 07:51:16 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 07:51:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ucXvfOF5GD/Render', 07:51:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:51:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:51:16 INFO - 'HOME': '/Users/cltbld', 07:51:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:51:16 INFO - 'LOGNAME': 'cltbld', 07:51:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:51:16 INFO - 'MOZ_NO_REMOTE': '1', 07:51:16 INFO - 'NO_EM_RESTART': '1', 07:51:16 INFO - 'PAGER': '/bin/cat', 07:51:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:51:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:51:16 INFO - 'PWD': '/builds/slave/test', 07:51:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:51:16 INFO - 'SHELL': '/bin/bash', 07:51:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KvjkzzEjiq/Listeners', 07:51:16 INFO - 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', 07:51:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:51:16 INFO - 'USER': 'cltbld', 07:51:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:51:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:51:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:51:16 INFO - 'XPC_FLAGS': '0x0', 07:51:16 INFO - 'XPC_SERVICE_NAME': '0', 07:51:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:51:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:51:16 INFO - Downloading/unpacking jsonschema 07:51:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:51:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:51:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:51:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:51:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:51:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:51:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:51:19 INFO - Downloading/unpacking functools32 (from jsonschema) 07:51:19 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 07:51:19 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 07:51:19 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 07:51:19 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 07:51:19 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 07:51:19 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 07:51:19 INFO - Downloading functools32-3.2.3-2.tar.gz 07:51:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:51:19 INFO - warning: no files found matching '*.txt' 07:51:19 INFO - no previously-included directories found matching 'build' 07:51:19 INFO - no previously-included directories found matching 'dist' 07:51:19 INFO - no previously-included directories found matching '.git*' 07:51:19 INFO - Installing collected packages: jsonschema, functools32 07:51:19 INFO - Running setup.py install for functools32 07:51:20 INFO - warning: no files found matching '*.txt' 07:51:20 INFO - no previously-included directories found matching 'build' 07:51:20 INFO - no previously-included directories found matching 'dist' 07:51:20 INFO - no previously-included directories found matching '.git*' 07:51:20 INFO - Successfully installed jsonschema functools32 07:51:20 INFO - Cleaning up... 07:51:20 INFO - Return code: 0 07:51:20 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 07:51:20 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')]} 07:51:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:51:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:20 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')]} 07:51:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:51:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:51:20 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 0x10f3d2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f3e2420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbe9849cb50>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f271e00>, '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 0x10f0dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f26cb20>, '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.ucXvfOF5GD/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.KvjkzzEjiq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/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 07:51:20 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 07:51:20 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 07:51:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ucXvfOF5GD/Render', 07:51:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:51:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:51:20 INFO - 'HOME': '/Users/cltbld', 07:51:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:51:20 INFO - 'LOGNAME': 'cltbld', 07:51:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:51:20 INFO - 'MOZ_NO_REMOTE': '1', 07:51:20 INFO - 'NO_EM_RESTART': '1', 07:51:20 INFO - 'PAGER': '/bin/cat', 07:51:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:51:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:51:20 INFO - 'PWD': '/builds/slave/test', 07:51:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:51:20 INFO - 'SHELL': '/bin/bash', 07:51:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KvjkzzEjiq/Listeners', 07:51:20 INFO - 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', 07:51:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:51:20 INFO - 'USER': 'cltbld', 07:51:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:51:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:51:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:51:20 INFO - 'XPC_FLAGS': '0x0', 07:51:20 INFO - 'XPC_SERVICE_NAME': '0', 07:51:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:51:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:51:20 INFO - Downloading/unpacking flake8 07:51: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 07:51: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 07:51: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 07:51: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 07:51: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 07:51: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 07:51:22 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 07:51:22 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 07:51:22 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 07:51:22 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 07:51:22 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 07:51:22 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 07:51:22 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 07:51:22 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 07:51:23 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 07:51:23 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 07:51: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 07:51: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 07:51: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 07:51: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 07:51: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 07:51: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 07:51:23 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 07:51: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 07:51: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 07:51: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 07:51: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 07:51: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 07:51: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 07:51:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 07:51:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:51:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:51:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:51:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:51:24 INFO - no previously-included directories found matching 'docs/_build' 07:51:24 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 07:51:24 INFO - Running setup.py install for pep8 07:51:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 07:51:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 07:51:24 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 07:51:24 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 07:51:24 INFO - no previously-included directories found matching 'docs/_build' 07:51:24 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 07:51:24 INFO - Successfully installed flake8 mccabe pyflakes pep8 07:51:24 INFO - Cleaning up... 07:51:24 INFO - Return code: 0 07:51:24 INFO - Running post-action listener: _resource_record_post_action 07:51:24 INFO - Running post-action listener: _start_resource_monitoring 07:51:24 INFO - Starting resource monitoring. 07:51:24 INFO - ##### 07:51:24 INFO - ##### Running install step. 07:51:24 INFO - ##### 07:51:24 INFO - Running pre-action listener: _resource_record_pre_action 07:51:24 INFO - Running main action method: install 07:51:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:51:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:51:24 INFO - Reading from file tmpfile_stdout 07:51:24 INFO - Detecting whether we're running mozinstall >=1.0... 07:51:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:51:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:51:24 INFO - Reading from file tmpfile_stdout 07:51:24 INFO - Output received: 07:51:24 INFO - Usage: mozinstall [options] installer 07:51:24 INFO - Options: 07:51:24 INFO - -h, --help show this help message and exit 07:51:24 INFO - -d DEST, --destination=DEST 07:51:24 INFO - Directory to install application into. [default: 07:51:24 INFO - "/builds/slave/test"] 07:51:24 INFO - --app=APP Application being installed. [default: firefox] 07:51:24 INFO - mkdir: /builds/slave/test/build/application 07:51:24 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'] 07:51:24 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 07:51:56 INFO - Reading from file tmpfile_stdout 07:51:56 INFO - Output received: 07:51:56 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 07:51:56 INFO - Running post-action listener: _resource_record_post_action 07:51:56 INFO - ##### 07:51:56 INFO - ##### Running run-tests step. 07:51:56 INFO - ##### 07:51:56 INFO - Running pre-action listener: _resource_record_pre_action 07:51:56 INFO - Running main action method: run_tests 07:51:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:51:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:51:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:51:56 INFO - 2016-05-30 07:51:56.681 screenresolution[1666:10999] starting screenresolution argv=screenresolution get 07:51:56 INFO - 2016-05-30 07:51:56.684 screenresolution[1666:10999] Display 0: 1600x1200x32@60 07:51:56 INFO - 2016-05-30 07:51:56.689 screenresolution[1667:11002] starting screenresolution argv=screenresolution list 07:51:56 INFO - Available Modes on Display 0 07:51:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:51:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:51:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:51:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:51:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:51:56 INFO - Intel Iris: 07:51:56 INFO - Chipset Model: Intel Iris 07:51:56 INFO - Type: GPU 07:51:56 INFO - Bus: Built-In 07:51:56 INFO - VRAM (Dynamic, Max): 1536 MB 07:51:56 INFO - Vendor: Intel (0x8086) 07:51:56 INFO - Device ID: 0x0a2e 07:51:56 INFO - Revision ID: 0x0009 07:51:56 INFO - Displays: 07:51:56 INFO - Display: 07:51:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:51:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:51:56 INFO - Display Serial Number: 1600x1200 60 07:51:56 INFO - Main Display: Yes 07:51:56 INFO - Mirror: Off 07:51:56 INFO - Online: Yes 07:51:56 INFO - Rotation: Supported 07:51:56 INFO - Return code: 0 07:51:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:51:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:51:56 INFO - Python 2.7.3 07:51:56 INFO - Return code: 0 07:51:56 INFO - grabbing minidump binary from tooltool 07:51:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 07:51:56 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 0x10f271e00>, '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 0x10f0dadb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f26cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:51:56 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 07:51:56 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 07:51:57 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:51:57 INFO - Return code: 0 07:51:57 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:51:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:51:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:51:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:51:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:51:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 07:51:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 07:51:58 INFO - Return code: 0 07:51:58 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/cn0lKUxATnuJLEicI6z55Q/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0339', '--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 07:51:58 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/cn0lKUxATnuJLEicI6z55Q/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0339 --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 07:51:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ucXvfOF5GD/Render', 07:51:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:51:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:51:58 INFO - 'HOME': '/Users/cltbld', 07:51:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:51:58 INFO - 'LOGNAME': 'cltbld', 07:51:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:51:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:51:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:51:58 INFO - 'MOZ_NO_REMOTE': '1', 07:51:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:51:58 INFO - 'NO_EM_RESTART': '1', 07:51:58 INFO - 'PAGER': '/bin/cat', 07:51:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:51:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:51:58 INFO - 'PWD': '/builds/slave/test', 07:51:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:51:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:51:58 INFO - 'SHELL': '/bin/bash', 07:51:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.KvjkzzEjiq/Listeners', 07:51:58 INFO - 'TMPDIR': '/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/', 07:51:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:51:58 INFO - 'USER': 'cltbld', 07:51:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:51:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:51:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:51:58 INFO - 'XPC_FLAGS': '0x0', 07:51:58 INFO - 'XPC_SERVICE_NAME': '0', 07:51:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:51:58 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/cn0lKUxATnuJLEicI6z55Q/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0339', '--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 07:51:58 INFO - mozversion application_buildid: 20160530063837 07:51:58 INFO - mozversion application_changeset: 433520b687b76bc6332bf080db94b01ebf4ef881 07:51:58 INFO - mozversion application_display_name: FirefoxDeveloperEdition 07:51:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:51:58 INFO - mozversion application_name: Firefox 07:51:58 INFO - mozversion application_remotingname: firefox-dev 07:51:58 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:51:58 INFO - mozversion application_vendor: Mozilla 07:51:58 INFO - mozversion application_version: 48.0a2 07:51:58 INFO - mozversion platform_buildid: 20160530063837 07:51:58 INFO - mozversion platform_changeset: 433520b687b76bc6332bf080db94b01ebf4ef881 07:51:58 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 07:51:58 INFO - mozversion platform_version: 48.0a2 07:51:58 INFO - using testdate: 1464619918 07:51:58 INFO - actual date: 1464619918 07:51:58 INFO - starting webserver on 'localhost:49277' 07:51:58 INFO - SUITE-START | Running 2 tests 07:51:58 INFO - TEST-START | dromaeo_css 07:51:58 INFO - operating with platform_type : mac_ 07:51:58 INFO - Initialising browser for dromaeo_css test... 07:51:58 INFO - TEST-INFO | started process 1685 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/tmp89I_0u/profile http://localhost:49277/getInfo.html) 07:52:01 INFO - PROCESS | 1685 | __metrics Screen width/height:1600/1200 07:52:01 INFO - PROCESS | 1685 | colorDepth:24 07:52:01 INFO - PROCESS | 1685 | Browser inner width/height: 1024/689 07:52:01 INFO - PROCESS | 1685 | __metrics 07:52:01 ERROR - PROCESS | 1685 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 07:52:01 INFO - TEST-INFO | 1685: exit 0 07:52:01 INFO - Browser initialized. 07:52:01 INFO - Running cycle 1/1 for dromaeo_css test... 07:52:01 INFO - TEST-INFO | started process 1688 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/tmp89I_0u/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:52:13 INFO - PROCESS | 1688 | RSS: Main: 221192192 07:52:13 INFO - PROCESS | 1688 | 07:53:03 INFO - PROCESS | 1688 | 1464619983377 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] 07:55:14 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 07:55:15 INFO - PROCESS | 1688 | RSS: Main: 231641088 07:55:15 INFO - PROCESS | 1688 | 07:58:17 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 07:58:18 INFO - PROCESS | 1688 | RSS: Main: 243429376 07:58:18 INFO - PROCESS | 1688 | 08:01:20 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 08:01:20 INFO - PROCESS | 1688 | RSS: Main: 245489664 08:01:20 INFO - PROCESS | 1688 | 08:04:22 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 08:04:23 INFO - PROCESS | 1688 | RSS: Main: 247169024 08:04:23 INFO - PROCESS | 1688 | 08:07:25 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 08:07:26 INFO - PROCESS | 1688 | RSS: Main: 250015744 08:07:26 INFO - PROCESS | 1688 | 08:10:28 INFO - PROCESS | 1688 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 08:10:28 INFO - PROCESS | 1688 | RSS: Main: 250896384 08:10:28 INFO - PROCESS | 1688 | 08:10:28 INFO - PROCESS | 1688 | __start_tp_report 08:10:28 INFO - PROCESS | 1688 | _x_x_mozilla_page_load 08:10:28 INFO - PROCESS | 1688 | _x_x_mozilla_page_load_details 08:10:28 INFO - PROCESS | 1688 | |i|pagename|runs| 08:10:28 INFO - PROCESS | 1688 | |0;dojo.html;3340.8496617652204;3435.896923092307;3507.7719948203367;3543.557055368079;3575.785452872828;5709.571782116337;5741.540676745861;5750.971245143774;5763.481286684198;5782.508486778624;5750.654960702351;5763.567732420064;5765.596408251414;5780.4219578042175;5817.854553636162;5187.636865419422;5206.7656955437005;5253.6322457428;5264.947350526503;5277;5071.923921141186;5267.288915996349;5301.867453313679;5362.43694412085;5368.946310536903;5260.105782017047;5270.288511050997;5276.129438642639;5279.656822306538;5284.260203571503;5210.114280572312;5222.947770522284;5255.947440525603;5331.626786124973;5359.356877174725;5151.278820965068;5204.739763011872;5227.634065615409;5231.581473482112;5265.736713164327;4727.361806156159;5708.257926469532;5777.364489906107;5808.6224395414165;5850.473457388813;4965.751712414365;6108.847278818021;6211.161493198406;6254.530910181755;6285.622862628257;6240.687965601702;6244.781432649881;6245.594036387621;6256.906146407762;6264.310925798159;5897.764089436418;5924.229850119457;5954.46409823118;5967.6121052131475;6000.369961154103;6166.845828854314;6175.722092505848;6198.2871969723155;6224.408681175281;6277.748890044439;6172.537059720449;6204.906926396107;6223.408776166257;6225.719842607094;6232.750689972441;6178.62928224308;6258.186435763321;6265.624062556261;6267.4045965633195;6277.372262773777;6136.917362303815;6224.37756224374;6260.968695156495;6263.686815659199;6279.591826531261;6263.530235232376;6268.4671802897265;6272.278687950944;6287.559870808999;6295.338989406138;4917.385326834145;4982.277569752366;5026.698398096126;5029;5043.798248070036;4447.043885564619;4501.3022981437925;4507.504174540798;4545.545445455428;4549.499555048942;5880.9414305425435;5916.645001299936;5926.111083337534;5928.822135335947;5930.970345148246;6156.984097623931;6163.013917773134;6187.3503282155625;6247.031710084944;6257.155284036597;4871.442492691365;5866.120081987736;5910.261217347832;5934.05055191176;5981.940180598138;5475.616706830484;5556.99974004683;5558.194061861008;5558.9715902558155;5579.023670857584;6278.058291256348;6343.714532846033;6395.936040639534;6402;6414.903776443357;6215.160953271431;6233.875322493615;6248.375162483715;6266.811995640138;6271.592346497463;6390.137331460193;6419.743210271537;6437.420632143129;6444.097826304227;6453.870922581616;6139.969300153471;6156.045812899008;6162.352952939877;6245.0320200369015;6248.094026366242;6199.1011303359855;6216.285127210429;6222.471089957405;6236.688165591793;6238.15784869055;5925.288965324189;6039.335673076046;6184.752609895734;6199.783007594712;6204.255489341308;5115.87210319745;6102.267727872683;6169.56813023084;6182.876342473035;6222.844428889314;5951.761929522944;5968.612040217372;6058.969705151622;6063.484603808549;6095.603785753912;6023.216981792339;6114.847128821815;6189.04069869171;6213.751449941951;6225.439710426083;6039.6678182698615;6071.362506936708;6103.23709536308;6114.694265286629;6124.081387791867 08:10:28 INFO - PROCESS | 1688 | |1;ext.html;512.3953734593181;530.9707966061866;551.1347184919673;580.9600814541968;610.1975901689281;38163.61836381636;39469.407958880605;39513.604863951354;39576.40635390471;39768.80115599422;40637.59362406375;41324.58675413245;41328.58671413285;41337.17325653485;41441.378379324335;9423.199028082605;9571.234301255883;9642.951785241099;9692.321537492378;9706.077922597377;9501.80996380072;9719.46542940138;9721.222302215841;9747.171490423323;9776.951115244414;9725.56234969428;9727.756806079833;9745.31782775206;9767.413955162678;9796;9086.591578305373;9350.064993500664;9677.467739274338;9722.9513852431;9729.610815567368;9585.281103917234;9665.758356041086;9697.854532182022;9707.854382184272;9723.367981081277;39431.60568394308;39609;39860;39972.80013599942;39977.60022399768;3097.674744151854;3140.4975203967256;3224.290656055664;3268.754843386732;3311.288073064303;28012.101974010555;28544;28785.136445906657;29128;29534.26164345887;2349.6945397098266;2807.929801754952;2831.051597714768;2874.2814296425895;2882.351470919047;379838.1008094942;446650;452549;453127.4687253118;453233.9999999967;51194.48805511972;62048.68975655093;63242.367576323646;63449.36550634527;63578.0463293051;61760;70131;70920.64539677268;71620;71707.28292717111;14380.928095359457;17342.479725608253;17514.47456576305;17532.912335438243;17552.736708949375;13235.470581176838;13331;13338;13357.732845343038;13396.397162127727;19630.803691962898;19655.803441965396;19752.814831109892;19855.900720496305;20398;6812.931870681329;8226.424150309422;8246.185839115386;8266.8346633067;8269.379796515286;834.2980320146953;909.5543183840003;1044.3629386074488;1179.0685358566636;1180.0913296761616;24722.381940451272;27439.588406173923;29336.26659333534;29554.704452955626;30374.785008599407;1087.9891201087887;1115.286216821235;1127.610974213895;1246.8628450870397;1249.5064449542563;15579.298931548108;15626.609334766721;15697.293621787047;15730.842691573167;15912.76130858038;591554;647690;652585;655538;665417;61166.0825087624;61192;62018.37981620216;62087.068693969624;62167.067493987626;1493.27576125579;1495.7905893174748;1509.0719207687193;1510.7884896114554;1596.993893846869;29578;29914;29984.550231746976;30679.386412271182;30780;53444.73277633587;53578.92842143213;53737.925241494166;53786;54086.18870717021;468657;472908;475064;476600;477259;26856.925722971642;26879.462410752065;27037.729622703522;27472.862635686695;27599.862000689867;47381.76309118432;47413.81465463391;47563.762181188875;47635.761821190674;49003.26495102505;37990.81004594959;38603.61396386113;38613.80693096629;38651.420228697156;38808;31739;32011.839940800146;38083.42874856935;38694.6130538691;38741.2251754969;33101.18639915538;38182.809085954395;38634.8409547714;38846.41730374103;39275;31115.066548003597;32766.67233327636;32782;33113;33196.33607327792;25448.618270725583;25451.23646290614;25587.744122558535;25626.615600765632;25874.48251034931 08:10:28 INFO - PROCESS | 1688 | |2;jquery.html;383.28517315207137;396.9960300396995;400.7355145603902;400.74152171849175;408.6832704653893;14948.850511494882;15017.774733379008;19553.51116222095;20236.89881550592;20266.493337666547;17957.91021044893;18399.26402943884;18638.25446982119;18669.813301866976;18692.626147477044;4300.806463709133;6008.639481631116;6020.759169633209;6041.758329666808;6064.757409703607;4835.637327200457;5364;5773;5869.647821130746;5954.642721436728;5113.718745468997;5894.381089985554;5901.114832775075;5925.348211696732;5956.791512297071;5489.972550137244;5634.887302253973;5675.829725108253;5784.45047720468;5939.109133629947;5534.142207957773;5570.749316280777;5628.971855140698;5645.350784659735;5654.717264136777;13033.478660853554;13222.471101155943;13391.06262561621;13424.664383390396;20454.795452045586;2026.7770545240012;2117.237794394017;2422.321749910028;2510.9454029307735;2515.8742062896963;8098.75703728889;9737.123658870665;10072.496375181285;10105.89894101057;13421.0605257632;1711.981371084203;1717.6994026045393;1740.4604572582414;1770.9822901770947;1805.8103899090538;100177.4991125047;105275;109457.90542094636;110923.44538277338;111971.20071998183;11664.008559272557;11674.124440666985;11969.820452693217;12053.939730301292;12106.213096148722;14851.70296594062;15086.924565377323;15098.849011509965;15117.319720612599;15139;8901.554922253861;8918.866217006751;8925.687600933936;8943.418677785923;8992.730218093468;8913.42062765931;8920.330975176757;8958.238549723346;8981.416207946462;9054.411463254868;4523.344115103291;4607.216773148572;4612.607928326062;4641.419822522184;4659.510751371057;1935.777385600674;1981.7225588417632;1999.100404817838;2003.3589251439691;2009.7487814023248;899.7750562359411;975.8585005174352;991.9603215871284;996.267743208741;1007.2445665750687;19661.410157695293;20138.69791953122;20235.887026213404;20613.278535251193;20666.07002684883;851.4465597361765;907.9682211122579;961.6105477281712;1010.4291075542295;1033.6692258477215;7597.734079307308;10054.849177262347;10381.740456488496;10857.782844343068;10869;612027;632796;639873.4018989719;652701;653537;101544;120334.99162521007;123540;129551;130885;910.2444970674192;916.1434059154578;998.2513115163628;1008.742770593494;1008.9243306752023;17781.933084015;17853.107344632455;17936.91031544834;18047.007414592324;18200.99894505815;51880.740596296775;52207.73896130495;53353.60685976308;53998.460015399345;56013.15980260218;61361.159165225115;69469;74409.62795185989;79002.81495777683;79030.6048469754;7973.960130199312;8015.666576972496;8142.1857814220475;8279.420440569102;8378.204070613281;11059.22585419013;12283.631491055283;12461.563845265735;12715.55495557651;12862.935685321514;10692.251542392269;10844.783104338027;10860.022597966221;11018.944905275423;11208.439578021229;7462.8507429850015;10750.193735469715;10767.946160269463;10837.566497340018;11159.553617855196;10959.397233151934;11188.496517656562;11311.830322545333;11368.602098926498;11533.82699259495;8119.39104567148;8162.5510596915365;8322.875156872773;8347.290480308999;8526;9542.952285238529;10740.946295268473;11057.170712196457;11061.557537698402;11094.944525277322 08:10:28 INFO - PROCESS | 1688 | |3;mootools.html;1545.8995165314254;1565.1391734545996;1791.4267434420983;1807.737878007688;1882.830545250928;7410.962945185273;7526.661300241489;7850.96074519628;8522;8912.777180570482;8560.65757369706;8609.612567434464;8663.783405414868;8687.652493900252;8688.956555217232;2067.7311949446553;2437.707475102986;2456.7297597264287;2466.1491785334033;2478.1450399612104;2158.6006588781047;2346.9647955280684;2495.438526331579;2504.4490212153296;2508.347829564317;2476.987615061931;2491.53906527293;2496.9750302496923;2502.3744063984004;2520.3825062859623;2473.5547601431736;2477.0834791127218;2483.379155211197;2486.428121532049;2515.5975043993058;2305.273838740808;2445.9755402445926;2467.370820440794;2468.8765561721843;2483.180550418376;8763.298936085097;8825.338099642488;8888.511131887808;8892.244159246471;8926;5424.755885985141;5532.031894418501;5554.944450555524;5564.10974244119;5609.439056094398;3533.5229743984746;3597.1007248187952;3627.2201476682376;3655.250673611903;3681.852725890961;1032.1381646325328;1049.522467277387;1049.8215303398365;1050.6637875879721;1054.3041592548882;11943.34311612861;12126.211796233305;12152.966997805197;12163.209391389531;12169.878301216962;4829.492903245108;4886.53577910098;4908.9263661045115;4930.482299358516;4964.553190212898;10236.027577380137;10245.282830201962;10253.58985640581;10269.075783179549;10272.897271027343;5392.110301800159;5453.263809385761;5454.672719636835;5495.477929596682;5523.8066667666435;4431.158079964796;4835.661503694778;4958.330625365602;4962.851114466572;4963.45402005779;4055.9797201013807;4103.056297051695;4152.688548358885;4163.375493675972;4171.91656166875;1354.1536539662711;1365.8702423269774;1366.7471517769245;1369.6165073779357;1372.6499742565582;817.7260617693139;884.5709830732089;928.8653145293895;948.6395169835419;948.9525523723786;5454.881749241396;5627.465390787868;5834.854128646818;6110;6180.289266734293;465.51586350196095;472.4377990191661;473.3136951420454;478.7749757613916;482.53917508779034;3431.227973705896;3975.642192202658;3980.104476492766;4055.7972101395403;4076.6534844537955;10475.528601212964;10842.783144337069;10977.780444391226;11051.447427628586;11057.889421105847;10599.205059620559;10622.415767132727;10624.468776561142;10688.25182237236;10865.619703310345;8263.917360826435;8328.916710832935;8335.624896879655;8346.8330633387;8377.70678026266;5526.917096243479;5708.429157084258;5760.539156867524;5801.6519008859605;5855.3266374367495;9580.568874400808;9611.663591774255;9631.988641192575;9645.614175432904;9645.99999999972;9419.481928493999;9868.358556693793;9898.158656514277;9905.207583393167;10095.495225238856;6744.190697116377;7255.637218139177;7457.627118643937;7519.022527071445;7596.658150383357;8189.262966332821;8221.835563288581;8242.876356854771;8260.628271727666;8275.172482751677;7226.241244669444;7359.963200183965;7391.150017747813;7474.364679002346;7525.397968162643;7294.927050729425;7296.343329100407;7341.412686985135;7353.889691654523;7396.445266604918;7022.754203603054;7050.823729406806;7059.223485416497;7079.115110611174;7080.610566418896;4826.782794774173;4835.492273311393;4846.854594362175;4943.2585112233455;4947.48051454592;4890.828820991248;4893.119238536955;4918.336024636628;4961.677490963076;5118.513741194581 08:10:28 INFO - PROCESS | 1688 | |4;prototype.html;292.5567764836273;328.99177520562;331.491161067761;335.426420820397;339.4687314353039;6838.658067096644;6854.657267136642;6862.485313601474;6865.759698410557;6898.241193468714;6740.629265390414;6773.72905083796;6804.3535864092955;6825.078614387056;6840.863182736342;4762.952370476294;4871.391076115486;4995.500449955011;5019.397672279331;5027.069992051483;4955.702657840523;5019.071471777715;5019.121653710605;5027.748612569375;5037.974810125944;4942.209246520558;4954.207326827708;4968.503149685021;4973.875653108665;4982.5017498250245;4960.528749768766;4965.106280869442;4973.353464049686;4974.97512512437;5000.874978125576;3540.7344449166417;4289.592488713567;4309.870703878889;4334.176506463762;4343.891402714926;5773.700917293618;5905.6751878646655;6623.503237257177;6625.503087268426;6685.866282674319;4662.344867571997;4793.520647935214;5094.6688465249645;5944.316403613597;6016.639001659871;6997.116600286839;7298.854022919512;7312.37844783194;7313.561186328838;7322.267773222688;4011.438398624195;4860.586850117745;4875.293082503017;4878.243872199815;4879.121758083544;7544.622768861535;7560.2439756024505;7580.355669768076;7604.581748003858;7630.8473830523635;4749.95250047502;4916.385451818523;4930.408350997904;4932.260160975882;5012.674176178537;5016.423111342242;5059.848204553869;5076.187809950464;5078.873028174326;5084.5932325414615;4841.782119804618;5947.9702601486715;5979.431953964367;6000.489958353502;6011.218541589653;7375.856742204966;7388.335049845432;7389.445791565654;7399.112106547249;7408.9629551851895;1741.1294352823588;1763.9118044097743;1765.6203916158088;1768.32803534656;1770.0884044716981;1090.1442367741286;1127;1137.425600071975;1141.6917432293235;1142.857142857143;6623.503237257225;6629.204495460575;6681.33186681328;6683.7660681875905;6729.6971636276485;7394.704211831574;7436.628168591549;7458.888116678254;7483.738069167553;7485.962570187223;5646.322441307069;6766.424853887376;6883.449324054164;6912.654367281716;6942.479314051366;3984.103576695278;4014.0366312085475;4014.7791871447353;4032.294348489003;4036.2129384769687;8050.798730031679;8113.269805717396;8116.472429292077;8128.105908350077;8132.2680958714;7606.581638009962;7849.4897831640765;7868.606569671493;7885.960570197112;7914.445988780845;8103.635336409758;8164.959175204086;8167.264946154637;8186.918130818734;8187;7683.731069412544;7689.577073260801;7704.961475192588;7724.575148366894;7727.0727512696585;7563.470557060953;7576.090869095744;7579.54522728638;7583.165851756192;7589.696412143673;6780.86438271222;7702.691892324468;7933.801654958672;7956.363490920829;8161.877571836546;4602.102589994919;4673.626109911131;4676.46220684612;4712.929306060343;4742.383490146259;6042.577019608585;7128.429725622042;7531.661075251736;7560.168381477923;7587.279208475186;7312.122545294599;7319.890201646873;7337.339639432476;7370.520916140434;7398.482106252511;6581.769638062645;7123.074000379917;7295.927040729524;7299.854002919806;7306.561606303639;5697.772089116389;7075.964620176866;7114.288571142844;7129.394001509722;7135.571865687868;7258.274172582699;7314.780556583311;7334.706611735684;7354.70581176747;7374.078240219973;6898.2756810536175;7118.25258347866;7128.825708159061;7148.749793757402;7150.678219480032 08:10:28 INFO - PROCESS | 1688 | |5;yui.html;690.2028157478113;731.8170457385656;739.7706710919615;751.2074761127009;781.7967328494592;14992;15099.849001509981;15204.011739236941;15248.847511524882;15268.618284542878;13629.93185034076;14002.859971400283;14158.362873670683;14162;14187.787183192235;758.457702742539;926.9443833369986;927.8329900617886;931.6971984105159;942.6794889737488;932.7108596335124;944.8204841080208;945.6028468043404;946.186279799372;946.3375637054055;969.6315400147934;979.6081567373036;989.65362123257;991.8462638291077;993.9503024848765;938.3572253006712;982.7887004294112;983.4640121133964;985.9014098590155;987.9357841740335;694.4444444444475;721.9169795473535;732.3665029749282;737.8819388897804;739.6856336057208;14375.640608984755;14381.28093595316;14388.280585970764;14388.496402625962;14777.482788102365;12619.369031548385;12696.809547856697;12745.107842451032;12782.296973666445;12914.612561623166;9924.40453572788;9940.453275069869;10178.18574514037;10273.897261027369;10344.896551034468;3498.0904964709102;3510.069831494656;3535.8232088395475;3593.2094939113354;3766.3785475396526;60900;82375.5881220596;83316;84334.57832710858;84837;9381.108794664633;9448.527573621428;9536;9646.90353096474;9686.321957463051;20822.791772082084;20870;21104.05031773574;21109.68335474969;21127.577448450946;7354.742584009534;7521.473496855277;7530.585817780075;7550.7734767957045;7643.464957453036;4038.45480860086;4106.979465102655;4116.217918595457;4120.278951183531;4127.525334586501;3622.239329740784;3675.6140605236064;3684.8341824617955;3694.7598406103516;3709.6661300482547;659.6371995402509;663.6548994522822;674.6660403100399;684.6679360510149;686.584616307137;515.2168703570552;573.7590212110923;585.6749504025307;595.4075694683818;629.7292164369366;8064.959675201704;8149.7147599833725;8182.631781569844;8199.795005124919;8413.074561798197;581.1515187825821;581.5696384675394;593.5281451246183;595.7944509144338;596.627108057464;3764.981175094107;3827.2154208387215;3859.9035024124623;3873.496445462128;3994.6604538614542;72567.27432725711;73129;93205.53397232971;93235;96255.5936101603;76569;77185;77667;78076;79235.20764792278;53550.73224633852;69977.65011175013;70833.64583177154;70930.64534677396;71137;7537.886931696139;7737.226277372218;7872.094709108297;7966.203379661987;7979.9202007979175;44904.53025083652;45387.546124538334;49279;50361.748191260274;51060.23409648932;62352.376476234655;68193.3180668187;69906.9027929163;70803;72688.27311726815;10943.233973621771;11089.33463992163;11182.384968826793;11222.326660400402;11530.596429125277;11804.527818887149;11918.940405297919;11986.64040078799;12032.338221397908;12278.386080696107;9341.813163736551;11452.713682158013;11539.711507212387;11574.88425115738;11844.644660660195;11241.325520468798;11557.653270401901;11602.593909213138;11625.941870290595;11629.59296424621;10892.782144357227;11153.163512736739;11203.21577489568;11247.100231981258;11625.709357266136;9271.443713377219;9506.049395060438;9736.367136136128;9771.609135634495;9777.853332200166;9553.187979021583;9612.1829644481;9712.417254964725;9755.853662194932;9785.412875227508 08:10:28 INFO - PROCESS | 1688 | __end_tp_report 08:10:28 INFO - PROCESS | 1688 | __start_cc_report 08:10:28 INFO - PROCESS | 1688 | _x_x_mozilla_cycle_collect,415 08:10:28 INFO - PROCESS | 1688 | __end_cc_report 08:10:28 INFO - PROCESS | 1688 | __startTimestamp1464621028884__endTimestamp 08:10:28 INFO - PROCESS | 1688 | 08:10:28 INFO - PROCESS | 1688 | ------- Summary: start ------- 08:10:28 INFO - PROCESS | 1688 | Number of tests: 6 08:10:28 INFO - PROCESS | 1688 | 08:10:28 INFO - PROCESS | 1688 | [#0] dojo.html Cycles:165 Average:5784.50 Median:6039.67 stddev:633.38 (10.5%) stddev-sans-first:605.62 08:10:28 INFO - PROCESS | 1688 | Values: 3340.8 3435.9 3507.8 3543.6 3575.8 5709.6 5741.5 5751.0 5763.5 5782.5 5750.7 5763.6 5765.6 5780.4 5817.9 5187.6 5206.8 5253.6 5264.9 5277.0 5071.9 5267.3 5301.9 5362.4 5368.9 5260.1 5270.3 5276.1 5279.7 5284.3 5210.1 5222.9 5255.9 5331.6 5359.4 5151.3 5204.7 5227.6 5231.6 5265.7 4727.4 5708.3 5777.4 5808.6 5850.5 4965.8 6108.8 6211.2 6254.5 6285.6 6240.7 6244.8 6245.6 6256.9 6264.3 5897.8 5924.2 5954.5 5967.6 6000.4 6166.8 6175.7 6198.3 6224.4 6277.7 6172.5 6204.9 6223.4 6225.7 6232.8 6178.6 6258.2 6265.6 6267.4 6277.4 6136.9 6224.4 6261.0 6263.7 6279.6 6263.5 6268.5 6272.3 6287.6 6295.3 4917.4 4982.3 5026.7 5029.0 5043.8 4447.0 4501.3 4507.5 4545.5 4549.5 5880.9 5916.6 5926.1 5928.8 5931.0 6157.0 6163.0 6187.4 6247.0 6257.2 4871.4 5866.1 5910.3 5934.1 5981.9 5475.6 5557.0 5558.2 5559.0 5579.0 6278.1 6343.7 6395.9 6402.0 6414.9 6215.2 6233.9 6248.4 6266.8 6271.6 6390.1 6419.7 6437.4 6444.1 6453.9 6140.0 6156.0 6162.4 6245.0 6248.1 6199.1 6216.3 6222.5 6236.7 6238.2 5925.3 6039.3 6184.8 6199.8 6204.3 5115.9 6102.3 6169.6 6182.9 6222.8 5951.8 5968.6 6059.0 6063.5 6095.6 6023.2 6114.8 6189.0 6213.8 6225.4 6039.7 6071.4 6103.2 6114.7 6124.1 08:10:28 INFO - PROCESS | 1688 | 08:10:28 INFO - PROCESS | 1688 | [#1] ext.html Cycles:180 Average:66223.11 Median:39450.51 stddev:140381.42 (355.8%) stddev-sans-first:140688.53 08:10:28 INFO - PROCESS | 1688 | Values: 512.4 531.0 551.1 581.0 610.2 38163.6 39469.4 39513.6 39576.4 39768.8 40637.6 41324.6 41328.6 41337.2 41441.4 9423.2 9571.2 9643.0 9692.3 9706.1 9501.8 9719.5 9721.2 9747.2 9777.0 9725.6 9727.8 9745.3 9767.4 9796.0 9086.6 9350.1 9677.5 9723.0 9729.6 9585.3 9665.8 9697.9 9707.9 9723.4 39431.6 39609.0 39860.0 39972.8 39977.6 3097.7 3140.5 3224.3 3268.8 3311.3 28012.1 28544.0 28785.1 29128.0 29534.3 2349.7 2807.9 2831.1 2874.3 2882.4 379838.1 446650.0 452549.0 453127.5 453234.0 51194.5 62048.7 63242.4 63449.4 63578.0 61760.0 70131.0 70920.6 71620.0 71707.3 14380.9 17342.5 17514.5 17532.9 17552.7 13235.5 13331.0 13338.0 13357.7 13396.4 19630.8 19655.8 19752.8 19855.9 20398.0 6812.9 8226.4 8246.2 8266.8 8269.4 834.3 909.6 1044.4 1179.1 1180.1 24722.4 27439.6 29336.3 29554.7 30374.8 1088.0 1115.3 1127.6 1246.9 1249.5 15579.3 15626.6 15697.3 15730.8 15912.8 591554.0 647690.0 652585.0 655538.0 665417.0 61166.1 61192.0 62018.4 62087.1 62167.1 1493.3 1495.8 1509.1 1510.8 1597.0 29578.0 29914.0 29984.6 30679.4 30780.0 53444.7 53578.9 53737.9 53786.0 54086.2 468657.0 472908.0 475064.0 476600.0 477259.0 26856.9 26879.5 27037.7 27472.9 27599.9 47381.8 47413.8 47563.8 47635.8 49003.3 37990.8 38603.6 38613.8 38651.4 38808.0 31739.0 32011.8 38083.4 38694.6 38741.2 33101.2 38182.8 38634.8 38846.4 39275.0 31115.1 32766.7 32782.0 33113.0 33196.3 25448.6 25451.2 25587.7 25626.6 25874.5 08:10:28 INFO - PROCESS | 1688 | 08:10:28 INFO - PROCESS | 1688 | [#2] jquery.html Cycles:180 Average:35046.18 Median:11391.65 stddev:105859.85 (929.3%) stddev-sans-first:106124.81 08:10:28 INFO - PROCESS | 1688 | Values: 383.3 397.0 400.7 400.7 408.7 14948.9 15017.8 19553.5 20236.9 20266.5 17957.9 18399.3 18638.3 18669.8 18692.6 4300.8 6008.6 6020.8 6041.8 6064.8 4835.6 5364.0 5773.0 5869.6 5954.6 5113.7 5894.4 5901.1 5925.3 5956.8 5490.0 5634.9 5675.8 5784.5 5939.1 5534.1 5570.7 5629.0 5645.4 5654.7 13033.5 13222.5 13391.1 13424.7 20454.8 2026.8 2117.2 2422.3 2510.9 2515.9 8098.8 9737.1 10072.5 10105.9 13421.1 1712.0 1717.7 1740.5 1771.0 1805.8 100177.5 105275.0 109457.9 110923.4 111971.2 11664.0 11674.1 11969.8 12053.9 12106.2 14851.7 15086.9 15098.8 15117.3 15139.0 8901.6 8918.9 8925.7 8943.4 8992.7 8913.4 8920.3 8958.2 8981.4 9054.4 4523.3 4607.2 4612.6 4641.4 4659.5 1935.8 1981.7 1999.1 2003.4 2009.7 899.8 975.9 992.0 996.3 1007.2 19661.4 20138.7 20235.9 20613.3 20666.1 851.4 908.0 961.6 1010.4 1033.7 7597.7 10054.8 10381.7 10857.8 10869.0 612027.0 632796.0 639873.4 652701.0 653537.0 101544.0 120335.0 123540.0 129551.0 130885.0 910.2 916.1 998.3 1008.7 1008.9 17781.9 17853.1 17936.9 18047.0 18201.0 51880.7 52207.7 53353.6 53998.5 56013.2 61361.2 69469.0 74409.6 79002.8 79030.6 7974.0 8015.7 8142.2 8279.4 8378.2 11059.2 12283.6 12461.6 12715.6 12862.9 10692.3 10844.8 10860.0 11018.9 11208.4 7462.9 10750.2 10767.9 10837.6 11159.6 10959.4 11188.5 11311.8 11368.6 11533.8 8119.4 8162.6 8322.9 8347.3 8526.0 9543.0 10740.9 11057.2 11061.6 11094.9 08:10:28 INFO - PROCESS | 1688 | 08:10:28 INFO - PROCESS | 1688 | [#3] mootools.html Cycles:180 Average:5725.52 Median:4913.63 stddev:3200.81 (65.1%) stddev-sans-first:3194.37 08:10:28 INFO - PROCESS | 1688 | Values: 1545.9 1565.1 1791.4 1807.7 1882.8 7411.0 7526.7 7851.0 8522.0 8912.8 8560.7 8609.6 8663.8 8687.7 8689.0 2067.7 2437.7 2456.7 2466.1 2478.1 2158.6 2347.0 2495.4 2504.4 2508.3 2477.0 2491.5 2497.0 2502.4 2520.4 2473.6 2477.1 2483.4 2486.4 2515.6 2305.3 2446.0 2467.4 2468.9 2483.2 8763.3 8825.3 8888.5 8892.2 8926.0 5424.8 5532.0 5554.9 5564.1 5609.4 3533.5 3597.1 3627.2 3655.3 3681.9 1032.1 1049.5 1049.8 1050.7 1054.3 11943.3 12126.2 12153.0 12163.2 12169.9 4829.5 4886.5 4908.9 4930.5 4964.6 10236.0 10245.3 10253.6 10269.1 10272.9 5392.1 5453.3 5454.7 5495.5 5523.8 4431.2 4835.7 4958.3 4962.9 4963.5 4056.0 4103.1 4152.7 4163.4 4171.9 1354.2 1365.9 1366.7 1369.6 1372.6 817.7 884.6 928.9 948.6 949.0 5454.9 5627.5 5834.9 6110.0 6180.3 465.5 472.4 473.3 478.8 482.5 3431.2 3975.6 3980.1 4055.8 4076.7 10475.5 10842.8 10977.8 11051.4 11057.9 10599.2 10622.4 10624.5 10688.3 10865.6 8263.9 8328.9 8335.6 8346.8 8377.7 5526.9 5708.4 5760.5 5801.7 5855.3 9580.6 9611.7 9632.0 9645.6 9646.0 9419.5 9868.4 9898.2 9905.2 10095.5 6744.2 7255.6 7457.6 7519.0 7596.7 8189.3 8221.8 8242.9 8260.6 8275.2 7226.2 7360.0 7391.2 7474.4 7525.4 7294.9 7296.3 7341.4 7353.9 7396.4 7022.8 7050.8 7059.2 7079.1 7080.6 4826.8 4835.5 4846.9 4943.3 4947.5 4890.8 4893.1 4918.3 4961.7 5118.5 08:10:28 INFO - PROCESS | 1688 | 08:10:28 INFO - PROCESS | 1688 | [#4] prototype.html Cycles:180 Average:5961.87 Median:6770.08 stddev:1926.94 (28.5%) stddev-sans-first:1884.78 08:10:28 INFO - PROCESS | 1688 | Values: 292.6 329.0 331.5 335.4 339.5 6838.7 6854.7 6862.5 6865.8 6898.2 6740.6 6773.7 6804.4 6825.1 6840.9 4763.0 4871.4 4995.5 5019.4 5027.1 4955.7 5019.1 5019.1 5027.7 5038.0 4942.2 4954.2 4968.5 4973.9 4982.5 4960.5 4965.1 4973.4 4975.0 5000.9 3540.7 4289.6 4309.9 4334.2 4343.9 5773.7 5905.7 6623.5 6625.5 6685.9 4662.3 4793.5 5094.7 5944.3 6016.6 6997.1 7298.9 7312.4 7313.6 7322.3 4011.4 4860.6 4875.3 4878.2 4879.1 7544.6 7560.2 7580.4 7604.6 7630.8 4750.0 4916.4 4930.4 4932.3 5012.7 5016.4 5059.8 5076.2 5078.9 5084.6 4841.8 5948.0 5979.4 6000.5 6011.2 7375.9 7388.3 7389.4 7399.1 7409.0 1741.1 1763.9 1765.6 1768.3 1770.1 1090.1 1127.0 1137.4 1141.7 1142.9 6623.5 6629.2 6681.3 6683.8 6729.7 7394.7 7436.6 7458.9 7483.7 7486.0 5646.3 6766.4 6883.4 6912.7 6942.5 3984.1 4014.0 4014.8 4032.3 4036.2 8050.8 8113.3 8116.5 8128.1 8132.3 7606.6 7849.5 7868.6 7886.0 7914.4 8103.6 8165.0 8167.3 8186.9 8187.0 7683.7 7689.6 7705.0 7724.6 7727.1 7563.5 7576.1 7579.5 7583.2 7589.7 6780.9 7702.7 7933.8 7956.4 8161.9 4602.1 4673.6 4676.5 4712.9 4742.4 6042.6 7128.4 7531.7 7560.2 7587.3 7312.1 7319.9 7337.3 7370.5 7398.5 6581.8 7123.1 7295.9 7299.9 7306.6 5697.8 7076.0 7114.3 7129.4 7135.6 7258.3 7314.8 7334.7 7354.7 7374.1 6898.3 7118.3 7128.8 7148.7 7150.7 08:10:28 INFO - PROCESS | 1688 | 08:10:28 INFO - PROCESS | 1688 | [#5] yui.html Cycles:180 Average:17950.26 Median:661.65 stddev:25023.90 (3782.1%) stddev-sans-first:25060.54 08:10:28 INFO - PROCESS | 1688 | Values: 690.2 731.8 739.8 751.2 781.8 14992.0 15099.8 15204.0 15248.8 15268.6 13629.9 14002.9 14158.4 14162.0 14187.8 758.5 926.9 927.8 931.7 942.7 932.7 944.8 945.6 946.2 946.3 969.6 979.6 989.7 991.8 994.0 938.4 982.8 983.5 985.9 987.9 694.4 721.9 732.4 737.9 739.7 14375.6 14381.3 14388.3 14388.5 14777.5 12619.4 12696.8 12745.1 12782.3 12914.6 9924.4 9940.5 10178.2 10273.9 10344.9 3498.1 3510.1 3535.8 3593.2 3766.4 60900.0 82375.6 83316.0 84334.6 84837.0 9381.1 9448.5 9536.0 9646.9 9686.3 20822.8 20870.0 21104.1 21109.7 21127.6 7354.7 7521.5 7530.6 7550.8 7643.5 4038.5 4107.0 4116.2 4120.3 4127.5 3622.2 3675.6 3684.8 3694.8 3709.7 659.6 663.7 674.7 684.7 686.6 515.2 573.8 585.7 595.4 629.7 8065.0 8149.7 8182.6 8199.8 8413.1 581.2 581.6 593.5 595.8 596.6 3765.0 3827.2 3859.9 3873.5 3994.7 72567.3 73129.0 93205.5 93235.0 96255.6 76569.0 77185.0 77667.0 78076.0 79235.2 53550.7 69977.7 70833.6 70930.6 71137.0 7537.9 7737.2 7872.1 7966.2 7979.9 44904.5 45387.5 49279.0 50361.7 51060.2 62352.4 68193.3 69906.9 70803.0 72688.3 10943.2 11089.3 11182.4 11222.3 11530.6 11804.5 11918.9 11986.6 12032.3 12278.4 9341.8 11452.7 11539.7 11574.9 11844.6 11241.3 11557.7 11602.6 11625.9 11629.6 10892.8 11153.2 11203.2 11247.1 11625.7 9271.4 9506.0 9736.4 9771.6 9777.9 9553.2 9612.2 9712.4 9755.9 9785.4 08:10:28 INFO - PROCESS | 1688 | -------- Summary: end -------- 08:10:28 INFO - PROCESS | 1688 | 08:10:29 INFO - TEST-INFO | 1688: exit 0 08:10:29 INFO - TEST-OK | dromaeo_css | took 1111093ms 08:10:29 INFO - TEST-START | kraken 08:10:29 INFO - operating with platform_type : mac_ 08:10:29 INFO - Initialising browser for kraken test... 08:10:29 INFO - TEST-INFO | started process 1695 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/tmpm9Y2zP/profile http://localhost:49277/getInfo.html) 08:10:32 INFO - PROCESS | 1695 | __metrics Screen width/height:1600/1200 08:10:32 INFO - PROCESS | 1695 | colorDepth:24 08:10:32 INFO - PROCESS | 1695 | Browser inner width/height: 1024/689 08:10:32 INFO - PROCESS | 1695 | __metrics 08:10:32 ERROR - PROCESS | 1695 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 08:10:32 INFO - TEST-INFO | 1695: exit 0 08:10:32 INFO - Browser initialized. 08:10:32 INFO - Running cycle 1/1 for kraken test... 08:10:32 INFO - TEST-INFO | started process 1698 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/T/tmpm9Y2zP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:10:44 INFO - PROCESS | 1698 | RSS: Main: 221036544 08:10:44 INFO - PROCESS | 1698 | 08:10:47 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 08:10:48 INFO - PROCESS | 1698 | RSS: Main: 233086976 08:10:48 INFO - PROCESS | 1698 | 08:10:51 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 08:10:51 INFO - PROCESS | 1698 | RSS: Main: 242032640 08:10:51 INFO - PROCESS | 1698 | 08:10:55 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 08:10:55 INFO - PROCESS | 1698 | RSS: Main: 245698560 08:10:55 INFO - PROCESS | 1698 | 08:10:57 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 08:10:58 INFO - PROCESS | 1698 | RSS: Main: 246894592 08:10:58 INFO - PROCESS | 1698 | 08:11:00 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:11:01 INFO - PROCESS | 1698 | RSS: Main: 248565760 08:11:01 INFO - PROCESS | 1698 | 08:11:04 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 08:11:04 INFO - PROCESS | 1698 | RSS: Main: 247873536 08:11:04 INFO - PROCESS | 1698 | 08:11:07 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 08:11:08 INFO - PROCESS | 1698 | RSS: Main: 247922688 08:11:08 INFO - PROCESS | 1698 | 08:11:11 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 08:11:12 INFO - PROCESS | 1698 | RSS: Main: 248025088 08:11:12 INFO - PROCESS | 1698 | 08:11:13 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:11:14 INFO - PROCESS | 1698 | RSS: Main: 247898112 08:11:14 INFO - PROCESS | 1698 | 08:11:16 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:11:17 INFO - PROCESS | 1698 | RSS: Main: 247463936 08:11:17 INFO - PROCESS | 1698 | 08:11:19 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:11:19 INFO - PROCESS | 1698 | RSS: Main: 247508992 08:11:19 INFO - PROCESS | 1698 | 08:11:22 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 08:11:23 INFO - PROCESS | 1698 | RSS: Main: 247529472 08:11:23 INFO - PROCESS | 1698 | 08:11:26 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 08:11:26 INFO - PROCESS | 1698 | RSS: Main: 247648256 08:11:26 INFO - PROCESS | 1698 | 08:11:28 INFO - PROCESS | 1698 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 08:11:28 INFO - PROCESS | 1698 | RSS: Main: 249507840 08:11:28 INFO - PROCESS | 1698 | 08:11:28 INFO - PROCESS | 1698 | __start_tp_report 08:11:28 INFO - PROCESS | 1698 | _x_x_mozilla_page_load 08:11:28 INFO - PROCESS | 1698 | _x_x_mozilla_page_load_details 08:11:28 INFO - PROCESS | 1698 | |i|pagename|runs| 08:11:28 INFO - PROCESS | 1698 | |0;ai-astar;115;120;110;118;119;118;106;117;115;117 08:11:28 INFO - PROCESS | 1698 | |1;audio-beat-detection;190;111;175;162;141;170;150;154;157;120 08:11:28 INFO - PROCESS | 1698 | |2;audio-dft;194;194;197;198;194;199;234;251;221;195 08:11:28 INFO - PROCESS | 1698 | |3;audio-fft;67;66;80;145;55;57;55;64;123;56 08:11:28 INFO - PROCESS | 1698 | |4;audio-oscillator;69;97;127;75;85;130;164;111;79;121 08:11:28 INFO - PROCESS | 1698 | |5;imaging-gaussian-blur;90;88;90;88;90;86;109;85;134;118 08:11:28 INFO - PROCESS | 1698 | |6;imaging-darkroom;150;134;95;96;96;126;107;150;100;139 08:11:28 INFO - PROCESS | 1698 | |7;imaging-desaturate;93;103;80;151;126;135;100;146;118;147 08:11:28 INFO - PROCESS | 1698 | |8;json-parse-financial;59;64;66;65;67;66;66;86;73;93 08:11:28 INFO - PROCESS | 1698 | |9;json-stringify-tinderbox;63;67;66;73;70;84;75;68;68;71 08:11:28 INFO - PROCESS | 1698 | |10;stanford-crypto-aes;82;84;82;83;80;82;81;82;88;88 08:11:28 INFO - PROCESS | 1698 | |11;stanford-crypto-ccm;144;126;155;140;142;132;127;129;150;166 08:11:28 INFO - PROCESS | 1698 | |12;stanford-crypto-pbkdf2;168;169;171;178;177;165;170;169;165;165 08:11:28 INFO - PROCESS | 1698 | |13;stanford-crypto-sha256-iterative;71;74;71;83;100;92;93;96;73;69 08:11:28 INFO - PROCESS | 1698 | __end_tp_report 08:11:28 INFO - PROCESS | 1698 | __start_cc_report 08:11:28 INFO - PROCESS | 1698 | _x_x_mozilla_cycle_collect,1092 08:11:28 INFO - PROCESS | 1698 | __end_cc_report 08:11:28 INFO - PROCESS | 1698 | __startTimestamp1464621088838__endTimestamp 08:11:28 INFO - PROCESS | 1698 | 08:11:28 INFO - PROCESS | 1698 | ------- Summary: start ------- 08:11:28 INFO - PROCESS | 1698 | Number of tests: 14 08:11:28 INFO - PROCESS | 1698 | 08:11:28 INFO - PROCESS | 1698 | [#0] ai-astar Cycles:10 Average:115.50 Median:117.50 stddev:4.35 (3.7%) stddev-sans-first:4.61 08:11:28 INFO - PROCESS | 1698 | Values: 115.0 120.0 110.0 118.0 119.0 118.0 106.0 117.0 115.0 117.0 08:11:28 INFO - PROCESS | 1698 | 08:11:28 INFO - PROCESS | 1698 | [#1] audio-beat-detection Cycles:10 Average:153.00 Median:159.50 stddev:24.14 (15.1%) stddev-sans-first:21.58 08:11:28 INFO - PROCESS | 1698 | Values: 190.0 111.0 175.0 162.0 141.0 170.0 150.0 154.0 157.0 120.0 08:11:28 INFO - PROCESS | 1698 | 08:11:28 INFO - PROCESS | 1698 | [#2] audio-dft Cycles:10 Average:207.70 Median:198.50 stddev:20.42 (10.3%) stddev-sans-first:21.05 08:11:28 INFO - PROCESS | 1698 | Values: 194.0 194.0 197.0 198.0 194.0 199.0 234.0 251.0 221.0 195.0 08:11:28 INFO - PROCESS | 1698 | 08:11:28 INFO - PROCESS | 1698 | [#3] audio-fft Cycles:10 Average:76.80 Median:60.50 stddev:31.53 (52.1%) stddev-sans-first:33.24 08:11:28 INFO - PROCESS | 1698 | Values: 67.0 66.0 80.0 145.0 55.0 57.0 55.0 64.0 123.0 56.0 08:11:28 INFO - PROCESS | 1698 | 08:11:28 INFO - PROCESS | 1698 | [#4] audio-oscillator Cycles:10 Average:105.80 Median:72.00 stddev:30.21 (42.0%) stddev-sans-first:28.95 08:11:28 INFO - PROCESS | 1698 | Values: 69.0 97.0 127.0 75.0 85.0 130.0 164.0 111.0 79.0 121.0 08:11:28 INFO - PROCESS | 1698 | 08:11:28 INFO - PROCESS | 1698 | [#5] imaging-gaussian-blur Cycles:10 Average:97.80 Median:88.00 stddev:16.74 (19.0%) stddev-sans-first:17.51 08:11:28 INFO - PROCESS | 1698 | Values: 90.0 88.0 90.0 88.0 90.0 86.0 109.0 85.0 134.0 118.0 08:11:28 INFO - PROCESS | 1698 | 08:11:28 INFO - PROCESS | 1698 | [#6] imaging-darkroom Cycles:10 Average:119.30 Median:150.00 stddev:22.93 (15.3%) stddev-sans-first:21.47 08:11:28 INFO - PROCESS | 1698 | Values: 150.0 134.0 95.0 96.0 96.0 126.0 107.0 150.0 100.0 139.0 08:11:28 INFO - PROCESS | 1698 | 08:11:28 INFO - PROCESS | 1698 | [#7] imaging-desaturate Cycles:10 Average:119.90 Median:146.50 stddev:25.05 (17.1%) stddev-sans-first:24.61 08:11:28 INFO - PROCESS | 1698 | Values: 93.0 103.0 80.0 151.0 126.0 135.0 100.0 146.0 118.0 147.0 08:11:28 INFO - PROCESS | 1698 | 08:11:28 INFO - PROCESS | 1698 | [#8] json-parse-financial Cycles:10 Average:70.50 Median:66.50 stddev:10.70 (16.1%) stddev-sans-first:10.51 08:11:28 INFO - PROCESS | 1698 | Values: 59.0 64.0 66.0 65.0 67.0 66.0 66.0 86.0 73.0 93.0 08:11:28 INFO - PROCESS | 1698 | 08:11:28 INFO - PROCESS | 1698 | [#9] json-stringify-tinderbox Cycles:10 Average:70.50 Median:70.50 stddev:5.87 (8.3%) stddev-sans-first:5.57 08:11:28 INFO - PROCESS | 1698 | Values: 63.0 67.0 66.0 73.0 70.0 84.0 75.0 68.0 68.0 71.0 08:11:28 INFO - PROCESS | 1698 | 08:11:28 INFO - PROCESS | 1698 | [#10] stanford-crypto-aes Cycles:10 Average:83.20 Median:82.50 stddev:2.74 (3.3%) stddev-sans-first:2.87 08:11:28 INFO - PROCESS | 1698 | Values: 82.0 84.0 82.0 83.0 80.0 82.0 81.0 82.0 88.0 88.0 08:11:28 INFO - PROCESS | 1698 | 08:11:28 INFO - PROCESS | 1698 | [#11] stanford-crypto-ccm Cycles:10 Average:141.10 Median:143.00 stddev:13.16 (9.2%) stddev-sans-first:13.92 08:11:28 INFO - PROCESS | 1698 | Values: 144.0 126.0 155.0 140.0 142.0 132.0 127.0 129.0 150.0 166.0 08:11:28 INFO - PROCESS | 1698 | 08:11:28 INFO - PROCESS | 1698 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.70 Median:169.50 stddev:4.64 (2.7%) stddev-sans-first:4.88 08:11:28 INFO - PROCESS | 1698 | Values: 168.0 169.0 171.0 178.0 177.0 165.0 170.0 169.0 165.0 165.0 08:11:28 INFO - PROCESS | 1698 | 08:11:28 INFO - PROCESS | 1698 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:82.20 Median:78.50 stddev:12.01 (15.3%) stddev-sans-first:12.03 08:11:28 INFO - PROCESS | 1698 | Values: 71.0 74.0 71.0 83.0 100.0 92.0 93.0 96.0 73.0 69.0 08:11:28 INFO - PROCESS | 1698 | -------- Summary: end -------- 08:11:28 INFO - PROCESS | 1698 | 08:11:29 INFO - TEST-INFO | 1698: exit 0 08:11:29 INFO - TEST-OK | kraken | took 60291ms 08:11:29 INFO - SUITE-END | took 1171s 08:11:30 INFO - Completed test suite (00:19:31) 08:11:30 INFO - javascript benchmark 08:11:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3340.8496617652204, 3435.896923092307, 3507.7719948203367, 3543.557055368079, 3575.785452872828, 5709.571782116337, 5741.540676745861, 5750.971245143774, 5763.481286684198, 5782.508486778624, 5750.654960702351, 5763.567732420064, 5765.596408251414, 5780.4219578042175, 5817.854553636162, 5187.636865419422, 5206.7656955437005, 5253.6322457428, 5264.947350526503, 5277.0, 5071.923921141186, 5267.288915996349, 5301.867453313679, 5362.43694412085, 5368.946310536903, 5260.105782017047, 5270.288511050997, 5276.129438642639, 5279.656822306538, 5284.260203571503, 5210.114280572312, 5222.947770522284, 5255.947440525603, 5331.626786124973, 5359.356877174725, 5151.278820965068, 5204.739763011872, 5227.634065615409, 5231.581473482112, 5265.736713164327, 4727.361806156159, 5708.257926469532, 5777.364489906107, 5808.6224395414165, 5850.473457388813, 4965.751712414365, 6108.847278818021, 6211.161493198406, 6254.530910181755, 6285.622862628257, 6240.687965601702, 6244.781432649881, 6245.594036387621, 6256.906146407762, 6264.310925798159, 5897.764089436418, 5924.229850119457, 5954.46409823118, 5967.6121052131475, 6000.369961154103, 6166.845828854314, 6175.722092505848, 6198.2871969723155, 6224.408681175281, 6277.748890044439, 6172.537059720449, 6204.906926396107, 6223.408776166257, 6225.719842607094, 6232.750689972441, 6178.62928224308, 6258.186435763321, 6265.624062556261, 6267.4045965633195, 6277.372262773777, 6136.917362303815, 6224.37756224374, 6260.968695156495, 6263.686815659199, 6279.591826531261, 6263.530235232376, 6268.4671802897265, 6272.278687950944, 6287.559870808999, 6295.338989406138, 4917.385326834145, 4982.277569752366, 5026.698398096126, 5029.0, 5043.798248070036, 4447.043885564619, 4501.3022981437925, 4507.504174540798, 4545.545445455428, 4549.499555048942, 5880.9414305425435, 5916.645001299936, 5926.111083337534, 5928.822135335947, 5930.970345148246, 6156.984097623931, 6163.013917773134, 6187.3503282155625, 6247.031710084944, 6257.155284036597, 4871.442492691365, 5866.120081987736, 5910.261217347832, 5934.05055191176, 5981.940180598138, 5475.616706830484, 5556.99974004683, 5558.194061861008, 5558.9715902558155, 5579.023670857584, 6278.058291256348, 6343.714532846033, 6395.936040639534, 6402.0, 6414.903776443357, 6215.160953271431, 6233.875322493615, 6248.375162483715, 6266.811995640138, 6271.592346497463, 6390.137331460193, 6419.743210271537, 6437.420632143129, 6444.097826304227, 6453.870922581616, 6139.969300153471, 6156.045812899008, 6162.352952939877, 6245.0320200369015, 6248.094026366242, 6199.1011303359855, 6216.285127210429, 6222.471089957405, 6236.688165591793, 6238.15784869055, 5925.288965324189, 6039.335673076046, 6184.752609895734, 6199.783007594712, 6204.255489341308, 5115.87210319745, 6102.267727872683, 6169.56813023084, 6182.876342473035, 6222.844428889314, 5951.761929522944, 5968.612040217372, 6058.969705151622, 6063.484603808549, 6095.603785753912, 6023.216981792339, 6114.847128821815, 6189.04069869171, 6213.751449941951, 6225.439710426083, 6039.6678182698615, 6071.362506936708, 6103.23709536308, 6114.694265286629, 6124.081387791867], "value": 5746.368356221422, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [512.3953734593181, 530.9707966061866, 551.1347184919673, 580.9600814541968, 610.1975901689281, 38163.61836381636, 39469.407958880605, 39513.604863951354, 39576.40635390471, 39768.80115599422, 40637.59362406375, 41324.58675413245, 41328.58671413285, 41337.17325653485, 41441.378379324335, 9423.199028082605, 9571.234301255883, 9642.951785241099, 9692.321537492378, 9706.077922597377, 9501.80996380072, 9719.46542940138, 9721.222302215841, 9747.171490423323, 9776.951115244414, 9725.56234969428, 9727.756806079833, 9745.31782775206, 9767.413955162678, 9796.0, 9086.591578305373, 9350.064993500664, 9677.467739274338, 9722.9513852431, 9729.610815567368, 9585.281103917234, 9665.758356041086, 9697.854532182022, 9707.854382184272, 9723.367981081277, 39431.60568394308, 39609.0, 39860.0, 39972.80013599942, 39977.60022399768, 3097.674744151854, 3140.4975203967256, 3224.290656055664, 3268.754843386732, 3311.288073064303, 28012.101974010555, 28544.0, 28785.136445906657, 29128.0, 29534.26164345887, 2349.6945397098266, 2807.929801754952, 2831.051597714768, 2874.2814296425895, 2882.351470919047, 379838.1008094942, 446650.0, 452549.0, 453127.4687253118, 453233.9999999967, 51194.48805511972, 62048.68975655093, 63242.367576323646, 63449.36550634527, 63578.0463293051, 61760.0, 70131.0, 70920.64539677268, 71620.0, 71707.28292717111, 14380.928095359457, 17342.479725608253, 17514.47456576305, 17532.912335438243, 17552.736708949375, 13235.470581176838, 13331.0, 13338.0, 13357.732845343038, 13396.397162127727, 19630.803691962898, 19655.803441965396, 19752.814831109892, 19855.900720496305, 20398.0, 6812.931870681329, 8226.424150309422, 8246.185839115386, 8266.8346633067, 8269.379796515286, 834.2980320146953, 909.5543183840003, 1044.3629386074488, 1179.0685358566636, 1180.0913296761616, 24722.381940451272, 27439.588406173923, 29336.26659333534, 29554.704452955626, 30374.785008599407, 1087.9891201087887, 1115.286216821235, 1127.610974213895, 1246.8628450870397, 1249.5064449542563, 15579.298931548108, 15626.609334766721, 15697.293621787047, 15730.842691573167, 15912.76130858038, 591554.0, 647690.0, 652585.0, 655538.0, 665417.0, 61166.0825087624, 61192.0, 62018.37981620216, 62087.068693969624, 62167.067493987626, 1493.27576125579, 1495.7905893174748, 1509.0719207687193, 1510.7884896114554, 1596.993893846869, 29578.0, 29914.0, 29984.550231746976, 30679.386412271182, 30780.0, 53444.73277633587, 53578.92842143213, 53737.925241494166, 53786.0, 54086.18870717021, 468657.0, 472908.0, 475064.0, 476600.0, 477259.0, 26856.925722971642, 26879.462410752065, 27037.729622703522, 27472.862635686695, 27599.862000689867, 47381.76309118432, 47413.81465463391, 47563.762181188875, 47635.761821190674, 49003.26495102505, 37990.81004594959, 38603.61396386113, 38613.80693096629, 38651.420228697156, 38808.0, 31739.0, 32011.839940800146, 38083.42874856935, 38694.6130538691, 38741.2251754969, 33101.18639915538, 38182.809085954395, 38634.8409547714, 38846.41730374103, 39275.0, 31115.066548003597, 32766.67233327636, 32782.0, 33113.0, 33196.33607327792, 25448.618270725583, 25451.23646290614, 25587.744122558535, 25626.615600765632, 25874.48251034931], "value": 20007.30388918732, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.28517315207137, 396.9960300396995, 400.7355145603902, 400.74152171849175, 408.6832704653893, 14948.850511494882, 15017.774733379008, 19553.51116222095, 20236.89881550592, 20266.493337666547, 17957.91021044893, 18399.26402943884, 18638.25446982119, 18669.813301866976, 18692.626147477044, 4300.806463709133, 6008.639481631116, 6020.759169633209, 6041.758329666808, 6064.757409703607, 4835.637327200457, 5364.0, 5773.0, 5869.647821130746, 5954.642721436728, 5113.718745468997, 5894.381089985554, 5901.114832775075, 5925.348211696732, 5956.791512297071, 5489.972550137244, 5634.887302253973, 5675.829725108253, 5784.45047720468, 5939.109133629947, 5534.142207957773, 5570.749316280777, 5628.971855140698, 5645.350784659735, 5654.717264136777, 13033.478660853554, 13222.471101155943, 13391.06262561621, 13424.664383390396, 20454.795452045586, 2026.7770545240012, 2117.237794394017, 2422.321749910028, 2510.9454029307735, 2515.8742062896963, 8098.75703728889, 9737.123658870665, 10072.496375181285, 10105.89894101057, 13421.0605257632, 1711.981371084203, 1717.6994026045393, 1740.4604572582414, 1770.9822901770947, 1805.8103899090538, 100177.4991125047, 105275.0, 109457.90542094636, 110923.44538277338, 111971.20071998183, 11664.008559272557, 11674.124440666985, 11969.820452693217, 12053.939730301292, 12106.213096148722, 14851.70296594062, 15086.924565377323, 15098.849011509965, 15117.319720612599, 15139.0, 8901.554922253861, 8918.866217006751, 8925.687600933936, 8943.418677785923, 8992.730218093468, 8913.42062765931, 8920.330975176757, 8958.238549723346, 8981.416207946462, 9054.411463254868, 4523.344115103291, 4607.216773148572, 4612.607928326062, 4641.419822522184, 4659.510751371057, 1935.777385600674, 1981.7225588417632, 1999.100404817838, 2003.3589251439691, 2009.7487814023248, 899.7750562359411, 975.8585005174352, 991.9603215871284, 996.267743208741, 1007.2445665750687, 19661.410157695293, 20138.69791953122, 20235.887026213404, 20613.278535251193, 20666.07002684883, 851.4465597361765, 907.9682211122579, 961.6105477281712, 1010.4291075542295, 1033.6692258477215, 7597.734079307308, 10054.849177262347, 10381.740456488496, 10857.782844343068, 10869.0, 612027.0, 632796.0, 639873.4018989719, 652701.0, 653537.0, 101544.0, 120334.99162521007, 123540.0, 129551.0, 130885.0, 910.2444970674192, 916.1434059154578, 998.2513115163628, 1008.742770593494, 1008.9243306752023, 17781.933084015, 17853.107344632455, 17936.91031544834, 18047.007414592324, 18200.99894505815, 51880.740596296775, 52207.73896130495, 53353.60685976308, 53998.460015399345, 56013.15980260218, 61361.159165225115, 69469.0, 74409.62795185989, 79002.81495777683, 79030.6048469754, 7973.960130199312, 8015.666576972496, 8142.1857814220475, 8279.420440569102, 8378.204070613281, 11059.22585419013, 12283.631491055283, 12461.563845265735, 12715.55495557651, 12862.935685321514, 10692.251542392269, 10844.783104338027, 10860.022597966221, 11018.944905275423, 11208.439578021229, 7462.8507429850015, 10750.193735469715, 10767.946160269463, 10837.566497340018, 11159.553617855196, 10959.397233151934, 11188.496517656562, 11311.830322545333, 11368.602098926498, 11533.82699259495, 8119.39104567148, 8162.5510596915365, 8322.875156872773, 8347.290480308999, 8526.0, 9542.952285238529, 10740.946295268473, 11057.170712196457, 11061.557537698402, 11094.944525277322], "value": 9300.399886765668, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1545.8995165314254, 1565.1391734545996, 1791.4267434420983, 1807.737878007688, 1882.830545250928, 7410.962945185273, 7526.661300241489, 7850.96074519628, 8522.0, 8912.777180570482, 8560.65757369706, 8609.612567434464, 8663.783405414868, 8687.652493900252, 8688.956555217232, 2067.7311949446553, 2437.707475102986, 2456.7297597264287, 2466.1491785334033, 2478.1450399612104, 2158.6006588781047, 2346.9647955280684, 2495.438526331579, 2504.4490212153296, 2508.347829564317, 2476.987615061931, 2491.53906527293, 2496.9750302496923, 2502.3744063984004, 2520.3825062859623, 2473.5547601431736, 2477.0834791127218, 2483.379155211197, 2486.428121532049, 2515.5975043993058, 2305.273838740808, 2445.9755402445926, 2467.370820440794, 2468.8765561721843, 2483.180550418376, 8763.298936085097, 8825.338099642488, 8888.511131887808, 8892.244159246471, 8926.0, 5424.755885985141, 5532.031894418501, 5554.944450555524, 5564.10974244119, 5609.439056094398, 3533.5229743984746, 3597.1007248187952, 3627.2201476682376, 3655.250673611903, 3681.852725890961, 1032.1381646325328, 1049.522467277387, 1049.8215303398365, 1050.6637875879721, 1054.3041592548882, 11943.34311612861, 12126.211796233305, 12152.966997805197, 12163.209391389531, 12169.878301216962, 4829.492903245108, 4886.53577910098, 4908.9263661045115, 4930.482299358516, 4964.553190212898, 10236.027577380137, 10245.282830201962, 10253.58985640581, 10269.075783179549, 10272.897271027343, 5392.110301800159, 5453.263809385761, 5454.672719636835, 5495.477929596682, 5523.8066667666435, 4431.158079964796, 4835.661503694778, 4958.330625365602, 4962.851114466572, 4963.45402005779, 4055.9797201013807, 4103.056297051695, 4152.688548358885, 4163.375493675972, 4171.91656166875, 1354.1536539662711, 1365.8702423269774, 1366.7471517769245, 1369.6165073779357, 1372.6499742565582, 817.7260617693139, 884.5709830732089, 928.8653145293895, 948.6395169835419, 948.9525523723786, 5454.881749241396, 5627.465390787868, 5834.854128646818, 6110.0, 6180.289266734293, 465.51586350196095, 472.4377990191661, 473.3136951420454, 478.7749757613916, 482.53917508779034, 3431.227973705896, 3975.642192202658, 3980.104476492766, 4055.7972101395403, 4076.6534844537955, 10475.528601212964, 10842.783144337069, 10977.780444391226, 11051.447427628586, 11057.889421105847, 10599.205059620559, 10622.415767132727, 10624.468776561142, 10688.25182237236, 10865.619703310345, 8263.917360826435, 8328.916710832935, 8335.624896879655, 8346.8330633387, 8377.70678026266, 5526.917096243479, 5708.429157084258, 5760.539156867524, 5801.6519008859605, 5855.3266374367495, 9580.568874400808, 9611.663591774255, 9631.988641192575, 9645.614175432904, 9645.99999999972, 9419.481928493999, 9868.358556693793, 9898.158656514277, 9905.207583393167, 10095.495225238856, 6744.190697116377, 7255.637218139177, 7457.627118643937, 7519.022527071445, 7596.658150383357, 8189.262966332821, 8221.835563288581, 8242.876356854771, 8260.628271727666, 8275.172482751677, 7226.241244669444, 7359.963200183965, 7391.150017747813, 7474.364679002346, 7525.397968162643, 7294.927050729425, 7296.343329100407, 7341.412686985135, 7353.889691654523, 7396.445266604918, 7022.754203603054, 7050.823729406806, 7059.223485416497, 7079.115110611174, 7080.610566418896, 4826.782794774173, 4835.492273311393, 4846.854594362175, 4943.2585112233455, 4947.48051454592, 4890.828820991248, 4893.119238536955, 4918.336024636628, 4961.677490963076, 5118.513741194581], "value": 4553.701657888041, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [292.5567764836273, 328.99177520562, 331.491161067761, 335.426420820397, 339.4687314353039, 6838.658067096644, 6854.657267136642, 6862.485313601474, 6865.759698410557, 6898.241193468714, 6740.629265390414, 6773.72905083796, 6804.3535864092955, 6825.078614387056, 6840.863182736342, 4762.952370476294, 4871.391076115486, 4995.500449955011, 5019.397672279331, 5027.069992051483, 4955.702657840523, 5019.071471777715, 5019.121653710605, 5027.748612569375, 5037.974810125944, 4942.209246520558, 4954.207326827708, 4968.503149685021, 4973.875653108665, 4982.5017498250245, 4960.528749768766, 4965.106280869442, 4973.353464049686, 4974.97512512437, 5000.874978125576, 3540.7344449166417, 4289.592488713567, 4309.870703878889, 4334.176506463762, 4343.891402714926, 5773.700917293618, 5905.6751878646655, 6623.503237257177, 6625.503087268426, 6685.866282674319, 4662.344867571997, 4793.520647935214, 5094.6688465249645, 5944.316403613597, 6016.639001659871, 6997.116600286839, 7298.854022919512, 7312.37844783194, 7313.561186328838, 7322.267773222688, 4011.438398624195, 4860.586850117745, 4875.293082503017, 4878.243872199815, 4879.121758083544, 7544.622768861535, 7560.2439756024505, 7580.355669768076, 7604.581748003858, 7630.8473830523635, 4749.95250047502, 4916.385451818523, 4930.408350997904, 4932.260160975882, 5012.674176178537, 5016.423111342242, 5059.848204553869, 5076.187809950464, 5078.873028174326, 5084.5932325414615, 4841.782119804618, 5947.9702601486715, 5979.431953964367, 6000.489958353502, 6011.218541589653, 7375.856742204966, 7388.335049845432, 7389.445791565654, 7399.112106547249, 7408.9629551851895, 1741.1294352823588, 1763.9118044097743, 1765.6203916158088, 1768.32803534656, 1770.0884044716981, 1090.1442367741286, 1127.0, 1137.425600071975, 1141.6917432293235, 1142.857142857143, 6623.503237257225, 6629.204495460575, 6681.33186681328, 6683.7660681875905, 6729.6971636276485, 7394.704211831574, 7436.628168591549, 7458.888116678254, 7483.738069167553, 7485.962570187223, 5646.322441307069, 6766.424853887376, 6883.449324054164, 6912.654367281716, 6942.479314051366, 3984.103576695278, 4014.0366312085475, 4014.7791871447353, 4032.294348489003, 4036.2129384769687, 8050.798730031679, 8113.269805717396, 8116.472429292077, 8128.105908350077, 8132.2680958714, 7606.581638009962, 7849.4897831640765, 7868.606569671493, 7885.960570197112, 7914.445988780845, 8103.635336409758, 8164.959175204086, 8167.264946154637, 8186.918130818734, 8187.0, 7683.731069412544, 7689.577073260801, 7704.961475192588, 7724.575148366894, 7727.0727512696585, 7563.470557060953, 7576.090869095744, 7579.54522728638, 7583.165851756192, 7589.696412143673, 6780.86438271222, 7702.691892324468, 7933.801654958672, 7956.363490920829, 8161.877571836546, 4602.102589994919, 4673.626109911131, 4676.46220684612, 4712.929306060343, 4742.383490146259, 6042.577019608585, 7128.429725622042, 7531.661075251736, 7560.168381477923, 7587.279208475186, 7312.122545294599, 7319.890201646873, 7337.339639432476, 7370.520916140434, 7398.482106252511, 6581.769638062645, 7123.074000379917, 7295.927040729524, 7299.854002919806, 7306.561606303639, 5697.772089116389, 7075.964620176866, 7114.288571142844, 7129.394001509722, 7135.571865687868, 7258.274172582699, 7314.780556583311, 7334.706611735684, 7354.70581176747, 7374.078240219973, 6898.2756810536175, 7118.25258347866, 7128.825708159061, 7148.749793757402, 7150.678219480032], "value": 5319.854313775268, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [690.2028157478113, 731.8170457385656, 739.7706710919615, 751.2074761127009, 781.7967328494592, 14992.0, 15099.849001509981, 15204.011739236941, 15248.847511524882, 15268.618284542878, 13629.93185034076, 14002.859971400283, 14158.362873670683, 14162.0, 14187.787183192235, 758.457702742539, 926.9443833369986, 927.8329900617886, 931.6971984105159, 942.6794889737488, 932.7108596335124, 944.8204841080208, 945.6028468043404, 946.186279799372, 946.3375637054055, 969.6315400147934, 979.6081567373036, 989.65362123257, 991.8462638291077, 993.9503024848765, 938.3572253006712, 982.7887004294112, 983.4640121133964, 985.9014098590155, 987.9357841740335, 694.4444444444475, 721.9169795473535, 732.3665029749282, 737.8819388897804, 739.6856336057208, 14375.640608984755, 14381.28093595316, 14388.280585970764, 14388.496402625962, 14777.482788102365, 12619.369031548385, 12696.809547856697, 12745.107842451032, 12782.296973666445, 12914.612561623166, 9924.40453572788, 9940.453275069869, 10178.18574514037, 10273.897261027369, 10344.896551034468, 3498.0904964709102, 3510.069831494656, 3535.8232088395475, 3593.2094939113354, 3766.3785475396526, 60900.0, 82375.5881220596, 83316.0, 84334.57832710858, 84837.0, 9381.108794664633, 9448.527573621428, 9536.0, 9646.90353096474, 9686.321957463051, 20822.791772082084, 20870.0, 21104.05031773574, 21109.68335474969, 21127.577448450946, 7354.742584009534, 7521.473496855277, 7530.585817780075, 7550.7734767957045, 7643.464957453036, 4038.45480860086, 4106.979465102655, 4116.217918595457, 4120.278951183531, 4127.525334586501, 3622.239329740784, 3675.6140605236064, 3684.8341824617955, 3694.7598406103516, 3709.6661300482547, 659.6371995402509, 663.6548994522822, 674.6660403100399, 684.6679360510149, 686.584616307137, 515.2168703570552, 573.7590212110923, 585.6749504025307, 595.4075694683818, 629.7292164369366, 8064.959675201704, 8149.7147599833725, 8182.631781569844, 8199.795005124919, 8413.074561798197, 581.1515187825821, 581.5696384675394, 593.5281451246183, 595.7944509144338, 596.627108057464, 3764.981175094107, 3827.2154208387215, 3859.9035024124623, 3873.496445462128, 3994.6604538614542, 72567.27432725711, 73129.0, 93205.53397232971, 93235.0, 96255.5936101603, 76569.0, 77185.0, 77667.0, 78076.0, 79235.20764792278, 53550.73224633852, 69977.65011175013, 70833.64583177154, 70930.64534677396, 71137.0, 7537.886931696139, 7737.226277372218, 7872.094709108297, 7966.203379661987, 7979.9202007979175, 44904.53025083652, 45387.546124538334, 49279.0, 50361.748191260274, 51060.23409648932, 62352.376476234655, 68193.3180668187, 69906.9027929163, 70803.0, 72688.27311726815, 10943.233973621771, 11089.33463992163, 11182.384968826793, 11222.326660400402, 11530.596429125277, 11804.527818887149, 11918.940405297919, 11986.64040078799, 12032.338221397908, 12278.386080696107, 9341.813163736551, 11452.713682158013, 11539.711507212387, 11574.88425115738, 11844.644660660195, 11241.325520468798, 11557.653270401901, 11602.593909213138, 11625.941870290595, 11629.59296424621, 10892.782144357227, 11153.163512736739, 11203.21577489568, 11247.100231981258, 11625.709357266136, 9271.443713377219, 9506.049395060438, 9736.367136136128, 9771.609135634495, 9777.853332200166, 9553.187979021583, 9612.1829644481, 9712.417254964725, 9755.853662194932, 9785.412875227508], "value": 6917.056341048074, "unit": "score"}], "name": "dromaeo_css", "value": 7508.462222588403, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 120.0, 110.0, 118.0, 119.0, 118.0, 106.0, 117.0, 115.0, 117.0], "value": 115.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [190.0, 111.0, 175.0, 162.0, 141.0, 170.0, 150.0, 154.0, 157.0, 120.0], "value": 153.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 194.0, 197.0, 198.0, 194.0, 199.0, 234.0, 251.0, 221.0, 195.0], "value": 207.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 80.0, 145.0, 55.0, 57.0, 55.0, 64.0, 123.0, 56.0], "value": 76.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 97.0, 127.0, 75.0, 85.0, 130.0, 164.0, 111.0, 79.0, 121.0], "value": 105.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 90.0, 88.0, 90.0, 86.0, 109.0, 85.0, 134.0, 118.0], "value": 97.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 134.0, 95.0, 96.0, 96.0, 126.0, 107.0, 150.0, 100.0, 139.0], "value": 119.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 103.0, 80.0, 151.0, 126.0, 135.0, 100.0, 146.0, 118.0, 147.0], "value": 119.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 64.0, 66.0, 65.0, 67.0, 66.0, 66.0, 86.0, 73.0, 93.0], "value": 70.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 67.0, 66.0, 73.0, 70.0, 84.0, 75.0, 68.0, 68.0, 71.0], "value": 70.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 82.0, 83.0, 80.0, 82.0, 81.0, 82.0, 88.0, 88.0], "value": 83.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 126.0, 155.0, 140.0, 142.0, 132.0, 127.0, 129.0, 150.0, 166.0], "value": 141.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 169.0, 171.0, 178.0, 177.0, 165.0, 170.0, 169.0, 165.0, 165.0], "value": 169.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 74.0, 71.0, 83.0, 100.0, 92.0, 93.0, 96.0, 73.0, 69.0], "value": 82.2, "unit": "score"}], "name": "kraken", "value": 1612.9999999999998, "alertThreshold": 2.0}]} 08:11:30 INFO - Return code: 0 08:11:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 08:11:30 ERROR - # TBPL SUCCESS # 08:11:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:11:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:11:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:11:30 INFO - 2016-05-30 08:11:30.175 screenresolution[1703:13607] starting screenresolution argv=screenresolution get 08:11:30 INFO - 2016-05-30 08:11:30.177 screenresolution[1703:13607] Display 0: 1600x1200x32@60 08:11:30 INFO - 2016-05-30 08:11:30.184 screenresolution[1704:13610] starting screenresolution argv=screenresolution list 08:11:30 INFO - Available Modes on Display 0 08:11:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:11:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:11:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:11:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:11:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:11:30 INFO - Intel Iris: 08:11:30 INFO - Chipset Model: Intel Iris 08:11:30 INFO - Type: GPU 08:11:30 INFO - Bus: Built-In 08:11:30 INFO - VRAM (Dynamic, Max): 1536 MB 08:11:30 INFO - Vendor: Intel (0x8086) 08:11:30 INFO - Device ID: 0x0a2e 08:11:30 INFO - Revision ID: 0x0009 08:11:30 INFO - Displays: 08:11:30 INFO - Display: 08:11:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:11:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:11:30 INFO - Display Serial Number: 1600x1200 60 08:11:30 INFO - Main Display: Yes 08:11:30 INFO - Mirror: Off 08:11:30 INFO - Online: Yes 08:11:30 INFO - Rotation: Supported 08:11:30 INFO - Return code: 0 08:11:30 INFO - Running post-action listener: _resource_record_post_action 08:11:30 INFO - Running post-run listener: _resource_record_post_run 08:11:31 INFO - Total resource usage - Wall time: 1205s; CPU: 54.0%; Read bytes: 31436288; Write bytes: 655508992; Read time: 194; Write time: 1842 08:11:31 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 354609664; Write bytes: 356853760; Read time: 27629; Write time: 732 08:11:31 INFO - run-tests - Wall time: 1174s; CPU: 53.0%; Read bytes: 29654528; Write bytes: 297807360; Read time: 179; Write time: 1107 08:11:31 INFO - Running post-run listener: _upload_blobber_files 08:11:31 INFO - Blob upload gear active. 08:11:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 08:11:31 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 08:11:31 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'] 08:11:31 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 08:11:31 INFO - (blobuploader) - INFO - Open directory for files ... 08:11:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 08:11:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:11:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:11:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 08:11:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:11:33 INFO - (blobuploader) - INFO - Done attempting. 08:11:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 08:11:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 08:11:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 08:11:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 08:11:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 08:11:34 INFO - (blobuploader) - INFO - Done attempting. 08:11:34 INFO - (blobuploader) - INFO - Iteration through files over. 08:11:34 INFO - Return code: 0 08:11:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 08:11:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 08:11:34 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d1b3f2e60fa356fd3b71eccc818d3decb49d3aefdc82fbd507d733dadfe161d57f4627278d65ac5c6edb7f34e8030d86492c5312b3c0be34374afbbc5f191f09", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9eb960cba26cec7ed7394e6210eb98d248a5ff144e3d653f7b91b0d5d57acb82bc887ec858df0e0264b1de83c179a123a0214901daddc8d2da633ef821b79987"} 08:11:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 08:11:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 08:11:34 INFO - Contents: 08:11:34 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d1b3f2e60fa356fd3b71eccc818d3decb49d3aefdc82fbd507d733dadfe161d57f4627278d65ac5c6edb7f34e8030d86492c5312b3c0be34374afbbc5f191f09", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9eb960cba26cec7ed7394e6210eb98d248a5ff144e3d653f7b91b0d5d57acb82bc887ec858df0e0264b1de83c179a123a0214901daddc8d2da633ef821b79987"} 08:11:34 INFO - Running post-run listener: copy_logs_to_upload_dir 08:11:34 INFO - Copying logs to upload dir... 08:11:34 INFO - mkdir: /builds/slave/test/build/upload/logs 08:11:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1293.193366 ========= master_lag: 3.09 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 36 secs) (at 2016-05-30 08:11:37.267017) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 08:11:37.271934) ========= 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.ucXvfOF5GD/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.KvjkzzEjiq/Listeners TMPDIR=/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/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/d1b3f2e60fa356fd3b71eccc818d3decb49d3aefdc82fbd507d733dadfe161d57f4627278d65ac5c6edb7f34e8030d86492c5312b3c0be34374afbbc5f191f09", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9eb960cba26cec7ed7394e6210eb98d248a5ff144e3d653f7b91b0d5d57acb82bc887ec858df0e0264b1de83c179a123a0214901daddc8d2da633ef821b79987"} build_url:https://queue.taskcluster.net/v1/task/cn0lKUxATnuJLEicI6z55Q/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011272 build_url: 'https://queue.taskcluster.net/v1/task/cn0lKUxATnuJLEicI6z55Q/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/d1b3f2e60fa356fd3b71eccc818d3decb49d3aefdc82fbd507d733dadfe161d57f4627278d65ac5c6edb7f34e8030d86492c5312b3c0be34374afbbc5f191f09", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9eb960cba26cec7ed7394e6210eb98d248a5ff144e3d653f7b91b0d5d57acb82bc887ec858df0e0264b1de83c179a123a0214901daddc8d2da633ef821b79987"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 08:11:37.314788) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 08:11:37.315387) ========= 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.ucXvfOF5GD/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.KvjkzzEjiq/Listeners TMPDIR=/var/folders/hh/jpj4h4js6sldqzcr1hnmqmnh00000w/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.005193 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 08:11:37.365406) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 08:11:37.365736) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 08:11:37.366141) ========= ========= Total master_lag: 3.24 =========