builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0105 starttime: 1463835493.61 results: success (0) buildid: 20160521050629 builduid: 89d9cda0ea4e41439844aefcac488dae revision: 110e5a10dce56ed02e43c58c179e6ac101df216d ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-21 05:58:13.615132) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-21 05:58:13.615516) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-21 05:58:13.616089) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-21 05:58:13.688466) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-21 05:58:13.688828) ========= 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.f3X7C8nl2m/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.4nrEk0gYWR/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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-21 05:58:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 12.6M=0.001s 2016-05-21 05:58:13 (12.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.108063 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-21 05:58:13.825414) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-21 05:58:13.825975) ========= 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.f3X7C8nl2m/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.4nrEk0gYWR/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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.027912 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-21 05:58:13.913610) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-21 05:58:13.914219) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 110e5a10dce56ed02e43c58c179e6ac101df216d --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 110e5a10dce56ed02e43c58c179e6ac101df216d --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.f3X7C8nl2m/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.4nrEk0gYWR/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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-21 05:58:14,001 truncating revision to first 12 chars 2016-05-21 05:58:14,001 Setting DEBUG logging. 2016-05-21 05:58:14,001 attempt 1/10 2016-05-21 05:58:14,001 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/110e5a10dce5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-21 05:58:15,439 unpacking tar archive at: mozilla-aurora-110e5a10dce5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.688719 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-21 05:58:15.630792) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-21 05:58:15.631101) ========= script_repo_revision: 110e5a10dce56ed02e43c58c179e6ac101df216d ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-21 05:58:15.631484) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-21 05:58:15.631799) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-21 05:58:15.690953) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 25 secs) (at 2016-05-21 05:58:15.691342) ========= /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.f3X7C8nl2m/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.4nrEk0gYWR/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 05:58:15 INFO - MultiFileLogger online at 20160521 05:58:15 in /builds/slave/test 05:58:15 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 05:58:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:58:15 INFO - {'append_to_log': False, 05:58:15 INFO - 'base_work_dir': '/builds/slave/test', 05:58:15 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 05:58:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:58:15 INFO - 'branch': 'Mozilla-Aurora', 05:58:15 INFO - 'buildbot_json_path': 'buildprops.json', 05:58:15 INFO - 'config_files': ('talos/mac_config.py',), 05:58:15 INFO - 'default_actions': ('clobber', 05:58:15 INFO - 'read-buildbot-config', 05:58:15 INFO - 'download-and-extract', 05:58:15 INFO - 'populate-webroot', 05:58:15 INFO - 'create-virtualenv', 05:58:15 INFO - 'install', 05:58:15 INFO - 'run-tests'), 05:58:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:58:15 INFO - 'download_minidump_stackwalk': True, 05:58:15 INFO - 'download_symbols': 'ondemand', 05:58:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:58:15 INFO - 'tooltool.py': '/tools/tooltool.py', 05:58:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:58:15 INFO - '/tools/misc-python/virtualenv.py')}, 05:58:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:58:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:58:15 INFO - 'installer_path': 'installer.exe', 05:58:15 INFO - 'log_level': 'info', 05:58:15 INFO - 'log_name': 'talos', 05:58:15 INFO - 'log_to_console': True, 05:58:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:58:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:58:15 INFO - 'opt_config_files': (), 05:58:15 INFO - 'pip_index': False, 05:58:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:58:15 INFO - 'cmd': ('bash', 05:58:15 INFO - '-c', 05:58:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:58:15 INFO - 'enabled': True, 05:58:15 INFO - 'halt_on_failure': False, 05:58:15 INFO - 'name': 'check_screen_resolution'},), 05:58:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:58:15 INFO - 'cmd': ('bash', 05:58:15 INFO - '-c', 05:58:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:58:15 INFO - 'enabled': True, 05:58:15 INFO - 'halt_on_failure': False, 05:58:15 INFO - 'name': 'check_screen_resolution'},), 05:58:15 INFO - 'run_cmd_checks_enabled': True, 05:58:15 INFO - 'sps_profile': False, 05:58:15 INFO - 'sps_profile_interval': 0, 05:58:15 INFO - 'suite': 'dromaeojs-e10s', 05:58:15 INFO - 'system_bits': '32', 05:58:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:58:15 INFO - 'title': 't-yosemite-r7-0105', 05:58:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:58:15 INFO - 'use_talos_json': True, 05:58:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:58:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:58:15 INFO - 'work_dir': 'build'} 05:58:15 INFO - ##### 05:58:15 INFO - ##### Running clobber step. 05:58:15 INFO - ##### 05:58:15 INFO - Running pre-action listener: _resource_record_pre_action 05:58:15 INFO - Running main action method: clobber 05:58:15 INFO - rmtree: /builds/slave/test/build 05:58:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:58:17 INFO - Running post-action listener: _resource_record_post_action 05:58:17 INFO - ##### 05:58:17 INFO - ##### Running read-buildbot-config step. 05:58:17 INFO - ##### 05:58:17 INFO - Running pre-action listener: _resource_record_pre_action 05:58:17 INFO - Running main action method: read_buildbot_config 05:58:17 INFO - Using buildbot properties: 05:58:17 INFO - { 05:58:17 INFO - "project": "", 05:58:17 INFO - "product": "firefox", 05:58:17 INFO - "script_repo_revision": "production", 05:58:17 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 05:58:17 INFO - "repository": "", 05:58:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 05:58:17 INFO - "buildid": "20160521050629", 05:58:17 INFO - "pgo_build": "False", 05:58:17 INFO - "basedir": "/builds/slave/test", 05:58:17 INFO - "buildnumber": 5, 05:58:17 INFO - "slavename": "t-yosemite-r7-0105", 05:58:17 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 05:58:17 INFO - "platform": "yosemite_r7", 05:58:17 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 05:58:17 INFO - "branch": "mozilla-aurora", 05:58:17 INFO - "repo_path": "releases/mozilla-aurora", 05:58:17 INFO - "revision": "110e5a10dce56ed02e43c58c179e6ac101df216d", 05:58:17 INFO - "stage_platform": "macosx64", 05:58:17 INFO - "builduid": "89d9cda0ea4e41439844aefcac488dae", 05:58:17 INFO - "slavebuilddir": "test" 05:58:17 INFO - } 05:58:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/Gk_4CRggRjiA3PF2DCjllw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 05:58:17 INFO - Running post-action listener: _resource_record_post_action 05:58:17 INFO - ##### 05:58:17 INFO - ##### Running download-and-extract step. 05:58:17 INFO - ##### 05:58:17 INFO - Running pre-action listener: _resource_record_pre_action 05:58:17 INFO - Running main action method: download_and_extract 05:58:17 INFO - mkdir: /builds/slave/test/build/tests 05:58:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:58:17 INFO - https://queue.taskcluster.net/v1/task/Gk_4CRggRjiA3PF2DCjllw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 05:58:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gk_4CRggRjiA3PF2DCjllw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 05:58:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gk_4CRggRjiA3PF2DCjllw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 05:58:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gk_4CRggRjiA3PF2DCjllw/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 05:58:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gk_4CRggRjiA3PF2DCjllw/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 05:58:47 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gk_4CRggRjiA3PF2DCjllw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json: timed out 05:58:47 INFO - retry: attempt #1 caught exception: timed out 05:58:47 INFO - retry: Failed, sleeping 30 seconds before retrying 05:59:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gk_4CRggRjiA3PF2DCjllw/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 05:59:30 INFO - Downloaded 1199 bytes. 05:59:30 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 05:59:30 INFO - Using the following test package requirements: 05:59:30 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 05:59:30 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:59:30 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 05:59:30 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:59:30 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 05:59:30 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:59:30 INFO - u'jsshell-mac.zip'], 05:59:30 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:59:30 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 05:59:30 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 05:59:30 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:59:30 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 05:59:30 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:59:30 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 05:59:30 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:59:30 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 05:59:30 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 05:59:30 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 05:59:30 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 05:59:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:30 INFO - https://queue.taskcluster.net/v1/task/Gk_4CRggRjiA3PF2DCjllw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:59:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gk_4CRggRjiA3PF2DCjllw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 05:59:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gk_4CRggRjiA3PF2DCjllw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 05:59:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gk_4CRggRjiA3PF2DCjllw/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 05:59:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gk_4CRggRjiA3PF2DCjllw/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 05:59:34 INFO - Downloaded 18102367 bytes. 05:59:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 05:59:34 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 05:59:37 INFO - Return code: 0 05:59:37 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 05:59: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')]} 05:59:37 INFO - https://queue.taskcluster.net/v1/task/Gk_4CRggRjiA3PF2DCjllw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 05:59:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gk_4CRggRjiA3PF2DCjllw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 05:59:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gk_4CRggRjiA3PF2DCjllw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 05:59:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gk_4CRggRjiA3PF2DCjllw/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 05:59:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gk_4CRggRjiA3PF2DCjllw/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 05:59:42 INFO - Downloaded 18102367 bytes. 05:59:42 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'] 05:59:42 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 05:59:46 INFO - Return code: 0 05:59:46 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')]} 05:59:46 INFO - https://queue.taskcluster.net/v1/task/Gk_4CRggRjiA3PF2DCjllw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 05:59:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gk_4CRggRjiA3PF2DCjllw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 05:59:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gk_4CRggRjiA3PF2DCjllw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 05:59:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gk_4CRggRjiA3PF2DCjllw/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 05:59:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gk_4CRggRjiA3PF2DCjllw/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 05:59:46 INFO - Downloaded 10840145 bytes. 05:59:46 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'] 05:59:46 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 05:59:47 INFO - Return code: 0 05:59:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:47 INFO - https://queue.taskcluster.net/v1/task/Gk_4CRggRjiA3PF2DCjllw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 05:59:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gk_4CRggRjiA3PF2DCjllw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 05:59:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gk_4CRggRjiA3PF2DCjllw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 05:59:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gk_4CRggRjiA3PF2DCjllw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 05:59:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Gk_4CRggRjiA3PF2DCjllw/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 05:59:51 INFO - Downloaded 114133235 bytes. 05:59:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Gk_4CRggRjiA3PF2DCjllw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 05:59:51 INFO - mkdir: /builds/slave/test/properties 05:59:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:59:51 INFO - Writing to file /builds/slave/test/properties/build_url 05:59:51 INFO - Contents: 05:59:51 INFO - build_url:https://queue.taskcluster.net/v1/task/Gk_4CRggRjiA3PF2DCjllw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 05:59:52 INFO - Running post-action listener: _resource_record_post_action 05:59:52 INFO - Running post-action listener: set_extra_try_arguments 05:59:52 INFO - ##### 05:59:52 INFO - ##### Running populate-webroot step. 05:59:52 INFO - ##### 05:59:52 INFO - Running pre-action listener: _resource_record_pre_action 05:59:52 INFO - Running main action method: populate_webroot 05:59:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:59:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:59:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:59:52 INFO - '--tppagecycles', 05:59:52 INFO - '7'], 05:59:52 INFO - 'tests': ['tsvgm']}}, 05:59:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:59:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:59:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:59:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:59:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:59:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:59:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:52 INFO - 'talos_options': ['--disable-e10s'], 05:59:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:59:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:59:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:52 INFO - 'talos_options': ['--disable-e10s'], 05:59:52 INFO - 'tests': ['damp', 'tps']}, 05:59:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:52 INFO - 'tests': ['damp', 'tps']}, 05:59:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:59:52 INFO - 'tests': ['dromaeo_dom']}, 05:59:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:59:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:59:52 INFO - 'tests': ['a11yr', 05:59:52 INFO - 'ts_paint', 05:59:52 INFO - 'tpaint', 05:59:52 INFO - 'sessionrestore', 05:59:52 INFO - 'sessionrestore_no_auto_restore', 05:59:52 INFO - 'tabpaint']}, 05:59:52 INFO - 'other-e10s': {'tests': ['a11yr', 05:59:52 INFO - 'ts_paint', 05:59:52 INFO - 'tpaint', 05:59:52 INFO - 'sessionrestore', 05:59:52 INFO - 'sessionrestore_no_auto_restore', 05:59:52 INFO - 'tabpaint']}, 05:59:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:59:52 INFO - 'ts_paint', 05:59:52 INFO - 'tpaint', 05:59:52 INFO - 'sessionrestore', 05:59:52 INFO - 'sessionrestore_no_auto_restore', 05:59:52 INFO - 'tabpaint']}, 05:59:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:59:52 INFO - 'ts_paint', 05:59:52 INFO - 'tpaint', 05:59:52 INFO - 'sessionrestore', 05:59:52 INFO - 'sessionrestore_no_auto_restore', 05:59:52 INFO - 'tabpaint']}, 05:59:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:59:52 INFO - 'tests': ['a11yr', 05:59:52 INFO - 'ts_paint', 05:59:52 INFO - 'tpaint', 05:59:52 INFO - 'sessionrestore', 05:59:52 INFO - 'sessionrestore_no_auto_restore', 05:59:52 INFO - 'tabpaint']}, 05:59:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:59:52 INFO - 'tests': ['a11yr', 05:59:52 INFO - 'ts_paint', 05:59:52 INFO - 'tpaint', 05:59:52 INFO - 'sessionrestore', 05:59:52 INFO - 'sessionrestore_no_auto_restore', 05:59:52 INFO - 'tabpaint']}, 05:59:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:59:52 INFO - 'tests': ['tsvgx', 05:59:52 INFO - 'tsvgr_opacity', 05:59:52 INFO - 'tart', 05:59:52 INFO - 'tscrollx', 05:59:52 INFO - 'cart']}, 05:59:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:59:52 INFO - 'tsvgr_opacity', 05:59:52 INFO - 'tart', 05:59:52 INFO - 'tscrollx', 05:59:52 INFO - 'cart']}, 05:59:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:52 INFO - 'talos_options': ['--disable-e10s'], 05:59:52 INFO - 'tests': ['tp5o']}, 05:59:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:52 INFO - 'tests': ['tp5o']}, 05:59:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:52 INFO - 'talos_options': ['--disable-e10s', 05:59:52 INFO - '--xperf_path', 05:59:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:59:52 INFO - 'tests': ['tp5n']}, 05:59:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:59:52 INFO - 'talos_options': ['--xperf_path', 05:59:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:59:52 INFO - 'tests': ['tp5n']}}, 05:59:52 INFO - 'talos.zip': {'path': '', 05:59:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:59:52 INFO - Running post-action listener: _resource_record_post_action 05:59:52 INFO - ##### 05:59:52 INFO - ##### Running create-virtualenv step. 05:59:52 INFO - ##### 05:59:52 INFO - Running pre-action listener: _resource_record_pre_action 05:59:52 INFO - Running main action method: create_virtualenv 05:59:52 INFO - Creating virtualenv /builds/slave/test/build/venv 05:59:52 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 05:59:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:59:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:59:52 INFO - Using real prefix '/tools/python27' 05:59:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:59:53 INFO - Installing distribute.............................................................................................................................................................................................done. 05:59:56 INFO - Installing pip.................done. 05:59:56 INFO - Return code: 0 05:59:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:59:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 05:59:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59:56 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 0x10b232a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b242420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0b1590ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0d1e00>, '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 0x10af3adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0ccb20>, '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.f3X7C8nl2m/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.4nrEk0gYWR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 05:59:56 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 05:59:56 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 05:59:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f3X7C8nl2m/Render', 05:59:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:59:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:59:56 INFO - 'HOME': '/Users/cltbld', 05:59:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:59:56 INFO - 'LOGNAME': 'cltbld', 05:59:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:56 INFO - 'MOZ_NO_REMOTE': '1', 05:59:56 INFO - 'NO_EM_RESTART': '1', 05:59:56 INFO - 'PAGER': '/bin/cat', 05:59:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:59:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:56 INFO - 'PWD': '/builds/slave/test', 05:59:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:59:56 INFO - 'SHELL': '/bin/bash', 05:59:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4nrEk0gYWR/Listeners', 05:59:56 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 05:59:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:59:56 INFO - 'USER': 'cltbld', 05:59:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:59:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:59:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:56 INFO - 'XPC_FLAGS': '0x0', 05:59:56 INFO - 'XPC_SERVICE_NAME': '0', 05:59:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:59:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:57 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 05:59:57 INFO - Cleaning up... 05:59:57 INFO - Return code: 0 05:59:57 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 05:59: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')]} 05:59:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:59:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59: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')]} 05:59:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:59:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:59: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', '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 0x10b232a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b242420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0b1590ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0d1e00>, '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 0x10af3adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0ccb20>, '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.f3X7C8nl2m/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.4nrEk0gYWR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 05:59: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', 'psutil>=0.7.1'] in /builds/slave/test/build 05:59: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 psutil>=0.7.1 05:59:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f3X7C8nl2m/Render', 05:59:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:59:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:59:57 INFO - 'HOME': '/Users/cltbld', 05:59:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:59:57 INFO - 'LOGNAME': 'cltbld', 05:59:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:59:57 INFO - 'MOZ_NO_REMOTE': '1', 05:59:57 INFO - 'NO_EM_RESTART': '1', 05:59:57 INFO - 'PAGER': '/bin/cat', 05:59:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:59:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:59:57 INFO - 'PWD': '/builds/slave/test', 05:59:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:59:57 INFO - 'SHELL': '/bin/bash', 05:59:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4nrEk0gYWR/Listeners', 05:59:57 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 05:59:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:59:57 INFO - 'USER': 'cltbld', 05:59:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:59:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:59:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:59:57 INFO - 'XPC_FLAGS': '0x0', 05:59:57 INFO - 'XPC_SERVICE_NAME': '0', 05:59:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:59:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:59:57 INFO - Downloading/unpacking psutil>=0.7.1 05:59: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 05:59: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 05:59: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 05:59: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 05:59: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 05:59: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 06:00:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:00:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:00:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:00:00 INFO - Installing collected packages: psutil 06:00:00 INFO - Running setup.py install for psutil 06:00:00 INFO - building 'psutil._psutil_osx' extension 06:00:00 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 06:00:00 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 06:00:01 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 06:00:01 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 06:00:01 INFO - building 'psutil._psutil_posix' extension 06:00:01 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 06:00:01 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:00:01 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:00:01 INFO - ^ 06:00:01 INFO - 1 warning generated. 06:00:01 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 06:00:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:00:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:00:01 INFO - Successfully installed psutil 06:00:01 INFO - Cleaning up... 06:00:01 INFO - Return code: 0 06:00:01 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 06:00: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')]} 06:00:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:00:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:00: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')]} 06:00:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:00:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:00:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10b232a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b242420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0b1590ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0d1e00>, '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 0x10af3adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0ccb20>, '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.f3X7C8nl2m/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.4nrEk0gYWR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 06:00:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 06:00:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 06:00:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f3X7C8nl2m/Render', 06:00:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:00:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:00:01 INFO - 'HOME': '/Users/cltbld', 06:00:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:00:01 INFO - 'LOGNAME': 'cltbld', 06:00:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:00:01 INFO - 'MOZ_NO_REMOTE': '1', 06:00:01 INFO - 'NO_EM_RESTART': '1', 06:00:01 INFO - 'PAGER': '/bin/cat', 06:00:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:00:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:00:01 INFO - 'PWD': '/builds/slave/test', 06:00:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:00:01 INFO - 'SHELL': '/bin/bash', 06:00:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4nrEk0gYWR/Listeners', 06:00:01 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 06:00:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:00:01 INFO - 'USER': 'cltbld', 06:00:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:00:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:00:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:00:01 INFO - 'XPC_FLAGS': '0x0', 06:00:01 INFO - 'XPC_SERVICE_NAME': '0', 06:00:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:00:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:00:01 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 06:00:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:00:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:00:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:00:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:00:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:00:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:00:04 INFO - Downloading mozsystemmonitor-0.0.tar.gz 06:00:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:00:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 06:00:04 INFO - Installing collected packages: mozsystemmonitor 06:00:04 INFO - Running setup.py install for mozsystemmonitor 06:00:04 INFO - Successfully installed mozsystemmonitor 06:00:04 INFO - Cleaning up... 06:00:04 INFO - Return code: 0 06:00:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:00:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:00:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:00:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:00:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:00:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:00:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:00:04 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 0x10b232a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b242420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0b1590ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0d1e00>, '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 0x10af3adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0ccb20>, '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.f3X7C8nl2m/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.4nrEk0gYWR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 06:00:04 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 06:00:04 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 06:00:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f3X7C8nl2m/Render', 06:00:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:00:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:00:04 INFO - 'HOME': '/Users/cltbld', 06:00:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:00:04 INFO - 'LOGNAME': 'cltbld', 06:00:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:00:04 INFO - 'MOZ_NO_REMOTE': '1', 06:00:04 INFO - 'NO_EM_RESTART': '1', 06:00:04 INFO - 'PAGER': '/bin/cat', 06:00:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:00:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:00:04 INFO - 'PWD': '/builds/slave/test', 06:00:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:00:04 INFO - 'SHELL': '/bin/bash', 06:00:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4nrEk0gYWR/Listeners', 06:00:04 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 06:00:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:00:04 INFO - 'USER': 'cltbld', 06:00:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:00:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:00:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:00:04 INFO - 'XPC_FLAGS': '0x0', 06:00:04 INFO - 'XPC_SERVICE_NAME': '0', 06:00:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:00:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:00:04 INFO - Downloading/unpacking blobuploader==1.2.4 06:00:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:00:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:00:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:00:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:00:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:00:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:00:07 INFO - Downloading blobuploader-1.2.4.tar.gz 06:00:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:00:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:00:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:00:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:00:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:00:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:00:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:00:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:00:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:00:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:00:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:00:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:00:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:00:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:00:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:00:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:00:08 INFO - Downloading docopt-0.6.1.tar.gz 06:00:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:00:08 INFO - Installing collected packages: blobuploader, requests, docopt 06:00:08 INFO - Running setup.py install for blobuploader 06:00:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:00:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:00:08 INFO - Running setup.py install for requests 06:00:09 INFO - Running setup.py install for docopt 06:00:09 INFO - Successfully installed blobuploader requests docopt 06:00:09 INFO - Cleaning up... 06:00:09 INFO - Return code: 0 06:00:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:00:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:00:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:00:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:00:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:00:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:00:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:00:09 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 0x10b232a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b242420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0b1590ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0d1e00>, '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 0x10af3adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0ccb20>, '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.f3X7C8nl2m/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.4nrEk0gYWR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 06:00:09 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 06:00:09 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 06:00:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f3X7C8nl2m/Render', 06:00:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:00:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:00:09 INFO - 'HOME': '/Users/cltbld', 06:00:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:00:09 INFO - 'LOGNAME': 'cltbld', 06:00:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:00:09 INFO - 'MOZ_NO_REMOTE': '1', 06:00:09 INFO - 'NO_EM_RESTART': '1', 06:00:09 INFO - 'PAGER': '/bin/cat', 06:00:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:00:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:00:09 INFO - 'PWD': '/builds/slave/test', 06:00:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:00:09 INFO - 'SHELL': '/bin/bash', 06:00:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4nrEk0gYWR/Listeners', 06:00:09 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 06:00:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:00:09 INFO - 'USER': 'cltbld', 06:00:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:00:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:00:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:00:09 INFO - 'XPC_FLAGS': '0x0', 06:00:09 INFO - 'XPC_SERVICE_NAME': '0', 06:00:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:00:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:00:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:00:09 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-Hdht8q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:00:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:00:09 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-5MrYbI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:00:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:00:09 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-NF7BMy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:00:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:00:10 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-Jl6Hlb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:00:10 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-85HOLD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:00:10 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-1fq8_D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:00:10 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-3fkBlg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:00:10 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-ixexhu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:00:10 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-CxDbkp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:00:10 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-g6XC1x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:00:10 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-ahr9tp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:00:10 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-gSU4kz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:00:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:00:10 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-NFzObz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:00:11 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-xHWuCb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:00:11 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-g_XJcj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:00:11 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-XoIxvk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:00:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:00:11 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-IMcfZ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:00:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:00:11 INFO - Running setup.py install for manifestparser 06:00:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:00:11 INFO - Running setup.py install for mozcrash 06:00:11 INFO - Running setup.py install for mozdebug 06:00:11 INFO - Running setup.py install for mozdevice 06:00:12 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:00:12 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:00:12 INFO - Running setup.py install for mozfile 06:00:12 INFO - Running setup.py install for mozhttpd 06:00:12 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:00:12 INFO - Running setup.py install for mozinfo 06:00:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:00:12 INFO - Running setup.py install for mozInstall 06:00:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:00:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:00:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:00:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:00:12 INFO - Running setup.py install for mozleak 06:00:12 INFO - Running setup.py install for mozlog 06:00:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:00:12 INFO - Running setup.py install for moznetwork 06:00:13 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:00:13 INFO - Running setup.py install for mozprocess 06:00:13 INFO - Running setup.py install for mozprofile 06:00:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:00:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:00:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:00:13 INFO - Running setup.py install for mozrunner 06:00:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:00:13 INFO - Running setup.py install for mozscreenshot 06:00:13 INFO - Running setup.py install for moztest 06:00:13 INFO - Running setup.py install for mozversion 06:00:14 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:00:14 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:00:14 INFO - Cleaning up... 06:00:14 INFO - Return code: 0 06:00:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:00:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:00:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:00:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:00:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:00:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:00:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:00:14 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 0x10b232a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b242420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0b1590ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0d1e00>, '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 0x10af3adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0ccb20>, '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.f3X7C8nl2m/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.4nrEk0gYWR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 06:00:14 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 06:00:14 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 06:00:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f3X7C8nl2m/Render', 06:00:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:00:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:00:14 INFO - 'HOME': '/Users/cltbld', 06:00:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:00:14 INFO - 'LOGNAME': 'cltbld', 06:00:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:00:14 INFO - 'MOZ_NO_REMOTE': '1', 06:00:14 INFO - 'NO_EM_RESTART': '1', 06:00:14 INFO - 'PAGER': '/bin/cat', 06:00:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:00:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:00:14 INFO - 'PWD': '/builds/slave/test', 06:00:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:00:14 INFO - 'SHELL': '/bin/bash', 06:00:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4nrEk0gYWR/Listeners', 06:00:14 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 06:00:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:00:14 INFO - 'USER': 'cltbld', 06:00:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:00:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:00:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:00:14 INFO - 'XPC_FLAGS': '0x0', 06:00:14 INFO - 'XPC_SERVICE_NAME': '0', 06:00:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:00:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:00:14 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-MuHKiF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:00:14 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)) 06:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:00:14 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-8HdQtE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:00:14 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)) 06:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:00:14 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-6Vhh7g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:00:14 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)) 06:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:00:14 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-cMzmtF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:00:14 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)) 06:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:00:14 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-e5lDFy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:00:14 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)) 06:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:00:14 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-RwcK4E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:00:14 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)) 06:00:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:00:14 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-8D_r4O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:00:15 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)) 06:00:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:00:15 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-o1QL2d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:00:15 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)) 06:00:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:00:15 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-diyLZp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:00:15 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)) 06:00:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:00:15 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-wXVjx2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:00:15 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)) 06:00:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:00:15 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-rTiXH1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:00:15 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)) 06:00:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:00:15 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-3O7VWg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:00:15 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)) 06:00:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:00:15 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-G05KGW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:00:15 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)) 06:00:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:00:15 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-AioibA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:00:15 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)) 06:00:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:00:15 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-qb7lHk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:00:15 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)) 06:00:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:00:15 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-lXzLAw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:00:16 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)) 06:00:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:00:16 INFO - Running setup.py (path:/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/pip-YEHnIo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:00:16 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)) 06:00:16 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)) 06:00:16 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)) 06:00:16 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)) 06:00:16 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)) 06:00:16 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)) 06:00:16 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:00:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:00:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:00:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:00:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:00:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:00:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:00:18 INFO - Downloading blessings-1.6.tar.gz 06:00:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:00:18 INFO - Installing collected packages: blessings 06:00:18 INFO - Running setup.py install for blessings 06:00:18 INFO - Successfully installed blessings 06:00:18 INFO - Cleaning up... 06:00:18 INFO - Return code: 0 06:00:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:00:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:00:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:00:19 INFO - Reading from file tmpfile_stdout 06:00:19 INFO - Current package versions: 06:00:19 INFO - blessings == 1.6 06:00:19 INFO - blobuploader == 1.2.4 06:00:19 INFO - docopt == 0.6.1 06:00:19 INFO - manifestparser == 1.1 06:00:19 INFO - mozInstall == 1.12 06:00:19 INFO - mozcrash == 0.17 06:00:19 INFO - mozdebug == 0.1 06:00:19 INFO - mozdevice == 0.48 06:00:19 INFO - mozfile == 1.2 06:00:19 INFO - mozhttpd == 0.7 06:00:19 INFO - mozinfo == 0.9 06:00:19 INFO - mozleak == 0.1 06:00:19 INFO - mozlog == 3.1 06:00:19 INFO - moznetwork == 0.27 06:00:19 INFO - mozprocess == 0.22 06:00:19 INFO - mozprofile == 0.28 06:00:19 INFO - mozrunner == 6.11 06:00:19 INFO - mozscreenshot == 0.1 06:00:19 INFO - mozsystemmonitor == 0.0 06:00:19 INFO - moztest == 0.7 06:00:19 INFO - mozversion == 1.4 06:00:19 INFO - psutil == 3.1.1 06:00:19 INFO - requests == 1.2.3 06:00:19 INFO - wsgiref == 0.1.2 06:00:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:00:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:00:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:00:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:00:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:00:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:00:19 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 0x10b232a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b242420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0b1590ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0d1e00>, '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 0x10af3adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0ccb20>, '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.f3X7C8nl2m/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.4nrEk0gYWR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 06:00:19 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 06:00:19 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 06:00:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f3X7C8nl2m/Render', 06:00:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:00:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:00:19 INFO - 'HOME': '/Users/cltbld', 06:00:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:00:19 INFO - 'LOGNAME': 'cltbld', 06:00:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:00:19 INFO - 'MOZ_NO_REMOTE': '1', 06:00:19 INFO - 'NO_EM_RESTART': '1', 06:00:19 INFO - 'PAGER': '/bin/cat', 06:00:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:00:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:00:19 INFO - 'PWD': '/builds/slave/test', 06:00:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:00:19 INFO - 'SHELL': '/bin/bash', 06:00:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4nrEk0gYWR/Listeners', 06:00:19 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 06:00:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:00:19 INFO - 'USER': 'cltbld', 06:00:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:00:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:00:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:00:19 INFO - 'XPC_FLAGS': '0x0', 06:00:19 INFO - 'XPC_SERVICE_NAME': '0', 06:00:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:00:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:00:19 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)) 06:00:19 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)) 06:00:19 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)) 06:00:19 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)) 06:00:19 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)) 06:00:19 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)) 06:00:19 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)) 06:00:19 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)) 06:00:19 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)) 06:00:19 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)) 06:00:19 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)) 06:00:19 INFO - Cleaning up... 06:00:19 INFO - Return code: 0 06:00:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:00:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:00:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:00:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:00:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:00:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:00:19 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 0x10b232a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b242420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0b1590ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0d1e00>, '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 0x10af3adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0ccb20>, '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.f3X7C8nl2m/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.4nrEk0gYWR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 06:00:19 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 06:00:19 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 06:00:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f3X7C8nl2m/Render', 06:00:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:00:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:00:19 INFO - 'HOME': '/Users/cltbld', 06:00:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:00:19 INFO - 'LOGNAME': 'cltbld', 06:00:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:00:19 INFO - 'MOZ_NO_REMOTE': '1', 06:00:19 INFO - 'NO_EM_RESTART': '1', 06:00:19 INFO - 'PAGER': '/bin/cat', 06:00:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:00:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:00:19 INFO - 'PWD': '/builds/slave/test', 06:00:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:00:19 INFO - 'SHELL': '/bin/bash', 06:00:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4nrEk0gYWR/Listeners', 06:00:19 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 06:00:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:00:19 INFO - 'USER': 'cltbld', 06:00:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:00:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:00:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:00:19 INFO - 'XPC_FLAGS': '0x0', 06:00:19 INFO - 'XPC_SERVICE_NAME': '0', 06:00:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:00:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:00:19 INFO - Downloading/unpacking jsonschema 06:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:00:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:00:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:00:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:00:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:00:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:00:22 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:00:22 INFO - Downloading/unpacking functools32 (from jsonschema) 06:00:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:00:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:00:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:00:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:00:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:00:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:00:22 INFO - Downloading functools32-3.2.3-2.tar.gz 06:00:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:00:22 INFO - warning: no files found matching '*.txt' 06:00:22 INFO - no previously-included directories found matching 'build' 06:00:22 INFO - no previously-included directories found matching 'dist' 06:00:22 INFO - no previously-included directories found matching '.git*' 06:00:22 INFO - Installing collected packages: jsonschema, functools32 06:00:22 INFO - Running setup.py install for functools32 06:00:22 INFO - warning: no files found matching '*.txt' 06:00:22 INFO - no previously-included directories found matching 'build' 06:00:22 INFO - no previously-included directories found matching 'dist' 06:00:22 INFO - no previously-included directories found matching '.git*' 06:00:22 INFO - Successfully installed jsonschema functools32 06:00:22 INFO - Cleaning up... 06:00:22 INFO - Return code: 0 06:00:22 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 06:00:22 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')]} 06:00:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:00:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:00:22 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')]} 06:00:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:00:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:00:22 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 0x10b232a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b242420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0b1590ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b0d1e00>, '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 0x10af3adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0ccb20>, '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.f3X7C8nl2m/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.4nrEk0gYWR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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 06:00:22 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 06:00:22 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 06:00:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f3X7C8nl2m/Render', 06:00:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:00:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:00:22 INFO - 'HOME': '/Users/cltbld', 06:00:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:00:22 INFO - 'LOGNAME': 'cltbld', 06:00:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:00:22 INFO - 'MOZ_NO_REMOTE': '1', 06:00:22 INFO - 'NO_EM_RESTART': '1', 06:00:22 INFO - 'PAGER': '/bin/cat', 06:00:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:00:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:00:22 INFO - 'PWD': '/builds/slave/test', 06:00:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:00:22 INFO - 'SHELL': '/bin/bash', 06:00:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4nrEk0gYWR/Listeners', 06:00:22 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 06:00:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:00:22 INFO - 'USER': 'cltbld', 06:00:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:00:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:00:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:00:22 INFO - 'XPC_FLAGS': '0x0', 06:00:22 INFO - 'XPC_SERVICE_NAME': '0', 06:00:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:00:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:00:23 INFO - Downloading/unpacking flake8 06:00:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:00:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:00:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:00:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:00:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:00:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:00:25 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 06:00:25 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 06:00:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:00:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:00:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:00:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:00:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:00:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:00:25 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 06:00:26 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 06:00:26 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 06:00:26 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 06:00:26 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 06:00:26 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 06:00:26 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 06:00:26 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 06:00:26 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 06:00:26 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 06:00:26 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 06:00:26 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 06:00:26 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 06:00:26 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 06:00:26 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 06:00:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 06:00:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:00:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:00:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:00:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:00:26 INFO - no previously-included directories found matching 'docs/_build' 06:00:26 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 06:00:27 INFO - Running setup.py install for pep8 06:00:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:00:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:00:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:00:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:00:27 INFO - no previously-included directories found matching 'docs/_build' 06:00:27 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 06:00:27 INFO - Successfully installed flake8 mccabe pyflakes pep8 06:00:27 INFO - Cleaning up... 06:00:27 INFO - Return code: 0 06:00:27 INFO - Running post-action listener: _resource_record_post_action 06:00:27 INFO - Running post-action listener: _start_resource_monitoring 06:00:27 INFO - Starting resource monitoring. 06:00:27 INFO - ##### 06:00:27 INFO - ##### Running install step. 06:00:27 INFO - ##### 06:00:27 INFO - Running pre-action listener: _resource_record_pre_action 06:00:27 INFO - Running main action method: install 06:00:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:00:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:00:27 INFO - Reading from file tmpfile_stdout 06:00:27 INFO - Detecting whether we're running mozinstall >=1.0... 06:00:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:00:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:00:27 INFO - Reading from file tmpfile_stdout 06:00:27 INFO - Output received: 06:00:27 INFO - Usage: mozinstall [options] installer 06:00:27 INFO - Options: 06:00:27 INFO - -h, --help show this help message and exit 06:00:27 INFO - -d DEST, --destination=DEST 06:00:27 INFO - Directory to install application into. [default: 06:00:27 INFO - "/builds/slave/test"] 06:00:27 INFO - --app=APP Application being installed. [default: firefox] 06:00:27 INFO - mkdir: /builds/slave/test/build/application 06:00:27 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'] 06:00:27 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 06:01:00 INFO - Reading from file tmpfile_stdout 06:01:00 INFO - Output received: 06:01:00 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 06:01:00 INFO - Running post-action listener: _resource_record_post_action 06:01:00 INFO - ##### 06:01:00 INFO - ##### Running run-tests step. 06:01:00 INFO - ##### 06:01:00 INFO - Running pre-action listener: _resource_record_pre_action 06:01:00 INFO - Running main action method: run_tests 06:01:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:01:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:01:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:01:00 INFO - 2016-05-21 06:01:00.543 screenresolution[1640:7912] starting screenresolution argv=screenresolution get 06:01:00 INFO - 2016-05-21 06:01:00.545 screenresolution[1640:7912] Display 0: 1600x1200x32@60 06:01:00 INFO - 2016-05-21 06:01:00.550 screenresolution[1641:7914] starting screenresolution argv=screenresolution list 06:01:00 INFO - Available Modes on Display 0 06:01:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:01:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:01:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:01:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:01:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:01:00 INFO - Intel Iris: 06:01:00 INFO - Chipset Model: Intel Iris 06:01:00 INFO - Type: GPU 06:01:00 INFO - Bus: Built-In 06:01:00 INFO - VRAM (Dynamic, Max): 1536 MB 06:01:00 INFO - Vendor: Intel (0x8086) 06:01:00 INFO - Device ID: 0x0a2e 06:01:00 INFO - Revision ID: 0x0009 06:01:00 INFO - Displays: 06:01:00 INFO - Display: 06:01:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:01:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:01:00 INFO - Display Serial Number: 1600x1200 60 06:01:00 INFO - Main Display: Yes 06:01:00 INFO - Mirror: Off 06:01:00 INFO - Online: Yes 06:01:00 INFO - Rotation: Supported 06:01:00 INFO - Return code: 0 06:01:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:01:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:01:00 INFO - Python 2.7.3 06:01:00 INFO - Return code: 0 06:01:00 INFO - grabbing minidump binary from tooltool 06:01:00 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')]} 06:01:00 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 0x10b0d1e00>, '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 0x10af3adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b0ccb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:01:00 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 06:01:00 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 06:01:00 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:01:00 INFO - Return code: 0 06:01:00 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:01:00 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:01:00 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:01:00 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:01:00 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:01:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 06:01:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 06:01:01 INFO - Return code: 0 06:01:01 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/Gk_4CRggRjiA3PF2DCjllw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0105', '--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 06:01:01 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/Gk_4CRggRjiA3PF2DCjllw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0105 --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 06:01:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.f3X7C8nl2m/Render', 06:01:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:01:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:01:01 INFO - 'HOME': '/Users/cltbld', 06:01:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:01:01 INFO - 'LOGNAME': 'cltbld', 06:01:01 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:01:01 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:01:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:01:01 INFO - 'MOZ_NO_REMOTE': '1', 06:01:01 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:01:01 INFO - 'NO_EM_RESTART': '1', 06:01:01 INFO - 'PAGER': '/bin/cat', 06:01:01 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:01:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:01:01 INFO - 'PWD': '/builds/slave/test', 06:01:01 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:01:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:01:01 INFO - 'SHELL': '/bin/bash', 06:01:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4nrEk0gYWR/Listeners', 06:01:01 INFO - 'TMPDIR': '/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/', 06:01:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:01:01 INFO - 'USER': 'cltbld', 06:01:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:01:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:01:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:01:01 INFO - 'XPC_FLAGS': '0x0', 06:01:01 INFO - 'XPC_SERVICE_NAME': '0', 06:01:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:01:01 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/Gk_4CRggRjiA3PF2DCjllw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0105', '--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 06:01:02 INFO - mozversion application_buildid: 20160521050629 06:01:02 INFO - mozversion application_changeset: 110e5a10dce56ed02e43c58c179e6ac101df216d 06:01:02 INFO - mozversion application_display_name: FirefoxDeveloperEdition 06:01:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:01:02 INFO - mozversion application_name: Firefox 06:01:02 INFO - mozversion application_remotingname: firefox-dev 06:01:02 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 06:01:02 INFO - mozversion application_vendor: Mozilla 06:01:02 INFO - mozversion application_version: 48.0a2 06:01:02 INFO - mozversion platform_buildid: 20160521050629 06:01:02 INFO - mozversion platform_changeset: 110e5a10dce56ed02e43c58c179e6ac101df216d 06:01:02 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 06:01:02 INFO - mozversion platform_version: 48.0a2 06:01:02 INFO - using testdate: 1463835662 06:01:02 INFO - actual date: 1463835662 06:01:02 INFO - starting webserver on 'localhost:49271' 06:01:02 INFO - SUITE-START | Running 2 tests 06:01:02 INFO - TEST-START | dromaeo_css 06:01:02 INFO - operating with platform_type : mac_ 06:01:02 INFO - Initialising browser for dromaeo_css test... 06:01:02 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/tmpP50T0P/profile http://localhost:49271/getInfo.html) 06:01:05 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 06:01:05 INFO - PROCESS | 1659 | colorDepth:24 06:01:05 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 06:01:05 INFO - PROCESS | 1659 | __metrics 06:01:05 ERROR - PROCESS | 1659 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 06:01:06 INFO - TEST-INFO | 1659: exit 0 06:01:06 INFO - Browser initialized. 06:01:06 INFO - Running cycle 1/1 for dromaeo_css test... 06:01:06 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/tmpP50T0P/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:01:18 INFO - PROCESS | 1662 | RSS: Main: 221519872 06:01:18 INFO - PROCESS | 1662 | 06:02:07 INFO - PROCESS | 1662 | 1463835727743 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] 06:04:19 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 06:04:20 INFO - PROCESS | 1662 | RSS: Main: 231579648 06:04:20 INFO - PROCESS | 1662 | 06:07:22 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 06:07:23 INFO - PROCESS | 1662 | RSS: Main: 243109888 06:07:23 INFO - PROCESS | 1662 | 06:10:25 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 06:10:25 INFO - PROCESS | 1662 | RSS: Main: 245510144 06:10:25 INFO - PROCESS | 1662 | 06:13:27 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 06:13:28 INFO - PROCESS | 1662 | RSS: Main: 246689792 06:13:28 INFO - PROCESS | 1662 | 06:16:30 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 06:16:31 INFO - PROCESS | 1662 | RSS: Main: 249905152 06:16:31 INFO - PROCESS | 1662 | 06:19:33 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 06:19:33 INFO - PROCESS | 1662 | RSS: Main: 251076608 06:19:33 INFO - PROCESS | 1662 | 06:19:33 INFO - PROCESS | 1662 | __start_tp_report 06:19:33 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 06:19:33 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 06:19:33 INFO - PROCESS | 1662 | |i|pagename|runs| 06:19:33 INFO - PROCESS | 1662 | |0;dojo.html;3032.6967303269676;3209.518572214166;3520.6127325994134;3552.1652411683267;3582.9251224632603;5701.657900525961;5747.482726554609;5760.020796464601;5782.103773915044;5786.768529258829;5730.398308177644;5785.82642520724;5817.970910145454;5846.912296315548;5878.441548052939;5257.1851363038595;5268.841934741954;5315.760790764425;5321.973390133063;5340.599455040888;5244.527992480676;5287.894242115155;5327;5391.460853914617;5409.918851217235;5343.786248550054;5346.465353464642;5350.8394748157425;5358.866028349284;5368.8389348319615;5300.655457395257;5310.176922577007;5341.172118321647;5363.517283444508;5434.565234781208;5146.022255771429;5162.6902385856965;5196.480351964811;5273.868153296198;5286.709230992294;4564.908701825978;5197.870053248661;5206.68759874405;5659.51894089003;5748.770049198027;6289.842753931142;6323.810285691437;6332.145160403333;6348.682565871688;6353.682315884187;6179.938200618026;6253.187085678833;6271.529635277372;6304.716287767015;6323.7154328054885;5879.559033072494;5906.143609176645;5912.3496415394275;5924.940750592526;5963.761449542013;5493.681516436027;6338.207724034496;6384.585001974857;6400.839979000562;6419.935800642027;6142.682951206299;6270.655113968684;6274.843128921722;6288.182536270256;6288.276848162428;6332.176817013758;6341.556091073673;6349.873002539924;6371.426571608485;6376.968115159394;6137.039921714347;6170.56806023583;6267;6313.873722525615;6343.936560634427;6278.4035516625845;6311.147995020705;6323.20959880015;6351.777687780906;6364.1408409865;4960.801567937314;5005.399352077774;5016.924746128811;5024.673396229234;5031.270465782441;4317.956820431818;4486.663500237495;4512.81948722054;4537.8184872604725;4571.725696458223;5771.740271687784;5869.383714709979;5889.234399528034;5920.851978700567;5968.432998865101;6226.813195604139;6290.654014029273;6328.367163283635;6334.5249106317215;6363.33185015576;4927.531884470969;5754.827355179351;5887.558433117448;5954.106883967353;5967.305985258742;5475.507204351547;5510.448955104457;5515.7242137892945;5527.861803454865;5537.224788529609;6520.087187793618;6526.836829079311;6544.640044797582;6545.639989800511;6548.148740663683;6238.532110091671;6262.874742505033;6309.684515774285;6311.053341998737;6315.936840631719;6154.922888494585;6518.478521718346;6519.152510173647;6520.282768895512;6524.151860258136;5500.752466138953;5818.836232753551;6326.082718005955;6326.493880489642;6339.207599050119;6236.563440559299;6247.875042499216;6276.152719382825;6293.779717709858;6351.777687780906;6010.639361638316;6070;6252.687365631791;6253.030780229072;6275.905861411991;6085;6236.438720514994;6261.24865016201;6281.3404592518955;6283.59156654816;5909.674967876808;6017.608855424383;6099.268087829311;6107.664078475549;6127.172831667668;6223.906641400292;6230.108211330348;6255.093011513214;6263.3736626337;6271.216097987752;5964.731587078505;6092.664903430354;6153.2000839890525;6172.259328880384;6181.752729890754 06:19:33 INFO - PROCESS | 1662 | |1;ext.html;503.3456506541497;549.1076999875203;553.4963183503013;558.9301337332835;560.728046897255;38690;39252;39270.60729392709;39295.21409571807;39360.606393936054;31919.68080319196;39445.802770986105;40389.798051009704;40682.186356272854;40813.59186408135;9429.292803039782;9545.284103692216;9554.996725343843;9663.710088697315;9676.032396760304;8571.22858942695;9618.855717164248;9673.613055477772;9743.269254805882;9820.901790982105;9761.316707830454;9806.411615303105;9862.211023118134;9881.456519891402;9881.752956176082;9675.371100878421;9704.70885873425;9780.755481112958;9837.409755414697;9856.605735770561;8553.144685531459;9488.573014214377;9639.3734407264;9746.951265243699;9771;39656;39920.60079399198;40145;40147.3977890329;40171.7991410044;2774.3064233941514;3034.332446861687;3098.5662007318992;3122.953155702666;3243.108145260049;26120.955161793507;26179.580086365066;27135.592966105523;27582;27591;2656.893724251028;2667.7065522792477;2683.1279834053817;2730.481208570385;2761.4753196892525;370981;372286;373290;374174;375932.99999999726;34788;35100.473492897625;35114.64885351165;35474.46788298178;36091;35426;35767.64232357643;35817;36015.27969440597;36166.45750313748;15021.02363346379;15047.849521504864;15158.469453569072;15217.163056032025;15261;12617.990560755117;12653.430595623218;12966.286854223108;13005.349732513336;13111.803322950163;7900.644470998819;8012;8064.758057258292;8155.877661834958;8219.876701849476;3419.9316013679586;3439.5528581284775;3489.6684814943046;3497.562804649419;3522.559680039995;971.640493017588;979.9657012004545;1028.1106842581114;1110.8833572474934;1201.2972411139406;30129.548056779167;30470;30472.69527304743;30479.542806857913;30570.847145764128;1090.0353187429182;1122.5678113926197;1130.8982191602795;1132.6149109302876;1231.3166192763103;15212.46756363522;15508.844911550967;15600.84399155994;15817.367305307658;15874.365025399085;815578;971598;991579;994133;994136;61088.69455652782;61273.693631531554;61611.6919415409;61688.07467887985;61785;1488.761798112297;1495.5812372535925;1500.7598784194477;1500.932458039391;1502.8797696184279;29399.853000735715;30481.85331261678;30642.693573063985;30958.69041309558;31108.844455777577;52988.73505632601;53145.468545314056;53572;53582.464175357745;53761.46238537564;577479.4504109978;590543;591457;594373;594588;27835.860820695765;27855.721442785314;27875.581866271616;28122.85938570294;28191.43617127687;45165;45835.77082114568;46868;46992.530074698814;47627;38156.42765358577;38309.6169038306;38338.204801518674;38452.42321365126;39332.80333598314;37364.252714946095;37507.624923750416;37702.43446348252;38025.619743802206;38305.04237394087;33540;36917;38323.233535329695;38335.61664383432;38360;29643;31516.527252091695;32705;32903.67096329006;33132.34338283028;22964.311070667907;24972.750272497044;25037.624435633115;25092.372690682878;25418.618720718834 06:19:33 INFO - PROCESS | 1662 | |2;jquery.html;398.2811026097894;403.8384646141545;404.70052161400565;409.4799604502282;411.56785375355867;13697.767200951912;20276.493087672796;20535.38393848187;20977.580448391043;21054.263100791475;21057.947102644885;21443;21489.785102148973;21629.026693798776;22090.11639534421;5446.863828404302;5957.344692083867;6028.914795336859;6047.123167140762;6199.473044791198;4684.765761711918;5983.760649574012;6078.969605151968;6128.172696685957;6146.7541298348015;5870.26621672291;6066.272047354301;6067.211262535864;6089.2692876854835;6105.511559075263;5417.864553386177;6058.666773327487;6089.117078023706;6100.328963813977;6106.725197366107;5207.713575753332;5262.7631756571045;5330.013947419701;5349.839504814861;5371.650842695251;26935.595966060526;27148.592771108448;27263.86368068147;27344.316392090157;27395.31511712203;2306.550222706573;2316.131450705985;2751.683556391021;2776.6112744215825;2982.8806847726064;14869.776953345709;15258.08451492914;15279.083255004734;15324.923375383163;15485.767713484307;1760.4718584424622;1813.5284825945216;1821.6265665538533;1824.087956021989;1835.6879330513846;112543.25173256236;118879;120584.39707801405;121803.39098304452;121832.78167218303;10242.59029638821;10506.159507239403;10948.56205751761;11365.261258018203;11718.296902185895;13850.584482465541;13863;14332.283385830668;14439.92780036093;14442.855571444361;8917.375783695208;9115.179633832855;9131.26949844011;9154.404963677473;9221.216196623229;7583.62081895903;9305.581248843819;9306.674266400643;9339.299552533586;9429.15137637616;6882.242953275136;6906.343897329745;7001.374484568287;7001.789946301517;7014.579125252501;1477.4090363854543;1651.3642247734708;1940.4760714606978;1943.6015616798522;1951.375559820872;965.8358079126565;1009.3388830316155;1009.8030883977701;1011.6610935336597;1018.6604614931341;21248.78751212468;21779.629306258823;21934.45163870916;21987.491728320787;22200.439612665687;928.9920436326527;997.2620261006803;1003.6587560229411;1013.3514550687565;1061.5117046158698;11455;11778.234414763014;11794.410279485992;11797.587084452003;11843.348615826213;605871;606214;606527;609372.9531352404;635073.8246308739;66321;90152;114203;114400.42799785947;115521;985.3398223190336;1062.4846949229618;1071.5927947379946;1078.6494389323502;1093.4805967165532;18375.897446153274;18706.597005224936;18862.86822790637;18877.528061798566;19265.325713599956;49894.251586226965;50436.495635044645;50450;52779.73610131925;53478.46521534735;47092.05815883732;74218;74458.51082978418;76295.23704762882;76892.61553692419;8281.17188281167;8287.58562071906;8368.330533557422;8493.660253589787;8535.189157030072;13189.868101318863;13193.076484646366;13316.46734130624;13475.528356507475;13538.93230533841;11361.204715669825;11547.307161570332;11596.246243994114;11599.362035088152;11632;11228.214025018171;11290.435478225892;11435.542578296774;11446.313221206754;11479.36863472517;11647.6505704829;11677.299362038304;12047.698807529881;12058.939705301418;12060.577879774166;7027.9297207027275;8211.548364839991;8227.259546640831;8382.58087095655;8619.91380086191;9398.906010940076;11604.477798499256;11795.823062653895;11831.585894493652;11954.043676506033 06:19:33 INFO - PROCESS | 1662 | |3;mootools.html;1515.3559737111727;1597.1455271429784;1816.5458635341165;1898.2122419196025;1972.66464701001;7788.844223115534;8591.699290524833;8679.088695686949;8937.329700272488;8942.463452192866;8455.154484551542;8485.193906578881;8533;8571.014333351652;8596.527191004492;2429.416939934412;2435.0503303711566;2453.815963802713;2457.606782914734;2466.087547607379;2125.787421257877;2425.995660739664;2454.840435371704;2478.355627536844;2479.2686157583466;2448.1431498975307;2456.6929133858266;2461.4830885513975;2462.5936720441105;2471.0486462711806;2440.938976525601;2453;2462.8645424501647;2466.703995520531;2477.938051548717;2340.3096086654477;2361.8346715729904;2376.085207195242;2381.321323422816;2384.7376788553247;8662.177093176138;8704.782380440476;8708.912910870937;8718.302535797184;8749.56252187388;5055.51972562606;5169.327987361657;5174.97412512935;5184.740762961875;5195.986782577399;3413.6415676354122;3456.585209774818;3487.337405892872;3493.825308734553;3524.2951409717903;888.9466632002101;1023.4268809466722;1049.3231865446821;1054.984175237372;1067.8398240263946;10408.583656653725;10446.582136714447;10455.790884182274;10483.58065677372;10542.314749541256;4556.066006468666;4572.451305843254;4584.518625544337;4595.839145629954;4612.60792832613;9013;9095.408798428081;9117.908820911838;9121.543922803834;9125.908740912506;4487.125010622963;4956.35567376246;5101.362329708786;5101.923471147936;5136.948630513722;4061.5532291447917;4389.714668546534;4617.238155704271;4803.255495398219;4862.635302352338;4076.2662720709964;4115.271096697022;4170.311898536707;4191.203671302443;4199.496060472763;1342.778441557132;1374.924379159156;1378.30395650196;1379.5309594737837;1384.7161331926932;692.9722811087601;822.6503735912213;871.0549054276181;927.9396839205565;956.818204541135;5828.766849326064;5903.645781253139;5906.26171728534;5978.192943952511;6087.36082711309;448.6881617275962;457.9519150489217;462.0597084932147;462.3134645052095;467.09150701885187;3551;3913.7847418391693;3922.4508568800393;4012.9398059029136;4020.43713880057;10165.695029149138;10432.843507347396;10597;10917.072048875776;10954.397508137128;10325.225608079425;10338.379697218192;10341;10375.066244037922;10434.113100386401;7819.257170568787;7960.761177164579;8007.07918589358;8119.75640730779;8298.419110662317;5712.514436272963;5755.884882302415;5804.564657650609;5838;5838.211841401357;9415.2467802577;9445.527723613654;9543.665971690958;9602.039796020343;9639.614415423584;9700.9813969535;9770.80458390814;9822.75443113928;9832.262580306362;9860.556274967501;6191.783287584913;6793.184817821893;7047.541909775849;7456.366208872089;7558.2441755823975;7273.8908916365235;8227.76583512478;8329.95835020821;8387.580620968805;8397.916020839957;7276.672549735169;7378.1515125761225;7425.034745483053;7489.475736698379;7532.39740820744;7277.417806575355;7357.411407087528;7393.593352365671;7463.402927765657;7514.62426878643;6888.483363747639;6898.655067246718;6930.618815964968;6950.54821436605;6963.860722785617;4842.878928026827;4843.2735089736825;4848.321235027029;4941.234108713155;4944.1100601892;3799.8670046548236;4228.513720922133;4840.104580652591;4853.878653033702;4901.264810278487 06:19:33 INFO - PROCESS | 1662 | |4;prototype.html;312.16651540387176;319.99040028799135;325.3963896971118;325.7914934441956;327.2718202000549;6545.803625891219;6636.577649158059;6705.094812200357;6739.898901516475;6767.76312829051;5541.944580554194;6754.831129221772;6762.526623136382;6772.966135169318;6797.184337879461;4923.6307276954285;4944.975275123619;4964.304997300381;4976.178930476467;5006.098902197603;4965.478624744404;4966.428860681014;4969.527894850002;4976.4277108132665;4979.203327467606;4362.476502819666;4583.129205450954;4896.46138924718;4907.2148456247005;4923.236898280772;4744.715317080986;4901.65688401812;4907;4910.263460480938;4928.926066109011;3574.1779390740016;3693.6675699187203;4238.427812245338;4239.830406783725;4244.129953359554;6376.904346434807;6381.489480841522;6383.202099737533;6390.3609639036185;6425.582337148117;4814.374131362935;5853.858497592971;5929.970350148222;5953.13679516472;5961.821145365646;6129.908051379233;6869.694757995965;7158.319959603829;7180.748673796392;7259.528130671542;4563.247064234397;4771.904561908743;4797.856064318076;4800.399950006249;4810.6873053251775;7458.216887226818;7583.241675832428;7643.885341719879;7659.578723170223;7664.233576642403;4896.069746748107;4898.53463920927;4908.092002979461;4933.432655244621;4950.455449900508;4101.958980410158;4133.793310334531;4960.875978100504;4985.775640096204;4991.151504244287;4761.214399624093;5888.381719919433;5911.113333000085;5927.4665280125;5941.435563621449;7015.824604384931;7239.239879812544;7259.201487836333;7264.382527485223;7302.379297759644;1439.8776104031065;1693.898366098038;1712;1724.8275172482902;1728.075479618398;1031.3090229546222;1090.7436752363187;1096.2928910852456;1113.7716768062528;1124.971875703114;5522.696251706115;6122.806052819657;6620.4041636252;6782.694778734969;6814.591124532445;7525.473216874766;7556.2443755624;7572.561213369442;7573.129090154593;7573.2048134946135;5661.2923384576925;6539.08452816606;6668.599884006975;6786.4570834333135;6805.659717014229;3815.3895376740143;3948.190620922704;3971.8283106483655;3976.8608098716404;3988.282109220368;7613.667608168548;7873.842523149505;7875.566843823644;7888.566128862853;7895.092064412551;6470.320616335312;7476.925230747732;7680.9615951921;7757.107932587712;7966.880496792553;6336.936630633634;6632.8673426531195;6644.468442524587;7886;7909.762707118681;6437.388448097423;6908.1364829396325;7232.963835180791;7370.041894553674;7470.0662417197855;7344.742933997284;7345.045144131229;7359.080114985627;7362.484626076123;7382.335589796944;7832.7467605185175;7834.960825196065;7855;7877.133515313197;7915.248051435105;4850.708957462563;4861.4895435980125;4873.780679869344;4875.68308059975;4905.950940490549;7174.748883789042;7332.450066244946;7442.06974128234;7451.2921272479025;7469.1037075551285;7127.180374256819;7171.426285897011;7183.568985860865;7184.317489838457;7187.640617968976;7106.218315985134;7136;7155.821104472429;7171.211166771546;7186.496945213785;5750.424957504216;6979.720811167496;6989.440844732511;7039.1553013638695;7046.048783414173;6876.449884009367;7156.928430715626;7164;7186.748463803742;7209.603471808891;6085.2393450818645;6382.968085159545;6868.553544019623;7129.251428599922;7134.536255143399 06:19:33 INFO - PROCESS | 1662 | |5;yui.html;683.4703105093555;688.7520492622657;694.2744831650924;712.1667648850844;732.3847967707127;14143.080699754522;14555.417783288682;14925;15006.39974401024;15181.924090379547;11129.944350278258;13732.58802235932;13897.513587024456;14039.227842468661;14115.43538258471;708.252793303065;767.8464307138566;775.1976704111246;885.0220506340497;896.6682327538787;887.8490656588364;893.7363477774072;913.8218047480744;915.2403505090758;916.3814425262955;945.0643862576069;949.4682977532569;950.3110245072336;950.7765675066354;953.3945944325369;925.833349997007;941.2422999485411;948.340903072365;949.6201519392264;952.2144231009411;665.2050799294844;676.9932300677028;681.2097966359049;688.6487891175486;692.3976140757574;14138.222397768119;14181;14299.714005719932;14362.64093397663;14781.704365912728;12599.748005039939;12714;12789.296588687619;12862.742745145044;12887.742245155045;9958.90041099587;10016.949915250378;10154.441505717183;10170.237232207613;10190.541425635864;3462.549868517102;3486.250456151914;3495.8951231463097;3551.4317709166407;3556.022093924166;75477.62261188714;76248.61875690697;77608.22391776067;77814.61092694556;77993;9192.49441280736;9444.716658500256;9625.614975401046;9701.126898579161;9705.757356066155;21351.89324053401;21540.246091386725;21866.45333866666;21983.890080549496;22061.11755529761;7308.524945878501;7340.96329518349;7357.705691772269;7409.666565004588;7423.740169094056;3974.7217694761666;4021.678265738734;4067.247559201557;4083.7958102094776;4181.97909010453;3569.7679650822615;3579.19468119671;3602.1354874829854;3630.1650620356936;3641.308151451216;633.8478765096344;637.2416823979449;637.7927173668576;648.7437462202404;649.7141257846532;582.9679367634736;583.4748726146502;587.4595372257534;611.8280105625014;625.7278084033369;7975.282224599814;8180.304674102649;8304.294134998592;8444.282236009885;8619.439736417113;561.6237121128853;562.4600383631678;577.1026054485231;588.1501230721651;605.3704147686445;3692.649198326155;3821.7133714971487;3860.3051450738576;4045.5752146024834;4128.112455822013;66730.66634666892;85570.57214713887;87157;87414.56292718623;91771;72705.90941135887;73144.63427682828;73986.26013739794;74611.62694186495;74780.62609686916;39381.03094845246;58754.23737287888;59136;63130.68434657706;69997.65001174962;8468.237858592756;8498.74503764888;8609.397342186237;8648.135186481302;8715.694950676696;42098;45368.09263814771;48015.51984480111;49031.509684902696;50603.493965059875;61990.45023874259;62610;65801.34198657951;67346.98979515213;68315;10996.175286853359;11134.88865111371;11261.943690281496;11266.66200014001;11442.656720298404;11655.899016002319;12018.47776522137;12119.878801212228;12381.504739810307;12695.42870570808;9437.764055898657;9927;11397.772044558897;11866.99130573911;12011.879881201075;11078.833817492905;11339;11378.772424551296;11792.056635469313;12032.879671203176;9413.717588472357;10929.78140437171;11251.437428128396;11377.20359574855;11649.59226427071;7967.964164658557;9605.231581473325;9638.084381983701;9718.562664680248;9742.871614575439;9463.15468483639;9677.612895484102;9683.612655493984;9736.269779766688;9762.60949562038 06:19:33 INFO - PROCESS | 1662 | __end_tp_report 06:19:33 INFO - PROCESS | 1662 | __start_cc_report 06:19:33 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,416 06:19:33 INFO - PROCESS | 1662 | __end_cc_report 06:19:33 INFO - PROCESS | 1662 | __startTimestamp1463836773844__endTimestamp 06:19:33 INFO - PROCESS | 1662 | 06:19:33 INFO - PROCESS | 1662 | ------- Summary: start ------- 06:19:33 INFO - PROCESS | 1662 | Number of tests: 6 06:19:33 INFO - PROCESS | 1662 | 06:19:33 INFO - PROCESS | 1662 | [#0] dojo.html Cycles:165 Average:5818.25 Median:6070.00 stddev:667.87 (11.0%) stddev-sans-first:633.16 06:19:33 INFO - PROCESS | 1662 | Values: 3032.7 3209.5 3520.6 3552.2 3582.9 5701.7 5747.5 5760.0 5782.1 5786.8 5730.4 5785.8 5818.0 5846.9 5878.4 5257.2 5268.8 5315.8 5322.0 5340.6 5244.5 5287.9 5327.0 5391.5 5409.9 5343.8 5346.5 5350.8 5358.9 5368.8 5300.7 5310.2 5341.2 5363.5 5434.6 5146.0 5162.7 5196.5 5273.9 5286.7 4564.9 5197.9 5206.7 5659.5 5748.8 6289.8 6323.8 6332.1 6348.7 6353.7 6179.9 6253.2 6271.5 6304.7 6323.7 5879.6 5906.1 5912.3 5924.9 5963.8 5493.7 6338.2 6384.6 6400.8 6419.9 6142.7 6270.7 6274.8 6288.2 6288.3 6332.2 6341.6 6349.9 6371.4 6377.0 6137.0 6170.6 6267.0 6313.9 6343.9 6278.4 6311.1 6323.2 6351.8 6364.1 4960.8 5005.4 5016.9 5024.7 5031.3 4318.0 4486.7 4512.8 4537.8 4571.7 5771.7 5869.4 5889.2 5920.9 5968.4 6226.8 6290.7 6328.4 6334.5 6363.3 4927.5 5754.8 5887.6 5954.1 5967.3 5475.5 5510.4 5515.7 5527.9 5537.2 6520.1 6526.8 6544.6 6545.6 6548.1 6238.5 6262.9 6309.7 6311.1 6315.9 6154.9 6518.5 6519.2 6520.3 6524.2 5500.8 5818.8 6326.1 6326.5 6339.2 6236.6 6247.9 6276.2 6293.8 6351.8 6010.6 6070.0 6252.7 6253.0 6275.9 6085.0 6236.4 6261.2 6281.3 6283.6 5909.7 6017.6 6099.3 6107.7 6127.2 6223.9 6230.1 6255.1 6263.4 6271.2 5964.7 6092.7 6153.2 6172.3 6181.8 06:19:33 INFO - PROCESS | 1662 | 06:19:33 INFO - PROCESS | 1662 | [#1] ext.html Cycles:180 Average:74055.14 Median:205840.81 stddev:185356.69 (90.0%) stddev-sans-first:185794.40 06:19:33 INFO - PROCESS | 1662 | Values: 503.3 549.1 553.5 558.9 560.7 38690.0 39252.0 39270.6 39295.2 39360.6 31919.7 39445.8 40389.8 40682.2 40813.6 9429.3 9545.3 9555.0 9663.7 9676.0 8571.2 9618.9 9673.6 9743.3 9820.9 9761.3 9806.4 9862.2 9881.5 9881.8 9675.4 9704.7 9780.8 9837.4 9856.6 8553.1 9488.6 9639.4 9747.0 9771.0 39656.0 39920.6 40145.0 40147.4 40171.8 2774.3 3034.3 3098.6 3123.0 3243.1 26121.0 26179.6 27135.6 27582.0 27591.0 2656.9 2667.7 2683.1 2730.5 2761.5 370981.0 372286.0 373290.0 374174.0 375933.0 34788.0 35100.5 35114.6 35474.5 36091.0 35426.0 35767.6 35817.0 36015.3 36166.5 15021.0 15047.8 15158.5 15217.2 15261.0 12618.0 12653.4 12966.3 13005.3 13111.8 7900.6 8012.0 8064.8 8155.9 8219.9 3419.9 3439.6 3489.7 3497.6 3522.6 971.6 980.0 1028.1 1110.9 1201.3 30129.5 30470.0 30472.7 30479.5 30570.8 1090.0 1122.6 1130.9 1132.6 1231.3 15212.5 15508.8 15600.8 15817.4 15874.4 815578.0 971598.0 991579.0 994133.0 994136.0 61088.7 61273.7 61611.7 61688.1 61785.0 1488.8 1495.6 1500.8 1500.9 1502.9 29399.9 30481.9 30642.7 30958.7 31108.8 52988.7 53145.5 53572.0 53582.5 53761.5 577479.5 590543.0 591457.0 594373.0 594588.0 27835.9 27855.7 27875.6 28122.9 28191.4 45165.0 45835.8 46868.0 46992.5 47627.0 38156.4 38309.6 38338.2 38452.4 39332.8 37364.3 37507.6 37702.4 38025.6 38305.0 33540.0 36917.0 38323.2 38335.6 38360.0 29643.0 31516.5 32705.0 32903.7 33132.3 22964.3 24972.8 25037.6 25092.4 25418.6 06:19:33 INFO - PROCESS | 1662 | 06:19:33 INFO - PROCESS | 1662 | [#2] jquery.html Cycles:180 Average:34823.94 Median:22038.80 stddev:101437.68 (460.3%) stddev-sans-first:101689.31 06:19:33 INFO - PROCESS | 1662 | Values: 398.3 403.8 404.7 409.5 411.6 13697.8 20276.5 20535.4 20977.6 21054.3 21057.9 21443.0 21489.8 21629.0 22090.1 5446.9 5957.3 6028.9 6047.1 6199.5 4684.8 5983.8 6079.0 6128.2 6146.8 5870.3 6066.3 6067.2 6089.3 6105.5 5417.9 6058.7 6089.1 6100.3 6106.7 5207.7 5262.8 5330.0 5349.8 5371.7 26935.6 27148.6 27263.9 27344.3 27395.3 2306.6 2316.1 2751.7 2776.6 2982.9 14869.8 15258.1 15279.1 15324.9 15485.8 1760.5 1813.5 1821.6 1824.1 1835.7 112543.3 118879.0 120584.4 121803.4 121832.8 10242.6 10506.2 10948.6 11365.3 11718.3 13850.6 13863.0 14332.3 14439.9 14442.9 8917.4 9115.2 9131.3 9154.4 9221.2 7583.6 9305.6 9306.7 9339.3 9429.2 6882.2 6906.3 7001.4 7001.8 7014.6 1477.4 1651.4 1940.5 1943.6 1951.4 965.8 1009.3 1009.8 1011.7 1018.7 21248.8 21779.6 21934.5 21987.5 22200.4 929.0 997.3 1003.7 1013.4 1061.5 11455.0 11778.2 11794.4 11797.6 11843.3 605871.0 606214.0 606527.0 609373.0 635073.8 66321.0 90152.0 114203.0 114400.4 115521.0 985.3 1062.5 1071.6 1078.6 1093.5 18375.9 18706.6 18862.9 18877.5 19265.3 49894.3 50436.5 50450.0 52779.7 53478.5 47092.1 74218.0 74458.5 76295.2 76892.6 8281.2 8287.6 8368.3 8493.7 8535.2 13189.9 13193.1 13316.5 13475.5 13538.9 11361.2 11547.3 11596.2 11599.4 11632.0 11228.2 11290.4 11435.5 11446.3 11479.4 11647.7 11677.3 12047.7 12058.9 12060.6 7027.9 8211.5 8227.3 8382.6 8619.9 9398.9 11604.5 11795.8 11831.6 11954.0 06:19:33 INFO - PROCESS | 1662 | 06:19:33 INFO - PROCESS | 1662 | [#3] mootools.html Cycles:180 Average:5558.28 Median:4858.26 stddev:3058.10 (62.9%) stddev-sans-first:3051.58 06:19:33 INFO - PROCESS | 1662 | Values: 1515.4 1597.1 1816.5 1898.2 1972.7 7788.8 8591.7 8679.1 8937.3 8942.5 8455.2 8485.2 8533.0 8571.0 8596.5 2429.4 2435.1 2453.8 2457.6 2466.1 2125.8 2426.0 2454.8 2478.4 2479.3 2448.1 2456.7 2461.5 2462.6 2471.0 2440.9 2453.0 2462.9 2466.7 2477.9 2340.3 2361.8 2376.1 2381.3 2384.7 8662.2 8704.8 8708.9 8718.3 8749.6 5055.5 5169.3 5175.0 5184.7 5196.0 3413.6 3456.6 3487.3 3493.8 3524.3 888.9 1023.4 1049.3 1055.0 1067.8 10408.6 10446.6 10455.8 10483.6 10542.3 4556.1 4572.5 4584.5 4595.8 4612.6 9013.0 9095.4 9117.9 9121.5 9125.9 4487.1 4956.4 5101.4 5101.9 5136.9 4061.6 4389.7 4617.2 4803.3 4862.6 4076.3 4115.3 4170.3 4191.2 4199.5 1342.8 1374.9 1378.3 1379.5 1384.7 693.0 822.7 871.1 927.9 956.8 5828.8 5903.6 5906.3 5978.2 6087.4 448.7 458.0 462.1 462.3 467.1 3551.0 3913.8 3922.5 4012.9 4020.4 10165.7 10432.8 10597.0 10917.1 10954.4 10325.2 10338.4 10341.0 10375.1 10434.1 7819.3 7960.8 8007.1 8119.8 8298.4 5712.5 5755.9 5804.6 5838.0 5838.2 9415.2 9445.5 9543.7 9602.0 9639.6 9701.0 9770.8 9822.8 9832.3 9860.6 6191.8 6793.2 7047.5 7456.4 7558.2 7273.9 8227.8 8330.0 8387.6 8397.9 7276.7 7378.2 7425.0 7489.5 7532.4 7277.4 7357.4 7393.6 7463.4 7514.6 6888.5 6898.7 6930.6 6950.5 6963.9 4842.9 4843.3 4848.3 4941.2 4944.1 3799.9 4228.5 4840.1 4853.9 4901.3 06:19:33 INFO - PROCESS | 1662 | 06:19:33 INFO - PROCESS | 1662 | [#4] prototype.html Cycles:180 Average:5829.55 Median:6504.70 stddev:1869.31 (28.7%) stddev-sans-first:1828.11 06:19:33 INFO - PROCESS | 1662 | Values: 312.2 320.0 325.4 325.8 327.3 6545.8 6636.6 6705.1 6739.9 6767.8 5541.9 6754.8 6762.5 6773.0 6797.2 4923.6 4945.0 4964.3 4976.2 5006.1 4965.5 4966.4 4969.5 4976.4 4979.2 4362.5 4583.1 4896.5 4907.2 4923.2 4744.7 4901.7 4907.0 4910.3 4928.9 3574.2 3693.7 4238.4 4239.8 4244.1 6376.9 6381.5 6383.2 6390.4 6425.6 4814.4 5853.9 5930.0 5953.1 5961.8 6129.9 6869.7 7158.3 7180.7 7259.5 4563.2 4771.9 4797.9 4800.4 4810.7 7458.2 7583.2 7643.9 7659.6 7664.2 4896.1 4898.5 4908.1 4933.4 4950.5 4102.0 4133.8 4960.9 4985.8 4991.2 4761.2 5888.4 5911.1 5927.5 5941.4 7015.8 7239.2 7259.2 7264.4 7302.4 1439.9 1693.9 1712.0 1724.8 1728.1 1031.3 1090.7 1096.3 1113.8 1125.0 5522.7 6122.8 6620.4 6782.7 6814.6 7525.5 7556.2 7572.6 7573.1 7573.2 5661.3 6539.1 6668.6 6786.5 6805.7 3815.4 3948.2 3971.8 3976.9 3988.3 7613.7 7873.8 7875.6 7888.6 7895.1 6470.3 7476.9 7681.0 7757.1 7966.9 6336.9 6632.9 6644.5 7886.0 7909.8 6437.4 6908.1 7233.0 7370.0 7470.1 7344.7 7345.0 7359.1 7362.5 7382.3 7832.7 7835.0 7855.0 7877.1 7915.2 4850.7 4861.5 4873.8 4875.7 4906.0 7174.7 7332.5 7442.1 7451.3 7469.1 7127.2 7171.4 7183.6 7184.3 7187.6 7106.2 7136.0 7155.8 7171.2 7186.5 5750.4 6979.7 6989.4 7039.2 7046.0 6876.4 7156.9 7164.0 7186.7 7209.6 6085.2 6383.0 6868.6 7129.3 7134.5 06:19:33 INFO - PROCESS | 1662 | 06:19:33 INFO - PROCESS | 1662 | [#5] yui.html Cycles:180 Average:17319.72 Median:62870.34 stddev:23727.26 (37.7%) stddev-sans-first:23760.94 06:19:33 INFO - PROCESS | 1662 | Values: 683.5 688.8 694.3 712.2 732.4 14143.1 14555.4 14925.0 15006.4 15181.9 11129.9 13732.6 13897.5 14039.2 14115.4 708.3 767.8 775.2 885.0 896.7 887.8 893.7 913.8 915.2 916.4 945.1 949.5 950.3 950.8 953.4 925.8 941.2 948.3 949.6 952.2 665.2 677.0 681.2 688.6 692.4 14138.2 14181.0 14299.7 14362.6 14781.7 12599.7 12714.0 12789.3 12862.7 12887.7 9958.9 10016.9 10154.4 10170.2 10190.5 3462.5 3486.3 3495.9 3551.4 3556.0 75477.6 76248.6 77608.2 77814.6 77993.0 9192.5 9444.7 9625.6 9701.1 9705.8 21351.9 21540.2 21866.5 21983.9 22061.1 7308.5 7341.0 7357.7 7409.7 7423.7 3974.7 4021.7 4067.2 4083.8 4182.0 3569.8 3579.2 3602.1 3630.2 3641.3 633.8 637.2 637.8 648.7 649.7 583.0 583.5 587.5 611.8 625.7 7975.3 8180.3 8304.3 8444.3 8619.4 561.6 562.5 577.1 588.2 605.4 3692.6 3821.7 3860.3 4045.6 4128.1 66730.7 85570.6 87157.0 87414.6 91771.0 72705.9 73144.6 73986.3 74611.6 74780.6 39381.0 58754.2 59136.0 63130.7 69997.7 8468.2 8498.7 8609.4 8648.1 8715.7 42098.0 45368.1 48015.5 49031.5 50603.5 61990.5 62610.0 65801.3 67347.0 68315.0 10996.2 11134.9 11261.9 11266.7 11442.7 11655.9 12018.5 12119.9 12381.5 12695.4 9437.8 9927.0 11397.8 11867.0 12011.9 11078.8 11339.0 11378.8 11792.1 12032.9 9413.7 10929.8 11251.4 11377.2 11649.6 7968.0 9605.2 9638.1 9718.6 9742.9 9463.2 9677.6 9683.6 9736.3 9762.6 06:19:33 INFO - PROCESS | 1662 | -------- Summary: end -------- 06:19:33 INFO - PROCESS | 1662 | 06:19:34 INFO - TEST-INFO | 1662: exit 0 06:19:34 INFO - TEST-OK | dromaeo_css | took 1112203ms 06:19:34 INFO - TEST-START | kraken 06:19:34 INFO - operating with platform_type : mac_ 06:19:34 INFO - Initialising browser for kraken test... 06:19:34 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/tmpBpqDAr/profile http://localhost:49271/getInfo.html) 06:19:37 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 06:19:37 INFO - PROCESS | 1671 | colorDepth:24 06:19:37 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 06:19:37 INFO - PROCESS | 1671 | __metrics 06:19:37 ERROR - PROCESS | 1671 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2946: TypeError: this.tabs is undefined 06:19:37 INFO - TEST-INFO | 1671: exit 0 06:19:37 INFO - Browser initialized. 06:19:37 INFO - Running cycle 1/1 for kraken test... 06:19:37 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/T/tmpBpqDAr/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:19:49 INFO - PROCESS | 1674 | RSS: Main: 220487680 06:19:49 INFO - PROCESS | 1674 | 06:19:52 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 06:19:53 INFO - PROCESS | 1674 | RSS: Main: 232640512 06:19:53 INFO - PROCESS | 1674 | 06:19:56 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 06:19:56 INFO - PROCESS | 1674 | RSS: Main: 241516544 06:19:56 INFO - PROCESS | 1674 | 06:19:59 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 06:20:00 INFO - PROCESS | 1674 | RSS: Main: 245100544 06:20:00 INFO - PROCESS | 1674 | 06:20:02 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 06:20:03 INFO - PROCESS | 1674 | RSS: Main: 246718464 06:20:03 INFO - PROCESS | 1674 | 06:20:05 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:20:05 INFO - PROCESS | 1674 | RSS: Main: 246976512 06:20:05 INFO - PROCESS | 1674 | 06:20:08 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 06:20:09 INFO - PROCESS | 1674 | RSS: Main: 247791616 06:20:09 INFO - PROCESS | 1674 | 06:20:12 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 06:20:12 INFO - PROCESS | 1674 | RSS: Main: 247554048 06:20:12 INFO - PROCESS | 1674 | 06:20:15 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 06:20:16 INFO - PROCESS | 1674 | RSS: Main: 247767040 06:20:16 INFO - PROCESS | 1674 | 06:20:18 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:20:18 INFO - PROCESS | 1674 | RSS: Main: 247742464 06:20:18 INFO - PROCESS | 1674 | 06:20:20 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:20:21 INFO - PROCESS | 1674 | RSS: Main: 247320576 06:20:21 INFO - PROCESS | 1674 | 06:20:23 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:20:24 INFO - PROCESS | 1674 | RSS: Main: 247365632 06:20:24 INFO - PROCESS | 1674 | 06:20:27 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:20:27 INFO - PROCESS | 1674 | RSS: Main: 247382016 06:20:27 INFO - PROCESS | 1674 | 06:20:30 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:20:31 INFO - PROCESS | 1674 | RSS: Main: 247345152 06:20:31 INFO - PROCESS | 1674 | 06:20:33 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 06:20:33 INFO - PROCESS | 1674 | RSS: Main: 249036800 06:20:33 INFO - PROCESS | 1674 | 06:20:33 INFO - PROCESS | 1674 | __start_tp_report 06:20:33 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 06:20:33 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 06:20:33 INFO - PROCESS | 1674 | |i|pagename|runs| 06:20:33 INFO - PROCESS | 1674 | |0;ai-astar;113;120;110;123;119;118;106;118;115;124 06:20:33 INFO - PROCESS | 1674 | |1;audio-beat-detection;166;142;165;156;124;136;113;180;193;144 06:20:33 INFO - PROCESS | 1674 | |2;audio-dft;199;201;198;198;195;192;196;201;197;197 06:20:33 INFO - PROCESS | 1674 | |3;audio-fft;66;71;80;138;56;56;58;63;124;56 06:20:33 INFO - PROCESS | 1674 | |4;audio-oscillator;73;78;128;81;82;81;117;82;79;124 06:20:33 INFO - PROCESS | 1674 | |5;imaging-gaussian-blur;93;91;92;91;95;91;94;88;123;90 06:20:33 INFO - PROCESS | 1674 | |6;imaging-darkroom;97;115;152;99;98;99;98;114;152;152 06:20:33 INFO - PROCESS | 1674 | |7;imaging-desaturate;97;95;94;146;151;90;93;92;92;115 06:20:33 INFO - PROCESS | 1674 | |8;json-parse-financial;51;97;99;55;72;95;104;116;80;61 06:20:33 INFO - PROCESS | 1674 | |9;json-stringify-tinderbox;67;67;84;112;61;90;100;60;93;105 06:20:33 INFO - PROCESS | 1674 | |10;stanford-crypto-aes;80;83;91;72;100;110;103;108;109;84 06:20:33 INFO - PROCESS | 1674 | |11;stanford-crypto-ccm;165;131;123;148;127;131;130;132;181;144 06:20:33 INFO - PROCESS | 1674 | |12;stanford-crypto-pbkdf2;171;169;197;234;200;168;167;167;152;153 06:20:33 INFO - PROCESS | 1674 | |13;stanford-crypto-sha256-iterative;58;99;101;99;70;67;68;66;71;70 06:20:33 INFO - PROCESS | 1674 | __end_tp_report 06:20:33 INFO - PROCESS | 1674 | __start_cc_report 06:20:33 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,1105 06:20:33 INFO - PROCESS | 1674 | __end_cc_report 06:20:33 INFO - PROCESS | 1674 | __startTimestamp1463836833554__endTimestamp 06:20:33 INFO - PROCESS | 1674 | 06:20:33 INFO - PROCESS | 1674 | ------- Summary: start ------- 06:20:33 INFO - PROCESS | 1674 | Number of tests: 14 06:20:33 INFO - PROCESS | 1674 | 06:20:33 INFO - PROCESS | 1674 | [#0] ai-astar Cycles:10 Average:116.60 Median:118.50 stddev:5.66 (4.8%) stddev-sans-first:5.85 06:20:33 INFO - PROCESS | 1674 | Values: 113.0 120.0 110.0 123.0 119.0 118.0 106.0 118.0 115.0 124.0 06:20:33 INFO - PROCESS | 1674 | 06:20:33 INFO - PROCESS | 1674 | [#1] audio-beat-detection Cycles:10 Average:151.90 Median:160.50 stddev:24.88 (15.5%) stddev-sans-first:25.86 06:20:33 INFO - PROCESS | 1674 | Values: 166.0 142.0 165.0 156.0 124.0 136.0 113.0 180.0 193.0 144.0 06:20:33 INFO - PROCESS | 1674 | 06:20:33 INFO - PROCESS | 1674 | [#2] audio-dft Cycles:10 Average:197.40 Median:198.00 stddev:2.72 (1.4%) stddev-sans-first:2.82 06:20:33 INFO - PROCESS | 1674 | Values: 199.0 201.0 198.0 198.0 195.0 192.0 196.0 201.0 197.0 197.0 06:20:33 INFO - PROCESS | 1674 | 06:20:33 INFO - PROCESS | 1674 | [#3] audio-fft Cycles:10 Average:76.80 Median:60.50 stddev:29.77 (49.2%) stddev-sans-first:31.32 06:20:33 INFO - PROCESS | 1674 | Values: 66.0 71.0 80.0 138.0 56.0 56.0 58.0 63.0 124.0 56.0 06:20:33 INFO - PROCESS | 1674 | 06:20:33 INFO - PROCESS | 1674 | [#4] audio-oscillator Cycles:10 Average:92.50 Median:80.00 stddev:21.37 (26.7%) stddev-sans-first:21.47 06:20:33 INFO - PROCESS | 1674 | Values: 73.0 78.0 128.0 81.0 82.0 81.0 117.0 82.0 79.0 124.0 06:20:33 INFO - PROCESS | 1674 | 06:20:33 INFO - PROCESS | 1674 | [#5] imaging-gaussian-blur Cycles:10 Average:94.80 Median:91.50 stddev:10.11 (11.0%) stddev-sans-first:10.70 06:20:33 INFO - PROCESS | 1674 | Values: 93.0 91.0 92.0 91.0 95.0 91.0 94.0 88.0 123.0 90.0 06:20:33 INFO - PROCESS | 1674 | 06:20:33 INFO - PROCESS | 1674 | [#6] imaging-darkroom Cycles:10 Average:117.60 Median:97.50 stddev:24.62 (25.2%) stddev-sans-first:24.96 06:20:33 INFO - PROCESS | 1674 | Values: 97.0 115.0 152.0 99.0 98.0 99.0 98.0 114.0 152.0 152.0 06:20:33 INFO - PROCESS | 1674 | 06:20:33 INFO - PROCESS | 1674 | [#7] imaging-desaturate Cycles:10 Average:106.50 Median:92.50 stddev:23.25 (25.1%) stddev-sans-first:24.41 06:20:33 INFO - PROCESS | 1674 | Values: 97.0 95.0 94.0 146.0 151.0 90.0 93.0 92.0 92.0 115.0 06:20:33 INFO - PROCESS | 1674 | 06:20:33 INFO - PROCESS | 1674 | [#8] json-parse-financial Cycles:10 Average:83.00 Median:76.00 stddev:22.48 (29.6%) stddev-sans-first:20.65 06:20:33 INFO - PROCESS | 1674 | Values: 51.0 97.0 99.0 55.0 72.0 95.0 104.0 116.0 80.0 61.0 06:20:33 INFO - PROCESS | 1674 | 06:20:33 INFO - PROCESS | 1674 | [#9] json-stringify-tinderbox Cycles:10 Average:83.90 Median:67.00 stddev:19.09 (28.5%) stddev-sans-first:19.25 06:20:33 INFO - PROCESS | 1674 | Values: 67.0 67.0 84.0 112.0 61.0 90.0 100.0 60.0 93.0 105.0 06:20:33 INFO - PROCESS | 1674 | 06:20:33 INFO - PROCESS | 1674 | [#10] stanford-crypto-aes Cycles:10 Average:94.00 Median:76.00 stddev:13.76 (18.1%) stddev-sans-first:13.63 06:20:33 INFO - PROCESS | 1674 | Values: 80.0 83.0 91.0 72.0 100.0 110.0 103.0 108.0 109.0 84.0 06:20:33 INFO - PROCESS | 1674 | 06:20:33 INFO - PROCESS | 1674 | [#11] stanford-crypto-ccm Cycles:10 Average:141.20 Median:138.00 stddev:18.72 (13.6%) stddev-sans-first:17.77 06:20:33 INFO - PROCESS | 1674 | Values: 165.0 131.0 123.0 148.0 127.0 131.0 130.0 132.0 181.0 144.0 06:20:33 INFO - PROCESS | 1674 | 06:20:33 INFO - PROCESS | 1674 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.80 Median:170.00 stddev:25.28 (14.9%) stddev-sans-first:26.70 06:20:33 INFO - PROCESS | 1674 | Values: 171.0 169.0 197.0 234.0 200.0 168.0 167.0 167.0 152.0 153.0 06:20:33 INFO - PROCESS | 1674 | 06:20:33 INFO - PROCESS | 1674 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.90 Median:70.00 stddev:16.13 (23.0%) stddev-sans-first:15.59 06:20:33 INFO - PROCESS | 1674 | Values: 58.0 99.0 101.0 99.0 70.0 67.0 68.0 66.0 71.0 70.0 06:20:33 INFO - PROCESS | 1674 | -------- Summary: end -------- 06:20:33 INFO - PROCESS | 1674 | 06:20:34 INFO - TEST-INFO | 1674: exit 0 06:20:34 INFO - TEST-OK | kraken | took 59906ms 06:20:34 INFO - SUITE-END | took 1172s 06:20:34 INFO - Completed test suite (00:19:32) 06:20:34 INFO - javascript benchmark 06:20:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3032.6967303269676, 3209.518572214166, 3520.6127325994134, 3552.1652411683267, 3582.9251224632603, 5701.657900525961, 5747.482726554609, 5760.020796464601, 5782.103773915044, 5786.768529258829, 5730.398308177644, 5785.82642520724, 5817.970910145454, 5846.912296315548, 5878.441548052939, 5257.1851363038595, 5268.841934741954, 5315.760790764425, 5321.973390133063, 5340.599455040888, 5244.527992480676, 5287.894242115155, 5327.0, 5391.460853914617, 5409.918851217235, 5343.786248550054, 5346.465353464642, 5350.8394748157425, 5358.866028349284, 5368.8389348319615, 5300.655457395257, 5310.176922577007, 5341.172118321647, 5363.517283444508, 5434.565234781208, 5146.022255771429, 5162.6902385856965, 5196.480351964811, 5273.868153296198, 5286.709230992294, 4564.908701825978, 5197.870053248661, 5206.68759874405, 5659.51894089003, 5748.770049198027, 6289.842753931142, 6323.810285691437, 6332.145160403333, 6348.682565871688, 6353.682315884187, 6179.938200618026, 6253.187085678833, 6271.529635277372, 6304.716287767015, 6323.7154328054885, 5879.559033072494, 5906.143609176645, 5912.3496415394275, 5924.940750592526, 5963.761449542013, 5493.681516436027, 6338.207724034496, 6384.585001974857, 6400.839979000562, 6419.935800642027, 6142.682951206299, 6270.655113968684, 6274.843128921722, 6288.182536270256, 6288.276848162428, 6332.176817013758, 6341.556091073673, 6349.873002539924, 6371.426571608485, 6376.968115159394, 6137.039921714347, 6170.56806023583, 6267.0, 6313.873722525615, 6343.936560634427, 6278.4035516625845, 6311.147995020705, 6323.20959880015, 6351.777687780906, 6364.1408409865, 4960.801567937314, 5005.399352077774, 5016.924746128811, 5024.673396229234, 5031.270465782441, 4317.956820431818, 4486.663500237495, 4512.81948722054, 4537.8184872604725, 4571.725696458223, 5771.740271687784, 5869.383714709979, 5889.234399528034, 5920.851978700567, 5968.432998865101, 6226.813195604139, 6290.654014029273, 6328.367163283635, 6334.5249106317215, 6363.33185015576, 4927.531884470969, 5754.827355179351, 5887.558433117448, 5954.106883967353, 5967.305985258742, 5475.507204351547, 5510.448955104457, 5515.7242137892945, 5527.861803454865, 5537.224788529609, 6520.087187793618, 6526.836829079311, 6544.640044797582, 6545.639989800511, 6548.148740663683, 6238.532110091671, 6262.874742505033, 6309.684515774285, 6311.053341998737, 6315.936840631719, 6154.922888494585, 6518.478521718346, 6519.152510173647, 6520.282768895512, 6524.151860258136, 5500.752466138953, 5818.836232753551, 6326.082718005955, 6326.493880489642, 6339.207599050119, 6236.563440559299, 6247.875042499216, 6276.152719382825, 6293.779717709858, 6351.777687780906, 6010.639361638316, 6070.0, 6252.687365631791, 6253.030780229072, 6275.905861411991, 6085.0, 6236.438720514994, 6261.24865016201, 6281.3404592518955, 6283.59156654816, 5909.674967876808, 6017.608855424383, 6099.268087829311, 6107.664078475549, 6127.172831667668, 6223.906641400292, 6230.108211330348, 6255.093011513214, 6263.3736626337, 6271.216097987752, 5964.731587078505, 6092.664903430354, 6153.2000839890525, 6172.259328880384, 6181.752729890754], "value": 5775.038031460785, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [503.3456506541497, 549.1076999875203, 553.4963183503013, 558.9301337332835, 560.728046897255, 38690.0, 39252.0, 39270.60729392709, 39295.21409571807, 39360.606393936054, 31919.68080319196, 39445.802770986105, 40389.798051009704, 40682.186356272854, 40813.59186408135, 9429.292803039782, 9545.284103692216, 9554.996725343843, 9663.710088697315, 9676.032396760304, 8571.22858942695, 9618.855717164248, 9673.613055477772, 9743.269254805882, 9820.901790982105, 9761.316707830454, 9806.411615303105, 9862.211023118134, 9881.456519891402, 9881.752956176082, 9675.371100878421, 9704.70885873425, 9780.755481112958, 9837.409755414697, 9856.605735770561, 8553.144685531459, 9488.573014214377, 9639.3734407264, 9746.951265243699, 9771.0, 39656.0, 39920.60079399198, 40145.0, 40147.3977890329, 40171.7991410044, 2774.3064233941514, 3034.332446861687, 3098.5662007318992, 3122.953155702666, 3243.108145260049, 26120.955161793507, 26179.580086365066, 27135.592966105523, 27582.0, 27591.0, 2656.893724251028, 2667.7065522792477, 2683.1279834053817, 2730.481208570385, 2761.4753196892525, 370981.0, 372286.0, 373290.0, 374174.0, 375932.99999999726, 34788.0, 35100.473492897625, 35114.64885351165, 35474.46788298178, 36091.0, 35426.0, 35767.64232357643, 35817.0, 36015.27969440597, 36166.45750313748, 15021.02363346379, 15047.849521504864, 15158.469453569072, 15217.163056032025, 15261.0, 12617.990560755117, 12653.430595623218, 12966.286854223108, 13005.349732513336, 13111.803322950163, 7900.644470998819, 8012.0, 8064.758057258292, 8155.877661834958, 8219.876701849476, 3419.9316013679586, 3439.5528581284775, 3489.6684814943046, 3497.562804649419, 3522.559680039995, 971.640493017588, 979.9657012004545, 1028.1106842581114, 1110.8833572474934, 1201.2972411139406, 30129.548056779167, 30470.0, 30472.69527304743, 30479.542806857913, 30570.847145764128, 1090.0353187429182, 1122.5678113926197, 1130.8982191602795, 1132.6149109302876, 1231.3166192763103, 15212.46756363522, 15508.844911550967, 15600.84399155994, 15817.367305307658, 15874.365025399085, 815578.0, 971598.0, 991579.0, 994133.0, 994136.0, 61088.69455652782, 61273.693631531554, 61611.6919415409, 61688.07467887985, 61785.0, 1488.761798112297, 1495.5812372535925, 1500.7598784194477, 1500.932458039391, 1502.8797696184279, 29399.853000735715, 30481.85331261678, 30642.693573063985, 30958.69041309558, 31108.844455777577, 52988.73505632601, 53145.468545314056, 53572.0, 53582.464175357745, 53761.46238537564, 577479.4504109978, 590543.0, 591457.0, 594373.0, 594588.0, 27835.860820695765, 27855.721442785314, 27875.581866271616, 28122.85938570294, 28191.43617127687, 45165.0, 45835.77082114568, 46868.0, 46992.530074698814, 47627.0, 38156.42765358577, 38309.6169038306, 38338.204801518674, 38452.42321365126, 39332.80333598314, 37364.252714946095, 37507.624923750416, 37702.43446348252, 38025.619743802206, 38305.04237394087, 33540.0, 36917.0, 38323.233535329695, 38335.61664383432, 38360.0, 29643.0, 31516.527252091695, 32705.0, 32903.67096329006, 33132.34338283028, 22964.311070667907, 24972.750272497044, 25037.624435633115, 25092.372690682878, 25418.618720718834], "value": 18506.86611032239, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [398.2811026097894, 403.8384646141545, 404.70052161400565, 409.4799604502282, 411.56785375355867, 13697.767200951912, 20276.493087672796, 20535.38393848187, 20977.580448391043, 21054.263100791475, 21057.947102644885, 21443.0, 21489.785102148973, 21629.026693798776, 22090.11639534421, 5446.863828404302, 5957.344692083867, 6028.914795336859, 6047.123167140762, 6199.473044791198, 4684.765761711918, 5983.760649574012, 6078.969605151968, 6128.172696685957, 6146.7541298348015, 5870.26621672291, 6066.272047354301, 6067.211262535864, 6089.2692876854835, 6105.511559075263, 5417.864553386177, 6058.666773327487, 6089.117078023706, 6100.328963813977, 6106.725197366107, 5207.713575753332, 5262.7631756571045, 5330.013947419701, 5349.839504814861, 5371.650842695251, 26935.595966060526, 27148.592771108448, 27263.86368068147, 27344.316392090157, 27395.31511712203, 2306.550222706573, 2316.131450705985, 2751.683556391021, 2776.6112744215825, 2982.8806847726064, 14869.776953345709, 15258.08451492914, 15279.083255004734, 15324.923375383163, 15485.767713484307, 1760.4718584424622, 1813.5284825945216, 1821.6265665538533, 1824.087956021989, 1835.6879330513846, 112543.25173256236, 118879.0, 120584.39707801405, 121803.39098304452, 121832.78167218303, 10242.59029638821, 10506.159507239403, 10948.56205751761, 11365.261258018203, 11718.296902185895, 13850.584482465541, 13863.0, 14332.283385830668, 14439.92780036093, 14442.855571444361, 8917.375783695208, 9115.179633832855, 9131.26949844011, 9154.404963677473, 9221.216196623229, 7583.62081895903, 9305.581248843819, 9306.674266400643, 9339.299552533586, 9429.15137637616, 6882.242953275136, 6906.343897329745, 7001.374484568287, 7001.789946301517, 7014.579125252501, 1477.4090363854543, 1651.3642247734708, 1940.4760714606978, 1943.6015616798522, 1951.375559820872, 965.8358079126565, 1009.3388830316155, 1009.8030883977701, 1011.6610935336597, 1018.6604614931341, 21248.78751212468, 21779.629306258823, 21934.45163870916, 21987.491728320787, 22200.439612665687, 928.9920436326527, 997.2620261006803, 1003.6587560229411, 1013.3514550687565, 1061.5117046158698, 11455.0, 11778.234414763014, 11794.410279485992, 11797.587084452003, 11843.348615826213, 605871.0, 606214.0, 606527.0, 609372.9531352404, 635073.8246308739, 66321.0, 90152.0, 114203.0, 114400.42799785947, 115521.0, 985.3398223190336, 1062.4846949229618, 1071.5927947379946, 1078.6494389323502, 1093.4805967165532, 18375.897446153274, 18706.597005224936, 18862.86822790637, 18877.528061798566, 19265.325713599956, 49894.251586226965, 50436.495635044645, 50450.0, 52779.73610131925, 53478.46521534735, 47092.05815883732, 74218.0, 74458.51082978418, 76295.23704762882, 76892.61553692419, 8281.17188281167, 8287.58562071906, 8368.330533557422, 8493.660253589787, 8535.189157030072, 13189.868101318863, 13193.076484646366, 13316.46734130624, 13475.528356507475, 13538.93230533841, 11361.204715669825, 11547.307161570332, 11596.246243994114, 11599.362035088152, 11632.0, 11228.214025018171, 11290.435478225892, 11435.542578296774, 11446.313221206754, 11479.36863472517, 11647.6505704829, 11677.299362038304, 12047.698807529881, 12058.939705301418, 12060.577879774166, 7027.9297207027275, 8211.548364839991, 8227.259546640831, 8382.58087095655, 8619.91380086191, 9398.906010940076, 11604.477798499256, 11795.823062653895, 11831.585894493652, 11954.043676506033], "value": 9921.43409327397, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1515.3559737111727, 1597.1455271429784, 1816.5458635341165, 1898.2122419196025, 1972.66464701001, 7788.844223115534, 8591.699290524833, 8679.088695686949, 8937.329700272488, 8942.463452192866, 8455.154484551542, 8485.193906578881, 8533.0, 8571.014333351652, 8596.527191004492, 2429.416939934412, 2435.0503303711566, 2453.815963802713, 2457.606782914734, 2466.087547607379, 2125.787421257877, 2425.995660739664, 2454.840435371704, 2478.355627536844, 2479.2686157583466, 2448.1431498975307, 2456.6929133858266, 2461.4830885513975, 2462.5936720441105, 2471.0486462711806, 2440.938976525601, 2453.0, 2462.8645424501647, 2466.703995520531, 2477.938051548717, 2340.3096086654477, 2361.8346715729904, 2376.085207195242, 2381.321323422816, 2384.7376788553247, 8662.177093176138, 8704.782380440476, 8708.912910870937, 8718.302535797184, 8749.56252187388, 5055.51972562606, 5169.327987361657, 5174.97412512935, 5184.740762961875, 5195.986782577399, 3413.6415676354122, 3456.585209774818, 3487.337405892872, 3493.825308734553, 3524.2951409717903, 888.9466632002101, 1023.4268809466722, 1049.3231865446821, 1054.984175237372, 1067.8398240263946, 10408.583656653725, 10446.582136714447, 10455.790884182274, 10483.58065677372, 10542.314749541256, 4556.066006468666, 4572.451305843254, 4584.518625544337, 4595.839145629954, 4612.60792832613, 9013.0, 9095.408798428081, 9117.908820911838, 9121.543922803834, 9125.908740912506, 4487.125010622963, 4956.35567376246, 5101.362329708786, 5101.923471147936, 5136.948630513722, 4061.5532291447917, 4389.714668546534, 4617.238155704271, 4803.255495398219, 4862.635302352338, 4076.2662720709964, 4115.271096697022, 4170.311898536707, 4191.203671302443, 4199.496060472763, 1342.778441557132, 1374.924379159156, 1378.30395650196, 1379.5309594737837, 1384.7161331926932, 692.9722811087601, 822.6503735912213, 871.0549054276181, 927.9396839205565, 956.818204541135, 5828.766849326064, 5903.645781253139, 5906.26171728534, 5978.192943952511, 6087.36082711309, 448.6881617275962, 457.9519150489217, 462.0597084932147, 462.3134645052095, 467.09150701885187, 3551.0, 3913.7847418391693, 3922.4508568800393, 4012.9398059029136, 4020.43713880057, 10165.695029149138, 10432.843507347396, 10597.0, 10917.072048875776, 10954.397508137128, 10325.225608079425, 10338.379697218192, 10341.0, 10375.066244037922, 10434.113100386401, 7819.257170568787, 7960.761177164579, 8007.07918589358, 8119.75640730779, 8298.419110662317, 5712.514436272963, 5755.884882302415, 5804.564657650609, 5838.0, 5838.211841401357, 9415.2467802577, 9445.527723613654, 9543.665971690958, 9602.039796020343, 9639.614415423584, 9700.9813969535, 9770.80458390814, 9822.75443113928, 9832.262580306362, 9860.556274967501, 6191.783287584913, 6793.184817821893, 7047.541909775849, 7456.366208872089, 7558.2441755823975, 7273.8908916365235, 8227.76583512478, 8329.95835020821, 8387.580620968805, 8397.916020839957, 7276.672549735169, 7378.1515125761225, 7425.034745483053, 7489.475736698379, 7532.39740820744, 7277.417806575355, 7357.411407087528, 7393.593352365671, 7463.402927765657, 7514.62426878643, 6888.483363747639, 6898.655067246718, 6930.618815964968, 6950.54821436605, 6963.860722785617, 4842.878928026827, 4843.2735089736825, 4848.321235027029, 4941.234108713155, 4944.1100601892, 3799.8670046548236, 4228.513720922133, 4840.104580652591, 4853.878653033702, 4901.264810278487], "value": 4438.807317479735, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [312.16651540387176, 319.99040028799135, 325.3963896971118, 325.7914934441956, 327.2718202000549, 6545.803625891219, 6636.577649158059, 6705.094812200357, 6739.898901516475, 6767.76312829051, 5541.944580554194, 6754.831129221772, 6762.526623136382, 6772.966135169318, 6797.184337879461, 4923.6307276954285, 4944.975275123619, 4964.304997300381, 4976.178930476467, 5006.098902197603, 4965.478624744404, 4966.428860681014, 4969.527894850002, 4976.4277108132665, 4979.203327467606, 4362.476502819666, 4583.129205450954, 4896.46138924718, 4907.2148456247005, 4923.236898280772, 4744.715317080986, 4901.65688401812, 4907.0, 4910.263460480938, 4928.926066109011, 3574.1779390740016, 3693.6675699187203, 4238.427812245338, 4239.830406783725, 4244.129953359554, 6376.904346434807, 6381.489480841522, 6383.202099737533, 6390.3609639036185, 6425.582337148117, 4814.374131362935, 5853.858497592971, 5929.970350148222, 5953.13679516472, 5961.821145365646, 6129.908051379233, 6869.694757995965, 7158.319959603829, 7180.748673796392, 7259.528130671542, 4563.247064234397, 4771.904561908743, 4797.856064318076, 4800.399950006249, 4810.6873053251775, 7458.216887226818, 7583.241675832428, 7643.885341719879, 7659.578723170223, 7664.233576642403, 4896.069746748107, 4898.53463920927, 4908.092002979461, 4933.432655244621, 4950.455449900508, 4101.958980410158, 4133.793310334531, 4960.875978100504, 4985.775640096204, 4991.151504244287, 4761.214399624093, 5888.381719919433, 5911.113333000085, 5927.4665280125, 5941.435563621449, 7015.824604384931, 7239.239879812544, 7259.201487836333, 7264.382527485223, 7302.379297759644, 1439.8776104031065, 1693.898366098038, 1712.0, 1724.8275172482902, 1728.075479618398, 1031.3090229546222, 1090.7436752363187, 1096.2928910852456, 1113.7716768062528, 1124.971875703114, 5522.696251706115, 6122.806052819657, 6620.4041636252, 6782.694778734969, 6814.591124532445, 7525.473216874766, 7556.2443755624, 7572.561213369442, 7573.129090154593, 7573.2048134946135, 5661.2923384576925, 6539.08452816606, 6668.599884006975, 6786.4570834333135, 6805.659717014229, 3815.3895376740143, 3948.190620922704, 3971.8283106483655, 3976.8608098716404, 3988.282109220368, 7613.667608168548, 7873.842523149505, 7875.566843823644, 7888.566128862853, 7895.092064412551, 6470.320616335312, 7476.925230747732, 7680.9615951921, 7757.107932587712, 7966.880496792553, 6336.936630633634, 6632.8673426531195, 6644.468442524587, 7886.0, 7909.762707118681, 6437.388448097423, 6908.1364829396325, 7232.963835180791, 7370.041894553674, 7470.0662417197855, 7344.742933997284, 7345.045144131229, 7359.080114985627, 7362.484626076123, 7382.335589796944, 7832.7467605185175, 7834.960825196065, 7855.0, 7877.133515313197, 7915.248051435105, 4850.708957462563, 4861.4895435980125, 4873.780679869344, 4875.68308059975, 4905.950940490549, 7174.748883789042, 7332.450066244946, 7442.06974128234, 7451.2921272479025, 7469.1037075551285, 7127.180374256819, 7171.426285897011, 7183.568985860865, 7184.317489838457, 7187.640617968976, 7106.218315985134, 7136.0, 7155.821104472429, 7171.211166771546, 7186.496945213785, 5750.424957504216, 6979.720811167496, 6989.440844732511, 7039.1553013638695, 7046.048783414173, 6876.449884009367, 7156.928430715626, 7164.0, 7186.748463803742, 7209.603471808891, 6085.2393450818645, 6382.968085159545, 6868.553544019623, 7129.251428599922, 7134.536255143399], "value": 5204.771462968241, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [683.4703105093555, 688.7520492622657, 694.2744831650924, 712.1667648850844, 732.3847967707127, 14143.080699754522, 14555.417783288682, 14925.0, 15006.39974401024, 15181.924090379547, 11129.944350278258, 13732.58802235932, 13897.513587024456, 14039.227842468661, 14115.43538258471, 708.252793303065, 767.8464307138566, 775.1976704111246, 885.0220506340497, 896.6682327538787, 887.8490656588364, 893.7363477774072, 913.8218047480744, 915.2403505090758, 916.3814425262955, 945.0643862576069, 949.4682977532569, 950.3110245072336, 950.7765675066354, 953.3945944325369, 925.833349997007, 941.2422999485411, 948.340903072365, 949.6201519392264, 952.2144231009411, 665.2050799294844, 676.9932300677028, 681.2097966359049, 688.6487891175486, 692.3976140757574, 14138.222397768119, 14181.0, 14299.714005719932, 14362.64093397663, 14781.704365912728, 12599.748005039939, 12714.0, 12789.296588687619, 12862.742745145044, 12887.742245155045, 9958.90041099587, 10016.949915250378, 10154.441505717183, 10170.237232207613, 10190.541425635864, 3462.549868517102, 3486.250456151914, 3495.8951231463097, 3551.4317709166407, 3556.022093924166, 75477.62261188714, 76248.61875690697, 77608.22391776067, 77814.61092694556, 77993.0, 9192.49441280736, 9444.716658500256, 9625.614975401046, 9701.126898579161, 9705.757356066155, 21351.89324053401, 21540.246091386725, 21866.45333866666, 21983.890080549496, 22061.11755529761, 7308.524945878501, 7340.96329518349, 7357.705691772269, 7409.666565004588, 7423.740169094056, 3974.7217694761666, 4021.678265738734, 4067.247559201557, 4083.7958102094776, 4181.97909010453, 3569.7679650822615, 3579.19468119671, 3602.1354874829854, 3630.1650620356936, 3641.308151451216, 633.8478765096344, 637.2416823979449, 637.7927173668576, 648.7437462202404, 649.7141257846532, 582.9679367634736, 583.4748726146502, 587.4595372257534, 611.8280105625014, 625.7278084033369, 7975.282224599814, 8180.304674102649, 8304.294134998592, 8444.282236009885, 8619.439736417113, 561.6237121128853, 562.4600383631678, 577.1026054485231, 588.1501230721651, 605.3704147686445, 3692.649198326155, 3821.7133714971487, 3860.3051450738576, 4045.5752146024834, 4128.112455822013, 66730.66634666892, 85570.57214713887, 87157.0, 87414.56292718623, 91771.0, 72705.90941135887, 73144.63427682828, 73986.26013739794, 74611.62694186495, 74780.62609686916, 39381.03094845246, 58754.23737287888, 59136.0, 63130.68434657706, 69997.65001174962, 8468.237858592756, 8498.74503764888, 8609.397342186237, 8648.135186481302, 8715.694950676696, 42098.0, 45368.09263814771, 48015.51984480111, 49031.509684902696, 50603.493965059875, 61990.45023874259, 62610.0, 65801.34198657951, 67346.98979515213, 68315.0, 10996.175286853359, 11134.88865111371, 11261.943690281496, 11266.66200014001, 11442.656720298404, 11655.899016002319, 12018.47776522137, 12119.878801212228, 12381.504739810307, 12695.42870570808, 9437.764055898657, 9927.0, 11397.772044558897, 11866.99130573911, 12011.879881201075, 11078.833817492905, 11339.0, 11378.772424551296, 11792.056635469313, 12032.879671203176, 9413.717588472357, 10929.78140437171, 11251.437428128396, 11377.20359574855, 11649.59226427071, 7967.964164658557, 9605.231581473325, 9638.084381983701, 9718.562664680248, 9742.871614575439, 9463.15468483639, 9677.612895484102, 9683.612655493984, 9736.269779766688, 9762.60949562038], "value": 6773.067668036702, "unit": "score"}], "name": "dromaeo_css", "value": 7412.965508984839, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 120.0, 110.0, 123.0, 119.0, 118.0, 106.0, 118.0, 115.0, 124.0], "value": 116.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 142.0, 165.0, 156.0, 124.0, 136.0, 113.0, 180.0, 193.0, 144.0], "value": 151.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 201.0, 198.0, 198.0, 195.0, 192.0, 196.0, 201.0, 197.0, 197.0], "value": 197.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 71.0, 80.0, 138.0, 56.0, 56.0, 58.0, 63.0, 124.0, 56.0], "value": 76.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 78.0, 128.0, 81.0, 82.0, 81.0, 117.0, 82.0, 79.0, 124.0], "value": 92.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 91.0, 92.0, 91.0, 95.0, 91.0, 94.0, 88.0, 123.0, 90.0], "value": 94.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 115.0, 152.0, 99.0, 98.0, 99.0, 98.0, 114.0, 152.0, 152.0], "value": 117.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 95.0, 94.0, 146.0, 151.0, 90.0, 93.0, 92.0, 92.0, 115.0], "value": 106.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 97.0, 99.0, 55.0, 72.0, 95.0, 104.0, 116.0, 80.0, 61.0], "value": 83.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 84.0, 112.0, 61.0, 90.0, 100.0, 60.0, 93.0, 105.0], "value": 83.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 83.0, 91.0, 72.0, 100.0, 110.0, 103.0, 108.0, 109.0, 84.0], "value": 94.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 131.0, 123.0, 148.0, 127.0, 131.0, 130.0, 132.0, 181.0, 144.0], "value": 141.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 169.0, 197.0, 234.0, 200.0, 168.0, 167.0, 167.0, 152.0, 153.0], "value": 177.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 99.0, 101.0, 99.0, 70.0, 67.0, 68.0, 66.0, 71.0, 70.0], "value": 76.9, "unit": "score"}], "name": "kraken", "value": 1610.9, "alertThreshold": 2.0}]} 06:20:34 INFO - Return code: 0 06:20:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 06:20:34 ERROR - # TBPL SUCCESS # 06:20:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:20:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:20:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:20:34 INFO - 2016-05-21 06:20:34.882 screenresolution[1679:10865] starting screenresolution argv=screenresolution get 06:20:34 INFO - 2016-05-21 06:20:34.885 screenresolution[1679:10865] Display 0: 1600x1200x32@60 06:20:34 INFO - 2016-05-21 06:20:34.891 screenresolution[1680:10868] starting screenresolution argv=screenresolution list 06:20:34 INFO - Available Modes on Display 0 06:20:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:20:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:20:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:20:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:20:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:20:35 INFO - Intel Iris: 06:20:35 INFO - Chipset Model: Intel Iris 06:20:35 INFO - Type: GPU 06:20:35 INFO - Bus: Built-In 06:20:35 INFO - VRAM (Dynamic, Max): 1536 MB 06:20:35 INFO - Vendor: Intel (0x8086) 06:20:35 INFO - Device ID: 0x0a2e 06:20:35 INFO - Revision ID: 0x0009 06:20:35 INFO - Displays: 06:20:35 INFO - Display: 06:20:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:20:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:20:35 INFO - Display Serial Number: 1600x1200 60 06:20:35 INFO - Main Display: Yes 06:20:35 INFO - Mirror: Off 06:20:35 INFO - Online: Yes 06:20:35 INFO - Rotation: Supported 06:20:35 INFO - Return code: 0 06:20:35 INFO - Running post-action listener: _resource_record_post_action 06:20:35 INFO - Running post-run listener: _resource_record_post_run 06:20:36 INFO - Total resource usage - Wall time: 1207s; CPU: 53.0%; Read bytes: 32562688; Write bytes: 655482368; Read time: 328; Write time: 2063 06:20:36 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 353190400; Write bytes: 347862528; Read time: 28776; Write time: 768 06:20:36 INFO - run-tests - Wall time: 1175s; CPU: 53.0%; Read bytes: 30735872; Write bytes: 297875456; Read time: 306; Write time: 1266 06:20:36 INFO - Running post-run listener: _upload_blobber_files 06:20:36 INFO - Blob upload gear active. 06:20:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:20:36 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 06:20:36 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'] 06:20:36 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 06:20:36 INFO - (blobuploader) - INFO - Open directory for files ... 06:20:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 06:20:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:20:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:20:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 06:20:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:20:38 INFO - (blobuploader) - INFO - Done attempting. 06:20:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 06:20:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:20:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:20:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 06:20:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:20:39 INFO - (blobuploader) - INFO - Done attempting. 06:20:39 INFO - (blobuploader) - INFO - Iteration through files over. 06:20:39 INFO - Return code: 0 06:20:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:20:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:20:39 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7edb8214495d00c3f9c342876d44de8656cba00442ae861e09189ed2f8200c500829528fe1028cf8a30d5443fbd7d461b8b3ce79ee54e5b7a9b0b882ec0dcfaa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0e02115eda2fb2dbcad63e989d848434613cf228e97e1df7ccb79b884b2359731da6a476bf82f28cfb24cac31338c7611ce66cec1f0255609aee85ffd0ca01b0"} 06:20:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:20:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:20:39 INFO - Contents: 06:20:39 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7edb8214495d00c3f9c342876d44de8656cba00442ae861e09189ed2f8200c500829528fe1028cf8a30d5443fbd7d461b8b3ce79ee54e5b7a9b0b882ec0dcfaa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0e02115eda2fb2dbcad63e989d848434613cf228e97e1df7ccb79b884b2359731da6a476bf82f28cfb24cac31338c7611ce66cec1f0255609aee85ffd0ca01b0"} 06:20:39 INFO - Running post-run listener: copy_logs_to_upload_dir 06:20:39 INFO - Copying logs to upload dir... 06:20:39 INFO - mkdir: /builds/slave/test/build/upload/logs 06:20:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1343.483249 ========= master_lag: 1.99 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 25 secs) (at 2016-05-21 06:20:41.168113) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-21 06:20:41.173555) ========= 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.f3X7C8nl2m/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.4nrEk0gYWR/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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/7edb8214495d00c3f9c342876d44de8656cba00442ae861e09189ed2f8200c500829528fe1028cf8a30d5443fbd7d461b8b3ce79ee54e5b7a9b0b882ec0dcfaa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0e02115eda2fb2dbcad63e989d848434613cf228e97e1df7ccb79b884b2359731da6a476bf82f28cfb24cac31338c7611ce66cec1f0255609aee85ffd0ca01b0"} build_url:https://queue.taskcluster.net/v1/task/Gk_4CRggRjiA3PF2DCjllw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011331 build_url: 'https://queue.taskcluster.net/v1/task/Gk_4CRggRjiA3PF2DCjllw/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/7edb8214495d00c3f9c342876d44de8656cba00442ae861e09189ed2f8200c500829528fe1028cf8a30d5443fbd7d461b8b3ce79ee54e5b7a9b0b882ec0dcfaa", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0e02115eda2fb2dbcad63e989d848434613cf228e97e1df7ccb79b884b2359731da6a476bf82f28cfb24cac31338c7611ce66cec1f0255609aee85ffd0ca01b0"}' ========= master_lag: 0.75 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-21 06:20:41.932199) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-21 06:20:41.932643) ========= 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.f3X7C8nl2m/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.4nrEk0gYWR/Listeners TMPDIR=/var/folders/bv/w75m6g3s5tv8p1fbxr1ssr2000000w/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.005247 ========= master_lag: 0.03 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-21 06:20:41.963190) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-21 06:20:41.963501) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-21 06:20:41.963871) ========= ========= Total master_lag: 2.88 =========