builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0391 starttime: 1464599291.58 results: success (0) buildid: 20160530004020 builduid: 0681b20ae89a48598ada7b07ae3a610d revision: ccf0f53adb66c86d81a39b53e32092489fb5f0b5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 02:08:11.578397) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 02:08:11.578768) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 02:08:11.579038) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 02:08:11.648602) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 02:08:11.648917) ========= 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.gncsWFv3CF/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.YuMZICUosS/Listeners TMPDIR=/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/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 02:08:11-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.9M=0.001s 2016-05-30 02:08:12 (18.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.562998 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 02:08:12.232097) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 02:08:12.232408) ========= 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.gncsWFv3CF/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.YuMZICUosS/Listeners TMPDIR=/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/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.025281 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 02:08:12.280128) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 02:08:12.280460) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev ccf0f53adb66c86d81a39b53e32092489fb5f0b5 --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 ccf0f53adb66c86d81a39b53e32092489fb5f0b5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gncsWFv3CF/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.YuMZICUosS/Listeners TMPDIR=/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/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 02:08:12,363 truncating revision to first 12 chars 2016-05-30 02:08:12,363 Setting DEBUG logging. 2016-05-30 02:08:12,363 attempt 1/10 2016-05-30 02:08:12,363 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/ccf0f53adb66?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-30 02:08:13,046 unpacking tar archive at: mozilla-aurora-ccf0f53adb66/testing/mozharness/ program finished with exit code 0 elapsedTime=0.951401 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 02:08:13.252113) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 02:08:13.252413) ========= script_repo_revision: ccf0f53adb66c86d81a39b53e32092489fb5f0b5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 02:08:13.252795) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 02:08:13.253058) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 02:08:13.269146) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 15 secs) (at 2016-05-30 02:08:13.269413) ========= /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.gncsWFv3CF/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.YuMZICUosS/Listeners TMPDIR=/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 02:08:13 INFO - MultiFileLogger online at 20160530 02:08:13 in /builds/slave/test 02:08:13 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 02:08:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:08:13 INFO - {'append_to_log': False, 02:08:13 INFO - 'base_work_dir': '/builds/slave/test', 02:08:13 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 02:08:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:08:13 INFO - 'branch': 'Mozilla-Aurora', 02:08:13 INFO - 'buildbot_json_path': 'buildprops.json', 02:08:13 INFO - 'config_files': ('talos/mac_config.py',), 02:08:13 INFO - 'default_actions': ('clobber', 02:08:13 INFO - 'read-buildbot-config', 02:08:13 INFO - 'download-and-extract', 02:08:13 INFO - 'populate-webroot', 02:08:13 INFO - 'create-virtualenv', 02:08:13 INFO - 'install', 02:08:13 INFO - 'run-tests'), 02:08:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:08:13 INFO - 'download_minidump_stackwalk': True, 02:08:13 INFO - 'download_symbols': 'ondemand', 02:08:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:08:13 INFO - 'tooltool.py': '/tools/tooltool.py', 02:08:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:08:13 INFO - '/tools/misc-python/virtualenv.py')}, 02:08:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:08:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:08:13 INFO - 'installer_path': 'installer.exe', 02:08:13 INFO - 'log_level': 'info', 02:08:13 INFO - 'log_name': 'talos', 02:08:13 INFO - 'log_to_console': True, 02:08:13 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:08:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:08:13 INFO - 'opt_config_files': (), 02:08:13 INFO - 'pip_index': False, 02:08:13 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:08:13 INFO - 'cmd': ('bash', 02:08:13 INFO - '-c', 02:08:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:08:13 INFO - 'enabled': True, 02:08:13 INFO - 'halt_on_failure': False, 02:08:13 INFO - 'name': 'check_screen_resolution'},), 02:08:13 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:08:13 INFO - 'cmd': ('bash', 02:08:13 INFO - '-c', 02:08:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:08:13 INFO - 'enabled': True, 02:08:13 INFO - 'halt_on_failure': False, 02:08:13 INFO - 'name': 'check_screen_resolution'},), 02:08:13 INFO - 'run_cmd_checks_enabled': True, 02:08:13 INFO - 'sps_profile': False, 02:08:13 INFO - 'sps_profile_interval': 0, 02:08:13 INFO - 'suite': 'dromaeojs-e10s', 02:08:13 INFO - 'system_bits': '32', 02:08:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:08:13 INFO - 'title': 't-yosemite-r7-0391', 02:08:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:08:13 INFO - 'use_talos_json': True, 02:08:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:08:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:08:13 INFO - 'work_dir': 'build'} 02:08:13 INFO - ##### 02:08:13 INFO - ##### Running clobber step. 02:08:13 INFO - ##### 02:08:13 INFO - Running pre-action listener: _resource_record_pre_action 02:08:13 INFO - Running main action method: clobber 02:08:13 INFO - rmtree: /builds/slave/test/build 02:08:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:08:15 INFO - Running post-action listener: _resource_record_post_action 02:08:15 INFO - ##### 02:08:15 INFO - ##### Running read-buildbot-config step. 02:08:15 INFO - ##### 02:08:15 INFO - Running pre-action listener: _resource_record_pre_action 02:08:15 INFO - Running main action method: read_buildbot_config 02:08:15 INFO - Using buildbot properties: 02:08:15 INFO - { 02:08:15 INFO - "project": "", 02:08:15 INFO - "product": "firefox", 02:08:15 INFO - "script_repo_revision": "production", 02:08:15 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 02:08:15 INFO - "repository": "", 02:08:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 02:08:15 INFO - "buildid": "20160530004020", 02:08:15 INFO - "stage_platform": "macosx64", 02:08:15 INFO - "basedir": "/builds/slave/test", 02:08:15 INFO - "nightly_build": "True", 02:08:15 INFO - "buildnumber": 13, 02:08:15 INFO - "slavename": "t-yosemite-r7-0391", 02:08:15 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 02:08:15 INFO - "platform": "yosemite_r7", 02:08:15 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 02:08:15 INFO - "branch": "mozilla-aurora", 02:08:15 INFO - "repo_path": "releases/mozilla-aurora", 02:08:15 INFO - "revision": "ccf0f53adb66c86d81a39b53e32092489fb5f0b5", 02:08:15 INFO - "pgo_build": "False", 02:08:15 INFO - "builduid": "0681b20ae89a48598ada7b07ae3a610d", 02:08:15 INFO - "slavebuilddir": "test" 02:08:15 INFO - } 02:08:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/ckszqfuhRsym8v0GKEg5Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 02:08:15 INFO - Running post-action listener: _resource_record_post_action 02:08:15 INFO - ##### 02:08:15 INFO - ##### Running download-and-extract step. 02:08:15 INFO - ##### 02:08:15 INFO - Running pre-action listener: _resource_record_pre_action 02:08:15 INFO - Running main action method: download_and_extract 02:08:15 INFO - mkdir: /builds/slave/test/build/tests 02:08:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:08:15 INFO - https://queue.taskcluster.net/v1/task/ckszqfuhRsym8v0GKEg5Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 02:08:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ckszqfuhRsym8v0GKEg5Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:08:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ckszqfuhRsym8v0GKEg5Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 02:08:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ckszqfuhRsym8v0GKEg5Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:08:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ckszqfuhRsym8v0GKEg5Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 02:08:45 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ckszqfuhRsym8v0GKEg5Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json: timed out 02:08:45 INFO - retry: attempt #1 caught exception: timed out 02:08:45 INFO - retry: Failed, sleeping 30 seconds before retrying 02:09:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ckszqfuhRsym8v0GKEg5Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #2 02:09:23 INFO - Downloaded 1199 bytes. 02:09:23 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 02:09:23 INFO - Using the following test package requirements: 02:09:23 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:09:23 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:09:23 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 02:09:23 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:09:23 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 02:09:23 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:09:23 INFO - u'jsshell-mac.zip'], 02:09:23 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:09:23 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 02:09:23 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 02:09:23 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:09:23 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 02:09:23 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:09:23 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 02:09:23 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:09:23 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 02:09:23 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 02:09:23 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 02:09:23 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 02:09:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:09:23 INFO - https://queue.taskcluster.net/v1/task/ckszqfuhRsym8v0GKEg5Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:09:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ckszqfuhRsym8v0GKEg5Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:09:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ckszqfuhRsym8v0GKEg5Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:09:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ckszqfuhRsym8v0GKEg5Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:09:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ckszqfuhRsym8v0GKEg5Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:09:25 INFO - Downloaded 18425657 bytes. 02:09:25 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:09:25 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:09:28 INFO - Return code: 0 02:09:28 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 02:09: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')]} 02:09:28 INFO - https://queue.taskcluster.net/v1/task/ckszqfuhRsym8v0GKEg5Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 02:09:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ckszqfuhRsym8v0GKEg5Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:09:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ckszqfuhRsym8v0GKEg5Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:09:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ckszqfuhRsym8v0GKEg5Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 02:09:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ckszqfuhRsym8v0GKEg5Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 02:09:32 INFO - Downloaded 18425657 bytes. 02:09:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:09:32 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 02:09:36 INFO - Return code: 0 02:09:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:09:36 INFO - https://queue.taskcluster.net/v1/task/ckszqfuhRsym8v0GKEg5Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 02:09:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ckszqfuhRsym8v0GKEg5Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:09:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ckszqfuhRsym8v0GKEg5Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:09:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ckszqfuhRsym8v0GKEg5Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 02:09:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ckszqfuhRsym8v0GKEg5Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 02:09:37 INFO - Downloaded 10840145 bytes. 02:09:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 02:09:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 02:09:37 INFO - Return code: 0 02:09:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:09:37 INFO - https://queue.taskcluster.net/v1/task/ckszqfuhRsym8v0GKEg5Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 02:09:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ckszqfuhRsym8v0GKEg5Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:09:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ckszqfuhRsym8v0GKEg5Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:09:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ckszqfuhRsym8v0GKEg5Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 02:09:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ckszqfuhRsym8v0GKEg5Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 02:09:44 INFO - Downloaded 114233169 bytes. 02:09:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ckszqfuhRsym8v0GKEg5Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:09:44 INFO - mkdir: /builds/slave/test/properties 02:09:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:09:44 INFO - Writing to file /builds/slave/test/properties/build_url 02:09:44 INFO - Contents: 02:09:44 INFO - build_url:https://queue.taskcluster.net/v1/task/ckszqfuhRsym8v0GKEg5Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 02:09:45 INFO - Running post-action listener: _resource_record_post_action 02:09:45 INFO - Running post-action listener: set_extra_try_arguments 02:09:45 INFO - ##### 02:09:45 INFO - ##### Running populate-webroot step. 02:09:45 INFO - ##### 02:09:45 INFO - Running pre-action listener: _resource_record_pre_action 02:09:45 INFO - Running main action method: populate_webroot 02:09:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:09:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:09:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:09:45 INFO - '--tppagecycles', 02:09:45 INFO - '7'], 02:09:45 INFO - 'tests': ['tsvgm']}}, 02:09:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:09:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:09:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:09:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:09:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:09:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:09:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:09:45 INFO - 'talos_options': ['--disable-e10s'], 02:09:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:09:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:09:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:09:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:09:45 INFO - 'talos_options': ['--disable-e10s'], 02:09:45 INFO - 'tests': ['damp', 'tps']}, 02:09:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:09:45 INFO - 'tests': ['damp', 'tps']}, 02:09:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:09:45 INFO - 'tests': ['dromaeo_dom']}, 02:09:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:09:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:09:45 INFO - 'tests': ['a11yr', 02:09:45 INFO - 'ts_paint', 02:09:45 INFO - 'tpaint', 02:09:45 INFO - 'sessionrestore', 02:09:45 INFO - 'sessionrestore_no_auto_restore', 02:09:45 INFO - 'tabpaint']}, 02:09:45 INFO - 'other-e10s': {'tests': ['a11yr', 02:09:45 INFO - 'ts_paint', 02:09:45 INFO - 'tpaint', 02:09:45 INFO - 'sessionrestore', 02:09:45 INFO - 'sessionrestore_no_auto_restore', 02:09:45 INFO - 'tabpaint']}, 02:09:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:09:45 INFO - 'ts_paint', 02:09:45 INFO - 'tpaint', 02:09:45 INFO - 'sessionrestore', 02:09:45 INFO - 'sessionrestore_no_auto_restore', 02:09:45 INFO - 'tabpaint']}, 02:09:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:09:45 INFO - 'ts_paint', 02:09:45 INFO - 'tpaint', 02:09:45 INFO - 'sessionrestore', 02:09:45 INFO - 'sessionrestore_no_auto_restore', 02:09:45 INFO - 'tabpaint']}, 02:09:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:09:45 INFO - 'tests': ['a11yr', 02:09:45 INFO - 'ts_paint', 02:09:45 INFO - 'tpaint', 02:09:45 INFO - 'sessionrestore', 02:09:45 INFO - 'sessionrestore_no_auto_restore', 02:09:45 INFO - 'tabpaint']}, 02:09:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:09:45 INFO - 'tests': ['a11yr', 02:09:45 INFO - 'ts_paint', 02:09:45 INFO - 'tpaint', 02:09:45 INFO - 'sessionrestore', 02:09:45 INFO - 'sessionrestore_no_auto_restore', 02:09:45 INFO - 'tabpaint']}, 02:09:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:09:45 INFO - 'tests': ['tsvgx', 02:09:45 INFO - 'tsvgr_opacity', 02:09:45 INFO - 'tart', 02:09:45 INFO - 'tscrollx', 02:09:45 INFO - 'cart']}, 02:09:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:09:45 INFO - 'tsvgr_opacity', 02:09:45 INFO - 'tart', 02:09:45 INFO - 'tscrollx', 02:09:45 INFO - 'cart']}, 02:09:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:09:45 INFO - 'talos_options': ['--disable-e10s'], 02:09:45 INFO - 'tests': ['tp5o']}, 02:09:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:09:45 INFO - 'tests': ['tp5o']}, 02:09:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:09:45 INFO - 'talos_options': ['--disable-e10s', 02:09:45 INFO - '--xperf_path', 02:09:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:09:45 INFO - 'tests': ['tp5n']}, 02:09:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:09:45 INFO - 'talos_options': ['--xperf_path', 02:09:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:09:45 INFO - 'tests': ['tp5n']}}, 02:09:45 INFO - 'talos.zip': {'path': '', 02:09:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:09:45 INFO - Running post-action listener: _resource_record_post_action 02:09:45 INFO - ##### 02:09:45 INFO - ##### Running create-virtualenv step. 02:09:45 INFO - ##### 02:09:45 INFO - Running pre-action listener: _resource_record_pre_action 02:09:45 INFO - Running main action method: create_virtualenv 02:09:45 INFO - Creating virtualenv /builds/slave/test/build/venv 02:09:45 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:09:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:09:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:09:45 INFO - Using real prefix '/tools/python27' 02:09:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:09:46 INFO - Installing distribute.............................................................................................................................................................................................done. 02:09:49 INFO - Installing pip.................done. 02:09:49 INFO - Return code: 0 02:09:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:09:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:09:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:09:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:09:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:09:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:49 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 0x102d19a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d29420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe24ad8eb30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bb8e00>, '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 0x102a21db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102bb3b20>, '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.gncsWFv3CF/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.YuMZICUosS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:09:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:09:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:09:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gncsWFv3CF/Render', 02:09:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:49 INFO - 'HOME': '/Users/cltbld', 02:09:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:49 INFO - 'LOGNAME': 'cltbld', 02:09:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:49 INFO - 'MOZ_NO_REMOTE': '1', 02:09:49 INFO - 'NO_EM_RESTART': '1', 02:09:49 INFO - 'PAGER': '/bin/cat', 02:09:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:49 INFO - 'PWD': '/builds/slave/test', 02:09:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:49 INFO - 'SHELL': '/bin/bash', 02:09:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YuMZICUosS/Listeners', 02:09:49 INFO - 'TMPDIR': '/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/', 02:09:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:49 INFO - 'USER': 'cltbld', 02:09:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:49 INFO - 'XPC_FLAGS': '0x0', 02:09:49 INFO - 'XPC_SERVICE_NAME': '0', 02:09:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:09:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:09:49 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 02:09:49 INFO - Cleaning up... 02:09:49 INFO - Return code: 0 02:09:49 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 02:09:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:09:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:09:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:09:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:09:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:49 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 0x102d19a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d29420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe24ad8eb30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bb8e00>, '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 0x102a21db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102bb3b20>, '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.gncsWFv3CF/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.YuMZICUosS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:09:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 02:09:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 02:09:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gncsWFv3CF/Render', 02:09:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:49 INFO - 'HOME': '/Users/cltbld', 02:09:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:49 INFO - 'LOGNAME': 'cltbld', 02:09:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:49 INFO - 'MOZ_NO_REMOTE': '1', 02:09:49 INFO - 'NO_EM_RESTART': '1', 02:09:49 INFO - 'PAGER': '/bin/cat', 02:09:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:49 INFO - 'PWD': '/builds/slave/test', 02:09:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:49 INFO - 'SHELL': '/bin/bash', 02:09:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YuMZICUosS/Listeners', 02:09:49 INFO - 'TMPDIR': '/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/', 02:09:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:49 INFO - 'USER': 'cltbld', 02:09:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:49 INFO - 'XPC_FLAGS': '0x0', 02:09:49 INFO - 'XPC_SERVICE_NAME': '0', 02:09:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:09:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:09:49 INFO - Downloading/unpacking psutil>=0.7.1 02:09:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:09:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:09:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:09:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:09:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:09:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:09:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:09:52 INFO - Installing collected packages: psutil 02:09:52 INFO - Running setup.py install for psutil 02:09:52 INFO - building 'psutil._psutil_osx' extension 02:09:52 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 02:09:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 02:09:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 02:09:53 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 02:09:53 INFO - building 'psutil._psutil_posix' extension 02:09:53 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 02:09:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:09:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:09:53 INFO - ^ 02:09:53 INFO - 1 warning generated. 02:09:53 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 02:09:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:09:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:09:53 INFO - Successfully installed psutil 02:09:53 INFO - Cleaning up... 02:09:54 INFO - Return code: 0 02:09:54 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 02:09: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')]} 02:09:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:09:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09: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')]} 02:09:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:09:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09: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', '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 0x102d19a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d29420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe24ad8eb30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bb8e00>, '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 0x102a21db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102bb3b20>, '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.gncsWFv3CF/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.YuMZICUosS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:09: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 02:09: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 mozsystemmonitor==0.0.0 02:09:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gncsWFv3CF/Render', 02:09:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:54 INFO - 'HOME': '/Users/cltbld', 02:09:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:54 INFO - 'LOGNAME': 'cltbld', 02:09:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:54 INFO - 'MOZ_NO_REMOTE': '1', 02:09:54 INFO - 'NO_EM_RESTART': '1', 02:09:54 INFO - 'PAGER': '/bin/cat', 02:09:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:54 INFO - 'PWD': '/builds/slave/test', 02:09:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:54 INFO - 'SHELL': '/bin/bash', 02:09:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YuMZICUosS/Listeners', 02:09:54 INFO - 'TMPDIR': '/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/', 02:09:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:54 INFO - 'USER': 'cltbld', 02:09:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:54 INFO - 'XPC_FLAGS': '0x0', 02:09:54 INFO - 'XPC_SERVICE_NAME': '0', 02:09:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:09:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:09:54 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 02:09: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 02:09: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 02:09: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 02:09: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 02:09: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 02:09: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 02:09:56 INFO - Downloading mozsystemmonitor-0.0.tar.gz 02:09:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:09:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 02:09:56 INFO - Installing collected packages: mozsystemmonitor 02:09:56 INFO - Running setup.py install for mozsystemmonitor 02:09:56 INFO - Successfully installed mozsystemmonitor 02:09:56 INFO - Cleaning up... 02:09:57 INFO - Return code: 0 02:09:57 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:09:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:09:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:09:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:09:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:09:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:09:57 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 0x102d19a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d29420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe24ad8eb30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bb8e00>, '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 0x102a21db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102bb3b20>, '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.gncsWFv3CF/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.YuMZICUosS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:09:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:09:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:09:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gncsWFv3CF/Render', 02:09:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:09:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:09:57 INFO - 'HOME': '/Users/cltbld', 02:09:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:09:57 INFO - 'LOGNAME': 'cltbld', 02:09:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:09:57 INFO - 'MOZ_NO_REMOTE': '1', 02:09:57 INFO - 'NO_EM_RESTART': '1', 02:09:57 INFO - 'PAGER': '/bin/cat', 02:09:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:09:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:09:57 INFO - 'PWD': '/builds/slave/test', 02:09:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:09:57 INFO - 'SHELL': '/bin/bash', 02:09:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YuMZICUosS/Listeners', 02:09:57 INFO - 'TMPDIR': '/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/', 02:09:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:09:57 INFO - 'USER': 'cltbld', 02:09:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:09:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:09:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:09:57 INFO - 'XPC_FLAGS': '0x0', 02:09:57 INFO - 'XPC_SERVICE_NAME': '0', 02:09:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:09:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:09:57 INFO - Downloading/unpacking blobuploader==1.2.4 02:09:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:09:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:09:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:09:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:09:59 INFO - Downloading blobuploader-1.2.4.tar.gz 02:09:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:09:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:09:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:09:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:09:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:09:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:09:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:10:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:10:00 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:10:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:10:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:10:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:10:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:10:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:10:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:10:00 INFO - Downloading docopt-0.6.1.tar.gz 02:10:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:10:01 INFO - Installing collected packages: blobuploader, requests, docopt 02:10:01 INFO - Running setup.py install for blobuploader 02:10:01 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:10:01 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:10:01 INFO - Running setup.py install for requests 02:10:01 INFO - Running setup.py install for docopt 02:10:01 INFO - Successfully installed blobuploader requests docopt 02:10:01 INFO - Cleaning up... 02:10:01 INFO - Return code: 0 02:10:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:10: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')]} 02:10:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:10:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:10: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')]} 02:10:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:10:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:10:01 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 0x102d19a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d29420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe24ad8eb30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bb8e00>, '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 0x102a21db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102bb3b20>, '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.gncsWFv3CF/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.YuMZICUosS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:10:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:10:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:10:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gncsWFv3CF/Render', 02:10:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:10:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:10:01 INFO - 'HOME': '/Users/cltbld', 02:10:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:10:01 INFO - 'LOGNAME': 'cltbld', 02:10:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:10:01 INFO - 'MOZ_NO_REMOTE': '1', 02:10:01 INFO - 'NO_EM_RESTART': '1', 02:10:01 INFO - 'PAGER': '/bin/cat', 02:10:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:10:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:10:01 INFO - 'PWD': '/builds/slave/test', 02:10:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:10:01 INFO - 'SHELL': '/bin/bash', 02:10:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YuMZICUosS/Listeners', 02:10:01 INFO - 'TMPDIR': '/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/', 02:10:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:10:01 INFO - 'USER': 'cltbld', 02:10:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:10:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:10:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:10:01 INFO - 'XPC_FLAGS': '0x0', 02:10:01 INFO - 'XPC_SERVICE_NAME': '0', 02:10:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:10:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:10:02 INFO - Running setup.py (path:/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/pip-etzHzF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:10:02 INFO - Running setup.py (path:/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/pip-nwCTPR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:10:02 INFO - Running setup.py (path:/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/pip-VEQWcY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:10:02 INFO - Running setup.py (path:/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/pip-spKQOb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:10:02 INFO - Running setup.py (path:/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/pip-COa2kb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:10:02 INFO - Running setup.py (path:/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/pip-5_mbXg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:10:02 INFO - Running setup.py (path:/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/pip-R0qWwI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:10:02 INFO - Running setup.py (path:/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/pip-3BnDhc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:10:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:10:02 INFO - Running setup.py (path:/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/pip-_q6PBj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:10:03 INFO - Running setup.py (path:/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/pip-sPy3Ly-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:10:03 INFO - Running setup.py (path:/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/pip-iUyNk7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:10:03 INFO - Running setup.py (path:/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/pip-jSjUVz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:10:03 INFO - Running setup.py (path:/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/pip-_9svP0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:10:03 INFO - Running setup.py (path:/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/pip-SVXsE1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:10:03 INFO - Running setup.py (path:/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/pip-b_zeVd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:10:03 INFO - Running setup.py (path:/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/pip-Va1YIH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:10:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:10:03 INFO - Running setup.py (path:/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/pip-woOx4X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:10:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:10:03 INFO - Running setup.py install for manifestparser 02:10:04 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:10:04 INFO - Running setup.py install for mozcrash 02:10:04 INFO - Running setup.py install for mozdebug 02:10:04 INFO - Running setup.py install for mozdevice 02:10:04 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:10:04 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:10:04 INFO - Running setup.py install for mozfile 02:10:04 INFO - Running setup.py install for mozhttpd 02:10:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:10:04 INFO - Running setup.py install for mozinfo 02:10:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:10:04 INFO - Running setup.py install for mozInstall 02:10:05 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:10:05 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:10:05 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:10:05 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:10:05 INFO - Running setup.py install for mozleak 02:10:05 INFO - Running setup.py install for mozlog 02:10:05 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:10:05 INFO - Running setup.py install for moznetwork 02:10:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:10:05 INFO - Running setup.py install for mozprocess 02:10:05 INFO - Running setup.py install for mozprofile 02:10:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:10:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:10:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:10:05 INFO - Running setup.py install for mozrunner 02:10:06 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:10:06 INFO - Running setup.py install for mozscreenshot 02:10:06 INFO - Running setup.py install for moztest 02:10:06 INFO - Running setup.py install for mozversion 02:10:06 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:10:06 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:10:06 INFO - Cleaning up... 02:10:06 INFO - Return code: 0 02:10:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:10: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')]} 02:10:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:10:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:10: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')]} 02:10:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:10:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:10:06 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 0x102d19a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d29420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe24ad8eb30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bb8e00>, '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 0x102a21db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102bb3b20>, '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.gncsWFv3CF/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.YuMZICUosS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:10:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:10:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:10:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gncsWFv3CF/Render', 02:10:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:10:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:10:06 INFO - 'HOME': '/Users/cltbld', 02:10:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:10:06 INFO - 'LOGNAME': 'cltbld', 02:10:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:10:06 INFO - 'MOZ_NO_REMOTE': '1', 02:10:06 INFO - 'NO_EM_RESTART': '1', 02:10:06 INFO - 'PAGER': '/bin/cat', 02:10:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:10:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:10:06 INFO - 'PWD': '/builds/slave/test', 02:10:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:10:06 INFO - 'SHELL': '/bin/bash', 02:10:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YuMZICUosS/Listeners', 02:10:06 INFO - 'TMPDIR': '/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/', 02:10:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:10:06 INFO - 'USER': 'cltbld', 02:10:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:10:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:10:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:10:06 INFO - 'XPC_FLAGS': '0x0', 02:10:06 INFO - 'XPC_SERVICE_NAME': '0', 02:10:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:10:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:10:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:10:06 INFO - Running setup.py (path:/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/pip-71l5NF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:10:06 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:10:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:10:06 INFO - Running setup.py (path:/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/pip-KENuqS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:10:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:10:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:10:06 INFO - Running setup.py (path:/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/pip-HbSqQ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:10:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:10:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:10:07 INFO - Running setup.py (path:/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/pip-WiEzAh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:10:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:10:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:10:07 INFO - Running setup.py (path:/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/pip-nK5emH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:10:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:10:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:10:07 INFO - Running setup.py (path:/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/pip-ViS6Vr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:10:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:10:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:10:07 INFO - Running setup.py (path:/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/pip-xdY5Y8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:10:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 02:10:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:10:07 INFO - Running setup.py (path:/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/pip-6NpUFv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:10:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:10:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:10:07 INFO - Running setup.py (path:/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/pip-PVt3Lg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:10:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 02:10:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:10:07 INFO - Running setup.py (path:/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/pip-cLtb9Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:10:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:10:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:10:07 INFO - Running setup.py (path:/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/pip-4gE2nb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:10:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 02:10:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:10:07 INFO - Running setup.py (path:/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/pip-jzD0W0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:10:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:10:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:10:08 INFO - Running setup.py (path:/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/pip-ABFWaW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:10:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:10:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:10:08 INFO - Running setup.py (path:/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/pip-podiIH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:10:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:10:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:10:08 INFO - Running setup.py (path:/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/pip-RkxpyP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:10:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:10:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:10:08 INFO - Running setup.py (path:/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/pip-JgYRbk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:10:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:10:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:10:08 INFO - Running setup.py (path:/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/pip-qqYbsl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:10:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 02:10:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:10:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:10:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:10:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:10:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:10:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:10:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:10:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:10:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:10:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:10:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:10:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:10:10 INFO - Downloading blessings-1.6.tar.gz 02:10:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:10:11 INFO - Installing collected packages: blessings 02:10:11 INFO - Running setup.py install for blessings 02:10:11 INFO - Successfully installed blessings 02:10:11 INFO - Cleaning up... 02:10:11 INFO - Return code: 0 02:10:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:10:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:10:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:10:11 INFO - Reading from file tmpfile_stdout 02:10:11 INFO - Current package versions: 02:10:11 INFO - blessings == 1.6 02:10:11 INFO - blobuploader == 1.2.4 02:10:11 INFO - docopt == 0.6.1 02:10:11 INFO - manifestparser == 1.1 02:10:11 INFO - mozInstall == 1.12 02:10:11 INFO - mozcrash == 0.17 02:10:11 INFO - mozdebug == 0.1 02:10:11 INFO - mozdevice == 0.48 02:10:11 INFO - mozfile == 1.2 02:10:11 INFO - mozhttpd == 0.7 02:10:11 INFO - mozinfo == 0.9 02:10:11 INFO - mozleak == 0.1 02:10:11 INFO - mozlog == 3.1 02:10:11 INFO - moznetwork == 0.27 02:10:11 INFO - mozprocess == 0.22 02:10:11 INFO - mozprofile == 0.28 02:10:11 INFO - mozrunner == 6.11 02:10:11 INFO - mozscreenshot == 0.1 02:10:11 INFO - mozsystemmonitor == 0.0 02:10:11 INFO - moztest == 0.7 02:10:11 INFO - mozversion == 1.4 02:10:11 INFO - psutil == 3.1.1 02:10:11 INFO - requests == 1.2.3 02:10:11 INFO - wsgiref == 0.1.2 02:10:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:10: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')]} 02:10:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:10:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:10: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')]} 02:10:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:10:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:10:11 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 0x102d19a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d29420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe24ad8eb30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bb8e00>, '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 0x102a21db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102bb3b20>, '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.gncsWFv3CF/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.YuMZICUosS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:10:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 02:10:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:10:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gncsWFv3CF/Render', 02:10:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:10:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:10:11 INFO - 'HOME': '/Users/cltbld', 02:10:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:10:11 INFO - 'LOGNAME': 'cltbld', 02:10:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:10:11 INFO - 'MOZ_NO_REMOTE': '1', 02:10:11 INFO - 'NO_EM_RESTART': '1', 02:10:11 INFO - 'PAGER': '/bin/cat', 02:10:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:10:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:10:11 INFO - 'PWD': '/builds/slave/test', 02:10:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:10:11 INFO - 'SHELL': '/bin/bash', 02:10:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YuMZICUosS/Listeners', 02:10:11 INFO - 'TMPDIR': '/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/', 02:10:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:10:11 INFO - 'USER': 'cltbld', 02:10:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:10:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:10:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:10:11 INFO - 'XPC_FLAGS': '0x0', 02:10:11 INFO - 'XPC_SERVICE_NAME': '0', 02:10:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:10:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:10:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:10:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 02:10:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 02:10:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:10:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 02:10:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 02:10:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 02:10:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 02:10:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 02:10:11 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:10:11 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:10:11 INFO - Cleaning up... 02:10:11 INFO - Return code: 0 02:10:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:10: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')]} 02:10:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:10:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:10: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')]} 02:10:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:10:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:10:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102d19a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d29420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe24ad8eb30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bb8e00>, '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 0x102a21db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102bb3b20>, '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.gncsWFv3CF/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.YuMZICUosS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:10:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:10:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:10:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gncsWFv3CF/Render', 02:10:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:10:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:10:11 INFO - 'HOME': '/Users/cltbld', 02:10:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:10:11 INFO - 'LOGNAME': 'cltbld', 02:10:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:10:11 INFO - 'MOZ_NO_REMOTE': '1', 02:10:11 INFO - 'NO_EM_RESTART': '1', 02:10:11 INFO - 'PAGER': '/bin/cat', 02:10:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:10:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:10:11 INFO - 'PWD': '/builds/slave/test', 02:10:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:10:11 INFO - 'SHELL': '/bin/bash', 02:10:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YuMZICUosS/Listeners', 02:10:11 INFO - 'TMPDIR': '/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/', 02:10:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:10:11 INFO - 'USER': 'cltbld', 02:10:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:10:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:10:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:10:11 INFO - 'XPC_FLAGS': '0x0', 02:10:11 INFO - 'XPC_SERVICE_NAME': '0', 02:10:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:10:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:10:12 INFO - Downloading/unpacking jsonschema 02:10:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:10:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:10:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:10:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:10:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:10:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:10:14 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:10:14 INFO - Downloading/unpacking functools32 (from jsonschema) 02:10:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:10:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:10:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:10:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:10:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:10:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:10:15 INFO - Downloading functools32-3.2.3-2.tar.gz 02:10:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:10:15 INFO - warning: no files found matching '*.txt' 02:10:15 INFO - no previously-included directories found matching 'build' 02:10:15 INFO - no previously-included directories found matching 'dist' 02:10:15 INFO - no previously-included directories found matching '.git*' 02:10:15 INFO - Installing collected packages: jsonschema, functools32 02:10:15 INFO - Running setup.py install for functools32 02:10:15 INFO - warning: no files found matching '*.txt' 02:10:15 INFO - no previously-included directories found matching 'build' 02:10:15 INFO - no previously-included directories found matching 'dist' 02:10:15 INFO - no previously-included directories found matching '.git*' 02:10:15 INFO - Successfully installed jsonschema functools32 02:10:15 INFO - Cleaning up... 02:10:15 INFO - Return code: 0 02:10:15 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 02:10:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:10:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:10:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:10:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:10:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:10:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:10:15 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 0x102d19a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d29420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe24ad8eb30>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102bb8e00>, '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 0x102a21db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102bb3b20>, '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.gncsWFv3CF/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.YuMZICUosS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 02:10:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 02:10:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 02:10:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gncsWFv3CF/Render', 02:10:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:10:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:10:15 INFO - 'HOME': '/Users/cltbld', 02:10:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:10:15 INFO - 'LOGNAME': 'cltbld', 02:10:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:10:15 INFO - 'MOZ_NO_REMOTE': '1', 02:10:15 INFO - 'NO_EM_RESTART': '1', 02:10:15 INFO - 'PAGER': '/bin/cat', 02:10:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:10:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:10:15 INFO - 'PWD': '/builds/slave/test', 02:10:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:10:15 INFO - 'SHELL': '/bin/bash', 02:10:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YuMZICUosS/Listeners', 02:10:15 INFO - 'TMPDIR': '/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/', 02:10:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:10:15 INFO - 'USER': 'cltbld', 02:10:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:10:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:10:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:10:15 INFO - 'XPC_FLAGS': '0x0', 02:10:15 INFO - 'XPC_SERVICE_NAME': '0', 02:10:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:10:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:10:15 INFO - Downloading/unpacking flake8 02:10:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:10:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:10:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:10:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:10:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:10:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:10:18 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 02:10:18 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 02:10:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:10:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:10:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:10:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:10:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:10:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:10:18 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 02:10:18 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 02:10:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:10:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:10:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:10:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:10:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:10:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:10:18 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 02:10:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:10:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:10:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:10:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:10:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:10:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:10:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 02:10:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:10:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:10:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:10:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:10:19 INFO - no previously-included directories found matching 'docs/_build' 02:10:19 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 02:10:19 INFO - Running setup.py install for pep8 02:10:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 02:10:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 02:10:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 02:10:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 02:10:19 INFO - no previously-included directories found matching 'docs/_build' 02:10:19 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 02:10:19 INFO - Successfully installed flake8 mccabe pyflakes pep8 02:10:19 INFO - Cleaning up... 02:10:19 INFO - Return code: 0 02:10:19 INFO - Running post-action listener: _resource_record_post_action 02:10:19 INFO - Running post-action listener: _start_resource_monitoring 02:10:19 INFO - Starting resource monitoring. 02:10:19 INFO - ##### 02:10:19 INFO - ##### Running install step. 02:10:19 INFO - ##### 02:10:19 INFO - Running pre-action listener: _resource_record_pre_action 02:10:19 INFO - Running main action method: install 02:10:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:10:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:10:20 INFO - Reading from file tmpfile_stdout 02:10:20 INFO - Detecting whether we're running mozinstall >=1.0... 02:10:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:10:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:10:20 INFO - Reading from file tmpfile_stdout 02:10:20 INFO - Output received: 02:10:20 INFO - Usage: mozinstall [options] installer 02:10:20 INFO - Options: 02:10:20 INFO - -h, --help show this help message and exit 02:10:20 INFO - -d DEST, --destination=DEST 02:10:20 INFO - Directory to install application into. [default: 02:10:20 INFO - "/builds/slave/test"] 02:10:20 INFO - --app=APP Application being installed. [default: firefox] 02:10:20 INFO - mkdir: /builds/slave/test/build/application 02:10:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:10:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 02:10:51 INFO - Reading from file tmpfile_stdout 02:10:51 INFO - Output received: 02:10:51 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 02:10:51 INFO - Running post-action listener: _resource_record_post_action 02:10:51 INFO - ##### 02:10:51 INFO - ##### Running run-tests step. 02:10:51 INFO - ##### 02:10:51 INFO - Running pre-action listener: _resource_record_pre_action 02:10:51 INFO - Running main action method: run_tests 02:10:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:10:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:10:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:10:51 INFO - 2016-05-30 02:10:51.758 screenresolution[1645:7805] starting screenresolution argv=screenresolution get 02:10:51 INFO - 2016-05-30 02:10:51.761 screenresolution[1645:7805] Display 0: 1600x1200x32@60 02:10:51 INFO - 2016-05-30 02:10:51.767 screenresolution[1646:7807] starting screenresolution argv=screenresolution list 02:10:51 INFO - Available Modes on Display 0 02:10:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:10:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:10:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:10:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:10:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:10:51 INFO - Intel Iris: 02:10:51 INFO - Chipset Model: Intel Iris 02:10:51 INFO - Type: GPU 02:10:51 INFO - Bus: Built-In 02:10:51 INFO - VRAM (Dynamic, Max): 1536 MB 02:10:51 INFO - Vendor: Intel (0x8086) 02:10:51 INFO - Device ID: 0x0a2e 02:10:51 INFO - Revision ID: 0x0009 02:10:51 INFO - Displays: 02:10:51 INFO - Display: 02:10:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:10:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:10:51 INFO - Display Serial Number: 1600x1200 60 02:10:51 INFO - Main Display: Yes 02:10:51 INFO - Mirror: Off 02:10:51 INFO - Online: Yes 02:10:51 INFO - Rotation: Supported 02:10:51 INFO - Return code: 0 02:10:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:10:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:10:52 INFO - Python 2.7.3 02:10:52 INFO - Return code: 0 02:10:52 INFO - grabbing minidump binary from tooltool 02:10:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 02:10:52 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 0x102bb8e00>, '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 0x102a21db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102bb3b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:10:52 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 02:10:52 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 02:10:52 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:10:52 INFO - Return code: 0 02:10:52 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:10:52 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:10:52 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:10:52 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:10:52 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:10:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 02:10:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 02:10:53 INFO - Return code: 0 02:10:53 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/ckszqfuhRsym8v0GKEg5Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0391', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 02:10:53 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/ckszqfuhRsym8v0GKEg5Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0391 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 02:10:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gncsWFv3CF/Render', 02:10:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:10:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:10:53 INFO - 'HOME': '/Users/cltbld', 02:10:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:10:53 INFO - 'LOGNAME': 'cltbld', 02:10:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:10:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:10:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:10:53 INFO - 'MOZ_NO_REMOTE': '1', 02:10:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:10:53 INFO - 'NO_EM_RESTART': '1', 02:10:53 INFO - 'PAGER': '/bin/cat', 02:10:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:10:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:10:53 INFO - 'PWD': '/builds/slave/test', 02:10:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:10:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:10:53 INFO - 'SHELL': '/bin/bash', 02:10:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YuMZICUosS/Listeners', 02:10:53 INFO - 'TMPDIR': '/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/', 02:10:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:10:53 INFO - 'USER': 'cltbld', 02:10:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:10:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:10:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:10:53 INFO - 'XPC_FLAGS': '0x0', 02:10:53 INFO - 'XPC_SERVICE_NAME': '0', 02:10:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:10:53 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/ckszqfuhRsym8v0GKEg5Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0391', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 02:10:53 INFO - mozversion application_buildid: 20160530004020 02:10:53 INFO - mozversion application_changeset: ccf0f53adb66c86d81a39b53e32092489fb5f0b5 02:10:53 INFO - mozversion application_display_name: FirefoxDeveloperEdition 02:10:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:10:53 INFO - mozversion application_name: Firefox 02:10:53 INFO - mozversion application_remotingname: firefox-dev 02:10:53 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:10:53 INFO - mozversion application_vendor: Mozilla 02:10:53 INFO - mozversion application_version: 48.0a2 02:10:53 INFO - mozversion platform_buildid: 20160530004020 02:10:53 INFO - mozversion platform_changeset: ccf0f53adb66c86d81a39b53e32092489fb5f0b5 02:10:53 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 02:10:53 INFO - mozversion platform_version: 48.0a2 02:10:53 INFO - using testdate: 1464599453 02:10:53 INFO - actual date: 1464599453 02:10:53 INFO - starting webserver on 'localhost:49276' 02:10:53 INFO - SUITE-START | Running 2 tests 02:10:53 INFO - TEST-START | dromaeo_css 02:10:53 INFO - operating with platform_type : mac_ 02:10:53 INFO - Initialising browser for dromaeo_css test... 02:10:53 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/tmp2qAWC1/profile http://localhost:49276/getInfo.html) 02:10:56 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 02:10:56 INFO - PROCESS | 1664 | colorDepth:24 02:10:56 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 02:10:56 INFO - PROCESS | 1664 | __metrics 02:10:56 ERROR - PROCESS | 1664 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 02:10:57 INFO - TEST-INFO | 1664: exit 0 02:10:57 INFO - Browser initialized. 02:10:57 INFO - Running cycle 1/1 for dromaeo_css test... 02:10:57 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/tmp2qAWC1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:11:09 INFO - PROCESS | 1668 | RSS: Main: 221569024 02:11:09 INFO - PROCESS | 1668 | 02:11:58 INFO - PROCESS | 1668 | 1464599518783 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 02:14:09 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49276/tests/dromaeo/cssquery-ext.html) 02:14:11 INFO - PROCESS | 1668 | RSS: Main: 231452672 02:14:11 INFO - PROCESS | 1668 | 02:17:13 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-ext.html (next: http://localhost:49276/tests/dromaeo/cssquery-jquery.html) 02:17:13 INFO - PROCESS | 1668 | RSS: Main: 243318784 02:17:13 INFO - PROCESS | 1668 | 02:20:15 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49276/tests/dromaeo/cssquery-mootools.html) 02:20:16 INFO - PROCESS | 1668 | RSS: Main: 245321728 02:20:16 INFO - PROCESS | 1668 | 02:23:17 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49276/tests/dromaeo/cssquery-prototype.html) 02:23:18 INFO - PROCESS | 1668 | RSS: Main: 247087104 02:23:18 INFO - PROCESS | 1668 | 02:26:21 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49276/tests/dromaeo/cssquery-yui.html) 02:26:21 INFO - PROCESS | 1668 | RSS: Main: 249958400 02:26:21 INFO - PROCESS | 1668 | 02:29:23 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-yui.html (next: http://localhost:49276/tests/dromaeo/cssquery-dojo.html) 02:29:24 INFO - PROCESS | 1668 | RSS: Main: 251183104 02:29:24 INFO - PROCESS | 1668 | 02:29:24 INFO - PROCESS | 1668 | __start_tp_report 02:29:24 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 02:29:24 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 02:29:24 INFO - PROCESS | 1668 | |i|pagename|runs| 02:29:24 INFO - PROCESS | 1668 | |0;dojo.html;3232.5312829639715;3339.5825521809775;3429.365567370037;3524.347995620809;3565.1800085980226;5844.035734103868;5847.5614328925285;5886.264216972879;5903.88192236155;5919.49684276837;5043.974780126104;5721.6853073080965;5868.295804503455;5901.085331773571;5901.38035506271;4765;5051.570616497584;5309.283246761676;5315.521603055715;5339.092354299778;5321.93561287744;5388.865278368033;5431.429699881515;5465.754041068161;5466.753996070186;4817.771468275585;4999.550040496355;5382.058139825514;5385.838424847241;5400.945990540104;5337.572994160477;5364.758585863646;5371.221172929922;5386.515213630752;5389.299391079174;5242.16125419931;5294.941011797656;5311.601629877775;5319.28189694394;5325.573954083703;5788.160716696056;5813.128030795415;5852.326982397036;5868.97065514674;6038.396160383971;5361.731913404353;5737.082066869322;6235.594686345418;6246.094316324107;6252.499800015988;6198.101275315054;6248.218972628421;6248.343923887972;6255.812325630239;6275.246970363585;5772.913406298909;5834.2998840139035;5934.940650593525;5980.910286345708;5990.191324171225;6295.685215739195;6307.590006649553;6324.652144132025;6345.397187267202;6360.236771587439;6205.751769929243;6207.317195108534;6215.440610345;6218.844528886814;6244.843878903063;6162.168107305546;6173.351798061229;6186.783462578788;6261.8747625047245;6290.182276304051;6224.937750622526;6227.564070515111;6248.656323902138;6258.530610204162;6269.78055768055;6241.375862413723;6251.093591429218;6255.96872015637;6266.154069200599;6266.874662506815;4845.030993801254;4956;5001.299818025479;5015.097282489114;5058.645894787403;4489.169503641837;4517.616002639812;4517.977410112994;4538;4559.088182363541;5861.6189947653265;5955.910661340084;5974.312954010257;5976.910346344808;6000.5799594028;6203.100550420164;6216.2540495140875;6243.594166379171;6274.1843560336865;6278.497720182466;5903.291605007426;5928.9407105928385;5947.5539334550085;5958.612690175125;5974.2830860297045;5395.70323632204;5446.618736688394;5450.591205659592;5505.944940550623;5508.20131080991;5418.593605479605;6524.836879078061;6535.117759102555;6552.770653027121;6573.9810329399015;6263.3736626337;6287.591306565058;6290.433860952537;6301.747930082745;6311.558190926775;6300.180976473029;6391.872162556816;6415.422611964946;6527.836804079936;6555;6224.097505861715;6249.375062493714;6253.218347706536;6266.185395898504;6320.399562041599;6146.754129834935;6242.843928901813;6265.342139075331;6270.090836828543;6281.246250449975;6040.909386359296;6137.5302083125;6172.845678858064;6236.937630623635;6247.312795592572;5136.512031357015;5963.254593175853;6076.756929722762;6083.391660833881;6102.694865256631;5449.891002180014;5845.152452894392;5873.588848780544;5928.081147422156;5933.614315069679;5249.658772179796;5996;6077.969610151921;6123.601965872204;6209.503239740719;6171.537134715004;6194.008958566607;6197.442230199304;6200.81397558074;6213.1922850029205 02:29:24 INFO - PROCESS | 1668 | |1;ext.html;539.0754855422949;582.126809785322;584.6316820403146;596.9492593129585;608.6317777744465;30008.399832003346;38723;38743.612563874354;38812.80593597032;38816.80591597045;40519.79740101303;40877.591224087824;40937.79531102348;40961.38557921634;41193.58806411935;9380.484073375945;9502.239820814319;9571.760705982337;9578.521073946275;9594.136527712504;9569.186619137381;9779.951100244489;9789.804203915917;9797.069278418574;9812.313138080337;9741.951290243538;9753.804923901518;9763.414195148314;9803.754906127333;9841.45871977041;9637.807243855119;9680.322377433582;9727.416355018686;9798.853017204749;9816;9606.75983100421;9636.566354514063;9717.56270967808;9725.708228753148;9739.977302383219;39282.41076383857;39303.60696393028;39660;39738.006549836195;39868.80393588197;3263.918402039968;3390.4914262860766;3584.6773790358734;3823.4647149399107;3825.3114439400897;23161;23583.76416235833;28974.710252897414;29028.854855725585;29419;2737.9589306160424;2770.570561562941;2816.9295767605972;2833.3908209735005;2891.2482754483985;389128;397214;401298.9999999971;401509;401937;36979.15104244777;38171;65791;66486;66765;37775;37823.24353512914;37902.43146352807;38049.61950380516;38129.04677383032;15148;15377.846221537642;15414.614634634;15651.530454086396;15714.68570628581;13176.275304858325;13274.203547787163;13389.799153012713;13481.932590336986;13510.919126469858;7412.221716719775;8308.459950103224;8496.40525163245;8520.318374530023;8530.317574594017;3566.0401866450784;3596.3166998270244;3629.564452265745;3630.49173115764;3639.3631114554846;983.0366241083635;1014.6955913226003;1049.0768124050787;1058.016219588272;1089.836524521328;23446.827658617;23791.762082378955;25418.237452876074;26750.73249267521;28156.71843281541;1045.1795340657548;1048.7220886465093;1093.3822390349585;1093.4150229627112;1131.6944425005202;13132.606021819362;13196.076274660682;13329.66675833112;16240.59398515023;16254.268557914686;526215.7378426243;580944;609022;619666;622971;49833;61441;62506;62726.686366567876;62808.05787913094;1491.7613181890845;1506.246876561719;1518.5596177108514;1520.1259275916393;1530.1584128729155;30527;30879.073627791204;30890.382192356476;31248.21879452941;31365.843170784;52672;53198.73400632972;53970.460295398116;54039.72980135232;54356.184657230966;572967;573264;575701;576270;578105;26623;27774.44451111007;43648.34527482027;44697.32954005757;45660;37486.62513374831;43579.34630980474;43776.12447751091;46702.766486167355;48580;34289.48565771466;35778.92663220107;36562.45156322604;36659;37388.813055934545;31551;35976.8201159003;36054.918352449466;36474.817625912765;36598.26803463969;32523.349533008735;36017;37974.43038354482;38268.42597360986;39369.40945885871;30850;31445.685543144275;32020.039398818073;32037.35925281528;32093.679063210002;24327.905244264406;24509.50980980406;24516.387090322885;24932.62601061022;24988.62517062209 02:29:24 INFO - PROCESS | 1668 | |2;jquery.html;353.61985865194913;398.0645483114679;402.8771224776442;403.9757614543126;407.0617227291095;15620.2970866311;17160.485185444442;19795.70306445403;19824.40526784197;20254.29109981151;20531.897340513315;20581.794182058176;20896.955152242364;21233.36299911005;21433.67849482259;5919.822405327836;5963.910541341883;6184.56708030438;6191.288001879797;6201.66664167204;6057.757689692407;6137.570370074097;6161.537884658646;6161.691915404234;6167.444929956303;4757.785899634508;5661.433856614347;5827.621204621707;5870.090136028901;6057.697115144248;4978.253262010728;5738.569607279471;5762.596618236725;5787.305523337205;6507.544471886969;4876.634252431047;5793.102069179284;5805.535557155417;5827.24245848037;5832.9708351457975;13072.673183170402;13258.204507729568;23670.881645591835;24985.50028999428;25091.874540627363;2120.756113047004;2138.283674968887;2207.834412419059;2270.7729227077325;2685.4092099738027;9411.43531388119;9421.76445588859;9888.90111098887;9918.156956658693;10102.494875256281;1752.50053734685;1776.8933863968202;1797.4877160009335;1821.9635607287912;1858.5539470526976;98448;104209;112240;112864;113388.43305783418;11880.643580692593;11927.403629818647;11969.46137423818;12028.158028938065;12062.095342849147;13415.262160581262;14141.43434262618;14614.976951613498;14892.180930048951;14914.70170596582;8420.031696354876;8623.870641940375;8993.70818732071;9013.729588112366;9029.503377314311;7379.262073792685;7456.32893039629;8993.23557497607;9158.679446219483;9181.72454826343;4614.353990441341;4623.53764623535;4662.230731929261;4717.198076327033;4718.457377401574;1952.4142757172792;1985.9900700496694;2004.168270167864;2021.090509270834;2030.106753028692;760.8896709977022;837.4891316297054;952.2953014769021;982.5283863745442;1029.505837198149;20808.063637136365;21194.576108477744;21220.151193952068;21515.78484215169;21559.892200538896;998.6454808543008;998.7553049502918;1001.6193846338344;1019.898010198989;1019.90820826126;9505.904940950502;10967.890321096846;11393.373364465035;11415.657530274106;11458.656240312805;596212;602387;616872;625515;625742;108587;113602.43198783953;117656.24533725773;121142.394288028;128132.35933820272;1054.4200689620677;1058.30680904008;1058.7988282226506;1079.8002369561657;1081.4862940103544;18346.633067338313;18742.969136697066;18874.339398121;18943;19191;39038.6096139035;40535.594644053184;49603.25595116148;51738;54304.456955431524;63777.362226378995;67397.32602674107;68619.31380686129;69176;69700.6514967422;5897.616654917417;7945.682172713026;8040.075391329838;8075.030996280484;8238.711645092393;8816.823663526822;11242.606508772154;11625.418729063682;11711.414429278673;12613.243205407705;10886.61896833607;10905.236633435583;11012.724681882697;11138.665840024812;11176.385298808644;10757.031867131996;10767.892321077003;10780.191485638452;10814.891851081704;10998.395088269903;9362.906370936204;11057;11302.4913878874;11347.602833901105;11446.255993360404;7744.225577442211;8001.879971800544;8022.358211343195;8127.674893004214;8178.7955301117945;9680;11141.387223702774;11165.330080195214;11227.775444491228;11340.546378144782 02:29:24 INFO - PROCESS | 1668 | |3;mootools.html;1568.129688023147;1612.7661489084087;1852.6294741051784;1915.7796853361858;2005.6490114230005;7982.441229113964;8673.653053877853;8802.031776504587;9036.367454278203;9049.547522623867;8775.692850750225;8840.95579522103;8847.071057538962;8881.822363552725;8883.64465421384;2400.8319417640773;2420.5884999550076;2434.6956630421196;2437.2566367257978;2442.4016116051594;2341.2507997440825;2419.9153029643967;2467.753224677536;2468.666729991446;2483.863387513686;2445.0586524188225;2453.7546245375497;2454.128784281581;2468.5803413419762;2481.255623313008;2447.119037146626;2449.5223431430877;2453.521563295149;2458.1273647854932;2474.74015228402;2450.088036050024;2469.9012039518398;2470.5923522618928;2474.307193985687;2476.2447453526665;9003.819923601492;9042.593083311202;9078.682246121354;9084.091590840862;9090.681826136053;5443.863903402407;5554.472325129106;5564.693941833198;5573.498385145316;5603.159526071081;3544.6278140795102;3637.3816451203093;3685.1708365617787;3695.464157697119;3695.7597756145765;1044.0864243786687;1058.7564860082225;1061.920355973305;1062.3678911047914;1063.9414832184227;12061.517539298418;12137.757244855053;12148.939255303756;12150.96716779066;12161;4763.190257656207;4803.807129388516;4855.344528488679;4866.805327786919;4892.168331383673;10392.324498907547;10417.322874013165;10428.478576071166;10437.634682786065;10441.947790261;5505.421930697299;5535.197396377503;5543.501084902378;5576.832695019156;5614.747336369873;3692.9105913755507;4446.977765111218;4847.394075740533;4907.9263811042865;4970.130227210224;3505.5968563615;4058.066644671742;4085.9857342739497;4150.5641907599875;4168.43726096973;1120.7310245541175;1140.9087273018138;1324.7019420630475;1326;1336.7593833110134;834.5910503853069;842.1704620948361;856.2550580994574;911.7447114807865;946.8816397950256;5931.6441013539325;5976.73104710289;5997.100434934795;6005;6058.4244496772735;932.137772560379;936.3305236755753;936.9765755856158;985.5318723606373;1031.5770534080991;3948.8223029963146;4010.63904248619;4019.437278780973;4033.1127152026506;4042.5755295694116;10520.473976301155;10987.175961802897;11072.003519683107;11076.889231107585;11165.83251251232;10866.185036122322;10915.890841091647;10929.56281748721;10973.341599504056;11100.833487497694;8306.460080094774;8394.916050839536;8456.32349412058;8559.015713192937;8561.14388561139;5848.239728835224;5915.733791979457;5937.139114828412;5990.64056156632;6073.635581864925;9523.23814094857;9680.612775489184;9814;9908.801823963337;9926.957669444893;9211.032841551541;9676.516174191122;10012.948640392653;10097.949510252402;10140.29017968735;6893.482988775962;7313.085864266966;7566.583837888969;7581.962090189514;7686.000819893378;8338.416310858182;8381.95809020951;8387.874181887399;8410.705625303084;8420.663173472993;7367.852642947004;7482;7555.206703296075;7556.319931206218;7626.9618651906385;7302.890456643261;7381.963090184515;7394.667239974106;7485.887711684438;7497.812554686177;7134.072570565793;7186.10173728284;7201.567905925661;7211.062561866924;7233.891491627735;4767;4868.099401610713;4877.780499877585;4983.978284451751;4998.325226094431;4325.351197320427;4932.1122198004705;4959.975200123977;5050.520200580939;5079.5428411443145 02:29:24 INFO - PROCESS | 1668 | |4;prototype.html;308.6527656386565;322.0451361712522;326.40431213036214;326.73697673372953;333.3233535922076;6653.866922661544;6687.69905354259;6714.899276510856;6724.260331363552;6817.386435220829;6664.433523150526;6665.333466653332;6683.632400217986;6685.966570167155;6712.261651218374;4562.680612357128;4617.792199351037;4759.738214398207;4799.088173247089;4800.255960326155;4735.408073990751;4768.23708206687;4841.8063277468855;4931.605471562267;4943.1102401567705;3893.968098453912;4800.8319708810195;4819.493953134923;4824.4210694716685;4826.420829500465;4788.712677239377;4792.688475249115;4808.447028591704;4819.373481447425;4822.397200349957;3229.3864165808423;4119.814608342632;4120.938185927213;4124.52567954686;4129.876103716893;6186.133941248228;6446.290908000116;6468;6477.255115661712;6482.254540727829;5625.46558076982;5672.574556908207;5674.9501342251815;5687.744051517691;5693.089105743103;5962.77763058572;7002.579845209304;7104.68028938699;7174.210836807947;7178.246284140195;4533.569310915458;4536.705114167602;4538.092381523708;4543.932175938652;4556.430446194226;7531;7589.962050189769;7631.923680763177;7642.579658118857;7649.120351159577;4408;4565.246734288871;4571.1543364477675;4586.128635559233;4609.884752881205;4645.837395691135;4676.13491504066;4678;4686.133065382915;4709.835155769531;5618.634788738719;5619.7471113799975;5636.802711905064;5640.915386269209;5660.943390566042;7272.418206543463;7279;7309.342159205697;7310.634468276565;7311.049563556704;1620.2222932992229;1635.5256975477216;1640.015990405747;1641.7455294429144;1643.2112585958807;862.9482231066156;982.945937973404;1016.7305663999047;1033.6433930294036;1034.6792494326783;5448.564114870801;6700.865982680319;6718.126643536309;6725.596464212065;6730.798076057628;7224.349808517259;7483.775486735407;7512.39900807934;7524.322810946932;7545.811354716176;6634.86730265392;6815.079964204769;6815.693293801791;6836.350546698056;6843;3913.3934240192816;3960;3966.8016599170505;3970.68234541236;3977.880663580097;7924.762257132295;7928.207179282025;7943.7219697311475;7950.6422211000645;7981.48120372174;6408.295087540367;7244.877044058179;7849.803754906059;7852.528848269122;7859.449838511364;7890.368770498461;7900.683972641145;7950.443468957234;7966.561839098905;7974.760757177294;6086.878262434815;7431.665575049244;7534.736284230025;7551.886721699069;7559.622018898923;7416.480846340704;7436.144843343085;7437.925620743723;7446.553206807609;7458.366038886756;6329.683515824282;6739.831504212434;6761.188657361148;7892.289693927574;7911.84176316482;4306.676999225008;4609.423822022247;4653.488116307136;4669.112868554964;4671.135839869635;5726;7465.776026719207;7573.129090154703;7593.696252150073;7601.125870524739;5780.508656764222;6862.382385585322;7196.244394338729;7248.746293879689;7255.4921155518605;7024.8595028098125;7209.0988626421695;7245.818854528679;7278.272172782679;7317.414606831334;6998.6150761708595;7052.471064670068;7074.646267686698;7103.1476222851525;7113.502054856111;5796.652200867961;7247.782566523013;7254.782356529313;7265.854682906418;7288.708451662086;7069.717211311696;7108.253633368422;7109.751158709627;7111.82220444493;7139.32176443237 02:29:24 INFO - PROCESS | 1668 | |5;yui.html;622.3776223776223;680.4420375292259;693.3794254142542;709.2552819539488;728.8578727148207;14967.027143235695;14976.775348369756;15025.474108406208;15242.466513672023;15289.847101528982;14160.858391416083;14172.71654566908;14205.644858878533;14211.21838298893;14264.500742474016;736.675862620448;873.1094283830489;937.6483818568037;942.8302905477011;944.0653752784729;886.2068448738376;913.1325241021024;924.36219008884;929.9814003719922;931.3201363005011;902.6479672927563;995.9850602240972;996.5415908681979;997.8004399120168;997.9451130187837;978.3249557805092;985.4530735441826;987.0031268418949;987.0770829274616;989.4113002763344;695.9060526828864;720.8630360231539;728.6429649471773;734.3904559215838;735.8932954721589;14714.55856324312;14727.631809204748;14896.925515372353;14953.850461495354;14981.925090374587;12852.935735321356;12968.286744229064;13025.218486890817;13168.341582920912;13262.93368533151;9916.603335866557;10136.391816491034;10172.59309627614;10210.846837297446;10282.588696452132;4059.04612416082;4081.551029386765;4143.523494798107;4218.725782824106;4237.385579091015;77967.22032779656;78260.60869695614;78665.60667196685;79298.8105178417;79409;8233.970753655793;8925.687600933936;9433.292503062297;9525.5713492893;9737.318387712792;18822.247110115717;20077.49806254826;20965.68551471729;21004.894975525025;21267.893660531598;6271.811845644638;7441.962790186014;7511.985881905982;7580.393568514505;7611;4036.253293140778;4059.6752259819145;4157.91684166315;4198.391233271159;4222.387753775685;2984.820910745362;3090.1656552730637;3539.3983022886173;3585.7848529088337;3588.174719814457;644.9916399160522;654.4404534123352;657.0374401501823;664.9634270115093;668.8996650502366;512.4184051102026;528.5665754081616;578.3828177496861;589.3517131155694;600.570592026703;6589.769358072444;8191.303739182236;8394.412391132562;8453.281471074904;8534.830811814581;506.2987761949653;571.2516603249757;593.664579512574;622.5797586629008;627.3600927054379;3669.192777588926;3876.8643097491454;3933.8426462941734;3965.4448377227213;4018.3972404139026;94920;95889;96430;96873.06253874883;97003.05993880224;75419;81007.59496202599;82960.17039829525;83356.58321708352;84311;66403;70534;70706;71171.64414177896;72125;8292.419530633038;8503.447275927045;8507.702230421906;8518.190771876662;8528.872066918877;50435.74782126066;50656;51445.48554514407;51585.7420712894;51756.48243517517;53940;65783;68981;69612.30387696058;70714.29285707283;10798.73003174927;10952.082774249171;11045.502952367324;11159;11224.38265895351;12014.459349329127;12093.939530302292;12186.390680466118;12324.075694322783;12431.813522796985;9224.215941644767;11299.152563557931;11318.4906679198;11543.942280288546;11598.304101753922;11320.547178112784;11337.149713771665;11345.319280843176;11606.75316430589;11835.408229588314;11063.944680276547;11111.611093611686;11168.274062185932;11539.4807233673;11760.235584686969;9403.670871519464;9647;9722.270829687943;9825.066618671215;9842.803143936939;9443.386179898285;9667.70996870095;9686.75783105428;9727.513624318613;9776.071273229316 02:29:24 INFO - PROCESS | 1668 | __end_tp_report 02:29:24 INFO - PROCESS | 1668 | __start_cc_report 02:29:24 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,420 02:29:24 INFO - PROCESS | 1668 | __end_cc_report 02:29:24 INFO - PROCESS | 1668 | __startTimestamp1464600564232__endTimestamp 02:29:24 INFO - PROCESS | 1668 | 02:29:24 INFO - PROCESS | 1668 | ------- Summary: start ------- 02:29:24 INFO - PROCESS | 1668 | Number of tests: 6 02:29:24 INFO - PROCESS | 1668 | 02:29:24 INFO - PROCESS | 1668 | [#0] dojo.html Cycles:165 Average:5796.92 Median:5980.91 stddev:642.56 (10.7%) stddev-sans-first:612.23 02:29:24 INFO - PROCESS | 1668 | Values: 3232.5 3339.6 3429.4 3524.3 3565.2 5844.0 5847.6 5886.3 5903.9 5919.5 5044.0 5721.7 5868.3 5901.1 5901.4 4765.0 5051.6 5309.3 5315.5 5339.1 5321.9 5388.9 5431.4 5465.8 5466.8 4817.8 4999.6 5382.1 5385.8 5400.9 5337.6 5364.8 5371.2 5386.5 5389.3 5242.2 5294.9 5311.6 5319.3 5325.6 5788.2 5813.1 5852.3 5869.0 6038.4 5361.7 5737.1 6235.6 6246.1 6252.5 6198.1 6248.2 6248.3 6255.8 6275.2 5772.9 5834.3 5934.9 5980.9 5990.2 6295.7 6307.6 6324.7 6345.4 6360.2 6205.8 6207.3 6215.4 6218.8 6244.8 6162.2 6173.4 6186.8 6261.9 6290.2 6224.9 6227.6 6248.7 6258.5 6269.8 6241.4 6251.1 6256.0 6266.2 6266.9 4845.0 4956.0 5001.3 5015.1 5058.6 4489.2 4517.6 4518.0 4538.0 4559.1 5861.6 5955.9 5974.3 5976.9 6000.6 6203.1 6216.3 6243.6 6274.2 6278.5 5903.3 5928.9 5947.6 5958.6 5974.3 5395.7 5446.6 5450.6 5505.9 5508.2 5418.6 6524.8 6535.1 6552.8 6574.0 6263.4 6287.6 6290.4 6301.7 6311.6 6300.2 6391.9 6415.4 6527.8 6555.0 6224.1 6249.4 6253.2 6266.2 6320.4 6146.8 6242.8 6265.3 6270.1 6281.2 6040.9 6137.5 6172.8 6236.9 6247.3 5136.5 5963.3 6076.8 6083.4 6102.7 5449.9 5845.2 5873.6 5928.1 5933.6 5249.7 5996.0 6078.0 6123.6 6209.5 6171.5 6194.0 6197.4 6200.8 6213.2 02:29:24 INFO - PROCESS | 1668 | 02:29:24 INFO - PROCESS | 1668 | [#1] ext.html Cycles:180 Average:64802.64 Median:20997.23 stddev:141629.62 (674.5%) stddev-sans-first:141944.74 02:29:24 INFO - PROCESS | 1668 | Values: 539.1 582.1 584.6 596.9 608.6 30008.4 38723.0 38743.6 38812.8 38816.8 40519.8 40877.6 40937.8 40961.4 41193.6 9380.5 9502.2 9571.8 9578.5 9594.1 9569.2 9780.0 9789.8 9797.1 9812.3 9742.0 9753.8 9763.4 9803.8 9841.5 9637.8 9680.3 9727.4 9798.9 9816.0 9606.8 9636.6 9717.6 9725.7 9740.0 39282.4 39303.6 39660.0 39738.0 39868.8 3263.9 3390.5 3584.7 3823.5 3825.3 23161.0 23583.8 28974.7 29028.9 29419.0 2738.0 2770.6 2816.9 2833.4 2891.2 389128.0 397214.0 401299.0 401509.0 401937.0 36979.2 38171.0 65791.0 66486.0 66765.0 37775.0 37823.2 37902.4 38049.6 38129.0 15148.0 15377.8 15414.6 15651.5 15714.7 13176.3 13274.2 13389.8 13481.9 13510.9 7412.2 8308.5 8496.4 8520.3 8530.3 3566.0 3596.3 3629.6 3630.5 3639.4 983.0 1014.7 1049.1 1058.0 1089.8 23446.8 23791.8 25418.2 26750.7 28156.7 1045.2 1048.7 1093.4 1093.4 1131.7 13132.6 13196.1 13329.7 16240.6 16254.3 526215.7 580944.0 609022.0 619666.0 622971.0 49833.0 61441.0 62506.0 62726.7 62808.1 1491.8 1506.2 1518.6 1520.1 1530.2 30527.0 30879.1 30890.4 31248.2 31365.8 52672.0 53198.7 53970.5 54039.7 54356.2 572967.0 573264.0 575701.0 576270.0 578105.0 26623.0 27774.4 43648.3 44697.3 45660.0 37486.6 43579.3 43776.1 46702.8 48580.0 34289.5 35778.9 36562.5 36659.0 37388.8 31551.0 35976.8 36054.9 36474.8 36598.3 32523.3 36017.0 37974.4 38268.4 39369.4 30850.0 31445.7 32020.0 32037.4 32093.7 24327.9 24509.5 24516.4 24932.6 24988.6 02:29:24 INFO - PROCESS | 1668 | 02:29:24 INFO - PROCESS | 1668 | [#2] jquery.html Cycles:180 Average:34220.89 Median:12970.83 stddev:101713.76 (784.2%) stddev-sans-first:101967.31 02:29:24 INFO - PROCESS | 1668 | Values: 353.6 398.1 402.9 404.0 407.1 15620.3 17160.5 19795.7 19824.4 20254.3 20531.9 20581.8 20897.0 21233.4 21433.7 5919.8 5963.9 6184.6 6191.3 6201.7 6057.8 6137.6 6161.5 6161.7 6167.4 4757.8 5661.4 5827.6 5870.1 6057.7 4978.3 5738.6 5762.6 5787.3 6507.5 4876.6 5793.1 5805.5 5827.2 5833.0 13072.7 13258.2 23670.9 24985.5 25091.9 2120.8 2138.3 2207.8 2270.8 2685.4 9411.4 9421.8 9888.9 9918.2 10102.5 1752.5 1776.9 1797.5 1822.0 1858.6 98448.0 104209.0 112240.0 112864.0 113388.4 11880.6 11927.4 11969.5 12028.2 12062.1 13415.3 14141.4 14615.0 14892.2 14914.7 8420.0 8623.9 8993.7 9013.7 9029.5 7379.3 7456.3 8993.2 9158.7 9181.7 4614.4 4623.5 4662.2 4717.2 4718.5 1952.4 1986.0 2004.2 2021.1 2030.1 760.9 837.5 952.3 982.5 1029.5 20808.1 21194.6 21220.2 21515.8 21559.9 998.6 998.8 1001.6 1019.9 1019.9 9505.9 10967.9 11393.4 11415.7 11458.7 596212.0 602387.0 616872.0 625515.0 625742.0 108587.0 113602.4 117656.2 121142.4 128132.4 1054.4 1058.3 1058.8 1079.8 1081.5 18346.6 18743.0 18874.3 18943.0 19191.0 39038.6 40535.6 49603.3 51738.0 54304.5 63777.4 67397.3 68619.3 69176.0 69700.7 5897.6 7945.7 8040.1 8075.0 8238.7 8816.8 11242.6 11625.4 11711.4 12613.2 10886.6 10905.2 11012.7 11138.7 11176.4 10757.0 10767.9 10780.2 10814.9 10998.4 9362.9 11057.0 11302.5 11347.6 11446.3 7744.2 8001.9 8022.4 8127.7 8178.8 9680.0 11141.4 11165.3 11227.8 11340.5 02:29:24 INFO - PROCESS | 1668 | 02:29:24 INFO - PROCESS | 1668 | [#3] mootools.html Cycles:180 Average:5809.46 Median:4900.05 stddev:3250.20 (66.3%) stddev-sans-first:3243.69 02:29:24 INFO - PROCESS | 1668 | Values: 1568.1 1612.8 1852.6 1915.8 2005.6 7982.4 8673.7 8802.0 9036.4 9049.5 8775.7 8841.0 8847.1 8881.8 8883.6 2400.8 2420.6 2434.7 2437.3 2442.4 2341.3 2419.9 2467.8 2468.7 2483.9 2445.1 2453.8 2454.1 2468.6 2481.3 2447.1 2449.5 2453.5 2458.1 2474.7 2450.1 2469.9 2470.6 2474.3 2476.2 9003.8 9042.6 9078.7 9084.1 9090.7 5443.9 5554.5 5564.7 5573.5 5603.2 3544.6 3637.4 3685.2 3695.5 3695.8 1044.1 1058.8 1061.9 1062.4 1063.9 12061.5 12137.8 12148.9 12151.0 12161.0 4763.2 4803.8 4855.3 4866.8 4892.2 10392.3 10417.3 10428.5 10437.6 10441.9 5505.4 5535.2 5543.5 5576.8 5614.7 3692.9 4447.0 4847.4 4907.9 4970.1 3505.6 4058.1 4086.0 4150.6 4168.4 1120.7 1140.9 1324.7 1326.0 1336.8 834.6 842.2 856.3 911.7 946.9 5931.6 5976.7 5997.1 6005.0 6058.4 932.1 936.3 937.0 985.5 1031.6 3948.8 4010.6 4019.4 4033.1 4042.6 10520.5 10987.2 11072.0 11076.9 11165.8 10866.2 10915.9 10929.6 10973.3 11100.8 8306.5 8394.9 8456.3 8559.0 8561.1 5848.2 5915.7 5937.1 5990.6 6073.6 9523.2 9680.6 9814.0 9908.8 9927.0 9211.0 9676.5 10012.9 10097.9 10140.3 6893.5 7313.1 7566.6 7582.0 7686.0 8338.4 8382.0 8387.9 8410.7 8420.7 7367.9 7482.0 7555.2 7556.3 7627.0 7302.9 7382.0 7394.7 7485.9 7497.8 7134.1 7186.1 7201.6 7211.1 7233.9 4767.0 4868.1 4877.8 4984.0 4998.3 4325.4 4932.1 4960.0 5050.5 5079.5 02:29:24 INFO - PROCESS | 1668 | 02:29:24 INFO - PROCESS | 1668 | [#4] prototype.html Cycles:180 Average:5815.55 Median:6684.80 stddev:1919.01 (28.7%) stddev-sans-first:1879.35 02:29:24 INFO - PROCESS | 1668 | Values: 308.7 322.0 326.4 326.7 333.3 6653.9 6687.7 6714.9 6724.3 6817.4 6664.4 6665.3 6683.6 6686.0 6712.3 4562.7 4617.8 4759.7 4799.1 4800.3 4735.4 4768.2 4841.8 4931.6 4943.1 3894.0 4800.8 4819.5 4824.4 4826.4 4788.7 4792.7 4808.4 4819.4 4822.4 3229.4 4119.8 4120.9 4124.5 4129.9 6186.1 6446.3 6468.0 6477.3 6482.3 5625.5 5672.6 5675.0 5687.7 5693.1 5962.8 7002.6 7104.7 7174.2 7178.2 4533.6 4536.7 4538.1 4543.9 4556.4 7531.0 7590.0 7631.9 7642.6 7649.1 4408.0 4565.2 4571.2 4586.1 4609.9 4645.8 4676.1 4678.0 4686.1 4709.8 5618.6 5619.7 5636.8 5640.9 5660.9 7272.4 7279.0 7309.3 7310.6 7311.0 1620.2 1635.5 1640.0 1641.7 1643.2 862.9 982.9 1016.7 1033.6 1034.7 5448.6 6700.9 6718.1 6725.6 6730.8 7224.3 7483.8 7512.4 7524.3 7545.8 6634.9 6815.1 6815.7 6836.4 6843.0 3913.4 3960.0 3966.8 3970.7 3977.9 7924.8 7928.2 7943.7 7950.6 7981.5 6408.3 7244.9 7849.8 7852.5 7859.4 7890.4 7900.7 7950.4 7966.6 7974.8 6086.9 7431.7 7534.7 7551.9 7559.6 7416.5 7436.1 7437.9 7446.6 7458.4 6329.7 6739.8 6761.2 7892.3 7911.8 4306.7 4609.4 4653.5 4669.1 4671.1 5726.0 7465.8 7573.1 7593.7 7601.1 5780.5 6862.4 7196.2 7248.7 7255.5 7024.9 7209.1 7245.8 7278.3 7317.4 6998.6 7052.5 7074.6 7103.1 7113.5 5796.7 7247.8 7254.8 7265.9 7288.7 7069.7 7108.3 7109.8 7111.8 7139.3 02:29:24 INFO - PROCESS | 1668 | 02:29:24 INFO - PROCESS | 1668 | [#5] yui.html Cycles:180 Average:18421.97 Median:3458.40 stddev:26063.04 (753.6%) stddev-sans-first:26101.88 02:29:24 INFO - PROCESS | 1668 | Values: 622.4 680.4 693.4 709.3 728.9 14967.0 14976.8 15025.5 15242.5 15289.8 14160.9 14172.7 14205.6 14211.2 14264.5 736.7 873.1 937.6 942.8 944.1 886.2 913.1 924.4 930.0 931.3 902.6 996.0 996.5 997.8 997.9 978.3 985.5 987.0 987.1 989.4 695.9 720.9 728.6 734.4 735.9 14714.6 14727.6 14896.9 14953.9 14981.9 12852.9 12968.3 13025.2 13168.3 13262.9 9916.6 10136.4 10172.6 10210.8 10282.6 4059.0 4081.6 4143.5 4218.7 4237.4 77967.2 78260.6 78665.6 79298.8 79409.0 8234.0 8925.7 9433.3 9525.6 9737.3 18822.2 20077.5 20965.7 21004.9 21267.9 6271.8 7442.0 7512.0 7580.4 7611.0 4036.3 4059.7 4157.9 4198.4 4222.4 2984.8 3090.2 3539.4 3585.8 3588.2 645.0 654.4 657.0 665.0 668.9 512.4 528.6 578.4 589.4 600.6 6589.8 8191.3 8394.4 8453.3 8534.8 506.3 571.3 593.7 622.6 627.4 3669.2 3876.9 3933.8 3965.4 4018.4 94920.0 95889.0 96430.0 96873.1 97003.1 75419.0 81007.6 82960.2 83356.6 84311.0 66403.0 70534.0 70706.0 71171.6 72125.0 8292.4 8503.4 8507.7 8518.2 8528.9 50435.7 50656.0 51445.5 51585.7 51756.5 53940.0 65783.0 68981.0 69612.3 70714.3 10798.7 10952.1 11045.5 11159.0 11224.4 12014.5 12093.9 12186.4 12324.1 12431.8 9224.2 11299.2 11318.5 11543.9 11598.3 11320.5 11337.1 11345.3 11606.8 11835.4 11063.9 11111.6 11168.3 11539.5 11760.2 9403.7 9647.0 9722.3 9825.1 9842.8 9443.4 9667.7 9686.8 9727.5 9776.1 02:29:24 INFO - PROCESS | 1668 | -------- Summary: end -------- 02:29:24 INFO - PROCESS | 1668 | 02:29:24 INFO - TEST-INFO | 1668: exit 0 02:29:24 INFO - TEST-OK | dromaeo_css | took 1111376ms 02:29:24 INFO - TEST-START | kraken 02:29:24 INFO - operating with platform_type : mac_ 02:29:24 INFO - Initialising browser for kraken test... 02:29:24 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/tmpn8uO9R/profile http://localhost:49276/getInfo.html) 02:29:27 INFO - PROCESS | 1675 | __metrics Screen width/height:1600/1200 02:29:27 INFO - PROCESS | 1675 | colorDepth:24 02:29:27 INFO - PROCESS | 1675 | Browser inner width/height: 1024/689 02:29:27 INFO - PROCESS | 1675 | __metrics 02:29:27 ERROR - PROCESS | 1675 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2931: TypeError: this.tabs is undefined 02:29:28 INFO - TEST-INFO | 1675: exit 0 02:29:28 INFO - Browser initialized. 02:29:28 INFO - Running cycle 1/1 for kraken test... 02:29:28 INFO - TEST-INFO | started process 1679 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/T/tmpn8uO9R/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:29:40 INFO - PROCESS | 1679 | RSS: Main: 220766208 02:29:40 INFO - PROCESS | 1679 | 02:29:42 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection) 02:29:43 INFO - PROCESS | 1679 | RSS: Main: 233222144 02:29:43 INFO - PROCESS | 1679 | 02:29:46 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-dft) 02:29:47 INFO - PROCESS | 1679 | RSS: Main: 242495488 02:29:47 INFO - PROCESS | 1679 | 02:29:50 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-fft) 02:29:51 INFO - PROCESS | 1679 | RSS: Main: 245436416 02:29:51 INFO - PROCESS | 1679 | 02:29:53 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator) 02:29:53 INFO - PROCESS | 1679 | RSS: Main: 246960128 02:29:53 INFO - PROCESS | 1679 | 02:29:55 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:29:56 INFO - PROCESS | 1679 | RSS: Main: 247394304 02:29:56 INFO - PROCESS | 1679 | 02:29:59 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom) 02:29:59 INFO - PROCESS | 1679 | RSS: Main: 247926784 02:29:59 INFO - PROCESS | 1679 | 02:30:02 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate) 02:30:03 INFO - PROCESS | 1679 | RSS: Main: 247943168 02:30:03 INFO - PROCESS | 1679 | 02:30:06 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial) 02:30:06 INFO - PROCESS | 1679 | RSS: Main: 248098816 02:30:06 INFO - PROCESS | 1679 | 02:30:08 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:30:08 INFO - PROCESS | 1679 | RSS: Main: 247558144 02:30:08 INFO - PROCESS | 1679 | 02:30:10 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:30:11 INFO - PROCESS | 1679 | RSS: Main: 247877632 02:30:11 INFO - PROCESS | 1679 | 02:30:13 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:30:14 INFO - PROCESS | 1679 | RSS: Main: 247877632 02:30:14 INFO - PROCESS | 1679 | 02:30:17 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:30:17 INFO - PROCESS | 1679 | RSS: Main: 248627200 02:30:17 INFO - PROCESS | 1679 | 02:30:20 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:30:21 INFO - PROCESS | 1679 | RSS: Main: 247857152 02:30:21 INFO - PROCESS | 1679 | 02:30:23 INFO - PROCESS | 1679 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49276/tests/kraken/driver.html?testName=ai-astar) 02:30:23 INFO - PROCESS | 1679 | RSS: Main: 249995264 02:30:23 INFO - PROCESS | 1679 | 02:30:23 INFO - PROCESS | 1679 | __start_tp_report 02:30:23 INFO - PROCESS | 1679 | _x_x_mozilla_page_load 02:30:23 INFO - PROCESS | 1679 | _x_x_mozilla_page_load_details 02:30:23 INFO - PROCESS | 1679 | |i|pagename|runs| 02:30:23 INFO - PROCESS | 1679 | |0;ai-astar;114;118;109;118;118;117;106;124;118;117 02:30:23 INFO - PROCESS | 1679 | |1;audio-beat-detection;151;125;141;163;134;177;135;150;166;135 02:30:23 INFO - PROCESS | 1679 | |2;audio-dft;196;199;225;225;198;198;233;276;200;197 02:30:23 INFO - PROCESS | 1679 | |3;audio-fft;65;75;72;138;57;57;57;67;143;50 02:30:23 INFO - PROCESS | 1679 | |4;audio-oscillator;81;84;133;77;78;89;121;80;83;122 02:30:23 INFO - PROCESS | 1679 | |5;imaging-gaussian-blur;89;86;88;86;90;86;89;102;134;87 02:30:23 INFO - PROCESS | 1679 | |6;imaging-darkroom;94;94;92;95;95;104;144;95;94;94 02:30:23 INFO - PROCESS | 1679 | |7;imaging-desaturate;92;94;89;89;99;90;88;80;138;83 02:30:23 INFO - PROCESS | 1679 | |8;json-parse-financial;63;62;66;67;67;66;67;81;65;60 02:30:23 INFO - PROCESS | 1679 | |9;json-stringify-tinderbox;69;66;76;73;79;75;70;67;66;68 02:30:23 INFO - PROCESS | 1679 | |10;stanford-crypto-aes;79;79;82;80;73;95;109;107;85;83 02:30:23 INFO - PROCESS | 1679 | |11;stanford-crypto-ccm;120;118;146;151;124;128;142;149;180;190 02:30:23 INFO - PROCESS | 1679 | |12;stanford-crypto-pbkdf2;195;195;155;204;218;212;168;168;167;179 02:30:23 INFO - PROCESS | 1679 | |13;stanford-crypto-sha256-iterative;65;69;67;70;71;65;68;69;92;71 02:30:23 INFO - PROCESS | 1679 | __end_tp_report 02:30:23 INFO - PROCESS | 1679 | __start_cc_report 02:30:23 INFO - PROCESS | 1679 | _x_x_mozilla_cycle_collect,1102 02:30:23 INFO - PROCESS | 1679 | __end_cc_report 02:30:23 INFO - PROCESS | 1679 | __startTimestamp1464600623342__endTimestamp 02:30:23 INFO - PROCESS | 1679 | 02:30:23 INFO - PROCESS | 1679 | ------- Summary: start ------- 02:30:23 INFO - PROCESS | 1679 | Number of tests: 14 02:30:23 INFO - PROCESS | 1679 | 02:30:23 INFO - PROCESS | 1679 | [#0] ai-astar Cycles:10 Average:115.90 Median:118.00 stddev:5.11 (4.3%) stddev-sans-first:5.37 02:30:23 INFO - PROCESS | 1679 | Values: 114.0 118.0 109.0 118.0 118.0 117.0 106.0 124.0 118.0 117.0 02:30:23 INFO - PROCESS | 1679 | 02:30:23 INFO - PROCESS | 1679 | [#1] audio-beat-detection Cycles:10 Average:147.70 Median:150.50 stddev:16.71 (11.1%) stddev-sans-first:17.68 02:30:23 INFO - PROCESS | 1679 | Values: 151.0 125.0 141.0 163.0 134.0 177.0 135.0 150.0 166.0 135.0 02:30:23 INFO - PROCESS | 1679 | 02:30:23 INFO - PROCESS | 1679 | [#2] audio-dft Cycles:10 Average:214.70 Median:212.50 stddev:25.79 (12.1%) stddev-sans-first:26.46 02:30:23 INFO - PROCESS | 1679 | Values: 196.0 199.0 225.0 225.0 198.0 198.0 233.0 276.0 200.0 197.0 02:30:23 INFO - PROCESS | 1679 | 02:30:23 INFO - PROCESS | 1679 | [#3] audio-fft Cycles:10 Average:78.10 Median:61.00 stddev:33.78 (55.4%) stddev-sans-first:35.49 02:30:23 INFO - PROCESS | 1679 | Values: 65.0 75.0 72.0 138.0 57.0 57.0 57.0 67.0 143.0 50.0 02:30:23 INFO - PROCESS | 1679 | 02:30:23 INFO - PROCESS | 1679 | [#4] audio-oscillator Cycles:10 Average:94.80 Median:80.50 stddev:21.56 (26.8%) stddev-sans-first:22.28 02:30:23 INFO - PROCESS | 1679 | Values: 81.0 84.0 133.0 77.0 78.0 89.0 121.0 80.0 83.0 122.0 02:30:23 INFO - PROCESS | 1679 | 02:30:23 INFO - PROCESS | 1679 | [#5] imaging-gaussian-blur Cycles:10 Average:93.70 Median:87.50 stddev:14.93 (17.1%) stddev-sans-first:15.74 02:30:23 INFO - PROCESS | 1679 | Values: 89.0 86.0 88.0 86.0 90.0 86.0 89.0 102.0 134.0 87.0 02:30:23 INFO - PROCESS | 1679 | 02:30:23 INFO - PROCESS | 1679 | [#6] imaging-darkroom Cycles:10 Average:100.10 Median:94.00 stddev:15.76 (16.8%) stddev-sans-first:16.56 02:30:23 INFO - PROCESS | 1679 | Values: 94.0 94.0 92.0 95.0 95.0 104.0 144.0 95.0 94.0 94.0 02:30:23 INFO - PROCESS | 1679 | 02:30:23 INFO - PROCESS | 1679 | [#7] imaging-desaturate Cycles:10 Average:94.20 Median:89.50 stddev:16.27 (18.2%) stddev-sans-first:17.24 02:30:23 INFO - PROCESS | 1679 | Values: 92.0 94.0 89.0 89.0 99.0 90.0 88.0 80.0 138.0 83.0 02:30:23 INFO - PROCESS | 1679 | 02:30:23 INFO - PROCESS | 1679 | [#8] json-parse-financial Cycles:10 Average:66.40 Median:66.50 stddev:5.66 (8.5%) stddev-sans-first:5.87 02:30:23 INFO - PROCESS | 1679 | Values: 63.0 62.0 66.0 67.0 67.0 66.0 67.0 81.0 65.0 60.0 02:30:23 INFO - PROCESS | 1679 | 02:30:23 INFO - PROCESS | 1679 | [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:71.50 stddev:4.58 (6.4%) stddev-sans-first:4.81 02:30:23 INFO - PROCESS | 1679 | Values: 69.0 66.0 76.0 73.0 79.0 75.0 70.0 67.0 66.0 68.0 02:30:23 INFO - PROCESS | 1679 | 02:30:23 INFO - PROCESS | 1679 | [#10] stanford-crypto-aes Cycles:10 Average:87.20 Median:81.00 stddev:12.32 (15.2%) stddev-sans-first:12.70 02:30:23 INFO - PROCESS | 1679 | Values: 79.0 79.0 82.0 80.0 73.0 95.0 109.0 107.0 85.0 83.0 02:30:23 INFO - PROCESS | 1679 | 02:30:23 INFO - PROCESS | 1679 | [#11] stanford-crypto-ccm Cycles:10 Average:144.80 Median:147.50 stddev:24.48 (16.6%) stddev-sans-first:24.27 02:30:23 INFO - PROCESS | 1679 | Values: 120.0 118.0 146.0 151.0 124.0 128.0 142.0 149.0 180.0 190.0 02:30:23 INFO - PROCESS | 1679 | 02:30:23 INFO - PROCESS | 1679 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.10 Median:195.00 stddev:21.62 (11.1%) stddev-sans-first:22.69 02:30:23 INFO - PROCESS | 1679 | Values: 195.0 195.0 155.0 204.0 218.0 212.0 168.0 168.0 167.0 179.0 02:30:23 INFO - PROCESS | 1679 | 02:30:23 INFO - PROCESS | 1679 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.70 Median:69.50 stddev:7.79 (11.2%) stddev-sans-first:7.98 02:30:23 INFO - PROCESS | 1679 | Values: 65.0 69.0 67.0 70.0 71.0 65.0 68.0 69.0 92.0 71.0 02:30:23 INFO - PROCESS | 1679 | -------- Summary: end -------- 02:30:23 INFO - PROCESS | 1679 | 02:30:24 INFO - TEST-INFO | 1679: exit 0 02:30:24 INFO - TEST-OK | kraken | took 59372ms 02:30:24 INFO - SUITE-END | took 1170s 02:30:24 INFO - Completed test suite (00:19:31) 02:30:24 INFO - javascript benchmark 02:30:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3232.5312829639715, 3339.5825521809775, 3429.365567370037, 3524.347995620809, 3565.1800085980226, 5844.035734103868, 5847.5614328925285, 5886.264216972879, 5903.88192236155, 5919.49684276837, 5043.974780126104, 5721.6853073080965, 5868.295804503455, 5901.085331773571, 5901.38035506271, 4765.0, 5051.570616497584, 5309.283246761676, 5315.521603055715, 5339.092354299778, 5321.93561287744, 5388.865278368033, 5431.429699881515, 5465.754041068161, 5466.753996070186, 4817.771468275585, 4999.550040496355, 5382.058139825514, 5385.838424847241, 5400.945990540104, 5337.572994160477, 5364.758585863646, 5371.221172929922, 5386.515213630752, 5389.299391079174, 5242.16125419931, 5294.941011797656, 5311.601629877775, 5319.28189694394, 5325.573954083703, 5788.160716696056, 5813.128030795415, 5852.326982397036, 5868.97065514674, 6038.396160383971, 5361.731913404353, 5737.082066869322, 6235.594686345418, 6246.094316324107, 6252.499800015988, 6198.101275315054, 6248.218972628421, 6248.343923887972, 6255.812325630239, 6275.246970363585, 5772.913406298909, 5834.2998840139035, 5934.940650593525, 5980.910286345708, 5990.191324171225, 6295.685215739195, 6307.590006649553, 6324.652144132025, 6345.397187267202, 6360.236771587439, 6205.751769929243, 6207.317195108534, 6215.440610345, 6218.844528886814, 6244.843878903063, 6162.168107305546, 6173.351798061229, 6186.783462578788, 6261.8747625047245, 6290.182276304051, 6224.937750622526, 6227.564070515111, 6248.656323902138, 6258.530610204162, 6269.78055768055, 6241.375862413723, 6251.093591429218, 6255.96872015637, 6266.154069200599, 6266.874662506815, 4845.030993801254, 4956.0, 5001.299818025479, 5015.097282489114, 5058.645894787403, 4489.169503641837, 4517.616002639812, 4517.977410112994, 4538.0, 4559.088182363541, 5861.6189947653265, 5955.910661340084, 5974.312954010257, 5976.910346344808, 6000.5799594028, 6203.100550420164, 6216.2540495140875, 6243.594166379171, 6274.1843560336865, 6278.497720182466, 5903.291605007426, 5928.9407105928385, 5947.5539334550085, 5958.612690175125, 5974.2830860297045, 5395.70323632204, 5446.618736688394, 5450.591205659592, 5505.944940550623, 5508.20131080991, 5418.593605479605, 6524.836879078061, 6535.117759102555, 6552.770653027121, 6573.9810329399015, 6263.3736626337, 6287.591306565058, 6290.433860952537, 6301.747930082745, 6311.558190926775, 6300.180976473029, 6391.872162556816, 6415.422611964946, 6527.836804079936, 6555.0, 6224.097505861715, 6249.375062493714, 6253.218347706536, 6266.185395898504, 6320.399562041599, 6146.754129834935, 6242.843928901813, 6265.342139075331, 6270.090836828543, 6281.246250449975, 6040.909386359296, 6137.5302083125, 6172.845678858064, 6236.937630623635, 6247.312795592572, 5136.512031357015, 5963.254593175853, 6076.756929722762, 6083.391660833881, 6102.694865256631, 5449.891002180014, 5845.152452894392, 5873.588848780544, 5928.081147422156, 5933.614315069679, 5249.658772179796, 5996.0, 6077.969610151921, 6123.601965872204, 6209.503239740719, 6171.537134715004, 6194.008958566607, 6197.442230199304, 6200.81397558074, 6213.1922850029205], "value": 5757.375857598984, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [539.0754855422949, 582.126809785322, 584.6316820403146, 596.9492593129585, 608.6317777744465, 30008.399832003346, 38723.0, 38743.612563874354, 38812.80593597032, 38816.80591597045, 40519.79740101303, 40877.591224087824, 40937.79531102348, 40961.38557921634, 41193.58806411935, 9380.484073375945, 9502.239820814319, 9571.760705982337, 9578.521073946275, 9594.136527712504, 9569.186619137381, 9779.951100244489, 9789.804203915917, 9797.069278418574, 9812.313138080337, 9741.951290243538, 9753.804923901518, 9763.414195148314, 9803.754906127333, 9841.45871977041, 9637.807243855119, 9680.322377433582, 9727.416355018686, 9798.853017204749, 9816.0, 9606.75983100421, 9636.566354514063, 9717.56270967808, 9725.708228753148, 9739.977302383219, 39282.41076383857, 39303.60696393028, 39660.0, 39738.006549836195, 39868.80393588197, 3263.918402039968, 3390.4914262860766, 3584.6773790358734, 3823.4647149399107, 3825.3114439400897, 23161.0, 23583.76416235833, 28974.710252897414, 29028.854855725585, 29419.0, 2737.9589306160424, 2770.570561562941, 2816.9295767605972, 2833.3908209735005, 2891.2482754483985, 389128.0, 397214.0, 401298.9999999971, 401509.0, 401937.0, 36979.15104244777, 38171.0, 65791.0, 66486.0, 66765.0, 37775.0, 37823.24353512914, 37902.43146352807, 38049.61950380516, 38129.04677383032, 15148.0, 15377.846221537642, 15414.614634634, 15651.530454086396, 15714.68570628581, 13176.275304858325, 13274.203547787163, 13389.799153012713, 13481.932590336986, 13510.919126469858, 7412.221716719775, 8308.459950103224, 8496.40525163245, 8520.318374530023, 8530.317574594017, 3566.0401866450784, 3596.3166998270244, 3629.564452265745, 3630.49173115764, 3639.3631114554846, 983.0366241083635, 1014.6955913226003, 1049.0768124050787, 1058.016219588272, 1089.836524521328, 23446.827658617, 23791.762082378955, 25418.237452876074, 26750.73249267521, 28156.71843281541, 1045.1795340657548, 1048.7220886465093, 1093.3822390349585, 1093.4150229627112, 1131.6944425005202, 13132.606021819362, 13196.076274660682, 13329.66675833112, 16240.59398515023, 16254.268557914686, 526215.7378426243, 580944.0, 609022.0, 619666.0, 622971.0, 49833.0, 61441.0, 62506.0, 62726.686366567876, 62808.05787913094, 1491.7613181890845, 1506.246876561719, 1518.5596177108514, 1520.1259275916393, 1530.1584128729155, 30527.0, 30879.073627791204, 30890.382192356476, 31248.21879452941, 31365.843170784, 52672.0, 53198.73400632972, 53970.460295398116, 54039.72980135232, 54356.184657230966, 572967.0, 573264.0, 575701.0, 576270.0, 578105.0, 26623.0, 27774.44451111007, 43648.34527482027, 44697.32954005757, 45660.0, 37486.62513374831, 43579.34630980474, 43776.12447751091, 46702.766486167355, 48580.0, 34289.48565771466, 35778.92663220107, 36562.45156322604, 36659.0, 37388.813055934545, 31551.0, 35976.8201159003, 36054.918352449466, 36474.817625912765, 36598.26803463969, 32523.349533008735, 36017.0, 37974.43038354482, 38268.42597360986, 39369.40945885871, 30850.0, 31445.685543144275, 32020.039398818073, 32037.35925281528, 32093.679063210002, 24327.905244264406, 24509.50980980406, 24516.387090322885, 24932.62601061022, 24988.62517062209], "value": 18630.28907748125, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.61985865194913, 398.0645483114679, 402.8771224776442, 403.9757614543126, 407.0617227291095, 15620.2970866311, 17160.485185444442, 19795.70306445403, 19824.40526784197, 20254.29109981151, 20531.897340513315, 20581.794182058176, 20896.955152242364, 21233.36299911005, 21433.67849482259, 5919.822405327836, 5963.910541341883, 6184.56708030438, 6191.288001879797, 6201.66664167204, 6057.757689692407, 6137.570370074097, 6161.537884658646, 6161.691915404234, 6167.444929956303, 4757.785899634508, 5661.433856614347, 5827.621204621707, 5870.090136028901, 6057.697115144248, 4978.253262010728, 5738.569607279471, 5762.596618236725, 5787.305523337205, 6507.544471886969, 4876.634252431047, 5793.102069179284, 5805.535557155417, 5827.24245848037, 5832.9708351457975, 13072.673183170402, 13258.204507729568, 23670.881645591835, 24985.50028999428, 25091.874540627363, 2120.756113047004, 2138.283674968887, 2207.834412419059, 2270.7729227077325, 2685.4092099738027, 9411.43531388119, 9421.76445588859, 9888.90111098887, 9918.156956658693, 10102.494875256281, 1752.50053734685, 1776.8933863968202, 1797.4877160009335, 1821.9635607287912, 1858.5539470526976, 98448.0, 104209.0, 112240.0, 112864.0, 113388.43305783418, 11880.643580692593, 11927.403629818647, 11969.46137423818, 12028.158028938065, 12062.095342849147, 13415.262160581262, 14141.43434262618, 14614.976951613498, 14892.180930048951, 14914.70170596582, 8420.031696354876, 8623.870641940375, 8993.70818732071, 9013.729588112366, 9029.503377314311, 7379.262073792685, 7456.32893039629, 8993.23557497607, 9158.679446219483, 9181.72454826343, 4614.353990441341, 4623.53764623535, 4662.230731929261, 4717.198076327033, 4718.457377401574, 1952.4142757172792, 1985.9900700496694, 2004.168270167864, 2021.090509270834, 2030.106753028692, 760.8896709977022, 837.4891316297054, 952.2953014769021, 982.5283863745442, 1029.505837198149, 20808.063637136365, 21194.576108477744, 21220.151193952068, 21515.78484215169, 21559.892200538896, 998.6454808543008, 998.7553049502918, 1001.6193846338344, 1019.898010198989, 1019.90820826126, 9505.904940950502, 10967.890321096846, 11393.373364465035, 11415.657530274106, 11458.656240312805, 596212.0, 602387.0, 616872.0, 625515.0, 625742.0, 108587.0, 113602.43198783953, 117656.24533725773, 121142.394288028, 128132.35933820272, 1054.4200689620677, 1058.30680904008, 1058.7988282226506, 1079.8002369561657, 1081.4862940103544, 18346.633067338313, 18742.969136697066, 18874.339398121, 18943.0, 19191.0, 39038.6096139035, 40535.594644053184, 49603.25595116148, 51738.0, 54304.456955431524, 63777.362226378995, 67397.32602674107, 68619.31380686129, 69176.0, 69700.6514967422, 5897.616654917417, 7945.682172713026, 8040.075391329838, 8075.030996280484, 8238.711645092393, 8816.823663526822, 11242.606508772154, 11625.418729063682, 11711.414429278673, 12613.243205407705, 10886.61896833607, 10905.236633435583, 11012.724681882697, 11138.665840024812, 11176.385298808644, 10757.031867131996, 10767.892321077003, 10780.191485638452, 10814.891851081704, 10998.395088269903, 9362.906370936204, 11057.0, 11302.4913878874, 11347.602833901105, 11446.255993360404, 7744.225577442211, 8001.879971800544, 8022.358211343195, 8127.674893004214, 8178.7955301117945, 9680.0, 11141.387223702774, 11165.330080195214, 11227.775444491228, 11340.546378144782], "value": 9392.575486804619, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1568.129688023147, 1612.7661489084087, 1852.6294741051784, 1915.7796853361858, 2005.6490114230005, 7982.441229113964, 8673.653053877853, 8802.031776504587, 9036.367454278203, 9049.547522623867, 8775.692850750225, 8840.95579522103, 8847.071057538962, 8881.822363552725, 8883.64465421384, 2400.8319417640773, 2420.5884999550076, 2434.6956630421196, 2437.2566367257978, 2442.4016116051594, 2341.2507997440825, 2419.9153029643967, 2467.753224677536, 2468.666729991446, 2483.863387513686, 2445.0586524188225, 2453.7546245375497, 2454.128784281581, 2468.5803413419762, 2481.255623313008, 2447.119037146626, 2449.5223431430877, 2453.521563295149, 2458.1273647854932, 2474.74015228402, 2450.088036050024, 2469.9012039518398, 2470.5923522618928, 2474.307193985687, 2476.2447453526665, 9003.819923601492, 9042.593083311202, 9078.682246121354, 9084.091590840862, 9090.681826136053, 5443.863903402407, 5554.472325129106, 5564.693941833198, 5573.498385145316, 5603.159526071081, 3544.6278140795102, 3637.3816451203093, 3685.1708365617787, 3695.464157697119, 3695.7597756145765, 1044.0864243786687, 1058.7564860082225, 1061.920355973305, 1062.3678911047914, 1063.9414832184227, 12061.517539298418, 12137.757244855053, 12148.939255303756, 12150.96716779066, 12161.0, 4763.190257656207, 4803.807129388516, 4855.344528488679, 4866.805327786919, 4892.168331383673, 10392.324498907547, 10417.322874013165, 10428.478576071166, 10437.634682786065, 10441.947790261, 5505.421930697299, 5535.197396377503, 5543.501084902378, 5576.832695019156, 5614.747336369873, 3692.9105913755507, 4446.977765111218, 4847.394075740533, 4907.9263811042865, 4970.130227210224, 3505.5968563615, 4058.066644671742, 4085.9857342739497, 4150.5641907599875, 4168.43726096973, 1120.7310245541175, 1140.9087273018138, 1324.7019420630475, 1326.0, 1336.7593833110134, 834.5910503853069, 842.1704620948361, 856.2550580994574, 911.7447114807865, 946.8816397950256, 5931.6441013539325, 5976.73104710289, 5997.100434934795, 6005.0, 6058.4244496772735, 932.137772560379, 936.3305236755753, 936.9765755856158, 985.5318723606373, 1031.5770534080991, 3948.8223029963146, 4010.63904248619, 4019.437278780973, 4033.1127152026506, 4042.5755295694116, 10520.473976301155, 10987.175961802897, 11072.003519683107, 11076.889231107585, 11165.83251251232, 10866.185036122322, 10915.890841091647, 10929.56281748721, 10973.341599504056, 11100.833487497694, 8306.460080094774, 8394.916050839536, 8456.32349412058, 8559.015713192937, 8561.14388561139, 5848.239728835224, 5915.733791979457, 5937.139114828412, 5990.64056156632, 6073.635581864925, 9523.23814094857, 9680.612775489184, 9814.0, 9908.801823963337, 9926.957669444893, 9211.032841551541, 9676.516174191122, 10012.948640392653, 10097.949510252402, 10140.29017968735, 6893.482988775962, 7313.085864266966, 7566.583837888969, 7581.962090189514, 7686.000819893378, 8338.416310858182, 8381.95809020951, 8387.874181887399, 8410.705625303084, 8420.663173472993, 7367.852642947004, 7482.0, 7555.206703296075, 7556.319931206218, 7626.9618651906385, 7302.890456643261, 7381.963090184515, 7394.667239974106, 7485.887711684438, 7497.812554686177, 7134.072570565793, 7186.10173728284, 7201.567905925661, 7211.062561866924, 7233.891491627735, 4767.0, 4868.099401610713, 4877.780499877585, 4983.978284451751, 4998.325226094431, 4325.351197320427, 4932.1122198004705, 4959.975200123977, 5050.520200580939, 5079.5428411443145], "value": 4674.7528779965905, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.6527656386565, 322.0451361712522, 326.40431213036214, 326.73697673372953, 333.3233535922076, 6653.866922661544, 6687.69905354259, 6714.899276510856, 6724.260331363552, 6817.386435220829, 6664.433523150526, 6665.333466653332, 6683.632400217986, 6685.966570167155, 6712.261651218374, 4562.680612357128, 4617.792199351037, 4759.738214398207, 4799.088173247089, 4800.255960326155, 4735.408073990751, 4768.23708206687, 4841.8063277468855, 4931.605471562267, 4943.1102401567705, 3893.968098453912, 4800.8319708810195, 4819.493953134923, 4824.4210694716685, 4826.420829500465, 4788.712677239377, 4792.688475249115, 4808.447028591704, 4819.373481447425, 4822.397200349957, 3229.3864165808423, 4119.814608342632, 4120.938185927213, 4124.52567954686, 4129.876103716893, 6186.133941248228, 6446.290908000116, 6468.0, 6477.255115661712, 6482.254540727829, 5625.46558076982, 5672.574556908207, 5674.9501342251815, 5687.744051517691, 5693.089105743103, 5962.77763058572, 7002.579845209304, 7104.68028938699, 7174.210836807947, 7178.246284140195, 4533.569310915458, 4536.705114167602, 4538.092381523708, 4543.932175938652, 4556.430446194226, 7531.0, 7589.962050189769, 7631.923680763177, 7642.579658118857, 7649.120351159577, 4408.0, 4565.246734288871, 4571.1543364477675, 4586.128635559233, 4609.884752881205, 4645.837395691135, 4676.13491504066, 4678.0, 4686.133065382915, 4709.835155769531, 5618.634788738719, 5619.7471113799975, 5636.802711905064, 5640.915386269209, 5660.943390566042, 7272.418206543463, 7279.0, 7309.342159205697, 7310.634468276565, 7311.049563556704, 1620.2222932992229, 1635.5256975477216, 1640.015990405747, 1641.7455294429144, 1643.2112585958807, 862.9482231066156, 982.945937973404, 1016.7305663999047, 1033.6433930294036, 1034.6792494326783, 5448.564114870801, 6700.865982680319, 6718.126643536309, 6725.596464212065, 6730.798076057628, 7224.349808517259, 7483.775486735407, 7512.39900807934, 7524.322810946932, 7545.811354716176, 6634.86730265392, 6815.079964204769, 6815.693293801791, 6836.350546698056, 6843.0, 3913.3934240192816, 3960.0, 3966.8016599170505, 3970.68234541236, 3977.880663580097, 7924.762257132295, 7928.207179282025, 7943.7219697311475, 7950.6422211000645, 7981.48120372174, 6408.295087540367, 7244.877044058179, 7849.803754906059, 7852.528848269122, 7859.449838511364, 7890.368770498461, 7900.683972641145, 7950.443468957234, 7966.561839098905, 7974.760757177294, 6086.878262434815, 7431.665575049244, 7534.736284230025, 7551.886721699069, 7559.622018898923, 7416.480846340704, 7436.144843343085, 7437.925620743723, 7446.553206807609, 7458.366038886756, 6329.683515824282, 6739.831504212434, 6761.188657361148, 7892.289693927574, 7911.84176316482, 4306.676999225008, 4609.423822022247, 4653.488116307136, 4669.112868554964, 4671.135839869635, 5726.0, 7465.776026719207, 7573.129090154703, 7593.696252150073, 7601.125870524739, 5780.508656764222, 6862.382385585322, 7196.244394338729, 7248.746293879689, 7255.4921155518605, 7024.8595028098125, 7209.0988626421695, 7245.818854528679, 7278.272172782679, 7317.414606831334, 6998.6150761708595, 7052.471064670068, 7074.646267686698, 7103.1476222851525, 7113.502054856111, 5796.652200867961, 7247.782566523013, 7254.782356529313, 7265.854682906418, 7288.708451662086, 7069.717211311696, 7108.253633368422, 7109.751158709627, 7111.82220444493, 7139.32176443237], "value": 5167.185928480448, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [622.3776223776223, 680.4420375292259, 693.3794254142542, 709.2552819539488, 728.8578727148207, 14967.027143235695, 14976.775348369756, 15025.474108406208, 15242.466513672023, 15289.847101528982, 14160.858391416083, 14172.71654566908, 14205.644858878533, 14211.21838298893, 14264.500742474016, 736.675862620448, 873.1094283830489, 937.6483818568037, 942.8302905477011, 944.0653752784729, 886.2068448738376, 913.1325241021024, 924.36219008884, 929.9814003719922, 931.3201363005011, 902.6479672927563, 995.9850602240972, 996.5415908681979, 997.8004399120168, 997.9451130187837, 978.3249557805092, 985.4530735441826, 987.0031268418949, 987.0770829274616, 989.4113002763344, 695.9060526828864, 720.8630360231539, 728.6429649471773, 734.3904559215838, 735.8932954721589, 14714.55856324312, 14727.631809204748, 14896.925515372353, 14953.850461495354, 14981.925090374587, 12852.935735321356, 12968.286744229064, 13025.218486890817, 13168.341582920912, 13262.93368533151, 9916.603335866557, 10136.391816491034, 10172.59309627614, 10210.846837297446, 10282.588696452132, 4059.04612416082, 4081.551029386765, 4143.523494798107, 4218.725782824106, 4237.385579091015, 77967.22032779656, 78260.60869695614, 78665.60667196685, 79298.8105178417, 79409.0, 8233.970753655793, 8925.687600933936, 9433.292503062297, 9525.5713492893, 9737.318387712792, 18822.247110115717, 20077.49806254826, 20965.68551471729, 21004.894975525025, 21267.893660531598, 6271.811845644638, 7441.962790186014, 7511.985881905982, 7580.393568514505, 7611.0, 4036.253293140778, 4059.6752259819145, 4157.91684166315, 4198.391233271159, 4222.387753775685, 2984.820910745362, 3090.1656552730637, 3539.3983022886173, 3585.7848529088337, 3588.174719814457, 644.9916399160522, 654.4404534123352, 657.0374401501823, 664.9634270115093, 668.8996650502366, 512.4184051102026, 528.5665754081616, 578.3828177496861, 589.3517131155694, 600.570592026703, 6589.769358072444, 8191.303739182236, 8394.412391132562, 8453.281471074904, 8534.830811814581, 506.2987761949653, 571.2516603249757, 593.664579512574, 622.5797586629008, 627.3600927054379, 3669.192777588926, 3876.8643097491454, 3933.8426462941734, 3965.4448377227213, 4018.3972404139026, 94920.0, 95889.0, 96430.0, 96873.06253874883, 97003.05993880224, 75419.0, 81007.59496202599, 82960.17039829525, 83356.58321708352, 84311.0, 66403.0, 70534.0, 70706.0, 71171.64414177896, 72125.0, 8292.419530633038, 8503.447275927045, 8507.702230421906, 8518.190771876662, 8528.872066918877, 50435.74782126066, 50656.0, 51445.48554514407, 51585.7420712894, 51756.48243517517, 53940.0, 65783.0, 68981.0, 69612.30387696058, 70714.29285707283, 10798.73003174927, 10952.082774249171, 11045.502952367324, 11159.0, 11224.38265895351, 12014.459349329127, 12093.939530302292, 12186.390680466118, 12324.075694322783, 12431.813522796985, 9224.215941644767, 11299.152563557931, 11318.4906679198, 11543.942280288546, 11598.304101753922, 11320.547178112784, 11337.149713771665, 11345.319280843176, 11606.75316430589, 11835.408229588314, 11063.944680276547, 11111.611093611686, 11168.274062185932, 11539.4807233673, 11760.235584686969, 9403.670871519464, 9647.0, 9722.270829687943, 9825.066618671215, 9842.803143936939, 9443.386179898285, 9667.70996870095, 9686.75783105428, 9727.513624318613, 9776.071273229316], "value": 6941.68171333338, "unit": "score"}], "name": "dromaeo_css", "value": 7435.155499603721, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 118.0, 109.0, 118.0, 118.0, 117.0, 106.0, 124.0, 118.0, 117.0], "value": 115.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 125.0, 141.0, 163.0, 134.0, 177.0, 135.0, 150.0, 166.0, 135.0], "value": 147.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 199.0, 225.0, 225.0, 198.0, 198.0, 233.0, 276.0, 200.0, 197.0], "value": 214.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 75.0, 72.0, 138.0, 57.0, 57.0, 57.0, 67.0, 143.0, 50.0], "value": 78.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 84.0, 133.0, 77.0, 78.0, 89.0, 121.0, 80.0, 83.0, 122.0], "value": 94.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 88.0, 86.0, 90.0, 86.0, 89.0, 102.0, 134.0, 87.0], "value": 93.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 92.0, 95.0, 95.0, 104.0, 144.0, 95.0, 94.0, 94.0], "value": 100.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 94.0, 89.0, 89.0, 99.0, 90.0, 88.0, 80.0, 138.0, 83.0], "value": 94.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 66.0, 67.0, 67.0, 66.0, 67.0, 81.0, 65.0, 60.0], "value": 66.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 66.0, 76.0, 73.0, 79.0, 75.0, 70.0, 67.0, 66.0, 68.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 82.0, 80.0, 73.0, 95.0, 109.0, 107.0, 85.0, 83.0], "value": 87.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 118.0, 146.0, 151.0, 124.0, 128.0, 142.0, 149.0, 180.0, 190.0], "value": 144.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 195.0, 155.0, 204.0, 218.0, 212.0, 168.0, 168.0, 167.0, 179.0], "value": 186.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 69.0, 67.0, 70.0, 71.0, 65.0, 68.0, 69.0, 92.0, 71.0], "value": 70.7, "unit": "score"}], "name": "kraken", "value": 1565.3, "alertThreshold": 2.0}]} 02:30:24 INFO - Return code: 0 02:30:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 02:30:24 ERROR - # TBPL SUCCESS # 02:30:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:30:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:30:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:30:24 INFO - 2016-05-30 02:30:24.632 screenresolution[1685:10534] starting screenresolution argv=screenresolution get 02:30:24 INFO - 2016-05-30 02:30:24.634 screenresolution[1685:10534] Display 0: 1600x1200x32@60 02:30:24 INFO - 2016-05-30 02:30:24.640 screenresolution[1686:10537] starting screenresolution argv=screenresolution list 02:30:24 INFO - Available Modes on Display 0 02:30:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:30:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:30:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:30:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:30:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:30:24 INFO - Intel Iris: 02:30:24 INFO - Chipset Model: Intel Iris 02:30:24 INFO - Type: GPU 02:30:24 INFO - Bus: Built-In 02:30:24 INFO - VRAM (Dynamic, Max): 1536 MB 02:30:24 INFO - Vendor: Intel (0x8086) 02:30:24 INFO - Device ID: 0x0a2e 02:30:24 INFO - Revision ID: 0x0009 02:30:24 INFO - Displays: 02:30:24 INFO - Display: 02:30:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:30:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:30:24 INFO - Display Serial Number: 1600x1200 60 02:30:24 INFO - Main Display: Yes 02:30:24 INFO - Mirror: Off 02:30:24 INFO - Online: Yes 02:30:24 INFO - Rotation: Supported 02:30:24 INFO - Return code: 0 02:30:24 INFO - Running post-action listener: _resource_record_post_action 02:30:24 INFO - Running post-run listener: _resource_record_post_run 02:30:25 INFO - Total resource usage - Wall time: 1204s; CPU: 53.0%; Read bytes: 31534592; Write bytes: 653984256; Read time: 197; Write time: 1812 02:30:25 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 342665728; Write bytes: 336545280; Read time: 26556; Write time: 665 02:30:25 INFO - run-tests - Wall time: 1173s; CPU: 53.0%; Read bytes: 30600704; Write bytes: 298101760; Read time: 186; Write time: 1090 02:30:25 INFO - Running post-run listener: _upload_blobber_files 02:30:25 INFO - Blob upload gear active. 02:30:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:30:25 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 02:30:25 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'] 02:30:25 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 02:30:26 INFO - (blobuploader) - INFO - Open directory for files ... 02:30:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 02:30:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:30:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:30:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 02:30:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:30:27 INFO - (blobuploader) - INFO - Done attempting. 02:30:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 02:30:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:30:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:30:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 02:30:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:30:28 INFO - (blobuploader) - INFO - Done attempting. 02:30:28 INFO - (blobuploader) - INFO - Iteration through files over. 02:30:28 INFO - Return code: 0 02:30:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:30:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:30:28 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f58be6a1c9d0d3357045d5872698528938ebc11ad0625df3803f331980369951971d82ec3cc739a407c410bc9bad5f52ab02d6e24c7cccb02310454141bdafc4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1bfa50c66ad6f34e1a93702494aebf78b18427aaa718968bf3580465ad134dde4517ae36ac232aaf91bd3f794573fcccca687cade2e93634b90c56912d278bc6"} 02:30:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:30:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:30:28 INFO - Contents: 02:30:28 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f58be6a1c9d0d3357045d5872698528938ebc11ad0625df3803f331980369951971d82ec3cc739a407c410bc9bad5f52ab02d6e24c7cccb02310454141bdafc4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1bfa50c66ad6f34e1a93702494aebf78b18427aaa718968bf3580465ad134dde4517ae36ac232aaf91bd3f794573fcccca687cade2e93634b90c56912d278bc6"} 02:30:28 INFO - Running post-run listener: copy_logs_to_upload_dir 02:30:28 INFO - Copying logs to upload dir... 02:30:28 INFO - mkdir: /builds/slave/test/build/upload/logs 02:30:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1335.190256 ========= master_lag: 0.13 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 15 secs) (at 2016-05-30 02:30:28.591289) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 02:30:28.594946) ========= 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.gncsWFv3CF/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.YuMZICUosS/Listeners TMPDIR=/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/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/f58be6a1c9d0d3357045d5872698528938ebc11ad0625df3803f331980369951971d82ec3cc739a407c410bc9bad5f52ab02d6e24c7cccb02310454141bdafc4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1bfa50c66ad6f34e1a93702494aebf78b18427aaa718968bf3580465ad134dde4517ae36ac232aaf91bd3f794573fcccca687cade2e93634b90c56912d278bc6"} build_url:https://queue.taskcluster.net/v1/task/ckszqfuhRsym8v0GKEg5Lg/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011218 build_url: 'https://queue.taskcluster.net/v1/task/ckszqfuhRsym8v0GKEg5Lg/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/f58be6a1c9d0d3357045d5872698528938ebc11ad0625df3803f331980369951971d82ec3cc739a407c410bc9bad5f52ab02d6e24c7cccb02310454141bdafc4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1bfa50c66ad6f34e1a93702494aebf78b18427aaa718968bf3580465ad134dde4517ae36ac232aaf91bd3f794573fcccca687cade2e93634b90c56912d278bc6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 02:30:28.629768) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 02:30:28.630319) ========= 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.gncsWFv3CF/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.YuMZICUosS/Listeners TMPDIR=/var/folders/y6/pkql209j29ggzzn7z5wkq_pm00000w/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.004963 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 02:30:28.681275) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 02:30:28.681800) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 02:30:28.682429) ========= ========= Total master_lag: 0.26 =========