builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0119 starttime: 1465120925.71 results: success (0) buildid: 20160605005144 builduid: 2a18fba1f5a14db4ad654ad750b1a20c revision: 9b770efcfb3f0b5127446d8a17682e2d7235ff51 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-05 03:02:05.713411) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-05 03:02:05.714151) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-05 03:02:05.714436) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-05 03:02:05.775164) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-05 03:02:05.775419) ========= 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.NFcNvtWico/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.ZFHyulxIH8/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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-06-05 03:02:05-- 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% 168M=0s 2016-06-05 03:02:05 (168 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.190928 ========= master_lag: 0.15 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-05 03:02:06.119635) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-05 03:02:06.119960) ========= 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.NFcNvtWico/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.ZFHyulxIH8/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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.028097 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-05 03:02:06.202783) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 9 secs) (at 2016-06-05 03:02:06.203113) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 9b770efcfb3f0b5127446d8a17682e2d7235ff51 --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 9b770efcfb3f0b5127446d8a17682e2d7235ff51 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NFcNvtWico/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.ZFHyulxIH8/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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-06-05 03:02:06,276 truncating revision to first 12 chars 2016-06-05 03:02:06,277 Setting DEBUG logging. 2016-06-05 03:02:06,277 attempt 1/10 2016-06-05 03:02:06,277 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/9b770efcfb3f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-05 03:02:07,534 unpacking tar archive at: mozilla-aurora-9b770efcfb3f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.506935 ========= master_lag: 8.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 9 secs) (at 2016-06-05 03:02:15.749283) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-05 03:02:15.749715) ========= script_repo_revision: 9b770efcfb3f0b5127446d8a17682e2d7235ff51 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-05 03:02:15.750129) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-05 03:02:15.750403) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-05 03:02:16.406049) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 4 secs) (at 2016-06-05 03:02:16.406348) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.NFcNvtWico/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.ZFHyulxIH8/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 03:02:16 INFO - MultiFileLogger online at 20160605 03:02:16 in /builds/slave/test 03:02:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:02:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:02:16 INFO - {'append_to_log': False, 03:02:16 INFO - 'base_work_dir': '/builds/slave/test', 03:02:16 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:02:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:02:16 INFO - 'branch': 'Mozilla-Aurora', 03:02:16 INFO - 'buildbot_json_path': 'buildprops.json', 03:02:16 INFO - 'config_files': ('talos/mac_config.py',), 03:02:16 INFO - 'default_actions': ('clobber', 03:02:16 INFO - 'read-buildbot-config', 03:02:16 INFO - 'download-and-extract', 03:02:16 INFO - 'populate-webroot', 03:02:16 INFO - 'create-virtualenv', 03:02:16 INFO - 'install', 03:02:16 INFO - 'run-tests'), 03:02:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:02:16 INFO - 'download_minidump_stackwalk': True, 03:02:16 INFO - 'download_symbols': 'ondemand', 03:02:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:02:16 INFO - 'tooltool.py': '/tools/tooltool.py', 03:02:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:02:16 INFO - '/tools/misc-python/virtualenv.py')}, 03:02:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:02:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:02:16 INFO - 'installer_path': 'installer.exe', 03:02:16 INFO - 'log_level': 'info', 03:02:16 INFO - 'log_name': 'talos', 03:02:16 INFO - 'log_to_console': True, 03:02:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:02:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:02:16 INFO - 'opt_config_files': (), 03:02:16 INFO - 'pip_index': False, 03:02:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:02:16 INFO - 'cmd': ('bash', 03:02:16 INFO - '-c', 03:02:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:02:16 INFO - 'enabled': True, 03:02:16 INFO - 'halt_on_failure': False, 03:02:16 INFO - 'name': 'check_screen_resolution'},), 03:02:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:02:16 INFO - 'cmd': ('bash', 03:02:16 INFO - '-c', 03:02:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:02:16 INFO - 'enabled': True, 03:02:16 INFO - 'halt_on_failure': False, 03:02:16 INFO - 'name': 'check_screen_resolution'},), 03:02:16 INFO - 'run_cmd_checks_enabled': True, 03:02:16 INFO - 'sps_profile': False, 03:02:16 INFO - 'sps_profile_interval': 0, 03:02:16 INFO - 'suite': 'dromaeojs', 03:02:16 INFO - 'system_bits': '32', 03:02:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:02:16 INFO - 'title': 't-yosemite-r7-0119', 03:02:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:02:16 INFO - 'use_talos_json': True, 03:02:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:02:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:02:16 INFO - 'work_dir': 'build'} 03:02:16 INFO - ##### 03:02:16 INFO - ##### Running clobber step. 03:02:16 INFO - ##### 03:02:16 INFO - Running pre-action listener: _resource_record_pre_action 03:02:16 INFO - Running main action method: clobber 03:02:16 INFO - rmtree: /builds/slave/test/build 03:02:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:02:18 INFO - Running post-action listener: _resource_record_post_action 03:02:18 INFO - ##### 03:02:18 INFO - ##### Running read-buildbot-config step. 03:02:18 INFO - ##### 03:02:18 INFO - Running pre-action listener: _resource_record_pre_action 03:02:18 INFO - Running main action method: read_buildbot_config 03:02:18 INFO - Using buildbot properties: 03:02:18 INFO - { 03:02:18 INFO - "project": "", 03:02:18 INFO - "product": "firefox", 03:02:18 INFO - "script_repo_revision": "production", 03:02:18 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 03:02:18 INFO - "repository": "", 03:02:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 03:02:18 INFO - "buildid": "20160605005144", 03:02:18 INFO - "pgo_build": "False", 03:02:18 INFO - "basedir": "/builds/slave/test", 03:02:18 INFO - "buildnumber": 371, 03:02:18 INFO - "slavename": "t-yosemite-r7-0119", 03:02:18 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 03:02:18 INFO - "platform": "yosemite_r7", 03:02:18 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:02:18 INFO - "branch": "mozilla-aurora", 03:02:18 INFO - "repo_path": "releases/mozilla-aurora", 03:02:18 INFO - "revision": "9b770efcfb3f0b5127446d8a17682e2d7235ff51", 03:02:18 INFO - "stage_platform": "macosx64", 03:02:18 INFO - "builduid": "2a18fba1f5a14db4ad654ad750b1a20c", 03:02:18 INFO - "slavebuilddir": "test" 03:02:18 INFO - } 03:02:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/XyXiEBfYQ7-P-WTUxHnuOw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 03:02:18 INFO - Running post-action listener: _resource_record_post_action 03:02:18 INFO - ##### 03:02:18 INFO - ##### Running download-and-extract step. 03:02:18 INFO - ##### 03:02:18 INFO - Running pre-action listener: _resource_record_pre_action 03:02:18 INFO - Running main action method: download_and_extract 03:02:18 INFO - mkdir: /builds/slave/test/build/tests 03:02:18 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')]} 03:02:18 INFO - https://queue.taskcluster.net/v1/task/XyXiEBfYQ7-P-WTUxHnuOw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 03:02:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XyXiEBfYQ7-P-WTUxHnuOw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 03:02:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XyXiEBfYQ7-P-WTUxHnuOw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 03:02:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XyXiEBfYQ7-P-WTUxHnuOw/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 03:02:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XyXiEBfYQ7-P-WTUxHnuOw/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 03:02:48 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XyXiEBfYQ7-P-WTUxHnuOw/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json: timed out 03:02:48 INFO - retry: attempt #1 caught exception: timed out 03:02:48 INFO - retry: Failed, sleeping 30 seconds before retrying 03:03:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XyXiEBfYQ7-P-WTUxHnuOw/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 03:03:19 INFO - Downloaded 1199 bytes. 03:03:19 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 03:03:19 INFO - Using the following test package requirements: 03:03:19 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 03:03:19 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:03:19 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 03:03:19 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:03:19 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 03:03:19 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:03:19 INFO - u'jsshell-mac.zip'], 03:03:19 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:03:19 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 03:03:19 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 03:03:19 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:03:19 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 03:03:19 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:03:19 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 03:03:19 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:03:19 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 03:03:19 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 03:03:19 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 03:03:19 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:03: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')]} 03:03:19 INFO - https://queue.taskcluster.net/v1/task/XyXiEBfYQ7-P-WTUxHnuOw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:03:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XyXiEBfYQ7-P-WTUxHnuOw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:03:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XyXiEBfYQ7-P-WTUxHnuOw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:03:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XyXiEBfYQ7-P-WTUxHnuOw/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 03:03:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XyXiEBfYQ7-P-WTUxHnuOw/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 03:03:20 INFO - Downloaded 18466863 bytes. 03:03:20 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'] 03:03:20 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 03:03:23 INFO - Return code: 0 03:03:23 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 03:03:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:03:23 INFO - https://queue.taskcluster.net/v1/task/XyXiEBfYQ7-P-WTUxHnuOw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:03:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XyXiEBfYQ7-P-WTUxHnuOw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:03:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XyXiEBfYQ7-P-WTUxHnuOw/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 03:03:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XyXiEBfYQ7-P-WTUxHnuOw/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 03:03:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XyXiEBfYQ7-P-WTUxHnuOw/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 03:03:24 INFO - Downloaded 18466863 bytes. 03:03:24 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'] 03:03:24 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 03:03:28 INFO - Return code: 0 03:03:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:03:28 INFO - https://queue.taskcluster.net/v1/task/XyXiEBfYQ7-P-WTUxHnuOw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 03:03:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XyXiEBfYQ7-P-WTUxHnuOw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 03:03:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XyXiEBfYQ7-P-WTUxHnuOw/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 03:03:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XyXiEBfYQ7-P-WTUxHnuOw/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 03:03:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XyXiEBfYQ7-P-WTUxHnuOw/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 03:03:29 INFO - Downloaded 10840145 bytes. 03:03:29 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'] 03:03:29 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 03:03:29 INFO - Return code: 0 03:03:29 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')]} 03:03:29 INFO - https://queue.taskcluster.net/v1/task/XyXiEBfYQ7-P-WTUxHnuOw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 03:03:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XyXiEBfYQ7-P-WTUxHnuOw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:03:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XyXiEBfYQ7-P-WTUxHnuOw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:03:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XyXiEBfYQ7-P-WTUxHnuOw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 03:03:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/XyXiEBfYQ7-P-WTUxHnuOw/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 03:03:33 INFO - Downloaded 114167732 bytes. 03:03:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/XyXiEBfYQ7-P-WTUxHnuOw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:03:33 INFO - mkdir: /builds/slave/test/properties 03:03:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:03:33 INFO - Writing to file /builds/slave/test/properties/build_url 03:03:33 INFO - Contents: 03:03:33 INFO - build_url:https://queue.taskcluster.net/v1/task/XyXiEBfYQ7-P-WTUxHnuOw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 03:03:34 INFO - Running post-action listener: _resource_record_post_action 03:03:34 INFO - Running post-action listener: set_extra_try_arguments 03:03:34 INFO - ##### 03:03:34 INFO - ##### Running populate-webroot step. 03:03:34 INFO - ##### 03:03:34 INFO - Running pre-action listener: _resource_record_pre_action 03:03:34 INFO - Running main action method: populate_webroot 03:03:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:03:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:03:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:03:34 INFO - '--tppagecycles', 03:03:34 INFO - '7'], 03:03:34 INFO - 'tests': ['tsvgm']}}, 03:03:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:03:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:03:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:03:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:03:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:03:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:03:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:03:34 INFO - 'talos_options': ['--disable-e10s'], 03:03:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:03:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:03:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:03:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:03:34 INFO - 'talos_options': ['--disable-e10s'], 03:03:34 INFO - 'tests': ['damp', 'tps']}, 03:03:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:03:34 INFO - 'tests': ['damp', 'tps']}, 03:03:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:03:34 INFO - 'tests': ['dromaeo_dom']}, 03:03:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:03:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:03:34 INFO - 'tests': ['a11yr', 03:03:34 INFO - 'ts_paint', 03:03:34 INFO - 'tpaint', 03:03:34 INFO - 'sessionrestore', 03:03:34 INFO - 'sessionrestore_no_auto_restore', 03:03:34 INFO - 'tabpaint']}, 03:03:34 INFO - 'other-e10s': {'tests': ['a11yr', 03:03:34 INFO - 'ts_paint', 03:03:34 INFO - 'tpaint', 03:03:34 INFO - 'sessionrestore', 03:03:34 INFO - 'sessionrestore_no_auto_restore', 03:03:34 INFO - 'tabpaint']}, 03:03:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:03:34 INFO - 'ts_paint', 03:03:34 INFO - 'tpaint', 03:03:34 INFO - 'sessionrestore', 03:03:34 INFO - 'sessionrestore_no_auto_restore', 03:03:34 INFO - 'tabpaint']}, 03:03:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:03:34 INFO - 'ts_paint', 03:03:34 INFO - 'tpaint', 03:03:34 INFO - 'sessionrestore', 03:03:34 INFO - 'sessionrestore_no_auto_restore', 03:03:34 INFO - 'tabpaint']}, 03:03:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:03:34 INFO - 'tests': ['a11yr', 03:03:34 INFO - 'ts_paint', 03:03:34 INFO - 'tpaint', 03:03:34 INFO - 'sessionrestore', 03:03:34 INFO - 'sessionrestore_no_auto_restore', 03:03:34 INFO - 'tabpaint']}, 03:03:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:03:34 INFO - 'tests': ['a11yr', 03:03:34 INFO - 'ts_paint', 03:03:34 INFO - 'tpaint', 03:03:34 INFO - 'sessionrestore', 03:03:34 INFO - 'sessionrestore_no_auto_restore', 03:03:34 INFO - 'tabpaint']}, 03:03:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:03:34 INFO - 'tests': ['tsvgx', 03:03:34 INFO - 'tsvgr_opacity', 03:03:34 INFO - 'tart', 03:03:34 INFO - 'tscrollx', 03:03:34 INFO - 'cart']}, 03:03:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:03:34 INFO - 'tsvgr_opacity', 03:03:34 INFO - 'tart', 03:03:34 INFO - 'tscrollx', 03:03:34 INFO - 'cart']}, 03:03:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:03:34 INFO - 'talos_options': ['--disable-e10s'], 03:03:34 INFO - 'tests': ['tp5o']}, 03:03:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:03:34 INFO - 'tests': ['tp5o']}, 03:03:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:03:34 INFO - 'talos_options': ['--disable-e10s', 03:03:34 INFO - '--xperf_path', 03:03:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:03:34 INFO - 'tests': ['tp5n']}, 03:03:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:03:34 INFO - 'talos_options': ['--xperf_path', 03:03:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:03:34 INFO - 'tests': ['tp5n']}}, 03:03:34 INFO - 'talos.zip': {'path': '', 03:03:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:03:34 INFO - Running post-action listener: _resource_record_post_action 03:03:34 INFO - ##### 03:03:34 INFO - ##### Running create-virtualenv step. 03:03:34 INFO - ##### 03:03:34 INFO - Running pre-action listener: _resource_record_pre_action 03:03:34 INFO - Running main action method: create_virtualenv 03:03:34 INFO - Creating virtualenv /builds/slave/test/build/venv 03:03:34 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 03:03:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:03:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:03:34 INFO - Using real prefix '/tools/python27' 03:03:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:03:35 INFO - Installing distribute.............................................................................................................................................................................................done. 03:03:37 INFO - Installing pip.................done. 03:03:37 INFO - Return code: 0 03:03:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:03: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')]} 03:03:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:03:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03: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')]} 03:03:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:03:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:37 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 0x107eeba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107efb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde6846b270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d8ae00>, '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 0x107bf3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107d85b20>, '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.NFcNvtWico/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.ZFHyulxIH8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 03:03:37 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 03:03:37 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 03:03:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NFcNvtWico/Render', 03:03:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:03:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:03:37 INFO - 'HOME': '/Users/cltbld', 03:03:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:03:37 INFO - 'LOGNAME': 'cltbld', 03:03:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:37 INFO - 'MOZ_NO_REMOTE': '1', 03:03:37 INFO - 'NO_EM_RESTART': '1', 03:03:37 INFO - 'PAGER': '/bin/cat', 03:03:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:03:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:37 INFO - 'PWD': '/builds/slave/test', 03:03:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:03:37 INFO - 'SHELL': '/bin/bash', 03:03:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZFHyulxIH8/Listeners', 03:03:37 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 03:03:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:03:37 INFO - 'USER': 'cltbld', 03:03:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:03:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:03:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:37 INFO - 'XPC_FLAGS': '0x0', 03:03:37 INFO - 'XPC_SERVICE_NAME': '0', 03:03:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:03:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:03:38 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 03:03:38 INFO - Cleaning up... 03:03:38 INFO - Return code: 0 03:03:38 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 03:03:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:03:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:03:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:03:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:03:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:38 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 0x107eeba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107efb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde6846b270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d8ae00>, '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 0x107bf3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107d85b20>, '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.NFcNvtWico/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.ZFHyulxIH8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 03:03:38 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 03:03:38 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 03:03:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NFcNvtWico/Render', 03:03:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:03:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:03:38 INFO - 'HOME': '/Users/cltbld', 03:03:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:03:38 INFO - 'LOGNAME': 'cltbld', 03:03:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:38 INFO - 'MOZ_NO_REMOTE': '1', 03:03:38 INFO - 'NO_EM_RESTART': '1', 03:03:38 INFO - 'PAGER': '/bin/cat', 03:03:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:03:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:38 INFO - 'PWD': '/builds/slave/test', 03:03:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:03:38 INFO - 'SHELL': '/bin/bash', 03:03:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZFHyulxIH8/Listeners', 03:03:38 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 03:03:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:03:38 INFO - 'USER': 'cltbld', 03:03:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:03:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:03:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:38 INFO - 'XPC_FLAGS': '0x0', 03:03:38 INFO - 'XPC_SERVICE_NAME': '0', 03:03:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:03:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:03:38 INFO - Downloading/unpacking psutil>=0.7.1 03:03:38 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 03:03:38 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 03:03:38 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 03:03:38 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 03:03:38 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 03:03:38 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 03:03:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:03:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:03:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:03:41 INFO - Installing collected packages: psutil 03:03:41 INFO - Running setup.py install for psutil 03:03:41 INFO - building 'psutil._psutil_osx' extension 03:03:41 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 03:03:42 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 03:03:42 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 03:03:42 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 03:03:42 INFO - building 'psutil._psutil_posix' extension 03:03:42 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 03:03:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:03:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:03:42 INFO - ^ 03:03:42 INFO - 1 warning generated. 03:03:42 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 03:03:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:03:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:03:42 INFO - Successfully installed psutil 03:03:42 INFO - Cleaning up... 03:03:42 INFO - Return code: 0 03:03:42 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 03:03:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:03:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:03:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:03:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:03:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:42 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 0x107eeba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107efb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde6846b270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d8ae00>, '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 0x107bf3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107d85b20>, '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.NFcNvtWico/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.ZFHyulxIH8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 03:03:42 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 03:03:42 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 03:03:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NFcNvtWico/Render', 03:03:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:03:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:03:42 INFO - 'HOME': '/Users/cltbld', 03:03:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:03:42 INFO - 'LOGNAME': 'cltbld', 03:03:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:42 INFO - 'MOZ_NO_REMOTE': '1', 03:03:42 INFO - 'NO_EM_RESTART': '1', 03:03:42 INFO - 'PAGER': '/bin/cat', 03:03:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:03:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:42 INFO - 'PWD': '/builds/slave/test', 03:03:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:03:42 INFO - 'SHELL': '/bin/bash', 03:03:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZFHyulxIH8/Listeners', 03:03:42 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 03:03:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:03:42 INFO - 'USER': 'cltbld', 03:03:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:03:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:03:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:42 INFO - 'XPC_FLAGS': '0x0', 03:03:42 INFO - 'XPC_SERVICE_NAME': '0', 03:03:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:03:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:03:42 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 03:03:42 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 03:03:42 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 03:03:42 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 03:03:42 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 03:03:42 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 03:03:42 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 03:03:45 INFO - Downloading mozsystemmonitor-0.0.tar.gz 03:03:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:03:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 03:03:45 INFO - Installing collected packages: mozsystemmonitor 03:03:45 INFO - Running setup.py install for mozsystemmonitor 03:03:45 INFO - Successfully installed mozsystemmonitor 03:03:45 INFO - Cleaning up... 03:03:45 INFO - Return code: 0 03:03:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:03:45 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')]} 03:03:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:03:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:45 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')]} 03:03:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:03:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:45 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 0x107eeba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107efb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde6846b270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d8ae00>, '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 0x107bf3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107d85b20>, '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.NFcNvtWico/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.ZFHyulxIH8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 03:03:45 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 03:03:45 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 03:03:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NFcNvtWico/Render', 03:03:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:03:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:03:45 INFO - 'HOME': '/Users/cltbld', 03:03:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:03:45 INFO - 'LOGNAME': 'cltbld', 03:03:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:45 INFO - 'MOZ_NO_REMOTE': '1', 03:03:45 INFO - 'NO_EM_RESTART': '1', 03:03:45 INFO - 'PAGER': '/bin/cat', 03:03:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:03:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:45 INFO - 'PWD': '/builds/slave/test', 03:03:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:03:45 INFO - 'SHELL': '/bin/bash', 03:03:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZFHyulxIH8/Listeners', 03:03:45 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 03:03:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:03:45 INFO - 'USER': 'cltbld', 03:03:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:03:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:03:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:45 INFO - 'XPC_FLAGS': '0x0', 03:03:45 INFO - 'XPC_SERVICE_NAME': '0', 03:03:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:03:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:03:46 INFO - Downloading/unpacking blobuploader==1.2.4 03:03:46 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 03:03:46 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 03:03:46 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 03:03:46 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 03:03:46 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 03:03:46 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 03:03:48 INFO - Downloading blobuploader-1.2.4.tar.gz 03:03:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:03:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:03:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:03:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:03:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:03:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:03:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:03:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:03:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:03:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:03:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:03:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:03:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:03:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:03:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:03:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:03:49 INFO - Downloading docopt-0.6.1.tar.gz 03:03:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:03:49 INFO - Installing collected packages: blobuploader, requests, docopt 03:03:49 INFO - Running setup.py install for blobuploader 03:03:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:03:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:03:49 INFO - Running setup.py install for requests 03:03:50 INFO - Running setup.py install for docopt 03:03:50 INFO - Successfully installed blobuploader requests docopt 03:03:50 INFO - Cleaning up... 03:03:50 INFO - Return code: 0 03:03:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:03:50 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')]} 03:03:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:03:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:50 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')]} 03:03:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:03:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:50 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 0x107eeba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107efb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde6846b270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d8ae00>, '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 0x107bf3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107d85b20>, '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.NFcNvtWico/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.ZFHyulxIH8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 03:03:50 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 03:03:50 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 03:03:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NFcNvtWico/Render', 03:03:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:03:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:03:50 INFO - 'HOME': '/Users/cltbld', 03:03:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:03:50 INFO - 'LOGNAME': 'cltbld', 03:03:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:50 INFO - 'MOZ_NO_REMOTE': '1', 03:03:50 INFO - 'NO_EM_RESTART': '1', 03:03:50 INFO - 'PAGER': '/bin/cat', 03:03:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:03:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:50 INFO - 'PWD': '/builds/slave/test', 03:03:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:03:50 INFO - 'SHELL': '/bin/bash', 03:03:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZFHyulxIH8/Listeners', 03:03:50 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 03:03:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:03:50 INFO - 'USER': 'cltbld', 03:03:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:03:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:03:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:50 INFO - 'XPC_FLAGS': '0x0', 03:03:50 INFO - 'XPC_SERVICE_NAME': '0', 03:03:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:03:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:03:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:03:50 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-e1YqXL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:03:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:03:50 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-95rqlL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:03:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:03:51 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-dRnVeR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:03:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:03:51 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-YIWW2O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:03:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:03:51 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-rfsWtb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:03:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:03:51 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-g_DklC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:03:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:03:51 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-kC37F9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:03:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:03:51 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-izeOhk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:03:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:03:51 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-hk1nCG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:03:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:03:51 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-GfYvmM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:03:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:03:51 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-2k4WuO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:03:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:03:52 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-Nm88Rj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:03:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:03:52 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-MdC4Ro-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:03:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:03:52 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-ltEQZu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:03:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:03:52 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-m5U5e8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:03:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:03:52 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-tm6OMH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:03:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:03:52 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-03pm_f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:03:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:03:52 INFO - Running setup.py install for manifestparser 03:03:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:03:52 INFO - Running setup.py install for mozcrash 03:03:52 INFO - Running setup.py install for mozdebug 03:03:53 INFO - Running setup.py install for mozdevice 03:03:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:03:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:03:53 INFO - Running setup.py install for mozfile 03:03:53 INFO - Running setup.py install for mozhttpd 03:03:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:03:53 INFO - Running setup.py install for mozinfo 03:03:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:03:53 INFO - Running setup.py install for mozInstall 03:03:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:03:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:03:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:03:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:03:53 INFO - Running setup.py install for mozleak 03:03:53 INFO - Running setup.py install for mozlog 03:03:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:03:54 INFO - Running setup.py install for moznetwork 03:03:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:03:54 INFO - Running setup.py install for mozprocess 03:03:54 INFO - Running setup.py install for mozprofile 03:03:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:03:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:03:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:03:54 INFO - Running setup.py install for mozrunner 03:03:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:03:54 INFO - Running setup.py install for mozscreenshot 03:03:54 INFO - Running setup.py install for moztest 03:03:55 INFO - Running setup.py install for mozversion 03:03:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:03:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:03:55 INFO - Cleaning up... 03:03:55 INFO - Return code: 0 03:03:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:03:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:03:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:03:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:03:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:03:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:03:55 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 0x107eeba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107efb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde6846b270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d8ae00>, '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 0x107bf3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107d85b20>, '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.NFcNvtWico/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.ZFHyulxIH8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 03:03:55 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 03:03:55 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 03:03:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NFcNvtWico/Render', 03:03:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:03:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:03:55 INFO - 'HOME': '/Users/cltbld', 03:03:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:03:55 INFO - 'LOGNAME': 'cltbld', 03:03:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:03:55 INFO - 'MOZ_NO_REMOTE': '1', 03:03:55 INFO - 'NO_EM_RESTART': '1', 03:03:55 INFO - 'PAGER': '/bin/cat', 03:03:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:03:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:03:55 INFO - 'PWD': '/builds/slave/test', 03:03:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:03:55 INFO - 'SHELL': '/bin/bash', 03:03:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZFHyulxIH8/Listeners', 03:03:55 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 03:03:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:03:55 INFO - 'USER': 'cltbld', 03:03:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:03:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:03:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:03:55 INFO - 'XPC_FLAGS': '0x0', 03:03:55 INFO - 'XPC_SERVICE_NAME': '0', 03:03:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:03:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:03:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:03:55 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-dTYV95-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:03:55 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)) 03:03:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:03:55 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-W6igEV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:03:55 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)) 03:03:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:03:55 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-UrP1oY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:03:55 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)) 03:03:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:03:55 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-OJsJag-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:03:55 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)) 03:03:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:03:55 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-A0i9dW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:03:55 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)) 03:03:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:03:55 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-_114k1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:03:56 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)) 03:03:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:03:56 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-Q0YJt6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:03:56 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)) 03:03:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:03:56 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-VF76QH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:03:56 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)) 03:03:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:03:56 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-CG0edM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:03:56 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)) 03:03:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:03:56 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-LC0KPH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:03:56 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)) 03:03:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:03:56 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-1uhE5j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:03:56 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)) 03:03:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:03:56 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-uFxdnk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:03:56 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)) 03:03:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:03:56 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-9al1l0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:03:56 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)) 03:03:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:03:56 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-ydDRGe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:03:56 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)) 03:03:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:03:56 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-0mYjkz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:03:57 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)) 03:03:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:03:57 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-9rHNm3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:03:57 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)) 03:03:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:03:57 INFO - Running setup.py (path:/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/pip-dYWSNh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:03:57 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)) 03:03:57 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)) 03:03:57 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)) 03:03:57 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)) 03:03:57 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)) 03:03:57 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)) 03:03:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:03: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 03:03: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 03:03: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 03:03: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 03:03: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 03:03: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 03:03:59 INFO - Downloading blessings-1.6.tar.gz 03:03:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:03:59 INFO - Installing collected packages: blessings 03:03:59 INFO - Running setup.py install for blessings 03:03:59 INFO - Successfully installed blessings 03:03:59 INFO - Cleaning up... 03:04:00 INFO - Return code: 0 03:04:00 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:04:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:04:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:04:00 INFO - Reading from file tmpfile_stdout 03:04:00 INFO - Current package versions: 03:04:00 INFO - blessings == 1.6 03:04:00 INFO - blobuploader == 1.2.4 03:04:00 INFO - docopt == 0.6.1 03:04:00 INFO - manifestparser == 1.1 03:04:00 INFO - mozInstall == 1.12 03:04:00 INFO - mozcrash == 0.17 03:04:00 INFO - mozdebug == 0.1 03:04:00 INFO - mozdevice == 0.48 03:04:00 INFO - mozfile == 1.2 03:04:00 INFO - mozhttpd == 0.7 03:04:00 INFO - mozinfo == 0.9 03:04:00 INFO - mozleak == 0.1 03:04:00 INFO - mozlog == 3.1 03:04:00 INFO - moznetwork == 0.27 03:04:00 INFO - mozprocess == 0.22 03:04:00 INFO - mozprofile == 0.28 03:04:00 INFO - mozrunner == 6.11 03:04:00 INFO - mozscreenshot == 0.1 03:04:00 INFO - mozsystemmonitor == 0.0 03:04:00 INFO - moztest == 0.7 03:04:00 INFO - mozversion == 1.4 03:04:00 INFO - psutil == 3.1.1 03:04:00 INFO - requests == 1.2.3 03:04:00 INFO - wsgiref == 0.1.2 03:04:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:04: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')]} 03:04:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:04:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:04: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')]} 03:04:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:04:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:04:00 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 0x107eeba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107efb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde6846b270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d8ae00>, '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 0x107bf3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107d85b20>, '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.NFcNvtWico/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.ZFHyulxIH8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 03:04:00 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 03:04:00 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 03:04:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NFcNvtWico/Render', 03:04:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:04:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:04:00 INFO - 'HOME': '/Users/cltbld', 03:04:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:04:00 INFO - 'LOGNAME': 'cltbld', 03:04:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:04:00 INFO - 'MOZ_NO_REMOTE': '1', 03:04:00 INFO - 'NO_EM_RESTART': '1', 03:04:00 INFO - 'PAGER': '/bin/cat', 03:04:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:04:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:04:00 INFO - 'PWD': '/builds/slave/test', 03:04:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:04:00 INFO - 'SHELL': '/bin/bash', 03:04:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZFHyulxIH8/Listeners', 03:04:00 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 03:04:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:04:00 INFO - 'USER': 'cltbld', 03:04:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:04:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:04:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:04:00 INFO - 'XPC_FLAGS': '0x0', 03:04:00 INFO - 'XPC_SERVICE_NAME': '0', 03:04:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:04:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:04:00 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)) 03:04:00 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)) 03:04:00 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)) 03:04:00 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)) 03:04:00 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)) 03:04:00 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)) 03:04:00 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)) 03:04:00 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)) 03:04:00 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)) 03:04:00 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)) 03:04:00 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)) 03:04:00 INFO - Cleaning up... 03:04:00 INFO - Return code: 0 03:04:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:04: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')]} 03:04:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:04:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:04: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')]} 03:04:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:04:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:04:00 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 0x107eeba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107efb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde6846b270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d8ae00>, '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 0x107bf3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107d85b20>, '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.NFcNvtWico/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.ZFHyulxIH8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 03:04:00 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 03:04:00 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 03:04:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NFcNvtWico/Render', 03:04:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:04:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:04:00 INFO - 'HOME': '/Users/cltbld', 03:04:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:04:00 INFO - 'LOGNAME': 'cltbld', 03:04:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:04:00 INFO - 'MOZ_NO_REMOTE': '1', 03:04:00 INFO - 'NO_EM_RESTART': '1', 03:04:00 INFO - 'PAGER': '/bin/cat', 03:04:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:04:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:04:00 INFO - 'PWD': '/builds/slave/test', 03:04:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:04:00 INFO - 'SHELL': '/bin/bash', 03:04:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZFHyulxIH8/Listeners', 03:04:00 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 03:04:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:04:00 INFO - 'USER': 'cltbld', 03:04:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:04:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:04:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:04:00 INFO - 'XPC_FLAGS': '0x0', 03:04:00 INFO - 'XPC_SERVICE_NAME': '0', 03:04:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:04:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:04:00 INFO - Downloading/unpacking jsonschema 03:04:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:04:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:04:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:04:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:04:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:04:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:04:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:04:03 INFO - Downloading/unpacking functools32 (from jsonschema) 03:04:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:04:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:04:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:04:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:04:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:04:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:04:03 INFO - Downloading functools32-3.2.3-2.tar.gz 03:04:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:04:03 INFO - warning: no files found matching '*.txt' 03:04:03 INFO - no previously-included directories found matching 'build' 03:04:03 INFO - no previously-included directories found matching 'dist' 03:04:03 INFO - no previously-included directories found matching '.git*' 03:04:03 INFO - Installing collected packages: jsonschema, functools32 03:04:03 INFO - Running setup.py install for functools32 03:04:03 INFO - warning: no files found matching '*.txt' 03:04:03 INFO - no previously-included directories found matching 'build' 03:04:03 INFO - no previously-included directories found matching 'dist' 03:04:03 INFO - no previously-included directories found matching '.git*' 03:04:04 INFO - Successfully installed jsonschema functools32 03:04:04 INFO - Cleaning up... 03:04:04 INFO - Return code: 0 03:04:04 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:04: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')]} 03:04:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:04:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:04: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')]} 03:04:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:04:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:04: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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107eeba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107efb420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fde6846b270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107d8ae00>, '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 0x107bf3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107d85b20>, '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.NFcNvtWico/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.ZFHyulxIH8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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 03:04: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', 'flake8'] in /builds/slave/test/build 03:04: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 flake8 03:04:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NFcNvtWico/Render', 03:04:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:04:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:04:04 INFO - 'HOME': '/Users/cltbld', 03:04:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:04:04 INFO - 'LOGNAME': 'cltbld', 03:04:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:04:04 INFO - 'MOZ_NO_REMOTE': '1', 03:04:04 INFO - 'NO_EM_RESTART': '1', 03:04:04 INFO - 'PAGER': '/bin/cat', 03:04:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:04:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:04:04 INFO - 'PWD': '/builds/slave/test', 03:04:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:04:04 INFO - 'SHELL': '/bin/bash', 03:04:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZFHyulxIH8/Listeners', 03:04:04 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 03:04:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:04:04 INFO - 'USER': 'cltbld', 03:04:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:04:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:04:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:04:04 INFO - 'XPC_FLAGS': '0x0', 03:04:04 INFO - 'XPC_SERVICE_NAME': '0', 03:04:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:04:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:04:04 INFO - Downloading/unpacking flake8 03:04: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 03:04: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 03:04: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 03:04: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 03:04: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 03:04: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 03:04:06 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:04:06 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:04:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:04:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:04:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:04:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:04:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:04:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:04:07 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:04:07 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:04: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 03:04: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 03:04: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 03:04: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 03:04: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 03:04: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 03:04:07 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:04: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 03:04: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 03:04: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 03:04: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 03:04: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 03:04: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 03:04:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:04:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:04:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:04:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:04:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:04:07 INFO - no previously-included directories found matching 'docs/_build' 03:04:08 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:04:08 INFO - Running setup.py install for pep8 03:04:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:04:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:04:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:04:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:04:08 INFO - no previously-included directories found matching 'docs/_build' 03:04:08 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:04:08 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:04:08 INFO - Cleaning up... 03:04:08 INFO - Return code: 0 03:04:08 INFO - Running post-action listener: _resource_record_post_action 03:04:08 INFO - Running post-action listener: _start_resource_monitoring 03:04:08 INFO - Starting resource monitoring. 03:04:08 INFO - ##### 03:04:08 INFO - ##### Running install step. 03:04:08 INFO - ##### 03:04:08 INFO - Running pre-action listener: _resource_record_pre_action 03:04:08 INFO - Running main action method: install 03:04:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:04:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:04:08 INFO - Reading from file tmpfile_stdout 03:04:08 INFO - Detecting whether we're running mozinstall >=1.0... 03:04:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:04:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:04:08 INFO - Reading from file tmpfile_stdout 03:04:08 INFO - Output received: 03:04:08 INFO - Usage: mozinstall [options] installer 03:04:08 INFO - Options: 03:04:08 INFO - -h, --help show this help message and exit 03:04:08 INFO - -d DEST, --destination=DEST 03:04:08 INFO - Directory to install application into. [default: 03:04:08 INFO - "/builds/slave/test"] 03:04:08 INFO - --app=APP Application being installed. [default: firefox] 03:04:08 INFO - mkdir: /builds/slave/test/build/application 03:04:08 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'] 03:04:08 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 03:04:40 INFO - Reading from file tmpfile_stdout 03:04:40 INFO - Output received: 03:04:40 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:04:40 INFO - Running post-action listener: _resource_record_post_action 03:04:40 INFO - ##### 03:04:40 INFO - ##### Running run-tests step. 03:04:40 INFO - ##### 03:04:40 INFO - Running pre-action listener: _resource_record_pre_action 03:04:40 INFO - Running main action method: run_tests 03:04:40 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:04:40 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:04:40 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:04:40 INFO - 2016-06-05 03:04:40.128 screenresolution[1648:7658] starting screenresolution argv=screenresolution get 03:04:40 INFO - 2016-06-05 03:04:40.131 screenresolution[1648:7658] Display 0: 1600x1200x32@60 03:04:40 INFO - 2016-06-05 03:04:40.137 screenresolution[1649:7661] starting screenresolution argv=screenresolution list 03:04:40 INFO - Available Modes on Display 0 03:04:40 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:04:40 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:04:40 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:04:40 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:04:40 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:04:40 INFO - Intel Iris: 03:04:40 INFO - Chipset Model: Intel Iris 03:04:40 INFO - Type: GPU 03:04:40 INFO - Bus: Built-In 03:04:40 INFO - VRAM (Dynamic, Max): 1536 MB 03:04:40 INFO - Vendor: Intel (0x8086) 03:04:40 INFO - Device ID: 0x0a2e 03:04:40 INFO - Revision ID: 0x0009 03:04:40 INFO - Displays: 03:04:40 INFO - Display: 03:04:40 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:04:40 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:04:40 INFO - Display Serial Number: 1600x1200 60 03:04:40 INFO - Main Display: Yes 03:04:40 INFO - Mirror: Off 03:04:40 INFO - Online: Yes 03:04:40 INFO - Rotation: Supported 03:04:40 INFO - Return code: 0 03:04:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:04:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:04:40 INFO - Python 2.7.3 03:04:40 INFO - Return code: 0 03:04:40 INFO - grabbing minidump binary from tooltool 03:04:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:04:40 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 0x107d8ae00>, '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 0x107bf3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107d85b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:04:40 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 03:04:40 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 03:04:40 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:04:40 INFO - Return code: 0 03:04:40 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:04:40 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:04:40 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:04:40 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:04:40 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:04:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:04:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:04:41 INFO - Return code: 0 03:04:41 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', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XyXiEBfYQ7-P-WTUxHnuOw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0119', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 03:04:41 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 --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/XyXiEBfYQ7-P-WTUxHnuOw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0119 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 03:04:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NFcNvtWico/Render', 03:04:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:04:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:04:41 INFO - 'HOME': '/Users/cltbld', 03:04:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:04:41 INFO - 'LOGNAME': 'cltbld', 03:04:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:04:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:04:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:04:41 INFO - 'MOZ_NO_REMOTE': '1', 03:04:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:04:41 INFO - 'NO_EM_RESTART': '1', 03:04:41 INFO - 'PAGER': '/bin/cat', 03:04:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:04:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:04:41 INFO - 'PWD': '/builds/slave/test', 03:04:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:04:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:04:41 INFO - 'SHELL': '/bin/bash', 03:04:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ZFHyulxIH8/Listeners', 03:04:41 INFO - 'TMPDIR': '/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/', 03:04:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:04:41 INFO - 'USER': 'cltbld', 03:04:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:04:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:04:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:04:41 INFO - 'XPC_FLAGS': '0x0', 03:04:41 INFO - 'XPC_SERVICE_NAME': '0', 03:04:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:04:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/XyXiEBfYQ7-P-WTUxHnuOw/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0119', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 03:04:41 INFO - mozversion application_buildid: 20160605005144 03:04:41 INFO - mozversion application_changeset: 9b770efcfb3f0b5127446d8a17682e2d7235ff51 03:04:41 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:04:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:04:41 INFO - mozversion application_name: Firefox 03:04:41 INFO - mozversion application_remotingname: firefox-dev 03:04:41 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:04:41 INFO - mozversion application_vendor: Mozilla 03:04:41 INFO - mozversion application_version: 48.0a2 03:04:41 INFO - mozversion platform_buildid: 20160605005144 03:04:41 INFO - mozversion platform_changeset: 9b770efcfb3f0b5127446d8a17682e2d7235ff51 03:04:41 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:04:41 INFO - mozversion platform_version: 48.0a2 03:04:41 INFO - using testdate: 1465121081 03:04:41 INFO - actual date: 1465121081 03:04:41 INFO - starting webserver on 'localhost:49276' 03:04:41 INFO - SUITE-START | Running 2 tests 03:04:41 INFO - TEST-START | dromaeo_css 03:04:41 INFO - operating with platform_type : mac_ 03:04:41 INFO - Initialising browser for dromaeo_css test... 03:04:41 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/tmpwbAznn/profile http://localhost:49276/getInfo.html) 03:04:44 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 03:04:44 INFO - PROCESS | 1667 | colorDepth:24 03:04:44 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 03:04:44 INFO - PROCESS | 1667 | __metrics 03:04:44 INFO - PROCESS | 1667 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:04:44 ERROR - PROCESS | 1667 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:04:44 INFO - TEST-INFO | 1667: exit 0 03:04:44 INFO - Browser initialized. 03:04:44 INFO - Running cycle 1/1 for dromaeo_css test... 03:04:44 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/tmpwbAznn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:04:56 INFO - PROCESS | 1669 | RSS: Main: 210821120 03:04:56 INFO - PROCESS | 1669 | 03:05:48 INFO - PROCESS | 1669 | 1465121148565 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] 03:07:59 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49276/tests/dromaeo/cssquery-ext.html) 03:08:00 INFO - PROCESS | 1669 | RSS: Main: 360292352 03:08:00 INFO - PROCESS | 1669 | 03:11:03 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-ext.html (next: http://localhost:49276/tests/dromaeo/cssquery-jquery.html) 03:11:04 INFO - PROCESS | 1669 | RSS: Main: 356737024 03:11:04 INFO - PROCESS | 1669 | 03:14:06 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49276/tests/dromaeo/cssquery-mootools.html) 03:14:07 INFO - PROCESS | 1669 | RSS: Main: 357146624 03:14:07 INFO - PROCESS | 1669 | 03:17:09 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49276/tests/dromaeo/cssquery-prototype.html) 03:17:10 INFO - PROCESS | 1669 | RSS: Main: 354562048 03:17:10 INFO - PROCESS | 1669 | 03:20:13 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49276/tests/dromaeo/cssquery-yui.html) 03:20:13 INFO - PROCESS | 1669 | RSS: Main: 323338240 03:20:13 INFO - PROCESS | 1669 | 03:23:16 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-yui.html (next: http://localhost:49276/tests/dromaeo/cssquery-dojo.html) 03:23:16 INFO - PROCESS | 1669 | RSS: Main: 363200512 03:23:16 INFO - PROCESS | 1669 | 03:23:16 INFO - PROCESS | 1669 | __start_tp_report 03:23:16 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 03:23:16 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 03:23:16 INFO - PROCESS | 1669 | |i|pagename|runs| 03:23:16 INFO - PROCESS | 1669 | |0;dojo.html;3315.254067834737;3375.750972140307;3385.898423047309;3419.2990436960426;3486.2678837444137;5510.283663123793;5510.448955104487;5531.668099913998;5538.889222215564;5558.055130627793;5520.978618955496;5575.888482230353;5578.135389014699;5578.553715702744;5631.352394474637;5015.3730783652045;5023.9497605023935;5064.493550644943;5098.439171691111;5131.974340128294;5097.643164978453;5176.0683077046115;5177.378714554259;5182.859770850407;5199.844004679847;4204.831806727727;5107.923381149286;5136.152534831748;5153.793848246066;5165.431802501722;5107.514786095315;5157.355330583677;5170.353705786777;5172.146595811687;5203.375594928595;5031.798728050873;5038.470960549163;5117.8464646060675;5122.078025955327;5136.717480538568;5461.535769459561;5476.123820188791;5477.75350109242;5501.807436739695;5518.668879867221;4987.526185012454;6064.484518815906;6069.878602427927;6084.087386891957;6089.634621922699;5881.235439392852;6026.3672314406795;6115.847103822395;6159.599626024294;6163.568550201488;5717.771289148387;5759.856003599902;5773.711314434346;5789.507891829199;5794.0439827428845;6292.528060395489;6354.26925903527;6361.173047503795;6363.61818290904;6378.362163783678;5854.2096816930025;5859.472647461664;6090.573659843857;6115.480184184395;6120.510359171256;5605.775768969277;6217.502599792006;6225.221847269091;6226.377362263737;6229.065640154013;6041.426064523863;6066.270178419641;6081.57428979976;6105;6118.632882027093;5937.079752638348;5997;6112.51099912006;6130.325664176937;6135.110408990671;4821.252705830602;4829.154897892855;4854.83008094715;4861;4864.416270047617;4399.934000989988;4430.401895744098;4431.423914891044;4463.799129039201;4464.084862603158;5621.044422448112;5638;5640.492355687926;5640.830775076754;5642.6614403135945;6126.693665316717;6156.907646385308;6172.598781079216;6187.443130118311;6191.597546159485;5659.971700141473;5666.773329066874;5674.404187560247;5677.063284558084;5685.403032681592;5130.102232109441;5314.62797604164;5315.282436870973;5327.121025030904;5331.360236771612;6308.716107775161;6347.841303967438;6351.2696039956;6376.84057898547;6391.96804015977;6221.471174949999;6234.875302494015;6254.562180647493;6258.624482531062;6275.623462592076;6246.063090536456;6339.302676705651;6340.175777148941;6353.5870168438905;6366.236051673829;6005.279366476051;6039.214902062704;6040.637561746309;6050.183225264533;6094.847628809315;6136.754529818757;6148.9692551536955;6159.106929495288;6175.351588083367;6179.536534760001;5841.4450627190345;5929.051351783866;5937.524998000236;5944.048952167633;5954.612950158225;5915.349311575809;5964.821055368346;5967.731452084753;5971.641701497924;6010.699465026643;5748.856278593069;5789.9710501447225;5811.56413268998;5813.79651712188;5820.447057529528;5827.096799996008;5895.9115613266695;5927.881442371042;5936.643801371932;5950.940490595039;5706.343770466449;5726.685032323262;5734.197212390185;5746.367899530965;5748.913766293593 03:23:16 INFO - PROCESS | 1669 | |1;ext.html;500.74211780932814;511.12852586340296;529.5842763430708;530.7054584705487;602.3043384890453;25374.985000599976;31712.682873171263;38155;38856.61143388565;38961.805190974075;41914.58085419145;42344.78827605865;42399;42415.3637695434;42495.57504424955;8712.912870871274;9046.773830654254;9292.117248861347;9533.14201721845;9545.856812147824;8551.957240213791;11598.59404920828;11742.001929835975;11742.882571174265;11771.411429428537;11358.772824543505;11437.599684011062;11440.970312671858;11457.484413201384;11515.36665483398;8654.048054713976;11397.316161030323;11496.31022138666;11506.597269095542;11516.884831151665;9725;11388.54445822166;11523.423828808525;11541.307521548733;11551.191416600841;37736.43395349072;38123.61876381229;38175;38199.809000955094;38242.79329273778;3281.196106953788;3356.664333566648;3452.361313157074;3527.894163175109;3605.4591811228265;27993.72006279952;28243;28386.858065709745;28530.286742831388;28856.71143288561;2743.1633351827686;2783.4850552647827;2803.243124356433;2864.942701145965;2898.1015885075694;499033.0483475812;501784;502226;503202;503502;35394.82302588522;36077.0980725484;36081.5567377302;36087.278254434765;59800.40199597948;36319.63680363215;36500.63499364972;36860;36861.631383686356;36952;15595.142267175188;15760.290786914617;15768.60578485547;15772.05367677943;15822.76265856013;12107.939460302818;12337.383130843422;13278.468861245636;13360.6659833503;13486.527971520949;7979.72070977513;8011.87833703282;8020.197980201933;8074.838503229903;8093.676252949934;2955.7487613552657;3405.6083550392023;3414.385410626111;3419.0254478603774;3440.7935523868646;1004.7136566078633;1073.9087177589836;1142.8228624563205;1144.6279959013189;1145.7823013627385;29949.85025074861;29987.100386988426;30055.849720751692;30564.54153187704;30851.382972340878;955.3886309749159;1132.4337831084458;1171.1041053593838;1174.0372894226653;1213.120487646467;15219.391224350902;15228.923855380874;15335.846641533664;15497.380104795908;15549.378024879104;635749;643273;643878;644640;648917;60610;61216.693916531025;61376;61383.999999999105;61436;1404.3399602186955;1488.2558720639681;1540.691861627679;1545.1501674079436;1546.1341648676778;28708.712912870604;28851.7114828849;28969.7103028967;29273.121806345844;29404.852975734986;44908.326375105054;45313;45332.32001520046;45348.31977520274;45389.092218154794;366735;373698;376916;377148;377566;23643.763562364846;26099.869500652374;26297;26569.468610628064;26619.068332608265;42124;42298.78850605727;43031;43386.56613433825;43781.56218437902;33442;34145.63417463274;34370.48444273288;35478;36057.45913811243;28135.71864281331;36549.08627284339;36688.63311366852;37380.25239495249;37458;35946.46080308745;36143;36593.45109823408;36937.44593831148;37244;26143.73856261489;31343.84328078345;31984.68015319817;32137.839310803298;32167.356652866343;24243.151489697775;24420.31376548614;24455.51088978246;24800.131995380798;24851.87574062118 03:23:16 INFO - PROCESS | 1669 | |2;jquery.html;393.9212157568487;396.9503812023497;397.0510479952911;397.8826246257354;410.41515839928115;13344.598817124208;17230.655386892286;20219.797802021993;20879.895600521995;20890.582188356224;16235.431759888406;17717.46847594571;20692.586148277027;20749.896250518763;20932.9533523324;5462.562994960394;6000.36996115406;6006.549508786837;6016.157737916695;6016.75932962681;4192.937105943413;5988.2215312009375;5999.250093738283;6000.909986350208;6004.849878753044;6203.255609326887;6525.543211975164;6527.967360163192;6533.31400202979;6535.0850880876715;5581.413951535071;5892.528597712216;6500.089987401744;6507.089007538948;6510.6744662766905;5656.773729050833;5694.715264236813;5702.971485142548;5706.315242170925;5721.856953576153;11987.460564274628;13161.539346122936;23430.711310877894;25353;25362.74637253622;1667.374734474572;2144.506763444401;2223.9444013899783;2377.8361690861234;2808.938525282433;9734.805303893883;9750.219982401391;10159.238057145743;10213.978602139801;11139.498722557504;1454.3164712585174;1747.9912600437044;1771.6545273671638;1783.9464816055538;1822.8723989320754;101683;103435;106865.46567267114;106943.46528267386;108561;11473.196876218584;11805;11805.704857378634;11949.402529873472;11957.34234617087;15098.245087745569;15105.773413398807;15111.31999060045;15114.395424183129;15158;8812.955935220283;8844.778880528038;8880.689175878942;8935.955320223356;8975;7047.189573199148;8627;8965.506897120591;9014.459132452073;9018.368714189945;4652.674312798072;4660.347551342747;4666.789994450258;4673.439187297478;4726.88182795426;1662.6674665066794;1940.3402843033437;1996.9600607987759;2010.9095090720953;2029.5636438165866;895.6193617712446;961.8220629183623;1015.9593616255268;1059.8092343378266;1103.663382668294;19524.414267571996;20067.397978060682;20247.696284555743;20302;20312.08595613201;947.7772723409993;962.6149540183983;963.677168148664;1007.7228762090484;1049.9160067194607;8217.753467395987;8994.190522852974;10484.842727359095;10655.84016239757;10739.194560408001;587243;587255;598169.1281610245;608440;619658.9017054886;121608;121803;126227;127753.3612331951;133243;452.4864279043244;470.0786458541297;472.06295503425673;473.47917290980337;475.071235734132;10062.949685251528;10149.391036537536;10974.067204287725;11042.116630669689;11242.325460472397;34250;43737;50704;52101.7394913023;53922.730386347816;65814.67092664697;68334;68979.3102068973;69135.65432172807;71483;8137.959310203411;8287.295579875778;8341.290990265896;8345;8347.2487476125;10591.046805787517;11511.251768635011;12239.20445171061;12672;12850.485980561047;9835.426331786879;10055.69832905014;10274.691759247235;10424.218183636409;10928.50821713009;8951.552422378985;10717.356958582199;10720.946395267974;10924;11020.834687479855;10905.5880532652;11553.537858485566;11564.653060408202;11749;11909.464074116859;8199.089323290145;8205.753827385188;8230;8306.916930830856;8320.126386729306;8394.622241999003;10359.430231337348;10755.300905441121;11179.776404472028;11476.827847582126 03:23:16 INFO - PROCESS | 1669 | |3;mootools.html;1450;1588.6346140387711;1614.241306585905;1846.0861873372683;1961.0978949683144;7824.374050075994;8520.57397130144;8679.132086791318;8684.56577171142;8688.131186881308;8428.789280267996;8665.61004754786;8672.52301123438;8767;8798;2145.7854214578497;2378.8334816562847;2441.841280316774;2459.2622213336017;2466.839655422401;2470.197185914576;2474.8020158387285;2474.814388920829;2476.3313905245573;2482.379405148713;2399.8800059997016;2441.59713647248;2462.125945289414;2463.9014439422403;2466.3464181991785;2153.8061574458216;2388.6297623868327;2448.8775561221955;2449.9142530011363;2455.287966489716;2429.550533151355;2429.9271021869195;2464.2730394533655;2465.149523414437;2466.469709012553;8612.698555550525;8696.913030869737;8699.260562852101;8713.041565427799;8714.128587141235;5302;5442;5480.314960629921;5488.17677348397;5491.670499769986;3340.315235376742;3376.1897144685345;3424.383610950028;3427.091820667525;3460.048486666187;1024.5759778470044;1039.693290479303;1053.7154968158554;1057.1225882517492;1063.2769716592713;10507.474626268733;10617.097546708539;10631.096356809681;10655.946720266349;12014.639560813102;4483.529229430928;4592.15045216636;4643.721376717408;4665.6034237090225;4705.470722015359;9199.310051746012;9224.8616270756;9229.769255768659;9250.72247832566;9250.982391936881;5246.658967167122;5262.236975638588;5284.180951952453;5297.894042119213;5301.310829592128;4100.5489396166395;4281.571842815756;4386.1008493258805;4734.171519984058;4887.973525559672;3626.0753507855857;3686.7972261525338;3716.75841070335;3735.43968404736;3750.7562008469363;1218.4029825385674;1230.7784598772307;1232.5131573028602;1238.2322959765004;1241.9068569857116;807.5356669914822;818.9222023907719;827.1430638217241;828.8549503836805;831.4263158420671;5825.825225243249;5939.881202376015;5955.225820643375;6058.333583305833;6239.0953311770445;457.97023193492316;467.67496589869717;467.9953200467952;469.11571687369553;469.35932452202496;3940.330143875548;3951.68386529077;3951.8419263229725;4027.395890616431;4034.415009823559;10151.390916545031;10893.07408870255;10903.236773425784;11015.953484418967;11038.061764750086;10388.324758890649;10723.624673136403;10909.07272881798;10928.289661171999;10950.56197752081;8358.623861926228;8416.789580260542;8469.788255293544;8473;8483.109273526314;5511.807086751945;5707.7716891325545;5732.398098199629;5804.448577385142;5977;9300;9407.85888211691;9521.571529281338;9529.666461673809;9543.093406126407;9241.953790231006;9634.807303854024;9653.951730241304;9705.854412183964;9866.408015519091;6585.967070164618;6735.090762747162;7290.781276561712;7429.7399591014055;7568.243175682388;8241.917580824354;8261.83476330458;8324.500529968222;8331.458455200393;8338.791530211793;7233.529820561646;7324.08448943882;7348.853022939405;7442.62786860644;7474.738384156529;7195.748238385219;7203.171635261802;7208.27917208275;7244.746433874789;7293.9635301823155;6884.277150899084;7014.912688533285;7032.648367581703;7033.9296607033275;7044.189918159477;4874.95125048745;4875.6099512038245;4883.194272945067;4999.10016197088;5028.421731500925;4944.670821348679;4993.226049962262;5022.970291090391;5082.034413461431;5230.5815534757885 03:23:16 INFO - PROCESS | 1669 | |4;prototype.html;316.2315573157227;320.94383482890487;329.96535363786785;330.96690330966896;334.94975753636965;5344.706041167739;6335.619862808235;6377.936220637792;6415.839604009902;6494.253160886499;6026.819195424133;6028.125921741356;6108.664023478706;6246.250449946001;6284.622922624646;4718.268668356393;4756.571908528232;4758.952410475886;4760.523947605247;4773.928391074137;4735.644826638016;4743.430788305408;4762.309465127554;4763.737994410306;4770.833020844271;4508.542844149946;4528.456585209779;4643.791029403685;4660.720356778605;4707.152712511746;4619.237825758746;4630.467496237942;4639.37368455261;4642.396488456496;4649.209634362167;4032.294348489003;4034.3141665916864;4040.030392705759;4041.656459200942;4046.190761847642;6036.456718895277;6039.3960603939695;6041.939580604226;6042.425969532887;6056.576039677225;5528.75120619573;5533.253010843525;5536.280283563151;5541.0580201365465;5541.556675465953;6585.736570537173;6786.321367863224;6803.557768745065;6834.453243740489;6843.247242803288;4566.360709500673;4611.377464042345;4612.769361531943;4615.238485649864;4618.49196588375;6877;7073.115860517487;7226.024486694281;7239.819004524877;7240.348368646847;4207.621314081686;4308.685850815531;4681.6722829401615;4682.993156471307;4683.695559788603;4750.477447480775;4753.643476739258;4767.809287628456;4770.666053376231;4776.665633405698;4611.492735799059;5589.413111623221;5595.356533998561;5607.7476513556985;5616.775328986876;6900.413464855544;6912.585244885323;6917.273686262971;6934.16789985205;6947.270536593686;1524.6798172383924;1617.4500669772333;1642.9507114786575;1646.300322362991;1648.3983346078835;1005.3967619428284;1006.1296978113879;1028.18773169197;1030.2376241581326;1030.3920686794827;6378.170837791057;6394.23269207698;6423.357664233539;6539.2479864815205;6556;6082.63140793318;7119.893201601876;7133.7146514139895;7134.143902731706;7158.176809666851;6229.283632382243;6401.423871851555;6404.103425520431;6418.839529011719;6420.293767685551;3745.382011968049;3787.62123787619;3791.33651610967;3795.5065841440437;3797.905052373713;7267.89098163528;7476.514026588255;7503.5497870127965;7505.211952744883;7505.362044226193;7411.295926886937;7467.327940485382;7505.24947505256;7515.473916825879;7537.359324457482;7123.572585644878;7564.167941526428;7574.583397913168;7579.810504737426;7580.810479738051;7246.5289756165685;7246.927530724625;7249.238829922993;7252.891206631799;7257.600831954082;6870.416014638612;7156.032091174969;7161.641917904188;7163.785086447415;7177.569345839266;7562.810929726801;7615.6953721850505;7619.352355049661;7625.123110842324;7628.275313845176;4501.324801279834;4572.245579479349;4572.839950601713;4575.405197324327;4577.176108300538;5811.4479124483105;6916.239213686591;7107.289271072851;7108.680109395193;7269.709211631475;5318.4681531848055;5618.887622247614;6565.934340656532;6890.414314783299;6988.37104660583;6986.301369862973;6987.056747339044;6988.615626140611;6989.266127056586;6990.720371185095;6498.480121590167;6804.897926531205;6816.727330906907;6820.897686534805;6827.907534794409;6866.725330986705;6871.209810871814;6884.724611015503;6896.931030689629;6900;6731.394174524316;6746;6748.797536073926;6755.223149337692;6772.830679233059 03:23:16 INFO - PROCESS | 1669 | |5;yui.html;682.9009793579932;693.9757108501198;720.6252748570744;745.2547452547452;750.8348163404049;14898.255087245636;14953.40186392543;15131.92434037831;15138.243087845605;15151.924240378796;14257.002009859289;14271.215083170422;14313.141211527316;14320.069195502285;14342.784858227134;756.9394448444111;774.5585016540574;926.6756635177668;950.353759443578;951.7858481841565;917.5183028909809;963.6241865672393;964.0504103458088;964.1708131007329;967.298708436385;1012.1042877053824;1017.3387298256118;1020.0615433800921;1020.6733845169549;1022.0086516079391;774.0517865614634;982.4547376206202;1007.2797949466094;1011.4133802394593;1015.1777060580916;746.2761121711985;747.0214019634296;748.9475736698433;749.5952185819651;751.9060117485315;14738.336774845158;14808;14826.481073162387;14833.48082817107;14878.851211487854;12331.876681233163;12519.12366134371;12747.872521274761;12748.872511274862;12764.36178191096;10074.798504029879;10077.748056298578;10083.092521673012;10136.746581335525;10203;3493.68556829884;3502.334556434295;3640.5813331466957;3699.9815000924823;3780.640839120279;66760;67075;67888.6605566969;68028.6598567004;68077.63844723128;9057.592408341641;9339.439633622005;9345.345825792265;9370.34407591462;9647.179989700953;19535;19546.60906781885;19575.412737617895;19575.92332421702;19581.1188496518;7283.70865165398;7382.6677799498075;7528.51064680794;7542.396608271325;7623.047119110111;4102.384642303678;4185.895352616209;4279.914401711948;4305.203537345601;4448.354988526646;3438.2435864109857;3496.056064862473;3500.8774692886136;3501.0197144799495;3533.1343820763564;604.5556515960766;627.5324882962132;634.3117717276806;636.379529958287;645.815942456407;518.264065027654;587.7443312159224;588.8645611509377;597.3459062326746;604.6885853785216;7957.681692732342;8030.07654119772;8125.4718443301;8235.1353107924;8289.917100828914;584.4447774614133;591.3052163707627;600.0908623435498;612.9478994285536;631.1983780598614;3719.367707489733;3730.1420673245307;3773.603771603997;3790.5640851302455;3810;62919.6854015727;75747;76484;76972.23027769651;78178;60266;60846.39153608496;64258.678706606166;67463.66268168628;68639.31360686329;62173.37826621766;62391.68804156041;62840;63160.684196578724;63815;7587.962060189664;7627.809304767426;7672.539647621161;7692.153863074946;7771.067471903408;45337.86655333511;45785.542144578125;46441;46603.766981164874;46737.76631116823;58244.708776455846;59949;60294.39705603063;60732.696336518035;61371.07943380942;8889.64441422336;8936.910630893868;8937.46375217489;9218.769530761785;10796.892031079902;11666;11834.704132396759;12044.337561434206;12107.091968102251;12177.573784917486;10016.59933602677;10045.397276163454;10935.234533582572;11214.719632009264;11902.761944760883;11356;11519.30884146954;11540.769184616092;11584.420778961186;11899.405029748652;9556.23550116003;10964.177686673691;11326;11452.541898323974;11792.29246245228;9331.72004839856;9387.202087822612;9713.27150463704;9769.16962058233;9907.256955728206;9457.896226274572;9654.758631034;9686.370385924893;9702.5148742564;9851.45816980073 03:23:16 INFO - PROCESS | 1669 | __end_tp_report 03:23:16 INFO - PROCESS | 1669 | __start_cc_report 03:23:16 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,1439 03:23:16 INFO - PROCESS | 1669 | __end_cc_report 03:23:16 INFO - PROCESS | 1669 | __startTimestamp1465122196896__endTimestamp 03:23:16 INFO - PROCESS | 1669 | 03:23:16 INFO - PROCESS | 1669 | ------- Summary: start ------- 03:23:16 INFO - PROCESS | 1669 | Number of tests: 6 03:23:16 INFO - PROCESS | 1669 | 03:23:16 INFO - PROCESS | 1669 | [#0] dojo.html Cycles:165 Average:5643.53 Median:5789.97 stddev:632.50 (10.9%) stddev-sans-first:607.49 03:23:16 INFO - PROCESS | 1669 | Values: 3315.3 3375.8 3385.9 3419.3 3486.3 5510.3 5510.4 5531.7 5538.9 5558.1 5521.0 5575.9 5578.1 5578.6 5631.4 5015.4 5023.9 5064.5 5098.4 5132.0 5097.6 5176.1 5177.4 5182.9 5199.8 4204.8 5107.9 5136.2 5153.8 5165.4 5107.5 5157.4 5170.4 5172.1 5203.4 5031.8 5038.5 5117.8 5122.1 5136.7 5461.5 5476.1 5477.8 5501.8 5518.7 4987.5 6064.5 6069.9 6084.1 6089.6 5881.2 6026.4 6115.8 6159.6 6163.6 5717.8 5759.9 5773.7 5789.5 5794.0 6292.5 6354.3 6361.2 6363.6 6378.4 5854.2 5859.5 6090.6 6115.5 6120.5 5605.8 6217.5 6225.2 6226.4 6229.1 6041.4 6066.3 6081.6 6105.0 6118.6 5937.1 5997.0 6112.5 6130.3 6135.1 4821.3 4829.2 4854.8 4861.0 4864.4 4399.9 4430.4 4431.4 4463.8 4464.1 5621.0 5638.0 5640.5 5640.8 5642.7 6126.7 6156.9 6172.6 6187.4 6191.6 5660.0 5666.8 5674.4 5677.1 5685.4 5130.1 5314.6 5315.3 5327.1 5331.4 6308.7 6347.8 6351.3 6376.8 6392.0 6221.5 6234.9 6254.6 6258.6 6275.6 6246.1 6339.3 6340.2 6353.6 6366.2 6005.3 6039.2 6040.6 6050.2 6094.8 6136.8 6149.0 6159.1 6175.4 6179.5 5841.4 5929.1 5937.5 5944.0 5954.6 5915.3 5964.8 5967.7 5971.6 6010.7 5748.9 5790.0 5811.6 5813.8 5820.4 5827.1 5895.9 5927.9 5936.6 5950.9 5706.3 5726.7 5734.2 5746.4 5748.9 03:23:16 INFO - PROCESS | 1669 | 03:23:16 INFO - PROCESS | 1669 | [#1] ext.html Cycles:180 Average:62682.66 Median:18782.33 stddev:138797.33 (739.0%) stddev-sans-first:139108.17 03:23:16 INFO - PROCESS | 1669 | Values: 500.7 511.1 529.6 530.7 602.3 25375.0 31712.7 38155.0 38856.6 38961.8 41914.6 42344.8 42399.0 42415.4 42495.6 8712.9 9046.8 9292.1 9533.1 9545.9 8552.0 11598.6 11742.0 11742.9 11771.4 11358.8 11437.6 11441.0 11457.5 11515.4 8654.0 11397.3 11496.3 11506.6 11516.9 9725.0 11388.5 11523.4 11541.3 11551.2 37736.4 38123.6 38175.0 38199.8 38242.8 3281.2 3356.7 3452.4 3527.9 3605.5 27993.7 28243.0 28386.9 28530.3 28856.7 2743.2 2783.5 2803.2 2864.9 2898.1 499033.0 501784.0 502226.0 503202.0 503502.0 35394.8 36077.1 36081.6 36087.3 59800.4 36319.6 36500.6 36860.0 36861.6 36952.0 15595.1 15760.3 15768.6 15772.1 15822.8 12107.9 12337.4 13278.5 13360.7 13486.5 7979.7 8011.9 8020.2 8074.8 8093.7 2955.7 3405.6 3414.4 3419.0 3440.8 1004.7 1073.9 1142.8 1144.6 1145.8 29949.9 29987.1 30055.8 30564.5 30851.4 955.4 1132.4 1171.1 1174.0 1213.1 15219.4 15228.9 15335.8 15497.4 15549.4 635749.0 643273.0 643878.0 644640.0 648917.0 60610.0 61216.7 61376.0 61384.0 61436.0 1404.3 1488.3 1540.7 1545.2 1546.1 28708.7 28851.7 28969.7 29273.1 29404.9 44908.3 45313.0 45332.3 45348.3 45389.1 366735.0 373698.0 376916.0 377148.0 377566.0 23643.8 26099.9 26297.0 26569.5 26619.1 42124.0 42298.8 43031.0 43386.6 43781.6 33442.0 34145.6 34370.5 35478.0 36057.5 28135.7 36549.1 36688.6 37380.3 37458.0 35946.5 36143.0 36593.5 36937.4 37244.0 26143.7 31343.8 31984.7 32137.8 32167.4 24243.2 24420.3 24455.5 24800.1 24851.9 03:23:16 INFO - PROCESS | 1669 | 03:23:16 INFO - PROCESS | 1669 | [#2] jquery.html Cycles:180 Average:33745.33 Median:25357.87 stddev:99824.97 (393.7%) stddev-sans-first:100073.60 03:23:16 INFO - PROCESS | 1669 | Values: 393.9 397.0 397.1 397.9 410.4 13344.6 17230.7 20219.8 20879.9 20890.6 16235.4 17717.5 20692.6 20749.9 20933.0 5462.6 6000.4 6006.5 6016.2 6016.8 4192.9 5988.2 5999.3 6000.9 6004.8 6203.3 6525.5 6528.0 6533.3 6535.1 5581.4 5892.5 6500.1 6507.1 6510.7 5656.8 5694.7 5703.0 5706.3 5721.9 11987.5 13161.5 23430.7 25353.0 25362.7 1667.4 2144.5 2223.9 2377.8 2808.9 9734.8 9750.2 10159.2 10214.0 11139.5 1454.3 1748.0 1771.7 1783.9 1822.9 101683.0 103435.0 106865.5 106943.5 108561.0 11473.2 11805.0 11805.7 11949.4 11957.3 15098.2 15105.8 15111.3 15114.4 15158.0 8813.0 8844.8 8880.7 8936.0 8975.0 7047.2 8627.0 8965.5 9014.5 9018.4 4652.7 4660.3 4666.8 4673.4 4726.9 1662.7 1940.3 1997.0 2010.9 2029.6 895.6 961.8 1016.0 1059.8 1103.7 19524.4 20067.4 20247.7 20302.0 20312.1 947.8 962.6 963.7 1007.7 1049.9 8217.8 8994.2 10484.8 10655.8 10739.2 587243.0 587255.0 598169.1 608440.0 619658.9 121608.0 121803.0 126227.0 127753.4 133243.0 452.5 470.1 472.1 473.5 475.1 10062.9 10149.4 10974.1 11042.1 11242.3 34250.0 43737.0 50704.0 52101.7 53922.7 65814.7 68334.0 68979.3 69135.7 71483.0 8138.0 8287.3 8341.3 8345.0 8347.2 10591.0 11511.3 12239.2 12672.0 12850.5 9835.4 10055.7 10274.7 10424.2 10928.5 8951.6 10717.4 10720.9 10924.0 11020.8 10905.6 11553.5 11564.7 11749.0 11909.5 8199.1 8205.8 8230.0 8306.9 8320.1 8394.6 10359.4 10755.3 11179.8 11476.8 03:23:16 INFO - PROCESS | 1669 | 03:23:16 INFO - PROCESS | 1669 | [#3] mootools.html Cycles:180 Average:5614.00 Median:5011.04 stddev:3120.77 (62.3%) stddev-sans-first:3113.83 03:23:16 INFO - PROCESS | 1669 | Values: 1450.0 1588.6 1614.2 1846.1 1961.1 7824.4 8520.6 8679.1 8684.6 8688.1 8428.8 8665.6 8672.5 8767.0 8798.0 2145.8 2378.8 2441.8 2459.3 2466.8 2470.2 2474.8 2474.8 2476.3 2482.4 2399.9 2441.6 2462.1 2463.9 2466.3 2153.8 2388.6 2448.9 2449.9 2455.3 2429.6 2429.9 2464.3 2465.1 2466.5 8612.7 8696.9 8699.3 8713.0 8714.1 5302.0 5442.0 5480.3 5488.2 5491.7 3340.3 3376.2 3424.4 3427.1 3460.0 1024.6 1039.7 1053.7 1057.1 1063.3 10507.5 10617.1 10631.1 10655.9 12014.6 4483.5 4592.2 4643.7 4665.6 4705.5 9199.3 9224.9 9229.8 9250.7 9251.0 5246.7 5262.2 5284.2 5297.9 5301.3 4100.5 4281.6 4386.1 4734.2 4888.0 3626.1 3686.8 3716.8 3735.4 3750.8 1218.4 1230.8 1232.5 1238.2 1241.9 807.5 818.9 827.1 828.9 831.4 5825.8 5939.9 5955.2 6058.3 6239.1 458.0 467.7 468.0 469.1 469.4 3940.3 3951.7 3951.8 4027.4 4034.4 10151.4 10893.1 10903.2 11016.0 11038.1 10388.3 10723.6 10909.1 10928.3 10950.6 8358.6 8416.8 8469.8 8473.0 8483.1 5511.8 5707.8 5732.4 5804.4 5977.0 9300.0 9407.9 9521.6 9529.7 9543.1 9242.0 9634.8 9654.0 9705.9 9866.4 6586.0 6735.1 7290.8 7429.7 7568.2 8241.9 8261.8 8324.5 8331.5 8338.8 7233.5 7324.1 7348.9 7442.6 7474.7 7195.7 7203.2 7208.3 7244.7 7294.0 6884.3 7014.9 7032.6 7033.9 7044.2 4875.0 4875.6 4883.2 4999.1 5028.4 4944.7 4993.2 5023.0 5082.0 5230.6 03:23:16 INFO - PROCESS | 1669 | 03:23:16 INFO - PROCESS | 1669 | [#4] prototype.html Cycles:180 Average:5632.41 Median:6310.12 stddev:1802.69 (28.6%) stddev-sans-first:1763.04 03:23:16 INFO - PROCESS | 1669 | Values: 316.2 320.9 330.0 331.0 334.9 5344.7 6335.6 6377.9 6415.8 6494.3 6026.8 6028.1 6108.7 6246.3 6284.6 4718.3 4756.6 4759.0 4760.5 4773.9 4735.6 4743.4 4762.3 4763.7 4770.8 4508.5 4528.5 4643.8 4660.7 4707.2 4619.2 4630.5 4639.4 4642.4 4649.2 4032.3 4034.3 4040.0 4041.7 4046.2 6036.5 6039.4 6041.9 6042.4 6056.6 5528.8 5533.3 5536.3 5541.1 5541.6 6585.7 6786.3 6803.6 6834.5 6843.2 4566.4 4611.4 4612.8 4615.2 4618.5 6877.0 7073.1 7226.0 7239.8 7240.3 4207.6 4308.7 4681.7 4683.0 4683.7 4750.5 4753.6 4767.8 4770.7 4776.7 4611.5 5589.4 5595.4 5607.7 5616.8 6900.4 6912.6 6917.3 6934.2 6947.3 1524.7 1617.5 1643.0 1646.3 1648.4 1005.4 1006.1 1028.2 1030.2 1030.4 6378.2 6394.2 6423.4 6539.2 6556.0 6082.6 7119.9 7133.7 7134.1 7158.2 6229.3 6401.4 6404.1 6418.8 6420.3 3745.4 3787.6 3791.3 3795.5 3797.9 7267.9 7476.5 7503.5 7505.2 7505.4 7411.3 7467.3 7505.2 7515.5 7537.4 7123.6 7564.2 7574.6 7579.8 7580.8 7246.5 7246.9 7249.2 7252.9 7257.6 6870.4 7156.0 7161.6 7163.8 7177.6 7562.8 7615.7 7619.4 7625.1 7628.3 4501.3 4572.2 4572.8 4575.4 4577.2 5811.4 6916.2 7107.3 7108.7 7269.7 5318.5 5618.9 6565.9 6890.4 6988.4 6986.3 6987.1 6988.6 6989.3 6990.7 6498.5 6804.9 6816.7 6820.9 6827.9 6866.7 6871.2 6884.7 6896.9 6900.0 6731.4 6746.0 6748.8 6755.2 6772.8 03:23:16 INFO - PROCESS | 1669 | 03:23:16 INFO - PROCESS | 1669 | [#5] yui.html Cycles:180 Average:16401.61 Median:30274.55 stddev:21574.21 (71.3%) stddev-sans-first:21602.44 03:23:16 INFO - PROCESS | 1669 | Values: 682.9 694.0 720.6 745.3 750.8 14898.3 14953.4 15131.9 15138.2 15151.9 14257.0 14271.2 14313.1 14320.1 14342.8 756.9 774.6 926.7 950.4 951.8 917.5 963.6 964.1 964.2 967.3 1012.1 1017.3 1020.1 1020.7 1022.0 774.1 982.5 1007.3 1011.4 1015.2 746.3 747.0 748.9 749.6 751.9 14738.3 14808.0 14826.5 14833.5 14878.9 12331.9 12519.1 12747.9 12748.9 12764.4 10074.8 10077.7 10083.1 10136.7 10203.0 3493.7 3502.3 3640.6 3700.0 3780.6 66760.0 67075.0 67888.7 68028.7 68077.6 9057.6 9339.4 9345.3 9370.3 9647.2 19535.0 19546.6 19575.4 19575.9 19581.1 7283.7 7382.7 7528.5 7542.4 7623.0 4102.4 4185.9 4279.9 4305.2 4448.4 3438.2 3496.1 3500.9 3501.0 3533.1 604.6 627.5 634.3 636.4 645.8 518.3 587.7 588.9 597.3 604.7 7957.7 8030.1 8125.5 8235.1 8289.9 584.4 591.3 600.1 612.9 631.2 3719.4 3730.1 3773.6 3790.6 3810.0 62919.7 75747.0 76484.0 76972.2 78178.0 60266.0 60846.4 64258.7 67463.7 68639.3 62173.4 62391.7 62840.0 63160.7 63815.0 7588.0 7627.8 7672.5 7692.2 7771.1 45337.9 45785.5 46441.0 46603.8 46737.8 58244.7 59949.0 60294.4 60732.7 61371.1 8889.6 8936.9 8937.5 9218.8 10796.9 11666.0 11834.7 12044.3 12107.1 12177.6 10016.6 10045.4 10935.2 11214.7 11902.8 11356.0 11519.3 11540.8 11584.4 11899.4 9556.2 10964.2 11326.0 11452.5 11792.3 9331.7 9387.2 9713.3 9769.2 9907.3 9457.9 9654.8 9686.4 9702.5 9851.5 03:23:16 INFO - PROCESS | 1669 | -------- Summary: end -------- 03:23:16 INFO - PROCESS | 1669 | 03:23:17 INFO - TEST-INFO | 1669: exit 0 03:23:17 INFO - TEST-OK | dromaeo_css | took 1115703ms 03:23:17 INFO - TEST-START | kraken 03:23:17 INFO - operating with platform_type : mac_ 03:23:17 INFO - Initialising browser for kraken test... 03:23:17 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/tmpuWZ3Jz/profile http://localhost:49276/getInfo.html) 03:23:19 INFO - PROCESS | 1676 | __metrics Screen width/height:1600/1200 03:23:19 INFO - PROCESS | 1676 | colorDepth:24 03:23:19 INFO - PROCESS | 1676 | Browser inner width/height: 1024/689 03:23:19 INFO - PROCESS | 1676 | __metrics 03:23:19 INFO - PROCESS | 1676 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:23:19 ERROR - PROCESS | 1676 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 03:23:20 INFO - TEST-INFO | 1676: exit 0 03:23:20 INFO - Browser initialized. 03:23:20 INFO - Running cycle 1/1 for kraken test... 03:23:20 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/T/tmpuWZ3Jz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:23:32 INFO - PROCESS | 1678 | RSS: Main: 211193856 03:23:32 INFO - PROCESS | 1678 | 03:23:35 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection) 03:23:35 INFO - PROCESS | 1678 | RSS: Main: 272908288 03:23:35 INFO - PROCESS | 1678 | 03:23:38 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-dft) 03:23:39 INFO - PROCESS | 1678 | RSS: Main: 286068736 03:23:39 INFO - PROCESS | 1678 | 03:23:42 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-fft) 03:23:43 INFO - PROCESS | 1678 | RSS: Main: 376111104 03:23:43 INFO - PROCESS | 1678 | 03:23:45 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator) 03:23:45 INFO - PROCESS | 1678 | RSS: Main: 279683072 03:23:45 INFO - PROCESS | 1678 | 03:23:48 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:23:48 INFO - PROCESS | 1678 | RSS: Main: 281542656 03:23:48 INFO - PROCESS | 1678 | 03:23:51 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom) 03:23:52 INFO - PROCESS | 1678 | RSS: Main: 319647744 03:23:52 INFO - PROCESS | 1678 | 03:23:54 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate) 03:23:55 INFO - PROCESS | 1678 | RSS: Main: 319848448 03:23:55 INFO - PROCESS | 1678 | 03:23:58 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial) 03:23:58 INFO - PROCESS | 1678 | RSS: Main: 317939712 03:23:58 INFO - PROCESS | 1678 | 03:24:00 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:24:01 INFO - PROCESS | 1678 | RSS: Main: 309796864 03:24:01 INFO - PROCESS | 1678 | 03:24:02 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:24:03 INFO - PROCESS | 1678 | RSS: Main: 316268544 03:24:03 INFO - PROCESS | 1678 | 03:24:05 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:24:06 INFO - PROCESS | 1678 | RSS: Main: 319492096 03:24:06 INFO - PROCESS | 1678 | 03:24:09 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:24:09 INFO - PROCESS | 1678 | RSS: Main: 324182016 03:24:09 INFO - PROCESS | 1678 | 03:24:12 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:24:13 INFO - PROCESS | 1678 | RSS: Main: 322076672 03:24:13 INFO - PROCESS | 1678 | 03:24:15 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49276/tests/kraken/driver.html?testName=ai-astar) 03:24:15 INFO - PROCESS | 1678 | RSS: Main: 386072576 03:24:15 INFO - PROCESS | 1678 | 03:24:15 INFO - PROCESS | 1678 | __start_tp_report 03:24:15 INFO - PROCESS | 1678 | _x_x_mozilla_page_load 03:24:15 INFO - PROCESS | 1678 | _x_x_mozilla_page_load_details 03:24:15 INFO - PROCESS | 1678 | |i|pagename|runs| 03:24:15 INFO - PROCESS | 1678 | |0;ai-astar;114;113;118;111;112;109;113;111;112;139 03:24:15 INFO - PROCESS | 1678 | |1;audio-beat-detection;155;122;155;160;118;150;127;137;205;150 03:24:15 INFO - PROCESS | 1678 | |2;audio-dft;195;263;206;187;184;182;185;183;182;184 03:24:15 INFO - PROCESS | 1678 | |3;audio-fft;70;82;74;182;55;57;54;62;128;56 03:24:15 INFO - PROCESS | 1678 | |4;audio-oscillator;92;93;183;115;99;85;128;79;80;127 03:24:15 INFO - PROCESS | 1678 | |5;imaging-gaussian-blur;87;85;87;84;87;84;88;85;87;84 03:24:15 INFO - PROCESS | 1678 | |6;imaging-darkroom;92;93;93;93;94;96;92;93;93;93 03:24:15 INFO - PROCESS | 1678 | |7;imaging-desaturate;84;84;87;85;86;85;85;87;86;84 03:24:15 INFO - PROCESS | 1678 | |8;json-parse-financial;60;64;64;63;64;66;63;85;61;59 03:24:15 INFO - PROCESS | 1678 | |9;json-stringify-tinderbox;63;66;63;71;76;76;77;67;69;68 03:24:15 INFO - PROCESS | 1678 | |10;stanford-crypto-aes;82;81;81;81;79;78;81;81;82;86 03:24:15 INFO - PROCESS | 1678 | |11;stanford-crypto-ccm;131;127;130;131;128;126;129;128;150;129 03:24:15 INFO - PROCESS | 1678 | |12;stanford-crypto-pbkdf2;167;164;175;164;174;167;175;221;187;164 03:24:15 INFO - PROCESS | 1678 | |13;stanford-crypto-sha256-iterative;69;69;70;81;96;94;66;60;91;89 03:24:15 INFO - PROCESS | 1678 | __end_tp_report 03:24:15 INFO - PROCESS | 1678 | __start_cc_report 03:24:15 INFO - PROCESS | 1678 | _x_x_mozilla_cycle_collect,1981 03:24:15 INFO - PROCESS | 1678 | __end_cc_report 03:24:15 INFO - PROCESS | 1678 | __startTimestamp1465122255513__endTimestamp 03:24:15 INFO - PROCESS | 1678 | 03:24:15 INFO - PROCESS | 1678 | ------- Summary: start ------- 03:24:15 INFO - PROCESS | 1678 | Number of tests: 14 03:24:15 INFO - PROCESS | 1678 | 03:24:15 INFO - PROCESS | 1678 | [#0] ai-astar Cycles:10 Average:115.20 Median:113.00 stddev:8.69 (7.7%) stddev-sans-first:9.21 03:24:15 INFO - PROCESS | 1678 | Values: 114.0 113.0 118.0 111.0 112.0 109.0 113.0 111.0 112.0 139.0 03:24:15 INFO - PROCESS | 1678 | 03:24:15 INFO - PROCESS | 1678 | [#1] audio-beat-detection Cycles:10 Average:147.90 Median:152.50 stddev:25.03 (16.4%) stddev-sans-first:26.41 03:24:15 INFO - PROCESS | 1678 | Values: 155.0 122.0 155.0 160.0 118.0 150.0 127.0 137.0 205.0 150.0 03:24:15 INFO - PROCESS | 1678 | 03:24:15 INFO - PROCESS | 1678 | [#2] audio-dft Cycles:10 Average:195.10 Median:186.00 stddev:25.02 (13.5%) stddev-sans-first:26.54 03:24:15 INFO - PROCESS | 1678 | Values: 195.0 263.0 206.0 187.0 184.0 182.0 185.0 183.0 182.0 184.0 03:24:15 INFO - PROCESS | 1678 | 03:24:15 INFO - PROCESS | 1678 | [#3] audio-fft Cycles:10 Average:82.00 Median:59.50 stddev:41.55 (69.8%) stddev-sans-first:43.84 03:24:15 INFO - PROCESS | 1678 | Values: 70.0 82.0 74.0 182.0 55.0 57.0 54.0 62.0 128.0 56.0 03:24:15 INFO - PROCESS | 1678 | 03:24:15 INFO - PROCESS | 1678 | [#4] audio-oscillator Cycles:10 Average:108.10 Median:82.50 stddev:31.89 (38.7%) stddev-sans-first:33.28 03:24:15 INFO - PROCESS | 1678 | Values: 92.0 93.0 183.0 115.0 99.0 85.0 128.0 79.0 80.0 127.0 03:24:15 INFO - PROCESS | 1678 | 03:24:15 INFO - PROCESS | 1678 | [#5] imaging-gaussian-blur Cycles:10 Average:85.80 Median:87.00 stddev:1.55 (1.8%) stddev-sans-first:1.58 03:24:15 INFO - PROCESS | 1678 | Values: 87.0 85.0 87.0 84.0 87.0 84.0 88.0 85.0 87.0 84.0 03:24:15 INFO - PROCESS | 1678 | 03:24:15 INFO - PROCESS | 1678 | [#6] imaging-darkroom Cycles:10 Average:93.20 Median:93.00 stddev:1.14 (1.2%) stddev-sans-first:1.12 03:24:15 INFO - PROCESS | 1678 | Values: 92.0 93.0 93.0 93.0 94.0 96.0 92.0 93.0 93.0 93.0 03:24:15 INFO - PROCESS | 1678 | 03:24:15 INFO - PROCESS | 1678 | [#7] imaging-desaturate Cycles:10 Average:85.30 Median:85.50 stddev:1.16 (1.4%) stddev-sans-first:1.13 03:24:15 INFO - PROCESS | 1678 | Values: 84.0 84.0 87.0 85.0 86.0 85.0 85.0 87.0 86.0 84.0 03:24:15 INFO - PROCESS | 1678 | 03:24:15 INFO - PROCESS | 1678 | [#8] json-parse-financial Cycles:10 Average:64.90 Median:64.00 stddev:7.37 (11.5%) stddev-sans-first:7.60 03:24:15 INFO - PROCESS | 1678 | Values: 60.0 64.0 64.0 63.0 64.0 66.0 63.0 85.0 61.0 59.0 03:24:15 INFO - PROCESS | 1678 | 03:24:15 INFO - PROCESS | 1678 | [#9] json-stringify-tinderbox Cycles:10 Average:69.60 Median:70.00 stddev:5.25 (7.5%) stddev-sans-first:5.00 03:24:15 INFO - PROCESS | 1678 | Values: 63.0 66.0 63.0 71.0 76.0 76.0 77.0 67.0 69.0 68.0 03:24:15 INFO - PROCESS | 1678 | 03:24:15 INFO - PROCESS | 1678 | [#10] stanford-crypto-aes Cycles:10 Average:81.20 Median:81.00 stddev:2.10 (2.6%) stddev-sans-first:2.20 03:24:15 INFO - PROCESS | 1678 | Values: 82.0 81.0 81.0 81.0 79.0 78.0 81.0 81.0 82.0 86.0 03:24:15 INFO - PROCESS | 1678 | 03:24:15 INFO - PROCESS | 1678 | [#11] stanford-crypto-ccm Cycles:10 Average:130.90 Median:129.50 stddev:6.90 (5.3%) stddev-sans-first:7.32 03:24:15 INFO - PROCESS | 1678 | Values: 131.0 127.0 130.0 131.0 128.0 126.0 129.0 128.0 150.0 129.0 03:24:15 INFO - PROCESS | 1678 | 03:24:15 INFO - PROCESS | 1678 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.80 Median:174.50 stddev:17.47 (10.0%) stddev-sans-first:18.23 03:24:15 INFO - PROCESS | 1678 | Values: 167.0 164.0 175.0 164.0 174.0 167.0 175.0 221.0 187.0 164.0 03:24:15 INFO - PROCESS | 1678 | 03:24:15 INFO - PROCESS | 1678 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.50 Median:85.00 stddev:13.21 (15.5%) stddev-sans-first:13.56 03:24:15 INFO - PROCESS | 1678 | Values: 69.0 69.0 70.0 81.0 96.0 94.0 66.0 60.0 91.0 89.0 03:24:15 INFO - PROCESS | 1678 | -------- Summary: end -------- 03:24:15 INFO - PROCESS | 1678 | 03:24:16 INFO - TEST-INFO | 1678: exit 0 03:24:16 INFO - TEST-OK | kraken | took 58813ms 03:24:16 INFO - SUITE-END | took 1174s 03:24:16 INFO - Completed test suite (00:19:34) 03:24:16 INFO - javascript benchmark 03:24:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3315.254067834737, 3375.750972140307, 3385.898423047309, 3419.2990436960426, 3486.2678837444137, 5510.283663123793, 5510.448955104487, 5531.668099913998, 5538.889222215564, 5558.055130627793, 5520.978618955496, 5575.888482230353, 5578.135389014699, 5578.553715702744, 5631.352394474637, 5015.3730783652045, 5023.9497605023935, 5064.493550644943, 5098.439171691111, 5131.974340128294, 5097.643164978453, 5176.0683077046115, 5177.378714554259, 5182.859770850407, 5199.844004679847, 4204.831806727727, 5107.923381149286, 5136.152534831748, 5153.793848246066, 5165.431802501722, 5107.514786095315, 5157.355330583677, 5170.353705786777, 5172.146595811687, 5203.375594928595, 5031.798728050873, 5038.470960549163, 5117.8464646060675, 5122.078025955327, 5136.717480538568, 5461.535769459561, 5476.123820188791, 5477.75350109242, 5501.807436739695, 5518.668879867221, 4987.526185012454, 6064.484518815906, 6069.878602427927, 6084.087386891957, 6089.634621922699, 5881.235439392852, 6026.3672314406795, 6115.847103822395, 6159.599626024294, 6163.568550201488, 5717.771289148387, 5759.856003599902, 5773.711314434346, 5789.507891829199, 5794.0439827428845, 6292.528060395489, 6354.26925903527, 6361.173047503795, 6363.61818290904, 6378.362163783678, 5854.2096816930025, 5859.472647461664, 6090.573659843857, 6115.480184184395, 6120.510359171256, 5605.775768969277, 6217.502599792006, 6225.221847269091, 6226.377362263737, 6229.065640154013, 6041.426064523863, 6066.270178419641, 6081.57428979976, 6105.0, 6118.632882027093, 5937.079752638348, 5997.0, 6112.51099912006, 6130.325664176937, 6135.110408990671, 4821.252705830602, 4829.154897892855, 4854.83008094715, 4861.0, 4864.416270047617, 4399.934000989988, 4430.401895744098, 4431.423914891044, 4463.799129039201, 4464.084862603158, 5621.044422448112, 5638.0, 5640.492355687926, 5640.830775076754, 5642.6614403135945, 6126.693665316717, 6156.907646385308, 6172.598781079216, 6187.443130118311, 6191.597546159485, 5659.971700141473, 5666.773329066874, 5674.404187560247, 5677.063284558084, 5685.403032681592, 5130.102232109441, 5314.62797604164, 5315.282436870973, 5327.121025030904, 5331.360236771612, 6308.716107775161, 6347.841303967438, 6351.2696039956, 6376.84057898547, 6391.96804015977, 6221.471174949999, 6234.875302494015, 6254.562180647493, 6258.624482531062, 6275.623462592076, 6246.063090536456, 6339.302676705651, 6340.175777148941, 6353.5870168438905, 6366.236051673829, 6005.279366476051, 6039.214902062704, 6040.637561746309, 6050.183225264533, 6094.847628809315, 6136.754529818757, 6148.9692551536955, 6159.106929495288, 6175.351588083367, 6179.536534760001, 5841.4450627190345, 5929.051351783866, 5937.524998000236, 5944.048952167633, 5954.612950158225, 5915.349311575809, 5964.821055368346, 5967.731452084753, 5971.641701497924, 6010.699465026643, 5748.856278593069, 5789.9710501447225, 5811.56413268998, 5813.79651712188, 5820.447057529528, 5827.096799996008, 5895.9115613266695, 5927.881442371042, 5936.643801371932, 5950.940490595039, 5706.343770466449, 5726.685032323262, 5734.197212390185, 5746.367899530965, 5748.913766293593], "value": 5603.923096544819, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [500.74211780932814, 511.12852586340296, 529.5842763430708, 530.7054584705487, 602.3043384890453, 25374.985000599976, 31712.682873171263, 38155.0, 38856.61143388565, 38961.805190974075, 41914.58085419145, 42344.78827605865, 42399.0, 42415.3637695434, 42495.57504424955, 8712.912870871274, 9046.773830654254, 9292.117248861347, 9533.14201721845, 9545.856812147824, 8551.957240213791, 11598.59404920828, 11742.001929835975, 11742.882571174265, 11771.411429428537, 11358.772824543505, 11437.599684011062, 11440.970312671858, 11457.484413201384, 11515.36665483398, 8654.048054713976, 11397.316161030323, 11496.31022138666, 11506.597269095542, 11516.884831151665, 9725.0, 11388.54445822166, 11523.423828808525, 11541.307521548733, 11551.191416600841, 37736.43395349072, 38123.61876381229, 38175.0, 38199.809000955094, 38242.79329273778, 3281.196106953788, 3356.664333566648, 3452.361313157074, 3527.894163175109, 3605.4591811228265, 27993.72006279952, 28243.0, 28386.858065709745, 28530.286742831388, 28856.71143288561, 2743.1633351827686, 2783.4850552647827, 2803.243124356433, 2864.942701145965, 2898.1015885075694, 499033.0483475812, 501784.0, 502226.0, 503202.0, 503502.0, 35394.82302588522, 36077.0980725484, 36081.5567377302, 36087.278254434765, 59800.40199597948, 36319.63680363215, 36500.63499364972, 36860.0, 36861.631383686356, 36952.0, 15595.142267175188, 15760.290786914617, 15768.60578485547, 15772.05367677943, 15822.76265856013, 12107.939460302818, 12337.383130843422, 13278.468861245636, 13360.6659833503, 13486.527971520949, 7979.72070977513, 8011.87833703282, 8020.197980201933, 8074.838503229903, 8093.676252949934, 2955.7487613552657, 3405.6083550392023, 3414.385410626111, 3419.0254478603774, 3440.7935523868646, 1004.7136566078633, 1073.9087177589836, 1142.8228624563205, 1144.6279959013189, 1145.7823013627385, 29949.85025074861, 29987.100386988426, 30055.849720751692, 30564.54153187704, 30851.382972340878, 955.3886309749159, 1132.4337831084458, 1171.1041053593838, 1174.0372894226653, 1213.120487646467, 15219.391224350902, 15228.923855380874, 15335.846641533664, 15497.380104795908, 15549.378024879104, 635749.0, 643273.0, 643878.0, 644640.0, 648917.0, 60610.0, 61216.693916531025, 61376.0, 61383.999999999105, 61436.0, 1404.3399602186955, 1488.2558720639681, 1540.691861627679, 1545.1501674079436, 1546.1341648676778, 28708.712912870604, 28851.7114828849, 28969.7103028967, 29273.121806345844, 29404.852975734986, 44908.326375105054, 45313.0, 45332.32001520046, 45348.31977520274, 45389.092218154794, 366735.0, 373698.0, 376916.0, 377148.0, 377566.0, 23643.763562364846, 26099.869500652374, 26297.0, 26569.468610628064, 26619.068332608265, 42124.0, 42298.78850605727, 43031.0, 43386.56613433825, 43781.56218437902, 33442.0, 34145.63417463274, 34370.48444273288, 35478.0, 36057.45913811243, 28135.71864281331, 36549.08627284339, 36688.63311366852, 37380.25239495249, 37458.0, 35946.46080308745, 36143.0, 36593.45109823408, 36937.44593831148, 37244.0, 26143.73856261489, 31343.84328078345, 31984.68015319817, 32137.839310803298, 32167.356652866343, 24243.151489697775, 24420.31376548614, 24455.51088978246, 24800.131995380798, 24851.87574062118], "value": 18339.540810442886, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [393.9212157568487, 396.9503812023497, 397.0510479952911, 397.8826246257354, 410.41515839928115, 13344.598817124208, 17230.655386892286, 20219.797802021993, 20879.895600521995, 20890.582188356224, 16235.431759888406, 17717.46847594571, 20692.586148277027, 20749.896250518763, 20932.9533523324, 5462.562994960394, 6000.36996115406, 6006.549508786837, 6016.157737916695, 6016.75932962681, 4192.937105943413, 5988.2215312009375, 5999.250093738283, 6000.909986350208, 6004.849878753044, 6203.255609326887, 6525.543211975164, 6527.967360163192, 6533.31400202979, 6535.0850880876715, 5581.413951535071, 5892.528597712216, 6500.089987401744, 6507.089007538948, 6510.6744662766905, 5656.773729050833, 5694.715264236813, 5702.971485142548, 5706.315242170925, 5721.856953576153, 11987.460564274628, 13161.539346122936, 23430.711310877894, 25353.0, 25362.74637253622, 1667.374734474572, 2144.506763444401, 2223.9444013899783, 2377.8361690861234, 2808.938525282433, 9734.805303893883, 9750.219982401391, 10159.238057145743, 10213.978602139801, 11139.498722557504, 1454.3164712585174, 1747.9912600437044, 1771.6545273671638, 1783.9464816055538, 1822.8723989320754, 101683.0, 103435.0, 106865.46567267114, 106943.46528267386, 108561.0, 11473.196876218584, 11805.0, 11805.704857378634, 11949.402529873472, 11957.34234617087, 15098.245087745569, 15105.773413398807, 15111.31999060045, 15114.395424183129, 15158.0, 8812.955935220283, 8844.778880528038, 8880.689175878942, 8935.955320223356, 8975.0, 7047.189573199148, 8627.0, 8965.506897120591, 9014.459132452073, 9018.368714189945, 4652.674312798072, 4660.347551342747, 4666.789994450258, 4673.439187297478, 4726.88182795426, 1662.6674665066794, 1940.3402843033437, 1996.9600607987759, 2010.9095090720953, 2029.5636438165866, 895.6193617712446, 961.8220629183623, 1015.9593616255268, 1059.8092343378266, 1103.663382668294, 19524.414267571996, 20067.397978060682, 20247.696284555743, 20302.0, 20312.08595613201, 947.7772723409993, 962.6149540183983, 963.677168148664, 1007.7228762090484, 1049.9160067194607, 8217.753467395987, 8994.190522852974, 10484.842727359095, 10655.84016239757, 10739.194560408001, 587243.0, 587255.0, 598169.1281610245, 608440.0, 619658.9017054886, 121608.0, 121803.0, 126227.0, 127753.3612331951, 133243.0, 452.4864279043244, 470.0786458541297, 472.06295503425673, 473.47917290980337, 475.071235734132, 10062.949685251528, 10149.391036537536, 10974.067204287725, 11042.116630669689, 11242.325460472397, 34250.0, 43737.0, 50704.0, 52101.7394913023, 53922.730386347816, 65814.67092664697, 68334.0, 68979.3102068973, 69135.65432172807, 71483.0, 8137.959310203411, 8287.295579875778, 8341.290990265896, 8345.0, 8347.2487476125, 10591.046805787517, 11511.251768635011, 12239.20445171061, 12672.0, 12850.485980561047, 9835.426331786879, 10055.69832905014, 10274.691759247235, 10424.218183636409, 10928.50821713009, 8951.552422378985, 10717.356958582199, 10720.946395267974, 10924.0, 11020.834687479855, 10905.5880532652, 11553.537858485566, 11564.653060408202, 11749.0, 11909.464074116859, 8199.089323290145, 8205.753827385188, 8230.0, 8306.916930830856, 8320.126386729306, 8394.622241999003, 10359.430231337348, 10755.300905441121, 11179.776404472028, 11476.827847582126], "value": 9053.626576434455, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1450.0, 1588.6346140387711, 1614.241306585905, 1846.0861873372683, 1961.0978949683144, 7824.374050075994, 8520.57397130144, 8679.132086791318, 8684.56577171142, 8688.131186881308, 8428.789280267996, 8665.61004754786, 8672.52301123438, 8767.0, 8798.0, 2145.7854214578497, 2378.8334816562847, 2441.841280316774, 2459.2622213336017, 2466.839655422401, 2470.197185914576, 2474.8020158387285, 2474.814388920829, 2476.3313905245573, 2482.379405148713, 2399.8800059997016, 2441.59713647248, 2462.125945289414, 2463.9014439422403, 2466.3464181991785, 2153.8061574458216, 2388.6297623868327, 2448.8775561221955, 2449.9142530011363, 2455.287966489716, 2429.550533151355, 2429.9271021869195, 2464.2730394533655, 2465.149523414437, 2466.469709012553, 8612.698555550525, 8696.913030869737, 8699.260562852101, 8713.041565427799, 8714.128587141235, 5302.0, 5442.0, 5480.314960629921, 5488.17677348397, 5491.670499769986, 3340.315235376742, 3376.1897144685345, 3424.383610950028, 3427.091820667525, 3460.048486666187, 1024.5759778470044, 1039.693290479303, 1053.7154968158554, 1057.1225882517492, 1063.2769716592713, 10507.474626268733, 10617.097546708539, 10631.096356809681, 10655.946720266349, 12014.639560813102, 4483.529229430928, 4592.15045216636, 4643.721376717408, 4665.6034237090225, 4705.470722015359, 9199.310051746012, 9224.8616270756, 9229.769255768659, 9250.72247832566, 9250.982391936881, 5246.658967167122, 5262.236975638588, 5284.180951952453, 5297.894042119213, 5301.310829592128, 4100.5489396166395, 4281.571842815756, 4386.1008493258805, 4734.171519984058, 4887.973525559672, 3626.0753507855857, 3686.7972261525338, 3716.75841070335, 3735.43968404736, 3750.7562008469363, 1218.4029825385674, 1230.7784598772307, 1232.5131573028602, 1238.2322959765004, 1241.9068569857116, 807.5356669914822, 818.9222023907719, 827.1430638217241, 828.8549503836805, 831.4263158420671, 5825.825225243249, 5939.881202376015, 5955.225820643375, 6058.333583305833, 6239.0953311770445, 457.97023193492316, 467.67496589869717, 467.9953200467952, 469.11571687369553, 469.35932452202496, 3940.330143875548, 3951.68386529077, 3951.8419263229725, 4027.395890616431, 4034.415009823559, 10151.390916545031, 10893.07408870255, 10903.236773425784, 11015.953484418967, 11038.061764750086, 10388.324758890649, 10723.624673136403, 10909.07272881798, 10928.289661171999, 10950.56197752081, 8358.623861926228, 8416.789580260542, 8469.788255293544, 8473.0, 8483.109273526314, 5511.807086751945, 5707.7716891325545, 5732.398098199629, 5804.448577385142, 5977.0, 9300.0, 9407.85888211691, 9521.571529281338, 9529.666461673809, 9543.093406126407, 9241.953790231006, 9634.807303854024, 9653.951730241304, 9705.854412183964, 9866.408015519091, 6585.967070164618, 6735.090762747162, 7290.781276561712, 7429.7399591014055, 7568.243175682388, 8241.917580824354, 8261.83476330458, 8324.500529968222, 8331.458455200393, 8338.791530211793, 7233.529820561646, 7324.08448943882, 7348.853022939405, 7442.62786860644, 7474.738384156529, 7195.748238385219, 7203.171635261802, 7208.27917208275, 7244.746433874789, 7293.9635301823155, 6884.277150899084, 7014.912688533285, 7032.648367581703, 7033.9296607033275, 7044.189918159477, 4874.95125048745, 4875.6099512038245, 4883.194272945067, 4999.10016197088, 5028.421731500925, 4944.670821348679, 4993.226049962262, 5022.970291090391, 5082.034413461431, 5230.5815534757885], "value": 4455.534800544319, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.2315573157227, 320.94383482890487, 329.96535363786785, 330.96690330966896, 334.94975753636965, 5344.706041167739, 6335.619862808235, 6377.936220637792, 6415.839604009902, 6494.253160886499, 6026.819195424133, 6028.125921741356, 6108.664023478706, 6246.250449946001, 6284.622922624646, 4718.268668356393, 4756.571908528232, 4758.952410475886, 4760.523947605247, 4773.928391074137, 4735.644826638016, 4743.430788305408, 4762.309465127554, 4763.737994410306, 4770.833020844271, 4508.542844149946, 4528.456585209779, 4643.791029403685, 4660.720356778605, 4707.152712511746, 4619.237825758746, 4630.467496237942, 4639.37368455261, 4642.396488456496, 4649.209634362167, 4032.294348489003, 4034.3141665916864, 4040.030392705759, 4041.656459200942, 4046.190761847642, 6036.456718895277, 6039.3960603939695, 6041.939580604226, 6042.425969532887, 6056.576039677225, 5528.75120619573, 5533.253010843525, 5536.280283563151, 5541.0580201365465, 5541.556675465953, 6585.736570537173, 6786.321367863224, 6803.557768745065, 6834.453243740489, 6843.247242803288, 4566.360709500673, 4611.377464042345, 4612.769361531943, 4615.238485649864, 4618.49196588375, 6877.0, 7073.115860517487, 7226.024486694281, 7239.819004524877, 7240.348368646847, 4207.621314081686, 4308.685850815531, 4681.6722829401615, 4682.993156471307, 4683.695559788603, 4750.477447480775, 4753.643476739258, 4767.809287628456, 4770.666053376231, 4776.665633405698, 4611.492735799059, 5589.413111623221, 5595.356533998561, 5607.7476513556985, 5616.775328986876, 6900.413464855544, 6912.585244885323, 6917.273686262971, 6934.16789985205, 6947.270536593686, 1524.6798172383924, 1617.4500669772333, 1642.9507114786575, 1646.300322362991, 1648.3983346078835, 1005.3967619428284, 1006.1296978113879, 1028.18773169197, 1030.2376241581326, 1030.3920686794827, 6378.170837791057, 6394.23269207698, 6423.357664233539, 6539.2479864815205, 6556.0, 6082.63140793318, 7119.893201601876, 7133.7146514139895, 7134.143902731706, 7158.176809666851, 6229.283632382243, 6401.423871851555, 6404.103425520431, 6418.839529011719, 6420.293767685551, 3745.382011968049, 3787.62123787619, 3791.33651610967, 3795.5065841440437, 3797.905052373713, 7267.89098163528, 7476.514026588255, 7503.5497870127965, 7505.211952744883, 7505.362044226193, 7411.295926886937, 7467.327940485382, 7505.24947505256, 7515.473916825879, 7537.359324457482, 7123.572585644878, 7564.167941526428, 7574.583397913168, 7579.810504737426, 7580.810479738051, 7246.5289756165685, 7246.927530724625, 7249.238829922993, 7252.891206631799, 7257.600831954082, 6870.416014638612, 7156.032091174969, 7161.641917904188, 7163.785086447415, 7177.569345839266, 7562.810929726801, 7615.6953721850505, 7619.352355049661, 7625.123110842324, 7628.275313845176, 4501.324801279834, 4572.245579479349, 4572.839950601713, 4575.405197324327, 4577.176108300538, 5811.4479124483105, 6916.239213686591, 7107.289271072851, 7108.680109395193, 7269.709211631475, 5318.4681531848055, 5618.887622247614, 6565.934340656532, 6890.414314783299, 6988.37104660583, 6986.301369862973, 6987.056747339044, 6988.615626140611, 6989.266127056586, 6990.720371185095, 6498.480121590167, 6804.897926531205, 6816.727330906907, 6820.897686534805, 6827.907534794409, 6866.725330986705, 6871.209810871814, 6884.724611015503, 6896.931030689629, 6900.0, 6731.394174524316, 6746.0, 6748.797536073926, 6755.223149337692, 6772.830679233059], "value": 5031.0744227124305, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [682.9009793579932, 693.9757108501198, 720.6252748570744, 745.2547452547452, 750.8348163404049, 14898.255087245636, 14953.40186392543, 15131.92434037831, 15138.243087845605, 15151.924240378796, 14257.002009859289, 14271.215083170422, 14313.141211527316, 14320.069195502285, 14342.784858227134, 756.9394448444111, 774.5585016540574, 926.6756635177668, 950.353759443578, 951.7858481841565, 917.5183028909809, 963.6241865672393, 964.0504103458088, 964.1708131007329, 967.298708436385, 1012.1042877053824, 1017.3387298256118, 1020.0615433800921, 1020.6733845169549, 1022.0086516079391, 774.0517865614634, 982.4547376206202, 1007.2797949466094, 1011.4133802394593, 1015.1777060580916, 746.2761121711985, 747.0214019634296, 748.9475736698433, 749.5952185819651, 751.9060117485315, 14738.336774845158, 14808.0, 14826.481073162387, 14833.48082817107, 14878.851211487854, 12331.876681233163, 12519.12366134371, 12747.872521274761, 12748.872511274862, 12764.36178191096, 10074.798504029879, 10077.748056298578, 10083.092521673012, 10136.746581335525, 10203.0, 3493.68556829884, 3502.334556434295, 3640.5813331466957, 3699.9815000924823, 3780.640839120279, 66760.0, 67075.0, 67888.6605566969, 68028.6598567004, 68077.63844723128, 9057.592408341641, 9339.439633622005, 9345.345825792265, 9370.34407591462, 9647.179989700953, 19535.0, 19546.60906781885, 19575.412737617895, 19575.92332421702, 19581.1188496518, 7283.70865165398, 7382.6677799498075, 7528.51064680794, 7542.396608271325, 7623.047119110111, 4102.384642303678, 4185.895352616209, 4279.914401711948, 4305.203537345601, 4448.354988526646, 3438.2435864109857, 3496.056064862473, 3500.8774692886136, 3501.0197144799495, 3533.1343820763564, 604.5556515960766, 627.5324882962132, 634.3117717276806, 636.379529958287, 645.815942456407, 518.264065027654, 587.7443312159224, 588.8645611509377, 597.3459062326746, 604.6885853785216, 7957.681692732342, 8030.07654119772, 8125.4718443301, 8235.1353107924, 8289.917100828914, 584.4447774614133, 591.3052163707627, 600.0908623435498, 612.9478994285536, 631.1983780598614, 3719.367707489733, 3730.1420673245307, 3773.603771603997, 3790.5640851302455, 3810.0, 62919.6854015727, 75747.0, 76484.0, 76972.23027769651, 78178.0, 60266.0, 60846.39153608496, 64258.678706606166, 67463.66268168628, 68639.31360686329, 62173.37826621766, 62391.68804156041, 62840.0, 63160.684196578724, 63815.0, 7587.962060189664, 7627.809304767426, 7672.539647621161, 7692.153863074946, 7771.067471903408, 45337.86655333511, 45785.542144578125, 46441.0, 46603.766981164874, 46737.76631116823, 58244.708776455846, 59949.0, 60294.39705603063, 60732.696336518035, 61371.07943380942, 8889.64441422336, 8936.910630893868, 8937.46375217489, 9218.769530761785, 10796.892031079902, 11666.0, 11834.704132396759, 12044.337561434206, 12107.091968102251, 12177.573784917486, 10016.59933602677, 10045.397276163454, 10935.234533582572, 11214.719632009264, 11902.761944760883, 11356.0, 11519.30884146954, 11540.769184616092, 11584.420778961186, 11899.405029748652, 9556.23550116003, 10964.177686673691, 11326.0, 11452.541898323974, 11792.29246245228, 9331.72004839856, 9387.202087822612, 9713.27150463704, 9769.16962058233, 9907.256955728206, 9457.896226274572, 9654.758631034, 9686.370385924893, 9702.5148742564, 9851.45816980073], "value": 6702.250472730786, "unit": "score"}], "name": "dromaeo_css", "value": 7204.216322854453, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 118.0, 111.0, 112.0, 109.0, 113.0, 111.0, 112.0, 139.0], "value": 115.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 122.0, 155.0, 160.0, 118.0, 150.0, 127.0, 137.0, 205.0, 150.0], "value": 147.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 263.0, 206.0, 187.0, 184.0, 182.0, 185.0, 183.0, 182.0, 184.0], "value": 195.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 82.0, 74.0, 182.0, 55.0, 57.0, 54.0, 62.0, 128.0, 56.0], "value": 82.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 183.0, 115.0, 99.0, 85.0, 128.0, 79.0, 80.0, 127.0], "value": 108.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 87.0, 84.0, 87.0, 84.0, 88.0, 85.0, 87.0, 84.0], "value": 85.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 93.0, 93.0, 93.0, 94.0, 96.0, 92.0, 93.0, 93.0, 93.0], "value": 93.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 87.0, 85.0, 86.0, 85.0, 85.0, 87.0, 86.0, 84.0], "value": 85.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 64.0, 64.0, 63.0, 64.0, 66.0, 63.0, 85.0, 61.0, 59.0], "value": 64.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 66.0, 63.0, 71.0, 76.0, 76.0, 77.0, 67.0, 69.0, 68.0], "value": 69.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 81.0, 81.0, 79.0, 78.0, 81.0, 81.0, 82.0, 86.0], "value": 81.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 127.0, 130.0, 131.0, 128.0, 126.0, 129.0, 128.0, 150.0, 129.0], "value": 130.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 164.0, 175.0, 164.0, 174.0, 167.0, 175.0, 221.0, 187.0, 164.0], "value": 175.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 70.0, 81.0, 96.0, 94.0, 66.0, 60.0, 91.0, 89.0], "value": 78.5, "unit": "score"}], "name": "kraken", "value": 1513.5, "alertThreshold": 2.0}]} 03:24:16 INFO - Return code: 0 03:24:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 03:24:16 ERROR - # TBPL SUCCESS # 03:24:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:24:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:24:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:24:16 INFO - 2016-06-05 03:24:16.781 screenresolution[1682:9877] starting screenresolution argv=screenresolution get 03:24:16 INFO - 2016-06-05 03:24:16.784 screenresolution[1682:9877] Display 0: 1600x1200x32@60 03:24:16 INFO - 2016-06-05 03:24:16.790 screenresolution[1683:9880] starting screenresolution argv=screenresolution list 03:24:16 INFO - Available Modes on Display 0 03:24:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:24:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:24:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:24:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:24:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:24:17 INFO - Intel Iris: 03:24:17 INFO - Chipset Model: Intel Iris 03:24:17 INFO - Type: GPU 03:24:17 INFO - Bus: Built-In 03:24:17 INFO - VRAM (Dynamic, Max): 1536 MB 03:24:17 INFO - Vendor: Intel (0x8086) 03:24:17 INFO - Device ID: 0x0a2e 03:24:17 INFO - Revision ID: 0x0009 03:24:17 INFO - Displays: 03:24:17 INFO - Display: 03:24:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:24:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:24:17 INFO - Display Serial Number: 1600x1200 60 03:24:17 INFO - Main Display: Yes 03:24:17 INFO - Mirror: Off 03:24:17 INFO - Online: Yes 03:24:17 INFO - Rotation: Supported 03:24:17 INFO - Return code: 0 03:24:17 INFO - Running post-action listener: _resource_record_post_action 03:24:17 INFO - Running post-run listener: _resource_record_post_run 03:24:18 INFO - Total resource usage - Wall time: 1208s; CPU: 53.0%; Read bytes: 32886272; Write bytes: 653609472; Read time: 326; Write time: 1841 03:24:18 INFO - install - Wall time: 32s; CPU: 54.0%; Read bytes: 347060736; Write bytes: 346187264; Read time: 26769; Write time: 646 03:24:18 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 31944192; Write bytes: 297460736; Read time: 312; Write time: 1162 03:24:18 INFO - Running post-run listener: _upload_blobber_files 03:24:18 INFO - Blob upload gear active. 03:24:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:24:18 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:24:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:24:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:24:18 INFO - (blobuploader) - INFO - Open directory for files ... 03:24:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:24:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:24:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:24:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:24:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:24:19 INFO - (blobuploader) - INFO - Done attempting. 03:24:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:24:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:24:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:24:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:24:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:24:20 INFO - (blobuploader) - INFO - Done attempting. 03:24:20 INFO - (blobuploader) - INFO - Iteration through files over. 03:24:20 INFO - Return code: 0 03:24:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:24:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:24:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0a8a542949e2b215abb783ac5c56fa433f46d3f96881c969512512c0d93f3cf1b7d9109331688bffd63a147bac9a36206f12230181448e7d6e5ce35731d31e02", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/24f82dd0bbf1bcdeb25ab62957cd301c9cb62d52140d729486edadc6477716fbb09ff3fc6c19b9be5e616537afbec917724a2d3bd70f7875f6c15200b59c8fb6"} 03:24:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:24:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:24:20 INFO - Contents: 03:24:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0a8a542949e2b215abb783ac5c56fa433f46d3f96881c969512512c0d93f3cf1b7d9109331688bffd63a147bac9a36206f12230181448e7d6e5ce35731d31e02", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/24f82dd0bbf1bcdeb25ab62957cd301c9cb62d52140d729486edadc6477716fbb09ff3fc6c19b9be5e616537afbec917724a2d3bd70f7875f6c15200b59c8fb6"} 03:24:20 INFO - Running post-run listener: copy_logs_to_upload_dir 03:24:20 INFO - Copying logs to upload dir... 03:24:20 INFO - mkdir: /builds/slave/test/build/upload/logs 03:24:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1324.263019 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 4 secs) (at 2016-06-05 03:24:20.719807) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-05 03:24:20.723170) ========= 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.NFcNvtWico/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.ZFHyulxIH8/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0a8a542949e2b215abb783ac5c56fa433f46d3f96881c969512512c0d93f3cf1b7d9109331688bffd63a147bac9a36206f12230181448e7d6e5ce35731d31e02", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/24f82dd0bbf1bcdeb25ab62957cd301c9cb62d52140d729486edadc6477716fbb09ff3fc6c19b9be5e616537afbec917724a2d3bd70f7875f6c15200b59c8fb6"} build_url:https://queue.taskcluster.net/v1/task/XyXiEBfYQ7-P-WTUxHnuOw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011328 build_url: 'https://queue.taskcluster.net/v1/task/XyXiEBfYQ7-P-WTUxHnuOw/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0a8a542949e2b215abb783ac5c56fa433f46d3f96881c969512512c0d93f3cf1b7d9109331688bffd63a147bac9a36206f12230181448e7d6e5ce35731d31e02", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/24f82dd0bbf1bcdeb25ab62957cd301c9cb62d52140d729486edadc6477716fbb09ff3fc6c19b9be5e616537afbec917724a2d3bd70f7875f6c15200b59c8fb6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-05 03:24:20.754364) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-05 03:24:20.754674) ========= 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.NFcNvtWico/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.ZFHyulxIH8/Listeners TMPDIR=/var/folders/xg/t9n0l1xx5tbbv7rdc0m9kfch00000w/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.005233 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-05 03:24:20.803500) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-05 03:24:20.803833) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-05 03:24:20.804185) ========= ========= Total master_lag: 8.36 =========