builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0387 starttime: 1464651733.39 results: success (0) buildid: 20160526112438 builduid: 7b7803f5dee74ad79ba6e8f098a8fa00 revision: 417e1ec42d8f68c55e52c336cdf81e83323db88c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 16:42:13.386317) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-30 16:42:13.386919) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 16:42:13.387250) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-30 16:42:13.771222) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 16:42:13.771513) ========= 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.YoC7mw0Wj1/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.iTm12pZbDD/Listeners TMPDIR=/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-30 16:42:13-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.4M=0.001s 2016-05-30 16:42:14 (18.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.917861 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 16:42:14.720818) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 16:42:14.721135) ========= 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.YoC7mw0Wj1/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.iTm12pZbDD/Listeners TMPDIR=/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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.025075 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 16:42:14.776902) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-30 16:42:14.777243) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 417e1ec42d8f68c55e52c336cdf81e83323db88c --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-beta --rev 417e1ec42d8f68c55e52c336cdf81e83323db88c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YoC7mw0Wj1/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.iTm12pZbDD/Listeners TMPDIR=/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-30 16:42:14,858 truncating revision to first 12 chars 2016-05-30 16:42:14,859 Setting DEBUG logging. 2016-05-30 16:42:14,859 attempt 1/10 2016-05-30 16:42:14,859 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/417e1ec42d8f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-30 16:42:16,006 unpacking tar archive at: mozilla-beta-417e1ec42d8f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.426420 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-30 16:42:16.225070) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 16:42:16.225420) ========= script_repo_revision: 417e1ec42d8f68c55e52c336cdf81e83323db88c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-30 16:42:16.225892) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 16:42:16.226205) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-30 16:42:16.243176) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 14 secs) (at 2016-05-30 16:42:16.243490) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YoC7mw0Wj1/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.iTm12pZbDD/Listeners TMPDIR=/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 16:42:16 INFO - MultiFileLogger online at 20160530 16:42:16 in /builds/slave/test 16:42:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 16:42:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:42:16 INFO - {'append_to_log': False, 16:42:16 INFO - 'base_work_dir': '/builds/slave/test', 16:42:16 INFO - 'blob_upload_branch': 'Mozilla-Beta', 16:42:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:42:16 INFO - 'branch': 'Mozilla-Beta', 16:42:16 INFO - 'buildbot_json_path': 'buildprops.json', 16:42:16 INFO - 'config_files': ('talos/mac_config.py',), 16:42:16 INFO - 'default_actions': ('clobber', 16:42:16 INFO - 'read-buildbot-config', 16:42:16 INFO - 'download-and-extract', 16:42:16 INFO - 'populate-webroot', 16:42:16 INFO - 'create-virtualenv', 16:42:16 INFO - 'install', 16:42:16 INFO - 'run-tests'), 16:42:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:42:16 INFO - 'download_minidump_stackwalk': True, 16:42:16 INFO - 'download_symbols': 'ondemand', 16:42:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:42:16 INFO - 'tooltool.py': '/tools/tooltool.py', 16:42:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:42:16 INFO - '/tools/misc-python/virtualenv.py')}, 16:42:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:42:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:42:16 INFO - 'installer_path': 'installer.exe', 16:42:16 INFO - 'log_level': 'info', 16:42:16 INFO - 'log_name': 'talos', 16:42:16 INFO - 'log_to_console': True, 16:42:16 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:42:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:42:16 INFO - 'opt_config_files': (), 16:42:16 INFO - 'pip_index': False, 16:42:16 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:42:16 INFO - 'cmd': ('bash', 16:42:16 INFO - '-c', 16:42:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:42:16 INFO - 'enabled': True, 16:42:16 INFO - 'halt_on_failure': False, 16:42:16 INFO - 'name': 'check_screen_resolution'},), 16:42:16 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:42:16 INFO - 'cmd': ('bash', 16:42:16 INFO - '-c', 16:42:16 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:42:16 INFO - 'enabled': True, 16:42:16 INFO - 'halt_on_failure': False, 16:42:16 INFO - 'name': 'check_screen_resolution'},), 16:42:16 INFO - 'run_cmd_checks_enabled': True, 16:42:16 INFO - 'sps_profile': False, 16:42:16 INFO - 'sps_profile_interval': 0, 16:42:16 INFO - 'suite': 'dromaeojs', 16:42:16 INFO - 'system_bits': '32', 16:42:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:42:16 INFO - 'title': 't-yosemite-r7-0387', 16:42:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:42:16 INFO - 'use_talos_json': True, 16:42:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:42:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:42:16 INFO - 'work_dir': 'build'} 16:42:16 INFO - ##### 16:42:16 INFO - ##### Running clobber step. 16:42:16 INFO - ##### 16:42:16 INFO - Running pre-action listener: _resource_record_pre_action 16:42:16 INFO - Running main action method: clobber 16:42:16 INFO - rmtree: /builds/slave/test/build 16:42:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:42:18 INFO - Running post-action listener: _resource_record_post_action 16:42:18 INFO - ##### 16:42:18 INFO - ##### Running read-buildbot-config step. 16:42:18 INFO - ##### 16:42:18 INFO - Running pre-action listener: _resource_record_pre_action 16:42:18 INFO - Running main action method: read_buildbot_config 16:42:18 INFO - Using buildbot properties: 16:42:18 INFO - { 16:42:18 INFO - "project": "", 16:42:18 INFO - "product": "firefox", 16:42:18 INFO - "script_repo_revision": "production", 16:42:18 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 16:42:18 INFO - "repository": "", 16:42:18 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 16:42:18 INFO - "buildid": "20160526112438", 16:42:18 INFO - "pgo_build": "False", 16:42:18 INFO - "basedir": "/builds/slave/test", 16:42:18 INFO - "buildnumber": 26, 16:42:18 INFO - "slavename": "t-yosemite-r7-0387", 16:42:18 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 16:42:18 INFO - "platform": "yosemite_r7", 16:42:18 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 16:42:18 INFO - "branch": "mozilla-beta", 16:42:18 INFO - "repo_path": "releases/mozilla-beta", 16:42:18 INFO - "revision": "417e1ec42d8f68c55e52c336cdf81e83323db88c", 16:42:18 INFO - "stage_platform": "macosx64", 16:42:18 INFO - "builduid": "7b7803f5dee74ad79ba6e8f098a8fa00", 16:42:18 INFO - "slavebuilddir": "test" 16:42:18 INFO - } 16:42:18 INFO - Found installer url https://queue.taskcluster.net/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.dmg. 16:42:18 INFO - Running post-action listener: _resource_record_post_action 16:42:18 INFO - ##### 16:42:18 INFO - ##### Running download-and-extract step. 16:42:18 INFO - ##### 16:42:18 INFO - Running pre-action listener: _resource_record_pre_action 16:42:18 INFO - Running main action method: download_and_extract 16:42:18 INFO - mkdir: /builds/slave/test/build/tests 16:42: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')]} 16:42:18 INFO - https://queue.taskcluster.net/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 16:42:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 16:42:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json 16:42:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 16:42:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json'}, attempt #1 16:42:30 INFO - retry: attempt #1 caught exception: '' 16:42:30 INFO - retry: Failed, sleeping 30 seconds before retrying 16:43:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json'}, attempt #2 16:43:30 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json: timed out 16:43:30 INFO - retry: attempt #2 caught exception: timed out 16:43:30 INFO - retry: Failed, sleeping 60 seconds before retrying 16:44:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json'}, attempt #3 16:44:39 INFO - Downloaded 1241 bytes. 16:44:39 INFO - Reading from file /builds/slave/test/build/firefox-47.0.en-US.mac.test_packages.json 16:44:39 INFO - Using the following test package requirements: 16:44:39 INFO - {u'common': [u'firefox-47.0.en-US.mac.common.tests.zip'], 16:44:39 INFO - u'cppunittest': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:44:39 INFO - u'firefox-47.0.en-US.mac.cppunittest.tests.zip'], 16:44:39 INFO - u'gtest': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:44:39 INFO - u'firefox-47.0.en-US.mac.gtest.tests.zip'], 16:44:39 INFO - u'jittest': [u'firefox-47.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 16:44:39 INFO - u'mochitest': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:44:39 INFO - u'firefox-47.0.en-US.mac.mochitest.tests.zip'], 16:44:39 INFO - u'mozbase': [u'firefox-47.0.en-US.mac.common.tests.zip'], 16:44:39 INFO - u'reftest': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:44:39 INFO - u'firefox-47.0.en-US.mac.reftest.tests.zip'], 16:44:39 INFO - u'talos': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:44:39 INFO - u'firefox-47.0.en-US.mac.talos.tests.zip'], 16:44:39 INFO - u'web-platform': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:44:39 INFO - u'firefox-47.0.en-US.mac.web-platform.tests.zip'], 16:44:39 INFO - u'webapprt': [u'firefox-47.0.en-US.mac.common.tests.zip'], 16:44:39 INFO - u'xpcshell': [u'firefox-47.0.en-US.mac.common.tests.zip', 16:44:39 INFO - u'firefox-47.0.en-US.mac.xpcshell.tests.zip']} 16:44:39 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip'] for test suite category: common 16:44:39 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')]} 16:44:39 INFO - https://queue.taskcluster.net/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:44:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 16:44:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 16:44:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip 16:44:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #1 16:44:41 INFO - Downloaded 17816939 bytes. 16:44:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:44:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:44:44 INFO - Return code: 0 16:44:44 INFO - Downloading packages: [u'firefox-47.0.en-US.mac.common.tests.zip', u'firefox-47.0.en-US.mac.talos.tests.zip'] for test suite category: talos 16:44:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:44 INFO - https://queue.taskcluster.net/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:44:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 16:44:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip 16:44:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip 16:44:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip'}, attempt #1 16:44:48 INFO - Downloaded 17816939 bytes. 16:44:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:44:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 16:44:52 INFO - Return code: 0 16:44:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:52 INFO - https://queue.taskcluster.net/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 16:44:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 16:44:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip 16:44:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip 16:44:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip'}, attempt #1 16:44:53 INFO - Downloaded 10845194 bytes. 16:44:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:44:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 16:44:53 INFO - Return code: 0 16:44:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:44:53 INFO - https://queue.taskcluster.net/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.dmg matches https://queue.taskcluster.net 16:44:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.dmg 16:44:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.dmg 16:44:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0.en-US.mac.dmg 16:44:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0.en-US.mac.dmg'}, attempt #1 16:44:56 INFO - Downloaded 88467627 bytes. 16:44:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.dmg 16:44:56 INFO - mkdir: /builds/slave/test/properties 16:44:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:44:56 INFO - Writing to file /builds/slave/test/properties/build_url 16:44:56 INFO - Contents: 16:44:56 INFO - build_url:https://queue.taskcluster.net/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.dmg 16:44:57 INFO - Running post-action listener: _resource_record_post_action 16:44:57 INFO - Running post-action listener: set_extra_try_arguments 16:44:57 INFO - ##### 16:44:57 INFO - ##### Running populate-webroot step. 16:44:57 INFO - ##### 16:44:57 INFO - Running pre-action listener: _resource_record_pre_action 16:44:57 INFO - Running main action method: populate_webroot 16:44:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:44:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:44:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:44:57 INFO - '--tppagecycles', 16:44:57 INFO - '7'], 16:44:57 INFO - 'tests': ['tsvgm']}}, 16:44:57 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 16:44:57 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 16:44:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:44:57 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 16:44:57 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 16:44:57 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:44:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:44:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:44:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:44:57 INFO - 'talos_options': ['--e10s'], 16:44:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:44:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:44:57 INFO - 'tests': ['damp', 'tps']}, 16:44:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:44:57 INFO - 'talos_options': ['--e10s'], 16:44:57 INFO - 'tests': ['damp', 'tps']}, 16:44:57 INFO - 'g3': {'tests': ['dromaeo_dom']}, 16:44:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:44:57 INFO - 'other': {'tests': ['a11yr', 16:44:57 INFO - 'ts_paint', 16:44:57 INFO - 'tpaint', 16:44:57 INFO - 'sessionrestore', 16:44:57 INFO - 'sessionrestore_no_auto_restore', 16:44:57 INFO - 'tabpaint']}, 16:44:57 INFO - 'other-e10s': {'talos_options': ['--e10s'], 16:44:57 INFO - 'tests': ['a11yr', 16:44:57 INFO - 'ts_paint', 16:44:57 INFO - 'tpaint', 16:44:57 INFO - 'sessionrestore', 16:44:57 INFO - 'sessionrestore_no_auto_restore', 16:44:57 INFO - 'tabpaint']}, 16:44:57 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 16:44:57 INFO - 'tests': ['a11yr', 16:44:57 INFO - 'ts_paint', 16:44:57 INFO - 'tpaint', 16:44:57 INFO - 'sessionrestore', 16:44:57 INFO - 'sessionrestore_no_auto_restore', 16:44:57 INFO - 'tabpaint']}, 16:44:57 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 16:44:57 INFO - 'tests': ['a11yr', 16:44:57 INFO - 'ts_paint', 16:44:57 INFO - 'tpaint', 16:44:57 INFO - 'sessionrestore', 16:44:57 INFO - 'sessionrestore_no_auto_restore', 16:44:57 INFO - 'tabpaint']}, 16:44:57 INFO - 'other_l64': {'tests': ['a11yr', 16:44:57 INFO - 'ts_paint', 16:44:57 INFO - 'tpaint', 16:44:57 INFO - 'sessionrestore', 16:44:57 INFO - 'sessionrestore_no_auto_restore', 16:44:57 INFO - 'tabpaint']}, 16:44:57 INFO - 'other_nol64': {'tests': ['a11yr', 16:44:57 INFO - 'ts_paint', 16:44:57 INFO - 'tpaint', 16:44:57 INFO - 'sessionrestore', 16:44:57 INFO - 'sessionrestore_no_auto_restore', 16:44:57 INFO - 'tabpaint']}, 16:44:57 INFO - 'svgr': {'tests': ['tsvgx', 16:44:57 INFO - 'tsvgr_opacity', 16:44:57 INFO - 'tart', 16:44:57 INFO - 'tscrollx', 16:44:57 INFO - 'cart']}, 16:44:57 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 16:44:57 INFO - 'tests': ['tsvgx', 16:44:57 INFO - 'tsvgr_opacity', 16:44:57 INFO - 'tart', 16:44:57 INFO - 'tscrollx', 16:44:57 INFO - 'cart']}, 16:44:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:44:57 INFO - 'tests': ['tp5o']}, 16:44:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:44:57 INFO - 'talos_options': ['--e10s'], 16:44:57 INFO - 'tests': ['tp5o']}, 16:44:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:44:57 INFO - 'talos_options': ['--xperf_path', 16:44:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:44:57 INFO - 'tests': ['tp5n']}, 16:44:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:44:57 INFO - 'talos_options': ['--e10s', 16:44:57 INFO - '--xperf_path', 16:44:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:44:57 INFO - 'tests': ['tp5n']}}, 16:44:57 INFO - 'talos.zip': {'path': '', 16:44:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:44:57 INFO - Running post-action listener: _resource_record_post_action 16:44:57 INFO - ##### 16:44:57 INFO - ##### Running create-virtualenv step. 16:44:57 INFO - ##### 16:44:57 INFO - Running pre-action listener: _resource_record_pre_action 16:44:57 INFO - Running main action method: create_virtualenv 16:44:57 INFO - Creating virtualenv /builds/slave/test/build/venv 16:44:57 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 16:44:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:44:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:44:57 INFO - Using real prefix '/tools/python27' 16:44:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:44:58 INFO - Installing distribute.............................................................................................................................................................................................done. 16:45:01 INFO - Installing pip.................done. 16:45:01 INFO - Return code: 0 16:45:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:45:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:45:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:45:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10523ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052434c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92b8d9c8d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050e0d30>, '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 0x104f44db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050d7b20>, '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.YoC7mw0Wj1/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.iTm12pZbDD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 16:45:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 16:45:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 16:45:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YoC7mw0Wj1/Render', 16:45:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:01 INFO - 'HOME': '/Users/cltbld', 16:45:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:01 INFO - 'LOGNAME': 'cltbld', 16:45:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:01 INFO - 'MOZ_NO_REMOTE': '1', 16:45:01 INFO - 'NO_EM_RESTART': '1', 16:45:01 INFO - 'PAGER': '/bin/cat', 16:45:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:01 INFO - 'PWD': '/builds/slave/test', 16:45:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:01 INFO - 'SHELL': '/bin/bash', 16:45:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iTm12pZbDD/Listeners', 16:45:01 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 16:45:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:01 INFO - 'USER': 'cltbld', 16:45:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:01 INFO - 'XPC_FLAGS': '0x0', 16:45:01 INFO - 'XPC_SERVICE_NAME': '0', 16:45:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:01 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 16:45:01 INFO - Cleaning up... 16:45:01 INFO - Return code: 0 16:45:01 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 16:45:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:45:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:45:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10523ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052434c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92b8d9c8d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050e0d30>, '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 0x104f44db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050d7b20>, '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.YoC7mw0Wj1/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.iTm12pZbDD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 16:45:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 16:45:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 16:45:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YoC7mw0Wj1/Render', 16:45:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:01 INFO - 'HOME': '/Users/cltbld', 16:45:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:01 INFO - 'LOGNAME': 'cltbld', 16:45:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:01 INFO - 'MOZ_NO_REMOTE': '1', 16:45:01 INFO - 'NO_EM_RESTART': '1', 16:45:01 INFO - 'PAGER': '/bin/cat', 16:45:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:01 INFO - 'PWD': '/builds/slave/test', 16:45:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:01 INFO - 'SHELL': '/bin/bash', 16:45:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iTm12pZbDD/Listeners', 16:45:01 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 16:45:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:01 INFO - 'USER': 'cltbld', 16:45:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:01 INFO - 'XPC_FLAGS': '0x0', 16:45:01 INFO - 'XPC_SERVICE_NAME': '0', 16:45:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:01 INFO - Downloading/unpacking psutil>=0.7.1 16:45:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:45:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:45:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:45:05 INFO - Installing collected packages: psutil 16:45:05 INFO - Running setup.py install for psutil 16:45:05 INFO - building 'psutil._psutil_osx' extension 16:45:05 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 16:45:05 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 16:45:05 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 16:45:06 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 16:45:06 INFO - building 'psutil._psutil_posix' extension 16:45:06 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 16:45:06 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:45:06 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:45:06 INFO - ^ 16:45:06 INFO - 1 warning generated. 16:45:06 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 16:45:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:45:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:45:06 INFO - Successfully installed psutil 16:45:06 INFO - Cleaning up... 16:45:06 INFO - Return code: 0 16:45:06 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 16:45:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:45:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:45:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:06 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 0x10523ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052434c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92b8d9c8d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050e0d30>, '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 0x104f44db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050d7b20>, '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.YoC7mw0Wj1/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.iTm12pZbDD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 16:45:06 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 16:45:06 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 16:45:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YoC7mw0Wj1/Render', 16:45:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:06 INFO - 'HOME': '/Users/cltbld', 16:45:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:06 INFO - 'LOGNAME': 'cltbld', 16:45:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:06 INFO - 'MOZ_NO_REMOTE': '1', 16:45:06 INFO - 'NO_EM_RESTART': '1', 16:45:06 INFO - 'PAGER': '/bin/cat', 16:45:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:06 INFO - 'PWD': '/builds/slave/test', 16:45:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:06 INFO - 'SHELL': '/bin/bash', 16:45:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iTm12pZbDD/Listeners', 16:45:06 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 16:45:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:06 INFO - 'USER': 'cltbld', 16:45:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:06 INFO - 'XPC_FLAGS': '0x0', 16:45:06 INFO - 'XPC_SERVICE_NAME': '0', 16:45:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:06 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 16:45: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 16:45: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 16:45: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 16:45: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 16:45: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 16:45: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 16:45:09 INFO - Downloading mozsystemmonitor-0.0.tar.gz 16:45:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:45:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 16:45:09 INFO - Installing collected packages: mozsystemmonitor 16:45:09 INFO - Running setup.py install for mozsystemmonitor 16:45:09 INFO - Successfully installed mozsystemmonitor 16:45:09 INFO - Cleaning up... 16:45:09 INFO - Return code: 0 16:45:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:45:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:45:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:45:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:09 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 0x10523ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052434c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92b8d9c8d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050e0d30>, '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 0x104f44db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050d7b20>, '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.YoC7mw0Wj1/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.iTm12pZbDD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 16:45:09 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 16:45:09 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 16:45:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YoC7mw0Wj1/Render', 16:45:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:09 INFO - 'HOME': '/Users/cltbld', 16:45:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:09 INFO - 'LOGNAME': 'cltbld', 16:45:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:09 INFO - 'MOZ_NO_REMOTE': '1', 16:45:09 INFO - 'NO_EM_RESTART': '1', 16:45:09 INFO - 'PAGER': '/bin/cat', 16:45:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:09 INFO - 'PWD': '/builds/slave/test', 16:45:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:09 INFO - 'SHELL': '/bin/bash', 16:45:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iTm12pZbDD/Listeners', 16:45:09 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 16:45:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:09 INFO - 'USER': 'cltbld', 16:45:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:09 INFO - 'XPC_FLAGS': '0x0', 16:45:09 INFO - 'XPC_SERVICE_NAME': '0', 16:45:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:09 INFO - Downloading/unpacking blobuploader==1.2.4 16:45:09 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 16:45:09 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 16:45:09 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 16:45:09 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 16:45:09 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 16:45:09 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 16:45:12 INFO - Downloading blobuploader-1.2.4.tar.gz 16:45:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:45:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:45:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:45:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:45:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:13 INFO - Downloading docopt-0.6.1.tar.gz 16:45:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:45:13 INFO - Installing collected packages: blobuploader, requests, docopt 16:45:13 INFO - Running setup.py install for blobuploader 16:45:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:45:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:45:13 INFO - Running setup.py install for requests 16:45:13 INFO - Running setup.py install for docopt 16:45:14 INFO - Successfully installed blobuploader requests docopt 16:45:14 INFO - Cleaning up... 16:45:14 INFO - Return code: 0 16:45:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:45:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:45:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:45:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:14 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 0x10523ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052434c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92b8d9c8d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050e0d30>, '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 0x104f44db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050d7b20>, '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.YoC7mw0Wj1/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.iTm12pZbDD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 16:45:14 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 16:45:14 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 16:45:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YoC7mw0Wj1/Render', 16:45:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:14 INFO - 'HOME': '/Users/cltbld', 16:45:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:14 INFO - 'LOGNAME': 'cltbld', 16:45:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:14 INFO - 'MOZ_NO_REMOTE': '1', 16:45:14 INFO - 'NO_EM_RESTART': '1', 16:45:14 INFO - 'PAGER': '/bin/cat', 16:45:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:14 INFO - 'PWD': '/builds/slave/test', 16:45:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:14 INFO - 'SHELL': '/bin/bash', 16:45:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iTm12pZbDD/Listeners', 16:45:14 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 16:45:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:14 INFO - 'USER': 'cltbld', 16:45:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:14 INFO - 'XPC_FLAGS': '0x0', 16:45:14 INFO - 'XPC_SERVICE_NAME': '0', 16:45:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:45:14 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-UmktXH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:45:14 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-d5p8A8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:45:14 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-vXhwyb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:45:14 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-qPqQKJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:45:14 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-5OTEdy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:45:15 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-eCG9Fj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:45:15 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-bDsBDS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:45:15 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-gF3fX5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:45:15 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-RYn5oZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:45:15 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-ZCbyYU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:45:15 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-0h9c9Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:45:15 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-ElvB8L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:45:15 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-tVEjAs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:45:15 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-Ry7_iv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:45:15 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-FbeNdv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:45:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:45:16 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-DGEhvD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:45:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:45:16 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-b1GdQD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:45:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:45:16 INFO - Running setup.py install for manifestparser 16:45:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:45:16 INFO - Running setup.py install for mozcrash 16:45:16 INFO - Running setup.py install for mozdebug 16:45:16 INFO - Running setup.py install for mozdevice 16:45:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:45:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:45:16 INFO - Running setup.py install for mozfile 16:45:17 INFO - Running setup.py install for mozhttpd 16:45:17 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:45:17 INFO - Running setup.py install for mozinfo 16:45:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:45:17 INFO - Running setup.py install for mozInstall 16:45:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:45:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:45:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:45:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:45:17 INFO - Running setup.py install for mozleak 16:45:17 INFO - Running setup.py install for mozlog 16:45:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:45:17 INFO - Running setup.py install for moznetwork 16:45:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:45:17 INFO - Running setup.py install for mozprocess 16:45:18 INFO - Running setup.py install for mozprofile 16:45:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:45:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:45:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:45:18 INFO - Running setup.py install for mozrunner 16:45:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:45:18 INFO - Running setup.py install for mozscreenshot 16:45:18 INFO - Running setup.py install for moztest 16:45:18 INFO - Running setup.py install for mozversion 16:45:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:45:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:45:18 INFO - Cleaning up... 16:45:18 INFO - Return code: 0 16:45:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:45: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')]} 16:45:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45: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')]} 16:45:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:18 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 0x10523ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052434c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92b8d9c8d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050e0d30>, '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 0x104f44db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050d7b20>, '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.YoC7mw0Wj1/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.iTm12pZbDD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 16:45:18 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 16:45:18 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 16:45:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YoC7mw0Wj1/Render', 16:45:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:18 INFO - 'HOME': '/Users/cltbld', 16:45:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:18 INFO - 'LOGNAME': 'cltbld', 16:45:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:18 INFO - 'MOZ_NO_REMOTE': '1', 16:45:18 INFO - 'NO_EM_RESTART': '1', 16:45:18 INFO - 'PAGER': '/bin/cat', 16:45:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:18 INFO - 'PWD': '/builds/slave/test', 16:45:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:18 INFO - 'SHELL': '/bin/bash', 16:45:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iTm12pZbDD/Listeners', 16:45:18 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 16:45:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:18 INFO - 'USER': 'cltbld', 16:45:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:18 INFO - 'XPC_FLAGS': '0x0', 16:45:18 INFO - 'XPC_SERVICE_NAME': '0', 16:45:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:45:19 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-rywYiv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:45:19 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)) 16:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:45:19 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-jUMBQz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:45:19 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)) 16:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:45:19 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-fj1CGH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:45:19 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)) 16:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:45:19 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-_e1_R5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:45:19 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)) 16:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:45:19 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-XzNO_V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:45:19 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)) 16:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:45:19 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-KMmMzk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:45:19 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)) 16:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:45:19 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-ePAWGC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:45:19 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)) 16:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:45:19 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-8DVhgm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:45:19 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)) 16:45:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:45:19 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-omhGuC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:45:20 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)) 16:45:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:45:20 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-jEm2IQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:45:20 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)) 16:45:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:45:20 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-XwA1zz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:45:20 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)) 16:45:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:45:20 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-xJ0802-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:45:20 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)) 16:45:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:45:20 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-V5XQvZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:45:20 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)) 16:45:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:45:20 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-dtSSHS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:45:20 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)) 16:45:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:45:20 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-Lsz98H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:45:20 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)) 16:45:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:45:20 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-9mZoC8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:45:20 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)) 16:45:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:45:20 INFO - Running setup.py (path:/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/pip-qZEcyp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:45:20 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)) 16:45:20 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)) 16:45:20 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)) 16:45:20 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)) 16:45:20 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)) 16:45:20 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)) 16:45:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:45:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:23 INFO - Downloading blessings-1.6.tar.gz 16:45:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:45:23 INFO - Installing collected packages: blessings 16:45:23 INFO - Running setup.py install for blessings 16:45:23 INFO - Successfully installed blessings 16:45:23 INFO - Cleaning up... 16:45:23 INFO - Return code: 0 16:45:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:45:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:45:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:45:24 INFO - Reading from file tmpfile_stdout 16:45:24 INFO - Current package versions: 16:45:24 INFO - blessings == 1.6 16:45:24 INFO - blobuploader == 1.2.4 16:45:24 INFO - docopt == 0.6.1 16:45:24 INFO - manifestparser == 1.1 16:45:24 INFO - mozInstall == 1.12 16:45:24 INFO - mozcrash == 0.17 16:45:24 INFO - mozdebug == 0.1 16:45:24 INFO - mozdevice == 0.48 16:45:24 INFO - mozfile == 1.2 16:45:24 INFO - mozhttpd == 0.7 16:45:24 INFO - mozinfo == 0.9 16:45:24 INFO - mozleak == 0.1 16:45:24 INFO - mozlog == 3.1 16:45:24 INFO - moznetwork == 0.27 16:45:24 INFO - mozprocess == 0.22 16:45:24 INFO - mozprofile == 0.28 16:45:24 INFO - mozrunner == 6.11 16:45:24 INFO - mozscreenshot == 0.1 16:45:24 INFO - mozsystemmonitor == 0.0 16:45:24 INFO - moztest == 0.7 16:45:24 INFO - mozversion == 1.4 16:45:24 INFO - psutil == 3.1.1 16:45:24 INFO - requests == 1.2.3 16:45:24 INFO - wsgiref == 0.1.2 16:45:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:45:24 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')]} 16:45:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:24 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')]} 16:45:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:24 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 0x10523ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052434c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92b8d9c8d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050e0d30>, '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 0x104f44db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050d7b20>, '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.YoC7mw0Wj1/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.iTm12pZbDD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 16:45:24 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 16:45:24 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 16:45:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YoC7mw0Wj1/Render', 16:45:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:24 INFO - 'HOME': '/Users/cltbld', 16:45:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:24 INFO - 'LOGNAME': 'cltbld', 16:45:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:24 INFO - 'MOZ_NO_REMOTE': '1', 16:45:24 INFO - 'NO_EM_RESTART': '1', 16:45:24 INFO - 'PAGER': '/bin/cat', 16:45:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:24 INFO - 'PWD': '/builds/slave/test', 16:45:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:24 INFO - 'SHELL': '/bin/bash', 16:45:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iTm12pZbDD/Listeners', 16:45:24 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 16:45:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:24 INFO - 'USER': 'cltbld', 16:45:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:24 INFO - 'XPC_FLAGS': '0x0', 16:45:24 INFO - 'XPC_SERVICE_NAME': '0', 16:45:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:24 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)) 16:45:24 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)) 16:45:24 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)) 16:45:24 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)) 16:45:24 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)) 16:45:24 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)) 16:45:24 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)) 16:45:24 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)) 16:45:24 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)) 16:45:24 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)) 16:45:24 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)) 16:45:24 INFO - Cleaning up... 16:45:24 INFO - Return code: 0 16:45:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:45:24 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')]} 16:45:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:24 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')]} 16:45:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:24 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 0x10523ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052434c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92b8d9c8d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050e0d30>, '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 0x104f44db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050d7b20>, '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.YoC7mw0Wj1/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.iTm12pZbDD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 16:45:24 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 16:45:24 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 16:45:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YoC7mw0Wj1/Render', 16:45:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:24 INFO - 'HOME': '/Users/cltbld', 16:45:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:24 INFO - 'LOGNAME': 'cltbld', 16:45:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:24 INFO - 'MOZ_NO_REMOTE': '1', 16:45:24 INFO - 'NO_EM_RESTART': '1', 16:45:24 INFO - 'PAGER': '/bin/cat', 16:45:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:24 INFO - 'PWD': '/builds/slave/test', 16:45:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:24 INFO - 'SHELL': '/bin/bash', 16:45:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iTm12pZbDD/Listeners', 16:45:24 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 16:45:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:24 INFO - 'USER': 'cltbld', 16:45:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:24 INFO - 'XPC_FLAGS': '0x0', 16:45:24 INFO - 'XPC_SERVICE_NAME': '0', 16:45:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:24 INFO - Downloading/unpacking jsonschema 16:45:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:45:26 INFO - Downloading/unpacking functools32 (from jsonschema) 16:45:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:45:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:45:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:45:27 INFO - Downloading functools32-3.2.3-2.tar.gz 16:45:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:45:27 INFO - warning: no files found matching '*.txt' 16:45:27 INFO - no previously-included directories found matching 'build' 16:45:27 INFO - no previously-included directories found matching 'dist' 16:45:27 INFO - no previously-included directories found matching '.git*' 16:45:27 INFO - Installing collected packages: jsonschema, functools32 16:45:27 INFO - Running setup.py install for functools32 16:45:27 INFO - warning: no files found matching '*.txt' 16:45:27 INFO - no previously-included directories found matching 'build' 16:45:27 INFO - no previously-included directories found matching 'dist' 16:45:27 INFO - no previously-included directories found matching '.git*' 16:45:27 INFO - Successfully installed jsonschema functools32 16:45:27 INFO - Cleaning up... 16:45:27 INFO - Return code: 0 16:45:27 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:45:27 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')]} 16:45:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:45:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:27 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')]} 16:45:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:45:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:45:27 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 0x10523ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1052434c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f92b8d9c8d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1050e0d30>, '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 0x104f44db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050d7b20>, '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.YoC7mw0Wj1/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.iTm12pZbDD/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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 16:45:27 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 16:45:27 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 16:45:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YoC7mw0Wj1/Render', 16:45:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:27 INFO - 'HOME': '/Users/cltbld', 16:45:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:27 INFO - 'LOGNAME': 'cltbld', 16:45:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:27 INFO - 'MOZ_NO_REMOTE': '1', 16:45:27 INFO - 'NO_EM_RESTART': '1', 16:45:27 INFO - 'PAGER': '/bin/cat', 16:45:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:27 INFO - 'PWD': '/builds/slave/test', 16:45:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:27 INFO - 'SHELL': '/bin/bash', 16:45:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iTm12pZbDD/Listeners', 16:45:27 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 16:45:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:27 INFO - 'USER': 'cltbld', 16:45:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:27 INFO - 'XPC_FLAGS': '0x0', 16:45:27 INFO - 'XPC_SERVICE_NAME': '0', 16:45:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:45:27 INFO - Downloading/unpacking flake8 16:45:27 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 16:45:27 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 16:45:27 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 16:45:27 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 16:45:27 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 16:45:27 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 16:45:30 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:45:30 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:45:30 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 16:45:30 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 16:45:30 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 16:45:30 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 16:45:30 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 16:45:30 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 16:45:30 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:45:30 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:45:30 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 16:45:30 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 16:45:30 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 16:45:30 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 16:45:30 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 16:45:30 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 16:45:31 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:45:31 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 16:45:31 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 16:45:31 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 16:45:31 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 16:45:31 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 16:45:31 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 16:45:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:45:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:45:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:45:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:45:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:45:31 INFO - no previously-included directories found matching 'docs/_build' 16:45:31 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:45:31 INFO - Running setup.py install for pep8 16:45:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:45:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:45:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:45:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:45:31 INFO - no previously-included directories found matching 'docs/_build' 16:45:31 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:45:31 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:45:31 INFO - Cleaning up... 16:45:32 INFO - Return code: 0 16:45:32 INFO - Running post-action listener: _resource_record_post_action 16:45:32 INFO - Running post-action listener: _start_resource_monitoring 16:45:32 INFO - Starting resource monitoring. 16:45:32 INFO - ##### 16:45:32 INFO - ##### Running install step. 16:45:32 INFO - ##### 16:45:32 INFO - Running pre-action listener: _resource_record_pre_action 16:45:32 INFO - Running main action method: install 16:45:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:45:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:45:32 INFO - Reading from file tmpfile_stdout 16:45:32 INFO - Detecting whether we're running mozinstall >=1.0... 16:45:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:45:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:45:32 INFO - Reading from file tmpfile_stdout 16:45:32 INFO - Output received: 16:45:32 INFO - Usage: mozinstall [options] installer 16:45:32 INFO - Options: 16:45:32 INFO - -h, --help show this help message and exit 16:45:32 INFO - -d DEST, --destination=DEST 16:45:32 INFO - Directory to install application into. [default: 16:45:32 INFO - "/builds/slave/test"] 16:45:32 INFO - --app=APP Application being installed. [default: firefox] 16:45:32 INFO - mkdir: /builds/slave/test/build/application 16:45:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 16:45:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0.en-US.mac.dmg --destination /builds/slave/test/build/application 16:45:54 INFO - Reading from file tmpfile_stdout 16:45:54 INFO - Output received: 16:45:54 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 16:45:54 INFO - Running post-action listener: _resource_record_post_action 16:45:54 INFO - ##### 16:45:54 INFO - ##### Running run-tests step. 16:45:54 INFO - ##### 16:45:54 INFO - Running pre-action listener: _resource_record_pre_action 16:45:54 INFO - Running main action method: run_tests 16:45:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:45:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:45:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:45:54 INFO - 2016-05-30 16:45:54.552 screenresolution[1645:8521] starting screenresolution argv=screenresolution get 16:45:54 INFO - 2016-05-30 16:45:54.555 screenresolution[1645:8521] Display 0: 1600x1200x32@60 16:45:54 INFO - 2016-05-30 16:45:54.561 screenresolution[1646:8524] starting screenresolution argv=screenresolution list 16:45:54 INFO - Available Modes on Display 0 16:45:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:45:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:45:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:45:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:45:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:45:54 INFO - Intel Iris: 16:45:54 INFO - Chipset Model: Intel Iris 16:45:54 INFO - Type: GPU 16:45:54 INFO - Bus: Built-In 16:45:54 INFO - VRAM (Dynamic, Max): 1536 MB 16:45:54 INFO - Vendor: Intel (0x8086) 16:45:54 INFO - Device ID: 0x0a2e 16:45:54 INFO - Revision ID: 0x0009 16:45:54 INFO - Displays: 16:45:54 INFO - Display: 16:45:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:45:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:45:54 INFO - Display Serial Number: 1600x1200 60 16:45:54 INFO - Main Display: Yes 16:45:54 INFO - Mirror: Off 16:45:54 INFO - Online: Yes 16:45:54 INFO - Rotation: Supported 16:45:54 INFO - Return code: 0 16:45:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:45:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:45:54 INFO - Python 2.7.3 16:45:54 INFO - Return code: 0 16:45:54 INFO - grabbing minidump binary from tooltool 16:45:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:45:54 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 0x1050e0d30>, '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 0x104f44db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1050d7b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:45:54 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 16:45:54 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 16:45:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:45:54 INFO - Return code: 0 16:45:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:45:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:45:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:45:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:45:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:45:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:45:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:45:56 INFO - Return code: 0 16:45:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0387', '--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 16:45:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0387 --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 16:45:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YoC7mw0Wj1/Render', 16:45:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:45:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:45:56 INFO - 'HOME': '/Users/cltbld', 16:45:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:45:56 INFO - 'LOGNAME': 'cltbld', 16:45:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:45:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:45:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:45:56 INFO - 'MOZ_NO_REMOTE': '1', 16:45:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:45:56 INFO - 'NO_EM_RESTART': '1', 16:45:56 INFO - 'PAGER': '/bin/cat', 16:45:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:45:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:45:56 INFO - 'PWD': '/builds/slave/test', 16:45:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:45:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:45:56 INFO - 'SHELL': '/bin/bash', 16:45:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iTm12pZbDD/Listeners', 16:45:56 INFO - 'TMPDIR': '/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/', 16:45:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:45:56 INFO - 'USER': 'cltbld', 16:45:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:45:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:45:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:45:56 INFO - 'XPC_FLAGS': '0x0', 16:45:56 INFO - 'XPC_SERVICE_NAME': '0', 16:45:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:45:56 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0387', '--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 16:45:56 INFO - mozversion application_buildid: 20160526112438 16:45:56 INFO - mozversion application_changeset: 417e1ec42d8f68c55e52c336cdf81e83323db88c 16:45:56 INFO - mozversion application_display_name: Firefox 16:45:56 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:45:56 INFO - mozversion application_name: Firefox 16:45:56 INFO - mozversion application_remotingname: firefox 16:45:56 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 16:45:56 INFO - mozversion application_vendor: Mozilla 16:45:56 INFO - mozversion application_version: 47.0 16:45:56 INFO - mozversion platform_buildid: 20160526112438 16:45:56 INFO - mozversion platform_changeset: 417e1ec42d8f68c55e52c336cdf81e83323db88c 16:45:56 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 16:45:56 INFO - mozversion platform_version: 47.0 16:45:56 INFO - using testdate: 1464651956 16:45:56 INFO - actual date: 1464651956 16:45:56 INFO - starting webserver on 'localhost:49277' 16:45:56 INFO - SUITE-START | Running 2 tests 16:45:56 INFO - TEST-START | dromaeo_css 16:45:56 INFO - operating with platform_type : mac_ 16:45:56 INFO - Initialising browser for dromaeo_css test... 16:45:56 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/tmpGtAbNA/profile http://localhost:49277/getInfo.html) 16:45:58 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 16:45:58 INFO - PROCESS | 1664 | colorDepth:24 16:45:58 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 16:45:58 INFO - PROCESS | 1664 | __metrics 16:45:58 INFO - PROCESS | 1664 | 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" 16:45:58 INFO - PROCESS | 1664 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 16:45:59 INFO - TEST-INFO | 1664: exit 0 16:45:59 INFO - Browser initialized. 16:45:59 INFO - Running cycle 1/1 for dromaeo_css test... 16:45:59 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/tmpGtAbNA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:46:11 INFO - PROCESS | 1668 | RSS: Main: 156786688 16:46:11 INFO - PROCESS | 1668 | 16:47:02 INFO - PROCESS | 1668 | 1464652022004 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] 16:49:11 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 16:49:13 INFO - PROCESS | 1668 | RSS: Main: 296427520 16:49:13 INFO - PROCESS | 1668 | 16:52:15 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 16:52:16 INFO - PROCESS | 1668 | RSS: Main: 298950656 16:52:16 INFO - PROCESS | 1668 | 16:55:17 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 16:55:18 INFO - PROCESS | 1668 | RSS: Main: 301670400 16:55:18 INFO - PROCESS | 1668 | 16:58:20 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 16:58:21 INFO - PROCESS | 1668 | RSS: Main: 257519616 16:58:21 INFO - PROCESS | 1668 | 17:01:23 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 17:01:24 INFO - PROCESS | 1668 | RSS: Main: 258314240 17:01:24 INFO - PROCESS | 1668 | 17:04:26 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 17:04:27 INFO - PROCESS | 1668 | RSS: Main: 306958336 17:04:27 INFO - PROCESS | 1668 | 17:04:27 INFO - PROCESS | 1668 | __start_tp_report 17:04:27 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 17:04:27 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 17:04:27 INFO - PROCESS | 1668 | |i|pagename|runs| 17:04:27 INFO - PROCESS | 1668 | |0;dojo.html;3496.37065328241;3560.2345495718446;3560.5193298904655;3585.5876574193967;3628.655277748613;5640.435956404358;5677.716114194294;5686.203931449595;5686.289213848269;5712.857178570538;5643.717814109299;5668.291463567054;5675.2054712340305;5677.716114194284;5690.886182276352;5108.259302401149;5136.484737002575;5166.147585648364;5183.403908550527;5212.582993360541;5276.577873770089;5277.6305658603915;5283.418823929364;5284.260203571503;5284.577233821305;4211.663066954651;5217.686938783685;5236.371635403757;5249.553787928031;5293.020791153649;5230.73846307685;5274.92087618686;5286.286351342558;5303.734813259361;5325.7869685212545;4987.800487980477;5190.299309593194;5192.169252919514;5198.766055527511;5213.191955246943;5520.889582208373;5537.640053396557;5547.417521160261;5572.136318870582;5589.944100558983;5720.85582883425;5743.339515955635;6087.634741915499;6247.219097612799;6259.405356491126;6207.193064901579;6208.689565521706;6217.658028808413;6293.842653933642;6329.082283068975;5799.710014499258;5855.531557475477;5861.325947516048;5887.411258874121;5898.056310990221;6220.813375598739;6223.0354295084335;6303.936960630335;6321.65230912295;6324.367563243639;6157.076438534166;6159.4456498915315;6178.5366097542865;6182.165407669997;6194.225721784777;6267.9373206267355;6272.40412160844;6278.152449419361;6281.811545653638;6283.120363149163;6054.303755068136;6115.969420152871;6117.431594453911;6195.070739389128;6227.09707092469;6206.72069756862;6219.253689557282;6247.187865577536;6256.936320825471;6259.029850373199;4938.678985865908;4939.851804445872;4940.407151141886;4951.084049450863;4966.850994470172;4334.4798624165305;4459.554044595515;4466.151431228056;4467.441569803775;4492.932606010912;5712.200291959129;5721.342045664718;5735.053716136874;5752.942470575241;5760.913586296209;6167.938320616826;6246.937530624727;6251.12484252205;6252.749890004349;6315.147455093504;5723.742431590588;5760.164776107441;5761.279840019997;5764.452377024176;5795.1017592273265;5372.811951581676;5374.328208973879;5376.623636345418;5379.354477462652;5389.51494365509;6216.067589861557;6220.098085777628;6271.623702577951;6287.371262873676;6305.716242769178;6306.558540902276;6317.305096439296;6317.399847014526;6337.65142917144;6338.65137417428;6252.687365631791;6314.210723659543;6316.05259211122;6323.4625056868845;6329.90505142414;6028.065649824284;6115.666295175977;6192.535559832941;6248.281447633581;6258.718357674006;6273.749050037947;6297.842553936188;6300.873982520416;6304.873902522016;6311.747530098744;5927.555433342603;6112.908306375497;6115.847103822262;6129.387061293835;6134.478569321479;5356.437574054668;6010.068439391901;6108.35862234459;6113.449789518964;6126.050462178369;5757.971210143923;5897.498712609477;5897.764089436375;5938.703064846654;5958.106284057253;5930.11048342752;6068.271807383142;6104.4505994460715;6126.938730612636;6132.141500189888;5969.641821490724;5986.730597123227;5987.520998320037;6012.969935150296;6030.969845150747 17:04:27 INFO - PROCESS | 1668 | |1;ext.html;473.7039350405996;502.60545471804596;596.3767862583602;673.7266566189901;677.2584020497555;31909.840450797743;32480;32638.510422343683;39781.80109099457;40420;43749;43955.34066988998;44114.779426102825;44164.77917610407;44165.77917110418;9724.611015559387;9978.700638980843;9979.50102494876;9986.800263994715;9994.650187243447;9800.264980126483;9945.10494055535;9945.801083978316;9963.302568820185;9974.601015959353;9864.802703945918;9952.850707239397;9954.303198776088;10006.649767258148;10055.145312648434;9971.700848974506;10020.34867733595;10036;10037.698869033904;10056.145227655657;9774.413535187841;9892.90107098927;9912.900870991341;9940.701778946643;9947.701568952943;42202.15595688069;42637.57362426367;42683.57316426858;42731.145377092595;42745.35881961773;3209.1335339458215;3309.9007029789145;3395.779274347184;3401.761876668634;3962.7027972902147;28692;28783.71216287852;28832.84668613253;30175.849120754257;30291.242718932204;2764.8064635475525;2779.041230775379;2854.3292326303304;2872.5834753960557;2890.3785686077595;494294;494563.52718236536;496787;498589.50705246604;498812;39224.019399515244;47951;64868;64931.350686493475;65177;46573.30140047902;46575;46843.5315646846;46874.76562617165;46912.061758764634;17091.572710682085;17171.055591942313;17203.1398430078;17217.139143042798;17232.827671723124;13595.932020339835;13651.931740341235;13659.863401366058;13676.521321753691;13727.931360343135;14924;15152.621184470476;15172;15180.468683596022;15340.539783806504;5744.615984366525;6203.286622038523;6290.748370065237;6390.808275751735;6442.194725659293;986.2061040862031;1062.2245760594808;1072.8466898084591;1091.891729894158;1113.565709373345;30505.38989220203;30549.38901221963;30683;31077.223069423446;31235.375292494024;1154.463174623804;1168.6793922867275;1192.7375977284983;1222.144498850808;1236.7031912341154;11918.761624767456;15889.602759930864;16208.51374458768;16329.918350408172;16575.751363729552;534738;536700;545546;547645;549191;60830.0875486868;61358.693206534575;61426;61553;61591;1386.5701632493958;1465.2893346726828;1523.5505525869894;1531.5175719648275;1538.70764554732;26847.060352887554;29078.854605726836;29476.199761022668;30166.698333017266;30208.54687179738;45506.54493455023;57848.42151578431;59399;59434;59555.10667339907;494693;501586.984130154;502422;503139;505344;37658.8117059413;37776.055598610255;38574;44383.77808110939;46247.306290404995;43792.562074378846;44323.556764431945;44958.775206125065;45361.319580205665;45878.54121458743;36709.63290367062;37733.622663773014;38281.234375312895;38793;39565.61520346774;37580.624193757714;37866.62133378631;38258;38551.61448385593;38735.8379248623;37298.06754831151;37920.620793791706;38191.61808381881;38393.23213535769;38865;33510;34247;34297.65702342945;34324.485132722526;34363.4845477323;22313.776862231818;24485.53086814797;25127.623085654097;25420.74579254184;26046.218613441626 17:04:27 INFO - PROCESS | 1668 | |2;jquery.html;361.40007587404915;382.10968443526576;385.1641936996715;386.1755152748882;394.19190659148757;19322;20951.266705665286;21128.049237784297;21897;21926.671099933475;20937.581248375023;21409.250676226296;21559.892200538976;22090.4477388065;22098.558028839412;5394.8921021579545;5579.330480342344;5590.664560126385;5591.245181900453;5593;5967.373425790294;6033;6062.818115456521;6078.088286756978;6080.513558915276;5726.3414707308775;6031.969840150793;6047.244094488189;6050.818475445722;6068.757249710028;5870.941290587103;5891;5934.050551911673;5936.821895343147;5958.225430694026;5583.4137415571595;5714.371419143891;5819.359870414251;5957.851053723648;5960.612560183574;13530.86469135306;13891.374888129958;14027.859721402758;14221.715565688628;14253.643658908506;2118.9152433902627;2120.703101565782;2172.532905425325;2180.953142491613;2268.9546209075725;10025.548850301753;10031.19750419972;10036.949815250877;10275.640352587698;10283.228757843117;1693.2634304077642;1708.6582683463232;1776.4493007167819;1784.1792775323238;1788.4098247578268;103907;106042.46978765134;107679.92320076778;110541.89458105396;110711;11219.980403527443;11508.482118304697;11604.187706860608;11615.593454229062;11727.882721172678;14586.927065364605;14651.92674036623;14660.486882959258;14670.046446980912;14678.85321146775;8631.525266110424;8913.866292005625;8988.685396011108;8993.505357205286;9060.909390906138;9286.860697089549;9290.95354523223;9301.627934882528;9385.57764900581;9409.76475588102;4496.617787488035;4566.771661416916;4581.289900065495;4611.8847028823875;4615.5845973862515;1886.5943822078045;1933.4586315831589;1936.1384184037968;1965;1981.3659628918897;860.2859626509982;898.3127907151035;937.5968333616611;984.8621193032837;1033.5917312661607;18971;20799.2565984381;21042.47393815136;21274.893625531775;21318.573628527654;946.7633091727068;972.8054389122204;990.301837204769;998.0867506231862;1027.5992362978445;10526.842097368546;11376;11379.032782213439;11392.25950313227;11420.31478111316;562926;587770;657424;688229;688626;108909.36635950467;120343;129790.70209297787;130025;133281.66718332694;991.5637119667323;992.4293531219578;1023.7850051489122;1069.4706120470416;1070.892910708923;18621.255149793855;18951.43145705631;19053.904730476814;19093.85436873792;19313.03434828281;51361.97276054383;53657.92684146217;53893;54074.18888716594;56354.15468768054;78198.6090069546;78684.60657696903;79452.60273698595;79748;79849;6639.767608133692;8157.06193787722;8178.67285308581;8209.917900821154;8475.449095808754;12324.753504930031;12832.486700531874;13062.281574513492;13216.735665286447;13352.732945341233;11306.208565400342;11347.148963827582;11366.090712742794;11530.962213400499;11607.419629018346;11132.833007505056;11145.275557088764;11283;11477.655670329903;11581.363025033705;10576.84134737995;11142.944285278521;11197.160212984221;11415.714607134556;11526;7963.960180199062;8083.393745468996;8163.1428699985645;8174.550399728072;8346.415750897379;8737.606807693543;11198.888011119785;11229.494672739584;11474.942625287154;11513.30920144794 17:04:27 INFO - PROCESS | 1668 | |3;mootools.html;1507.494989178625;1629.861461775749;1656.7514872769084;1987.920483180672;2062.804033616806;8146;8730.214280714743;8935;8940.06129356418;8945.910540894598;8576.27101696355;8825.91174088259;8923.687670931518;8936.10638936106;8941.239994600466;2509.1343486497176;2536.581464058428;2538.4161642822164;2539.441322908957;2541.733118022609;2182.847200695952;2470.567650661136;2555.9903837983984;2563.6410902473667;2572.292619529635;2537.124691981273;2550.5154020736095;2567.075852693029;2567.7303883092286;2582.225332400282;2509.3852006258494;2539.3524651214007;2539.7079335876424;2558.040734724478;2565.9101931432497;2501.9749802501924;2525.4696513732138;2534.9112781052577;2535.467551814121;2540.631608416788;7585.81035474112;8911.287097032222;8951.41815781972;8963.327750418744;8967.775805604846;5539.086050801613;5640.32316122068;5659.688717120557;5687.175359572881;5704.543636509069;3636.7636103653176;3743.513343265358;3786.337390956599;3851.1334949636107;3959.2873282809082;1062.102523367753;1082.626493859617;1085.853409789676;1119.4514687803;1120.6245907620957;11983.580574679932;12009.279443233434;12017;12017.579384721581;12022.15844890858;4561.791125351784;4690.64820138491;4895.779689913962;4978.904640978979;4990.5508504234795;10269.537870795832;10307.639232626821;10315.27793054479;10323.32898361604;10349.896501035044;5598.552115830724;5607.775688972477;5614.522765564972;5615.747291371817;5625.46558076982;4570.8171673133365;4702.247640377592;4963.975180124076;5082.085224659597;5106.974465127725;3983.322835117979;4033.415154802537;4047.1905618876344;4063.004563881868;4092.3452247640234;1351.3310911098872;1362.9795553066713;1364.3519328319167;1371.4102935737528;1375.559820857336;1416.3060100550656;1425.8930580206527;1450.8621680940294;1454.7308747881675;1456.803331550227;8293.709720159766;8480.194381533744;8485.95757021211;8487.660493580312;8509.064002959669;527.8126265175885;533.2561077297149;540.7161240348848;546.3307448375708;547.3158551810237;6344.873102538016;6363.300036995926;6403.199600049994;6403.551751377358;6415;10589.15286777056;10944.069754070993;11029.889701102886;11054.613088542023;11062.336259824437;10535.104516116062;10778.137748980063;10849.511771970281;10911.290766100177;11143.554257829757;7289.489735718555;8564.61459234336;8594.78513037179;8613.181747733955;8656.350773691998;7972.760817175494;8064.596770161351;8169.673213071411;8260.256576908107;8318.584070796314;7957.5225486471;9456.290778191526;9656.565454554422;9666.903330966601;9808.558614862206;9803.313768036169;10074.042965918226;10322.793544128925;10323.122534584645;10343.01741334572;6882.449404047764;7403.777886663409;7628.694852205849;7720.806979825325;7831.804204894695;8420.200080992296;8479.89761331023;8498.70254541088;8505.404621676671;8511.36164787631;6292.433680968552;7406.740764073232;7540.472166948261;7648.694052237848;7669.118051424158;7351.742689005859;7424.6287685614425;7454.552726836408;7460.3658689012045;7522.6614802335125;7827.217278272127;7932.0878099019355;7937.206279372017;7939.563324017235;7941.483803552751;7003;7135.42916566663;7157.856842863218;7225.096862892138;7244.492885497964;7213.6032518212005;7253.3834624057545;7298.160711518243;7308.159561650478;7308.634568271672 17:04:27 INFO - PROCESS | 1668 | |4;prototype.html;335.87740474726724;343.75593328736596;346.75380479859297;348.17829921385527;350.67212156633536;6629.999999999994;6704.86590268195;6721.3950744433;6725.394714475696;6760.222574403945;6686.765963191289;6688.364605362495;6690.565113267634;6690.63201523916;6698.631575263358;4811.446683631392;4933.914538801476;4934.777934992916;5031.345925029741;5033.823816166444;4968.180250258703;5011.348524691785;5038;5038.924416133761;5048.924266136011;4975.651704380694;4975.9004819903585;4980.327655766479;4984.4018717753925;4985.476524964881;4716.834910778123;4804.087223427555;4904.926426103611;4914;4924.384451943507;4198.685098617585;4209.6000879916355;4220.894477638053;4221.6490722968665;4228.936565951513;6362.427381535684;6384.32964538726;6387.93612063878;6390.488760899117;6401.583897046677;5821.446962538595;5829.562782791308;5834.183214349994;5835.737391817378;5840.941590584082;5705.857353566194;6153.876922461525;7076.646167691595;7081.291870812929;7083.964580177118;4780.139574876521;4800.255960326155;4801.111794318027;4802.831900883452;4804.855854324376;7443.702251909917;7505.099388073466;7506.136794268675;7515.736949206752;7526.435517336167;4863.805447782049;4876.853694389174;4877.951220487821;4882;4885.193942999436;4940.110780059624;4941.134142197517;4941.579965702884;4943.72809495474;4968.726720030433;4712.88217794551;5608.719564021782;5786.913196302059;5794.188813566104;5799.9130013049835;7057.823554411181;7109.786706398816;7126.465515086285;7143.964280178566;7165.247648996885;1523.6647937453743;1615.2569817883905;1723.6552689462158;1727.7494763259508;1727.9913600431917;1050.4380156616173;1099.8295264234057;1104.17738784606;1110.7945030169444;1133.0991861470152;6718.085345676556;6735.865282694319;6749.797506074826;6773.254941956381;6792.558483698544;5900.4984576310635;7335.926640733631;7342.743003994835;7343.669534870944;7354.816129596696;6737.292584278678;6757.3242675732035;6759.695813688396;6764.120664313707;6769.984502324691;3780.640839120279;3853.845846166178;3894.824732887027;3901.51231096113;3904;7584;7680.155182929873;7684.923150768533;7686.192949740197;7689.653965571562;7504.061992250969;7535.153887297647;7536.5101268417375;7554.735584254525;7566.205548417337;6565.967170164118;7628.923710762932;7633.2366763324335;7645.388368930583;7649.31156195945;7074.823129421806;7311.159216689936;7324.523905946096;7333.413326933725;7342.118945726547;6585.440237579859;7198.928010719826;7199;7204.24355442671;7239.348458638748;7506.136794268729;7606;7612.733554325572;7615.314621684075;7649.464537482323;4904.092742842443;4910.263460480956;4914.459409465027;4919.2129259318335;4925.778339974638;7181.1741649708865;7296.7081316746735;7303.8539229216185;7306.196318405077;7313.378362839218;7086.255943125898;7152.713891444284;7152.928470715435;7162.498625096402;7171.677274522764;7121.608311542707;7121.67952442131;7130.251323610946;7148.106486689164;7160.928390716026;6975.3024697529845;7036.366726994595;7042.225355211025;7043.330883566053;7046.506744527834;6947.201071876799;7160.427165826825;7177.569345839266;7188.353048225685;7199.172095208908;6780.491463140185;6914.0666010088;6919.550229235084;6925.6190909500465;7064.470164737562 17:04:27 INFO - PROCESS | 1668 | |5;yui.html;781.2890269854433;793.3137835772056;800.6477150053972;800.7237503061442;810.1129263456517;13930.582082537514;14042.57872263831;14060.718785624282;14068.718625627482;14098.929505352471;16378;16663.25015374308;16663.33346666132;16679.24943377548;16738.58153546162;819.1480859905691;978.2223132609557;994.6369575105107;1000.8398656215007;1004.7638804880847;831.9459235149695;972.9318947673665;994.4331730913382;995.5370752600039;997.0876647867193;1057.5135437698668;1064.6167379743285;1066.211003857144;1066.5094056733913;1069.2782371898936;845.8477474054667;1021.0402221911454;1051.3481641382316;1053.936763794171;1055.7255113670424;659.9670016499203;784.3058892879785;785.9782283032035;788.45202584204;788.6175205025557;15833.60415989598;15909.602259943478;15910.681786364208;15911.761323580156;15929;12510.499580016789;12534.185277956904;12580.308083055428;12675.619731408073;12990.350482475838;8446.972726545646;10369.585216591327;10382.117520010808;10609.098226650744;10909.290896091728;3556.146524834047;3592.910177245564;3602.423612222058;3630.9636903631153;3728.7576307540194;85517;86070;86788;87257.12742872554;87374.56312718459;9623.518824058769;9716.659916902874;9801.215902727765;9890.406575605486;10062.044105809937;22172.667409988862;22182.778172218394;22194.445138871335;22201.44496387571;22238.777612223996;7436.516626419266;7689.50018248812;7758.573278469738;7811.921880781119;8049.879251811228;4276.700630955866;4344.587264209895;4354.869353919387;4389.144116897238;4561.178987782231;3729.869454569077;3789.450529673182;3790.677792387678;3794.3359912015285;3797.905052373713;638.9393007664264;650.7917466410797;665.9400653941168;670.7518218259178;684.2199892122984;550.9256250406224;595.223233680043;617.5831313863124;622.1414448061646;629.7166275176189;7992.822608782266;8004.759857204177;8094.838103237902;8146.0632027317615;8532.36007299455;514.8146667199806;537.1218058474423;595.3897255313252;617.586217234445;642.6529673976094;3096.2723759916403;3857.4213867919475;3903.2974064668065;3910.9804450977563;4028.637422631977;91839.54080229557;94674;95938;95945;96281.51859240659;80364.59817700992;81446.59276703579;81874.59062704648;82057.58971205106;82578;73087;73201;73600.26399736041;74332.62833685905;74457.62771186109;8595.09751476111;8638.870416943626;8721.476711397336;8893.288536916902;9077.364584479286;49796.36708892329;50949.49050509447;51811.48188518066;52027.0743143944;53439.19841202463;69429.95855062077;70019;70688;73167;73668.63165684137;11277.7744445109;11420.200585958903;11481.36852473122;11657.53369865196;11786.823197651871;11850.58522951727;11971;12080;12150.513979440724;12573.811392829299;11284.830727539258;11550.59572914944;11660.242084264495;11761;12201.08491863096;11670.124740644316;11833.52665893389;11896.76206475883;11906;12324.32216228116;11464.828027579426;11602.18784685064;11698.76602467963;12094.818577721517;12140.271583705005;9565.521723913636;9819.950900245452;9998.150157236716;10032.347897386646;10337.224708146769;9678.661246856327;9934.950325248328;9982.307569974682;10025.598976040877;10041.196704263788 17:04:27 INFO - PROCESS | 1668 | __end_tp_report 17:04:27 INFO - PROCESS | 1668 | __start_cc_report 17:04:27 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,1162 17:04:27 INFO - PROCESS | 1668 | __end_cc_report 17:04:27 INFO - PROCESS | 1668 | __startTimestamp1464653067248__endTimestamp 17:04:27 INFO - PROCESS | 1668 | 17:04:27 INFO - PROCESS | 1668 | ------- Summary: start ------- 17:04:27 INFO - PROCESS | 1668 | Number of tests: 6 17:04:27 INFO - PROCESS | 1668 | 17:04:27 INFO - PROCESS | 1668 | [#0] dojo.html Cycles:165 Average:5742.07 Median:5930.11 stddev:622.99 (10.5%) stddev-sans-first:599.47 17:04:27 INFO - PROCESS | 1668 | Values: 3496.4 3560.2 3560.5 3585.6 3628.7 5640.4 5677.7 5686.2 5686.3 5712.9 5643.7 5668.3 5675.2 5677.7 5690.9 5108.3 5136.5 5166.1 5183.4 5212.6 5276.6 5277.6 5283.4 5284.3 5284.6 4211.7 5217.7 5236.4 5249.6 5293.0 5230.7 5274.9 5286.3 5303.7 5325.8 4987.8 5190.3 5192.2 5198.8 5213.2 5520.9 5537.6 5547.4 5572.1 5589.9 5720.9 5743.3 6087.6 6247.2 6259.4 6207.2 6208.7 6217.7 6293.8 6329.1 5799.7 5855.5 5861.3 5887.4 5898.1 6220.8 6223.0 6303.9 6321.7 6324.4 6157.1 6159.4 6178.5 6182.2 6194.2 6267.9 6272.4 6278.2 6281.8 6283.1 6054.3 6116.0 6117.4 6195.1 6227.1 6206.7 6219.3 6247.2 6256.9 6259.0 4938.7 4939.9 4940.4 4951.1 4966.9 4334.5 4459.6 4466.2 4467.4 4492.9 5712.2 5721.3 5735.1 5752.9 5760.9 6167.9 6246.9 6251.1 6252.7 6315.1 5723.7 5760.2 5761.3 5764.5 5795.1 5372.8 5374.3 5376.6 5379.4 5389.5 6216.1 6220.1 6271.6 6287.4 6305.7 6306.6 6317.3 6317.4 6337.7 6338.7 6252.7 6314.2 6316.1 6323.5 6329.9 6028.1 6115.7 6192.5 6248.3 6258.7 6273.7 6297.8 6300.9 6304.9 6311.7 5927.6 6112.9 6115.8 6129.4 6134.5 5356.4 6010.1 6108.4 6113.4 6126.1 5758.0 5897.5 5897.8 5938.7 5958.1 5930.1 6068.3 6104.5 6126.9 6132.1 5969.6 5986.7 5987.5 6013.0 6031.0 17:04:27 INFO - PROCESS | 1668 | 17:04:27 INFO - PROCESS | 1668 | [#1] ext.html Cycles:180 Average:65561.79 Median:38654.92 stddev:136749.16 (353.8%) stddev-sans-first:137045.46 17:04:27 INFO - PROCESS | 1668 | Values: 473.7 502.6 596.4 673.7 677.3 31909.8 32480.0 32638.5 39781.8 40420.0 43749.0 43955.3 44114.8 44164.8 44165.8 9724.6 9978.7 9979.5 9986.8 9994.7 9800.3 9945.1 9945.8 9963.3 9974.6 9864.8 9952.9 9954.3 10006.6 10055.1 9971.7 10020.3 10036.0 10037.7 10056.1 9774.4 9892.9 9912.9 9940.7 9947.7 42202.2 42637.6 42683.6 42731.1 42745.4 3209.1 3309.9 3395.8 3401.8 3962.7 28692.0 28783.7 28832.8 30175.8 30291.2 2764.8 2779.0 2854.3 2872.6 2890.4 494294.0 494563.5 496787.0 498589.5 498812.0 39224.0 47951.0 64868.0 64931.4 65177.0 46573.3 46575.0 46843.5 46874.8 46912.1 17091.6 17171.1 17203.1 17217.1 17232.8 13595.9 13651.9 13659.9 13676.5 13727.9 14924.0 15152.6 15172.0 15180.5 15340.5 5744.6 6203.3 6290.7 6390.8 6442.2 986.2 1062.2 1072.8 1091.9 1113.6 30505.4 30549.4 30683.0 31077.2 31235.4 1154.5 1168.7 1192.7 1222.1 1236.7 11918.8 15889.6 16208.5 16329.9 16575.8 534738.0 536700.0 545546.0 547645.0 549191.0 60830.1 61358.7 61426.0 61553.0 61591.0 1386.6 1465.3 1523.6 1531.5 1538.7 26847.1 29078.9 29476.2 30166.7 30208.5 45506.5 57848.4 59399.0 59434.0 59555.1 494693.0 501587.0 502422.0 503139.0 505344.0 37658.8 37776.1 38574.0 44383.8 46247.3 43792.6 44323.6 44958.8 45361.3 45878.5 36709.6 37733.6 38281.2 38793.0 39565.6 37580.6 37866.6 38258.0 38551.6 38735.8 37298.1 37920.6 38191.6 38393.2 38865.0 33510.0 34247.0 34297.7 34324.5 34363.5 22313.8 24485.5 25127.6 25420.7 26046.2 17:04:27 INFO - PROCESS | 1668 | 17:04:27 INFO - PROCESS | 1668 | [#2] jquery.html Cycles:180 Average:35591.62 Median:21484.57 stddev:106085.74 (493.8%) stddev-sans-first:106350.36 17:04:27 INFO - PROCESS | 1668 | Values: 361.4 382.1 385.2 386.2 394.2 19322.0 20951.3 21128.0 21897.0 21926.7 20937.6 21409.3 21559.9 22090.4 22098.6 5394.9 5579.3 5590.7 5591.2 5593.0 5967.4 6033.0 6062.8 6078.1 6080.5 5726.3 6032.0 6047.2 6050.8 6068.8 5870.9 5891.0 5934.1 5936.8 5958.2 5583.4 5714.4 5819.4 5957.9 5960.6 13530.9 13891.4 14027.9 14221.7 14253.6 2118.9 2120.7 2172.5 2181.0 2269.0 10025.5 10031.2 10036.9 10275.6 10283.2 1693.3 1708.7 1776.4 1784.2 1788.4 103907.0 106042.5 107679.9 110541.9 110711.0 11220.0 11508.5 11604.2 11615.6 11727.9 14586.9 14651.9 14660.5 14670.0 14678.9 8631.5 8913.9 8988.7 8993.5 9060.9 9286.9 9291.0 9301.6 9385.6 9409.8 4496.6 4566.8 4581.3 4611.9 4615.6 1886.6 1933.5 1936.1 1965.0 1981.4 860.3 898.3 937.6 984.9 1033.6 18971.0 20799.3 21042.5 21274.9 21318.6 946.8 972.8 990.3 998.1 1027.6 10526.8 11376.0 11379.0 11392.3 11420.3 562926.0 587770.0 657424.0 688229.0 688626.0 108909.4 120343.0 129790.7 130025.0 133281.7 991.6 992.4 1023.8 1069.5 1070.9 18621.3 18951.4 19053.9 19093.9 19313.0 51362.0 53657.9 53893.0 54074.2 56354.2 78198.6 78684.6 79452.6 79748.0 79849.0 6639.8 8157.1 8178.7 8209.9 8475.4 12324.8 12832.5 13062.3 13216.7 13352.7 11306.2 11347.1 11366.1 11531.0 11607.4 11132.8 11145.3 11283.0 11477.7 11581.4 10576.8 11142.9 11197.2 11415.7 11526.0 7964.0 8083.4 8163.1 8174.6 8346.4 8737.6 11198.9 11229.5 11474.9 11513.3 17:04:27 INFO - PROCESS | 1668 | 17:04:27 INFO - PROCESS | 1668 | [#3] mootools.html Cycles:180 Average:6163.98 Median:536.99 stddev:3239.24 (603.2%) stddev-sans-first:3229.41 17:04:27 INFO - PROCESS | 1668 | Values: 1507.5 1629.9 1656.8 1987.9 2062.8 8146.0 8730.2 8935.0 8940.1 8945.9 8576.3 8825.9 8923.7 8936.1 8941.2 2509.1 2536.6 2538.4 2539.4 2541.7 2182.8 2470.6 2556.0 2563.6 2572.3 2537.1 2550.5 2567.1 2567.7 2582.2 2509.4 2539.4 2539.7 2558.0 2565.9 2502.0 2525.5 2534.9 2535.5 2540.6 7585.8 8911.3 8951.4 8963.3 8967.8 5539.1 5640.3 5659.7 5687.2 5704.5 3636.8 3743.5 3786.3 3851.1 3959.3 1062.1 1082.6 1085.9 1119.5 1120.6 11983.6 12009.3 12017.0 12017.6 12022.2 4561.8 4690.6 4895.8 4978.9 4990.6 10269.5 10307.6 10315.3 10323.3 10349.9 5598.6 5607.8 5614.5 5615.7 5625.5 4570.8 4702.2 4964.0 5082.1 5107.0 3983.3 4033.4 4047.2 4063.0 4092.3 1351.3 1363.0 1364.4 1371.4 1375.6 1416.3 1425.9 1450.9 1454.7 1456.8 8293.7 8480.2 8486.0 8487.7 8509.1 527.8 533.3 540.7 546.3 547.3 6344.9 6363.3 6403.2 6403.6 6415.0 10589.2 10944.1 11029.9 11054.6 11062.3 10535.1 10778.1 10849.5 10911.3 11143.6 7289.5 8564.6 8594.8 8613.2 8656.4 7972.8 8064.6 8169.7 8260.3 8318.6 7957.5 9456.3 9656.6 9666.9 9808.6 9803.3 10074.0 10322.8 10323.1 10343.0 6882.4 7403.8 7628.7 7720.8 7831.8 8420.2 8479.9 8498.7 8505.4 8511.4 6292.4 7406.7 7540.5 7648.7 7669.1 7351.7 7424.6 7454.6 7460.4 7522.7 7827.2 7932.1 7937.2 7939.6 7941.5 7003.0 7135.4 7157.9 7225.1 7244.5 7213.6 7253.4 7298.2 7308.2 7308.6 17:04:27 INFO - PROCESS | 1668 | 17:04:27 INFO - PROCESS | 1668 | [#4] prototype.html Cycles:180 Average:5853.67 Median:6701.75 stddev:1829.39 (27.3%) stddev-sans-first:1787.03 17:04:27 INFO - PROCESS | 1668 | Values: 335.9 343.8 346.8 348.2 350.7 6630.0 6704.9 6721.4 6725.4 6760.2 6686.8 6688.4 6690.6 6690.6 6698.6 4811.4 4933.9 4934.8 5031.3 5033.8 4968.2 5011.3 5038.0 5038.9 5048.9 4975.7 4975.9 4980.3 4984.4 4985.5 4716.8 4804.1 4904.9 4914.0 4924.4 4198.7 4209.6 4220.9 4221.6 4228.9 6362.4 6384.3 6387.9 6390.5 6401.6 5821.4 5829.6 5834.2 5835.7 5840.9 5705.9 6153.9 7076.6 7081.3 7084.0 4780.1 4800.3 4801.1 4802.8 4804.9 7443.7 7505.1 7506.1 7515.7 7526.4 4863.8 4876.9 4878.0 4882.0 4885.2 4940.1 4941.1 4941.6 4943.7 4968.7 4712.9 5608.7 5786.9 5794.2 5799.9 7057.8 7109.8 7126.5 7144.0 7165.2 1523.7 1615.3 1723.7 1727.7 1728.0 1050.4 1099.8 1104.2 1110.8 1133.1 6718.1 6735.9 6749.8 6773.3 6792.6 5900.5 7335.9 7342.7 7343.7 7354.8 6737.3 6757.3 6759.7 6764.1 6770.0 3780.6 3853.8 3894.8 3901.5 3904.0 7584.0 7680.2 7684.9 7686.2 7689.7 7504.1 7535.2 7536.5 7554.7 7566.2 6566.0 7628.9 7633.2 7645.4 7649.3 7074.8 7311.2 7324.5 7333.4 7342.1 6585.4 7198.9 7199.0 7204.2 7239.3 7506.1 7606.0 7612.7 7615.3 7649.5 4904.1 4910.3 4914.5 4919.2 4925.8 7181.2 7296.7 7303.9 7306.2 7313.4 7086.3 7152.7 7152.9 7162.5 7171.7 7121.6 7121.7 7130.3 7148.1 7160.9 6975.3 7036.4 7042.2 7043.3 7046.5 6947.2 7160.4 7177.6 7188.4 7199.2 6780.5 6914.1 6919.6 6925.6 7064.5 17:04:27 INFO - PROCESS | 1668 | 17:04:27 INFO - PROCESS | 1668 | [#5] yui.html Cycles:180 Average:19108.31 Median:50372.93 stddev:26943.56 (53.5%) stddev-sans-first:26984.00 17:04:27 INFO - PROCESS | 1668 | Values: 781.3 793.3 800.6 800.7 810.1 13930.6 14042.6 14060.7 14068.7 14098.9 16378.0 16663.3 16663.3 16679.2 16738.6 819.1 978.2 994.6 1000.8 1004.8 831.9 972.9 994.4 995.5 997.1 1057.5 1064.6 1066.2 1066.5 1069.3 845.8 1021.0 1051.3 1053.9 1055.7 660.0 784.3 786.0 788.5 788.6 15833.6 15909.6 15910.7 15911.8 15929.0 12510.5 12534.2 12580.3 12675.6 12990.4 8447.0 10369.6 10382.1 10609.1 10909.3 3556.1 3592.9 3602.4 3631.0 3728.8 85517.0 86070.0 86788.0 87257.1 87374.6 9623.5 9716.7 9801.2 9890.4 10062.0 22172.7 22182.8 22194.4 22201.4 22238.8 7436.5 7689.5 7758.6 7811.9 8049.9 4276.7 4344.6 4354.9 4389.1 4561.2 3729.9 3789.5 3790.7 3794.3 3797.9 638.9 650.8 665.9 670.8 684.2 550.9 595.2 617.6 622.1 629.7 7992.8 8004.8 8094.8 8146.1 8532.4 514.8 537.1 595.4 617.6 642.7 3096.3 3857.4 3903.3 3911.0 4028.6 91839.5 94674.0 95938.0 95945.0 96281.5 80364.6 81446.6 81874.6 82057.6 82578.0 73087.0 73201.0 73600.3 74332.6 74457.6 8595.1 8638.9 8721.5 8893.3 9077.4 49796.4 50949.5 51811.5 52027.1 53439.2 69430.0 70019.0 70688.0 73167.0 73668.6 11277.8 11420.2 11481.4 11657.5 11786.8 11850.6 11971.0 12080.0 12150.5 12573.8 11284.8 11550.6 11660.2 11761.0 12201.1 11670.1 11833.5 11896.8 11906.0 12324.3 11464.8 11602.2 11698.8 12094.8 12140.3 9565.5 9820.0 9998.2 10032.3 10337.2 9678.7 9935.0 9982.3 10025.6 10041.2 17:04:27 INFO - PROCESS | 1668 | -------- Summary: end -------- 17:04:27 INFO - PROCESS | 1668 | 17:04:27 INFO - TEST-INFO | 1668: exit 0 17:04:27 INFO - TEST-OK | dromaeo_css | took 1111492ms 17:04:27 INFO - TEST-START | kraken 17:04:27 INFO - operating with platform_type : mac_ 17:04:27 INFO - Initialising browser for kraken test... 17:04:27 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/tmp9a3C3M/profile http://localhost:49277/getInfo.html) 17:04:29 INFO - PROCESS | 1675 | __metrics Screen width/height:1600/1200 17:04:29 INFO - PROCESS | 1675 | colorDepth:24 17:04:29 INFO - PROCESS | 1675 | Browser inner width/height: 1024/689 17:04:29 INFO - PROCESS | 1675 | __metrics 17:04:29 INFO - PROCESS | 1675 | 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" 17:04:29 INFO - PROCESS | 1675 | JavaScript error: resource://app/modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 17:04:30 INFO - TEST-INFO | 1675: exit 0 17:04:30 INFO - Browser initialized. 17:04:30 INFO - Running cycle 1/1 for kraken test... 17:04:30 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/T/tmp9a3C3M/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:04:42 INFO - PROCESS | 1678 | RSS: Main: 157020160 17:04:42 INFO - PROCESS | 1678 | 17:04:45 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 17:04:45 INFO - PROCESS | 1678 | RSS: Main: 218632192 17:04:45 INFO - PROCESS | 1678 | 17:04:48 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 17:04:48 INFO - PROCESS | 1678 | RSS: Main: 229613568 17:04:48 INFO - PROCESS | 1678 | 17:04:51 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 17:04:52 INFO - PROCESS | 1678 | RSS: Main: 220065792 17:04:52 INFO - PROCESS | 1678 | 17:04:54 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 17:04:54 INFO - PROCESS | 1678 | RSS: Main: 221687808 17:04:54 INFO - PROCESS | 1678 | 17:04:57 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:04:57 INFO - PROCESS | 1678 | RSS: Main: 222076928 17:04:57 INFO - PROCESS | 1678 | 17:05:00 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 17:05:00 INFO - PROCESS | 1678 | RSS: Main: 221786112 17:05:00 INFO - PROCESS | 1678 | 17:05:03 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 17:05:04 INFO - PROCESS | 1678 | RSS: Main: 221974528 17:05:04 INFO - PROCESS | 1678 | 17:05:06 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 17:05:07 INFO - PROCESS | 1678 | RSS: Main: 222830592 17:05:07 INFO - PROCESS | 1678 | 17:05:09 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:05:09 INFO - PROCESS | 1678 | RSS: Main: 255991808 17:05:09 INFO - PROCESS | 1678 | 17:05:11 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:05:12 INFO - PROCESS | 1678 | RSS: Main: 256962560 17:05:12 INFO - PROCESS | 1678 | 17:05:14 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:05:14 INFO - PROCESS | 1678 | RSS: Main: 261926912 17:05:14 INFO - PROCESS | 1678 | 17:05:17 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:05:17 INFO - PROCESS | 1678 | RSS: Main: 263385088 17:05:17 INFO - PROCESS | 1678 | 17:05:20 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:05:20 INFO - PROCESS | 1678 | RSS: Main: 263409664 17:05:20 INFO - PROCESS | 1678 | 17:05:22 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 17:05:22 INFO - PROCESS | 1678 | RSS: Main: 325472256 17:05:22 INFO - PROCESS | 1678 | 17:05:22 INFO - PROCESS | 1678 | __start_tp_report 17:05:22 INFO - PROCESS | 1678 | _x_x_mozilla_page_load 17:05:22 INFO - PROCESS | 1678 | _x_x_mozilla_page_load_details 17:05:22 INFO - PROCESS | 1678 | |i|pagename|runs| 17:05:22 INFO - PROCESS | 1678 | |0;ai-astar;111;112;111;110;112;113;129;118;163;159 17:05:22 INFO - PROCESS | 1678 | |1;audio-beat-detection;153;117;139;154;117;138;119;151;149;114 17:05:22 INFO - PROCESS | 1678 | |2;audio-dft;182;181;182;184;185;183;182;184;184;183 17:05:22 INFO - PROCESS | 1678 | |3;audio-fft;73;71;73;114;55;57;56;58;111;56 17:05:22 INFO - PROCESS | 1678 | |4;audio-oscillator;88;92;132;78;81;86;119;77;81;115 17:05:22 INFO - PROCESS | 1678 | |5;imaging-gaussian-blur;87;83;86;84;85;82;86;83;88;83 17:05:22 INFO - PROCESS | 1678 | |6;imaging-darkroom;92;92;91;93;91;92;93;92;91;91 17:05:22 INFO - PROCESS | 1678 | |7;imaging-desaturate;85;86;86;85;85;86;84;85;129;78 17:05:22 INFO - PROCESS | 1678 | |8;json-parse-financial;51;59;58;57;58;58;57;57;64;51 17:05:22 INFO - PROCESS | 1678 | |9;json-stringify-tinderbox;62;61;67;70;74;71;66;65;65;64 17:05:22 INFO - PROCESS | 1678 | |10;stanford-crypto-aes;80;81;80;79;81;81;78;80;78;85 17:05:22 INFO - PROCESS | 1678 | |11;stanford-crypto-ccm;119;101;107;116;126;130;122;117;116;117 17:05:22 INFO - PROCESS | 1678 | |12;stanford-crypto-pbkdf2;134;121;132;131;132;122;132;130;153;129 17:05:22 INFO - PROCESS | 1678 | |13;stanford-crypto-sha256-iterative;66;67;82;97;92;91;70;65;65;65 17:05:22 INFO - PROCESS | 1678 | __end_tp_report 17:05:22 INFO - PROCESS | 1678 | __start_cc_report 17:05:22 INFO - PROCESS | 1678 | _x_x_mozilla_cycle_collect,1206 17:05:22 INFO - PROCESS | 1678 | __end_cc_report 17:05:22 INFO - PROCESS | 1678 | __startTimestamp1464653122818__endTimestamp 17:05:22 INFO - PROCESS | 1678 | 17:05:22 INFO - PROCESS | 1678 | ------- Summary: start ------- 17:05:22 INFO - PROCESS | 1678 | Number of tests: 14 17:05:22 INFO - PROCESS | 1678 | 17:05:22 INFO - PROCESS | 1678 | [#0] ai-astar Cycles:10 Average:123.80 Median:115.50 stddev:20.41 (17.7%) stddev-sans-first:21.12 17:05:22 INFO - PROCESS | 1678 | Values: 111.0 112.0 111.0 110.0 112.0 113.0 129.0 118.0 163.0 159.0 17:05:22 INFO - PROCESS | 1678 | 17:05:22 INFO - PROCESS | 1678 | [#1] audio-beat-detection Cycles:10 Average:135.10 Median:144.00 stddev:16.69 (11.6%) stddev-sans-first:16.40 17:05:22 INFO - PROCESS | 1678 | Values: 153.0 117.0 139.0 154.0 117.0 138.0 119.0 151.0 149.0 114.0 17:05:22 INFO - PROCESS | 1678 | 17:05:22 INFO - PROCESS | 1678 | [#2] audio-dft Cycles:10 Average:183.00 Median:183.50 stddev:1.25 (0.7%) stddev-sans-first:1.27 17:05:22 INFO - PROCESS | 1678 | Values: 182.0 181.0 182.0 184.0 185.0 183.0 182.0 184.0 184.0 183.0 17:05:22 INFO - PROCESS | 1678 | 17:05:22 INFO - PROCESS | 1678 | [#3] audio-fft Cycles:10 Average:72.40 Median:57.50 stddev:22.38 (38.9%) stddev-sans-first:23.74 17:05:22 INFO - PROCESS | 1678 | Values: 73.0 71.0 73.0 114.0 55.0 57.0 56.0 58.0 111.0 56.0 17:05:22 INFO - PROCESS | 1678 | 17:05:22 INFO - PROCESS | 1678 | [#4] audio-oscillator Cycles:10 Average:94.90 Median:81.00 stddev:19.69 (24.3%) stddev-sans-first:20.72 17:05:22 INFO - PROCESS | 1678 | Values: 88.0 92.0 132.0 78.0 81.0 86.0 119.0 77.0 81.0 115.0 17:05:22 INFO - PROCESS | 1678 | 17:05:22 INFO - PROCESS | 1678 | [#5] imaging-gaussian-blur Cycles:10 Average:84.70 Median:85.50 stddev:2.00 (2.3%) stddev-sans-first:1.94 17:05:22 INFO - PROCESS | 1678 | Values: 87.0 83.0 86.0 84.0 85.0 82.0 86.0 83.0 88.0 83.0 17:05:22 INFO - PROCESS | 1678 | 17:05:22 INFO - PROCESS | 1678 | [#6] imaging-darkroom Cycles:10 Average:91.80 Median:92.00 stddev:0.79 (0.9%) stddev-sans-first:0.83 17:05:22 INFO - PROCESS | 1678 | Values: 92.0 92.0 91.0 93.0 91.0 92.0 93.0 92.0 91.0 91.0 17:05:22 INFO - PROCESS | 1678 | 17:05:22 INFO - PROCESS | 1678 | [#7] imaging-desaturate Cycles:10 Average:88.90 Median:85.00 stddev:14.29 (16.8%) stddev-sans-first:15.08 17:05:22 INFO - PROCESS | 1678 | Values: 85.0 86.0 86.0 85.0 85.0 86.0 84.0 85.0 129.0 78.0 17:05:22 INFO - PROCESS | 1678 | 17:05:22 INFO - PROCESS | 1678 | [#8] json-parse-financial Cycles:10 Average:57.00 Median:58.00 stddev:3.77 (6.5%) stddev-sans-first:3.32 17:05:22 INFO - PROCESS | 1678 | Values: 51.0 59.0 58.0 57.0 58.0 58.0 57.0 57.0 64.0 51.0 17:05:22 INFO - PROCESS | 1678 | 17:05:22 INFO - PROCESS | 1678 | [#9] json-stringify-tinderbox Cycles:10 Average:66.50 Median:66.50 stddev:4.09 (6.1%) stddev-sans-first:4.00 17:05:22 INFO - PROCESS | 1678 | Values: 62.0 61.0 67.0 70.0 74.0 71.0 66.0 65.0 65.0 64.0 17:05:22 INFO - PROCESS | 1678 | 17:05:22 INFO - PROCESS | 1678 | [#10] stanford-crypto-aes Cycles:10 Average:80.30 Median:80.50 stddev:2.00 (2.5%) stddev-sans-first:2.12 17:05:22 INFO - PROCESS | 1678 | Values: 80.0 81.0 80.0 79.0 81.0 81.0 78.0 80.0 78.0 85.0 17:05:22 INFO - PROCESS | 1678 | 17:05:22 INFO - PROCESS | 1678 | [#11] stanford-crypto-ccm Cycles:10 Average:117.10 Median:118.00 stddev:8.41 (7.1%) stddev-sans-first:8.89 17:05:22 INFO - PROCESS | 1678 | Values: 119.0 101.0 107.0 116.0 126.0 130.0 122.0 117.0 116.0 117.0 17:05:22 INFO - PROCESS | 1678 | 17:05:22 INFO - PROCESS | 1678 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:131.60 Median:132.00 stddev:8.68 (6.6%) stddev-sans-first:9.17 17:05:22 INFO - PROCESS | 1678 | Values: 134.0 121.0 132.0 131.0 132.0 122.0 132.0 130.0 153.0 129.0 17:05:22 INFO - PROCESS | 1678 | 17:05:22 INFO - PROCESS | 1678 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.00 Median:76.00 stddev:13.07 (17.2%) stddev-sans-first:13.36 17:05:22 INFO - PROCESS | 1678 | Values: 66.0 67.0 82.0 97.0 92.0 91.0 70.0 65.0 65.0 65.0 17:05:22 INFO - PROCESS | 1678 | -------- Summary: end -------- 17:05:22 INFO - PROCESS | 1678 | 17:05:23 INFO - TEST-INFO | 1678: exit 0 17:05:23 INFO - TEST-OK | kraken | took 55760ms 17:05:23 INFO - SUITE-END | took 1167s 17:05:23 INFO - Completed test suite (00:19:27) 17:05:23 INFO - javascript benchmark 17:05:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3496.37065328241, 3560.2345495718446, 3560.5193298904655, 3585.5876574193967, 3628.655277748613, 5640.435956404358, 5677.716114194294, 5686.203931449595, 5686.289213848269, 5712.857178570538, 5643.717814109299, 5668.291463567054, 5675.2054712340305, 5677.716114194284, 5690.886182276352, 5108.259302401149, 5136.484737002575, 5166.147585648364, 5183.403908550527, 5212.582993360541, 5276.577873770089, 5277.6305658603915, 5283.418823929364, 5284.260203571503, 5284.577233821305, 4211.663066954651, 5217.686938783685, 5236.371635403757, 5249.553787928031, 5293.020791153649, 5230.73846307685, 5274.92087618686, 5286.286351342558, 5303.734813259361, 5325.7869685212545, 4987.800487980477, 5190.299309593194, 5192.169252919514, 5198.766055527511, 5213.191955246943, 5520.889582208373, 5537.640053396557, 5547.417521160261, 5572.136318870582, 5589.944100558983, 5720.85582883425, 5743.339515955635, 6087.634741915499, 6247.219097612799, 6259.405356491126, 6207.193064901579, 6208.689565521706, 6217.658028808413, 6293.842653933642, 6329.082283068975, 5799.710014499258, 5855.531557475477, 5861.325947516048, 5887.411258874121, 5898.056310990221, 6220.813375598739, 6223.0354295084335, 6303.936960630335, 6321.65230912295, 6324.367563243639, 6157.076438534166, 6159.4456498915315, 6178.5366097542865, 6182.165407669997, 6194.225721784777, 6267.9373206267355, 6272.40412160844, 6278.152449419361, 6281.811545653638, 6283.120363149163, 6054.303755068136, 6115.969420152871, 6117.431594453911, 6195.070739389128, 6227.09707092469, 6206.72069756862, 6219.253689557282, 6247.187865577536, 6256.936320825471, 6259.029850373199, 4938.678985865908, 4939.851804445872, 4940.407151141886, 4951.084049450863, 4966.850994470172, 4334.4798624165305, 4459.554044595515, 4466.151431228056, 4467.441569803775, 4492.932606010912, 5712.200291959129, 5721.342045664718, 5735.053716136874, 5752.942470575241, 5760.913586296209, 6167.938320616826, 6246.937530624727, 6251.12484252205, 6252.749890004349, 6315.147455093504, 5723.742431590588, 5760.164776107441, 5761.279840019997, 5764.452377024176, 5795.1017592273265, 5372.811951581676, 5374.328208973879, 5376.623636345418, 5379.354477462652, 5389.51494365509, 6216.067589861557, 6220.098085777628, 6271.623702577951, 6287.371262873676, 6305.716242769178, 6306.558540902276, 6317.305096439296, 6317.399847014526, 6337.65142917144, 6338.65137417428, 6252.687365631791, 6314.210723659543, 6316.05259211122, 6323.4625056868845, 6329.90505142414, 6028.065649824284, 6115.666295175977, 6192.535559832941, 6248.281447633581, 6258.718357674006, 6273.749050037947, 6297.842553936188, 6300.873982520416, 6304.873902522016, 6311.747530098744, 5927.555433342603, 6112.908306375497, 6115.847103822262, 6129.387061293835, 6134.478569321479, 5356.437574054668, 6010.068439391901, 6108.35862234459, 6113.449789518964, 6126.050462178369, 5757.971210143923, 5897.498712609477, 5897.764089436375, 5938.703064846654, 5958.106284057253, 5930.11048342752, 6068.271807383142, 6104.4505994460715, 6126.938730612636, 6132.141500189888, 5969.641821490724, 5986.730597123227, 5987.520998320037, 6012.969935150296, 6030.969845150747], "unit": "score", "name": "dojo.html", "value": 5704.3291271305325}, {"lowerIsBetter": false, "replicates": [473.7039350405996, 502.60545471804596, 596.3767862583602, 673.7266566189901, 677.2584020497555, 31909.840450797743, 32480.0, 32638.510422343683, 39781.80109099457, 40420.0, 43749.0, 43955.34066988998, 44114.779426102825, 44164.77917610407, 44165.77917110418, 9724.611015559387, 9978.700638980843, 9979.50102494876, 9986.800263994715, 9994.650187243447, 9800.264980126483, 9945.10494055535, 9945.801083978316, 9963.302568820185, 9974.601015959353, 9864.802703945918, 9952.850707239397, 9954.303198776088, 10006.649767258148, 10055.145312648434, 9971.700848974506, 10020.34867733595, 10036.0, 10037.698869033904, 10056.145227655657, 9774.413535187841, 9892.90107098927, 9912.900870991341, 9940.701778946643, 9947.701568952943, 42202.15595688069, 42637.57362426367, 42683.57316426858, 42731.145377092595, 42745.35881961773, 3209.1335339458215, 3309.9007029789145, 3395.779274347184, 3401.761876668634, 3962.7027972902147, 28692.0, 28783.71216287852, 28832.84668613253, 30175.849120754257, 30291.242718932204, 2764.8064635475525, 2779.041230775379, 2854.3292326303304, 2872.5834753960557, 2890.3785686077595, 494294.0, 494563.52718236536, 496787.0, 498589.50705246604, 498812.0, 39224.019399515244, 47951.0, 64868.0, 64931.350686493475, 65177.0, 46573.30140047902, 46575.0, 46843.5315646846, 46874.76562617165, 46912.061758764634, 17091.572710682085, 17171.055591942313, 17203.1398430078, 17217.139143042798, 17232.827671723124, 13595.932020339835, 13651.931740341235, 13659.863401366058, 13676.521321753691, 13727.931360343135, 14924.0, 15152.621184470476, 15172.0, 15180.468683596022, 15340.539783806504, 5744.615984366525, 6203.286622038523, 6290.748370065237, 6390.808275751735, 6442.194725659293, 986.2061040862031, 1062.2245760594808, 1072.8466898084591, 1091.891729894158, 1113.565709373345, 30505.38989220203, 30549.38901221963, 30683.0, 31077.223069423446, 31235.375292494024, 1154.463174623804, 1168.6793922867275, 1192.7375977284983, 1222.144498850808, 1236.7031912341154, 11918.761624767456, 15889.602759930864, 16208.51374458768, 16329.918350408172, 16575.751363729552, 534738.0, 536700.0, 545546.0, 547645.0, 549191.0, 60830.0875486868, 61358.693206534575, 61426.0, 61553.0, 61591.0, 1386.5701632493958, 1465.2893346726828, 1523.5505525869894, 1531.5175719648275, 1538.70764554732, 26847.060352887554, 29078.854605726836, 29476.199761022668, 30166.698333017266, 30208.54687179738, 45506.54493455023, 57848.42151578431, 59399.0, 59434.0, 59555.10667339907, 494693.0, 501586.984130154, 502422.0, 503139.0, 505344.0, 37658.8117059413, 37776.055598610255, 38574.0, 44383.77808110939, 46247.306290404995, 43792.562074378846, 44323.556764431945, 44958.775206125065, 45361.319580205665, 45878.54121458743, 36709.63290367062, 37733.622663773014, 38281.234375312895, 38793.0, 39565.61520346774, 37580.624193757714, 37866.62133378631, 38258.0, 38551.61448385593, 38735.8379248623, 37298.06754831151, 37920.620793791706, 38191.61808381881, 38393.23213535769, 38865.0, 33510.0, 34247.0, 34297.65702342945, 34324.485132722526, 34363.4845477323, 22313.776862231818, 24485.53086814797, 25127.623085654097, 25420.74579254184, 26046.218613441626], "unit": "score", "name": "ext.html", "value": 19987.991696503002}, {"lowerIsBetter": false, "replicates": [361.40007587404915, 382.10968443526576, 385.1641936996715, 386.1755152748882, 394.19190659148757, 19322.0, 20951.266705665286, 21128.049237784297, 21897.0, 21926.671099933475, 20937.581248375023, 21409.250676226296, 21559.892200538976, 22090.4477388065, 22098.558028839412, 5394.8921021579545, 5579.330480342344, 5590.664560126385, 5591.245181900453, 5593.0, 5967.373425790294, 6033.0, 6062.818115456521, 6078.088286756978, 6080.513558915276, 5726.3414707308775, 6031.969840150793, 6047.244094488189, 6050.818475445722, 6068.757249710028, 5870.941290587103, 5891.0, 5934.050551911673, 5936.821895343147, 5958.225430694026, 5583.4137415571595, 5714.371419143891, 5819.359870414251, 5957.851053723648, 5960.612560183574, 13530.86469135306, 13891.374888129958, 14027.859721402758, 14221.715565688628, 14253.643658908506, 2118.9152433902627, 2120.703101565782, 2172.532905425325, 2180.953142491613, 2268.9546209075725, 10025.548850301753, 10031.19750419972, 10036.949815250877, 10275.640352587698, 10283.228757843117, 1693.2634304077642, 1708.6582683463232, 1776.4493007167819, 1784.1792775323238, 1788.4098247578268, 103907.0, 106042.46978765134, 107679.92320076778, 110541.89458105396, 110711.0, 11219.980403527443, 11508.482118304697, 11604.187706860608, 11615.593454229062, 11727.882721172678, 14586.927065364605, 14651.92674036623, 14660.486882959258, 14670.046446980912, 14678.85321146775, 8631.525266110424, 8913.866292005625, 8988.685396011108, 8993.505357205286, 9060.909390906138, 9286.860697089549, 9290.95354523223, 9301.627934882528, 9385.57764900581, 9409.76475588102, 4496.617787488035, 4566.771661416916, 4581.289900065495, 4611.8847028823875, 4615.5845973862515, 1886.5943822078045, 1933.4586315831589, 1936.1384184037968, 1965.0, 1981.3659628918897, 860.2859626509982, 898.3127907151035, 937.5968333616611, 984.8621193032837, 1033.5917312661607, 18971.0, 20799.2565984381, 21042.47393815136, 21274.893625531775, 21318.573628527654, 946.7633091727068, 972.8054389122204, 990.301837204769, 998.0867506231862, 1027.5992362978445, 10526.842097368546, 11376.0, 11379.032782213439, 11392.25950313227, 11420.31478111316, 562926.0, 587770.0, 657424.0, 688229.0, 688626.0, 108909.36635950467, 120343.0, 129790.70209297787, 130025.0, 133281.66718332694, 991.5637119667323, 992.4293531219578, 1023.7850051489122, 1069.4706120470416, 1070.892910708923, 18621.255149793855, 18951.43145705631, 19053.904730476814, 19093.85436873792, 19313.03434828281, 51361.97276054383, 53657.92684146217, 53893.0, 54074.18888716594, 56354.15468768054, 78198.6090069546, 78684.60657696903, 79452.60273698595, 79748.0, 79849.0, 6639.767608133692, 8157.06193787722, 8178.67285308581, 8209.917900821154, 8475.449095808754, 12324.753504930031, 12832.486700531874, 13062.281574513492, 13216.735665286447, 13352.732945341233, 11306.208565400342, 11347.148963827582, 11366.090712742794, 11530.962213400499, 11607.419629018346, 11132.833007505056, 11145.275557088764, 11283.0, 11477.655670329903, 11581.363025033705, 10576.84134737995, 11142.944285278521, 11197.160212984221, 11415.714607134556, 11526.0, 7963.960180199062, 8083.393745468996, 8163.1428699985645, 8174.550399728072, 8346.415750897379, 8737.606807693543, 11198.888011119785, 11229.494672739584, 11474.942625287154, 11513.30920144794], "unit": "score", "name": "jquery.html", "value": 9510.028874856715}, {"lowerIsBetter": false, "replicates": [1507.494989178625, 1629.861461775749, 1656.7514872769084, 1987.920483180672, 2062.804033616806, 8146.0, 8730.214280714743, 8935.0, 8940.06129356418, 8945.910540894598, 8576.27101696355, 8825.91174088259, 8923.687670931518, 8936.10638936106, 8941.239994600466, 2509.1343486497176, 2536.581464058428, 2538.4161642822164, 2539.441322908957, 2541.733118022609, 2182.847200695952, 2470.567650661136, 2555.9903837983984, 2563.6410902473667, 2572.292619529635, 2537.124691981273, 2550.5154020736095, 2567.075852693029, 2567.7303883092286, 2582.225332400282, 2509.3852006258494, 2539.3524651214007, 2539.7079335876424, 2558.040734724478, 2565.9101931432497, 2501.9749802501924, 2525.4696513732138, 2534.9112781052577, 2535.467551814121, 2540.631608416788, 7585.81035474112, 8911.287097032222, 8951.41815781972, 8963.327750418744, 8967.775805604846, 5539.086050801613, 5640.32316122068, 5659.688717120557, 5687.175359572881, 5704.543636509069, 3636.7636103653176, 3743.513343265358, 3786.337390956599, 3851.1334949636107, 3959.2873282809082, 1062.102523367753, 1082.626493859617, 1085.853409789676, 1119.4514687803, 1120.6245907620957, 11983.580574679932, 12009.279443233434, 12017.0, 12017.579384721581, 12022.15844890858, 4561.791125351784, 4690.64820138491, 4895.779689913962, 4978.904640978979, 4990.5508504234795, 10269.537870795832, 10307.639232626821, 10315.27793054479, 10323.32898361604, 10349.896501035044, 5598.552115830724, 5607.775688972477, 5614.522765564972, 5615.747291371817, 5625.46558076982, 4570.8171673133365, 4702.247640377592, 4963.975180124076, 5082.085224659597, 5106.974465127725, 3983.322835117979, 4033.415154802537, 4047.1905618876344, 4063.004563881868, 4092.3452247640234, 1351.3310911098872, 1362.9795553066713, 1364.3519328319167, 1371.4102935737528, 1375.559820857336, 1416.3060100550656, 1425.8930580206527, 1450.8621680940294, 1454.7308747881675, 1456.803331550227, 8293.709720159766, 8480.194381533744, 8485.95757021211, 8487.660493580312, 8509.064002959669, 527.8126265175885, 533.2561077297149, 540.7161240348848, 546.3307448375708, 547.3158551810237, 6344.873102538016, 6363.300036995926, 6403.199600049994, 6403.551751377358, 6415.0, 10589.15286777056, 10944.069754070993, 11029.889701102886, 11054.613088542023, 11062.336259824437, 10535.104516116062, 10778.137748980063, 10849.511771970281, 10911.290766100177, 11143.554257829757, 7289.489735718555, 8564.61459234336, 8594.78513037179, 8613.181747733955, 8656.350773691998, 7972.760817175494, 8064.596770161351, 8169.673213071411, 8260.256576908107, 8318.584070796314, 7957.5225486471, 9456.290778191526, 9656.565454554422, 9666.903330966601, 9808.558614862206, 9803.313768036169, 10074.042965918226, 10322.793544128925, 10323.122534584645, 10343.01741334572, 6882.449404047764, 7403.777886663409, 7628.694852205849, 7720.806979825325, 7831.804204894695, 8420.200080992296, 8479.89761331023, 8498.70254541088, 8505.404621676671, 8511.36164787631, 6292.433680968552, 7406.740764073232, 7540.472166948261, 7648.694052237848, 7669.118051424158, 7351.742689005859, 7424.6287685614425, 7454.552726836408, 7460.3658689012045, 7522.6614802335125, 7827.217278272127, 7932.0878099019355, 7937.206279372017, 7939.563324017235, 7941.483803552751, 7003.0, 7135.42916566663, 7157.856842863218, 7225.096862892138, 7244.492885497964, 7213.6032518212005, 7253.3834624057545, 7298.160711518243, 7308.159561650478, 7308.634568271672], "unit": "score", "name": "mootools.html", "value": 4971.619692840116}, {"lowerIsBetter": false, "replicates": [335.87740474726724, 343.75593328736596, 346.75380479859297, 348.17829921385527, 350.67212156633536, 6629.999999999994, 6704.86590268195, 6721.3950744433, 6725.394714475696, 6760.222574403945, 6686.765963191289, 6688.364605362495, 6690.565113267634, 6690.63201523916, 6698.631575263358, 4811.446683631392, 4933.914538801476, 4934.777934992916, 5031.345925029741, 5033.823816166444, 4968.180250258703, 5011.348524691785, 5038.0, 5038.924416133761, 5048.924266136011, 4975.651704380694, 4975.9004819903585, 4980.327655766479, 4984.4018717753925, 4985.476524964881, 4716.834910778123, 4804.087223427555, 4904.926426103611, 4914.0, 4924.384451943507, 4198.685098617585, 4209.6000879916355, 4220.894477638053, 4221.6490722968665, 4228.936565951513, 6362.427381535684, 6384.32964538726, 6387.93612063878, 6390.488760899117, 6401.583897046677, 5821.446962538595, 5829.562782791308, 5834.183214349994, 5835.737391817378, 5840.941590584082, 5705.857353566194, 6153.876922461525, 7076.646167691595, 7081.291870812929, 7083.964580177118, 4780.139574876521, 4800.255960326155, 4801.111794318027, 4802.831900883452, 4804.855854324376, 7443.702251909917, 7505.099388073466, 7506.136794268675, 7515.736949206752, 7526.435517336167, 4863.805447782049, 4876.853694389174, 4877.951220487821, 4882.0, 4885.193942999436, 4940.110780059624, 4941.134142197517, 4941.579965702884, 4943.72809495474, 4968.726720030433, 4712.88217794551, 5608.719564021782, 5786.913196302059, 5794.188813566104, 5799.9130013049835, 7057.823554411181, 7109.786706398816, 7126.465515086285, 7143.964280178566, 7165.247648996885, 1523.6647937453743, 1615.2569817883905, 1723.6552689462158, 1727.7494763259508, 1727.9913600431917, 1050.4380156616173, 1099.8295264234057, 1104.17738784606, 1110.7945030169444, 1133.0991861470152, 6718.085345676556, 6735.865282694319, 6749.797506074826, 6773.254941956381, 6792.558483698544, 5900.4984576310635, 7335.926640733631, 7342.743003994835, 7343.669534870944, 7354.816129596696, 6737.292584278678, 6757.3242675732035, 6759.695813688396, 6764.120664313707, 6769.984502324691, 3780.640839120279, 3853.845846166178, 3894.824732887027, 3901.51231096113, 3904.0, 7584.0, 7680.155182929873, 7684.923150768533, 7686.192949740197, 7689.653965571562, 7504.061992250969, 7535.153887297647, 7536.5101268417375, 7554.735584254525, 7566.205548417337, 6565.967170164118, 7628.923710762932, 7633.2366763324335, 7645.388368930583, 7649.31156195945, 7074.823129421806, 7311.159216689936, 7324.523905946096, 7333.413326933725, 7342.118945726547, 6585.440237579859, 7198.928010719826, 7199.0, 7204.24355442671, 7239.348458638748, 7506.136794268729, 7606.0, 7612.733554325572, 7615.314621684075, 7649.464537482323, 4904.092742842443, 4910.263460480956, 4914.459409465027, 4919.2129259318335, 4925.778339974638, 7181.1741649708865, 7296.7081316746735, 7303.8539229216185, 7306.196318405077, 7313.378362839218, 7086.255943125898, 7152.713891444284, 7152.928470715435, 7162.498625096402, 7171.677274522764, 7121.608311542707, 7121.67952442131, 7130.251323610946, 7148.106486689164, 7160.928390716026, 6975.3024697529845, 7036.366726994595, 7042.225355211025, 7043.330883566053, 7046.506744527834, 6947.201071876799, 7160.427165826825, 7177.569345839266, 7188.353048225685, 7199.172095208908, 6780.491463140185, 6914.0666010088, 6919.550229235084, 6925.6190909500465, 7064.470164737562], "unit": "score", "name": "prototype.html", "value": 5243.375835658094}, {"lowerIsBetter": false, "replicates": [781.2890269854433, 793.3137835772056, 800.6477150053972, 800.7237503061442, 810.1129263456517, 13930.582082537514, 14042.57872263831, 14060.718785624282, 14068.718625627482, 14098.929505352471, 16378.0, 16663.25015374308, 16663.33346666132, 16679.24943377548, 16738.58153546162, 819.1480859905691, 978.2223132609557, 994.6369575105107, 1000.8398656215007, 1004.7638804880847, 831.9459235149695, 972.9318947673665, 994.4331730913382, 995.5370752600039, 997.0876647867193, 1057.5135437698668, 1064.6167379743285, 1066.211003857144, 1066.5094056733913, 1069.2782371898936, 845.8477474054667, 1021.0402221911454, 1051.3481641382316, 1053.936763794171, 1055.7255113670424, 659.9670016499203, 784.3058892879785, 785.9782283032035, 788.45202584204, 788.6175205025557, 15833.60415989598, 15909.602259943478, 15910.681786364208, 15911.761323580156, 15929.0, 12510.499580016789, 12534.185277956904, 12580.308083055428, 12675.619731408073, 12990.350482475838, 8446.972726545646, 10369.585216591327, 10382.117520010808, 10609.098226650744, 10909.290896091728, 3556.146524834047, 3592.910177245564, 3602.423612222058, 3630.9636903631153, 3728.7576307540194, 85517.0, 86070.0, 86788.0, 87257.12742872554, 87374.56312718459, 9623.518824058769, 9716.659916902874, 9801.215902727765, 9890.406575605486, 10062.044105809937, 22172.667409988862, 22182.778172218394, 22194.445138871335, 22201.44496387571, 22238.777612223996, 7436.516626419266, 7689.50018248812, 7758.573278469738, 7811.921880781119, 8049.879251811228, 4276.700630955866, 4344.587264209895, 4354.869353919387, 4389.144116897238, 4561.178987782231, 3729.869454569077, 3789.450529673182, 3790.677792387678, 3794.3359912015285, 3797.905052373713, 638.9393007664264, 650.7917466410797, 665.9400653941168, 670.7518218259178, 684.2199892122984, 550.9256250406224, 595.223233680043, 617.5831313863124, 622.1414448061646, 629.7166275176189, 7992.822608782266, 8004.759857204177, 8094.838103237902, 8146.0632027317615, 8532.36007299455, 514.8146667199806, 537.1218058474423, 595.3897255313252, 617.586217234445, 642.6529673976094, 3096.2723759916403, 3857.4213867919475, 3903.2974064668065, 3910.9804450977563, 4028.637422631977, 91839.54080229557, 94674.0, 95938.0, 95945.0, 96281.51859240659, 80364.59817700992, 81446.59276703579, 81874.59062704648, 82057.58971205106, 82578.0, 73087.0, 73201.0, 73600.26399736041, 74332.62833685905, 74457.62771186109, 8595.09751476111, 8638.870416943626, 8721.476711397336, 8893.288536916902, 9077.364584479286, 49796.36708892329, 50949.49050509447, 51811.48188518066, 52027.0743143944, 53439.19841202463, 69429.95855062077, 70019.0, 70688.0, 73167.0, 73668.63165684137, 11277.7744445109, 11420.200585958903, 11481.36852473122, 11657.53369865196, 11786.823197651871, 11850.58522951727, 11971.0, 12080.0, 12150.513979440724, 12573.811392829299, 11284.830727539258, 11550.59572914944, 11660.242084264495, 11761.0, 12201.08491863096, 11670.124740644316, 11833.52665893389, 11896.76206475883, 11906.0, 12324.32216228116, 11464.828027579426, 11602.18784685064, 11698.76602467963, 12094.818577721517, 12140.271583705005, 9565.521723913636, 9819.950900245452, 9998.150157236716, 10032.347897386646, 10337.224708146769, 9678.661246856327, 9934.950325248328, 9982.307569974682, 10025.598976040877, 10041.196704263788], "unit": "score", "name": "yui.html", "value": 7206.613266329793}], "name": "dromaeo_css", "value": 7670.7587376978745}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [111.0, 112.0, 111.0, 110.0, 112.0, 113.0, 129.0, 118.0, 163.0, 159.0], "unit": "score", "name": "ai-astar", "value": 123.8}, {"lowerIsBetter": true, "replicates": [153.0, 117.0, 139.0, 154.0, 117.0, 138.0, 119.0, 151.0, 149.0, 114.0], "unit": "score", "name": "audio-beat-detection", "value": 135.1}, {"lowerIsBetter": true, "replicates": [182.0, 181.0, 182.0, 184.0, 185.0, 183.0, 182.0, 184.0, 184.0, 183.0], "unit": "score", "name": "audio-dft", "value": 183.0}, {"lowerIsBetter": true, "replicates": [73.0, 71.0, 73.0, 114.0, 55.0, 57.0, 56.0, 58.0, 111.0, 56.0], "unit": "score", "name": "audio-fft", "value": 72.4}, {"lowerIsBetter": true, "replicates": [88.0, 92.0, 132.0, 78.0, 81.0, 86.0, 119.0, 77.0, 81.0, 115.0], "unit": "score", "name": "audio-oscillator", "value": 94.9}, {"lowerIsBetter": true, "replicates": [87.0, 83.0, 86.0, 84.0, 85.0, 82.0, 86.0, 83.0, 88.0, 83.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 84.7}, {"lowerIsBetter": true, "replicates": [92.0, 92.0, 91.0, 93.0, 91.0, 92.0, 93.0, 92.0, 91.0, 91.0], "unit": "score", "name": "imaging-darkroom", "value": 91.8}, {"lowerIsBetter": true, "replicates": [85.0, 86.0, 86.0, 85.0, 85.0, 86.0, 84.0, 85.0, 129.0, 78.0], "unit": "score", "name": "imaging-desaturate", "value": 88.9}, {"lowerIsBetter": true, "replicates": [51.0, 59.0, 58.0, 57.0, 58.0, 58.0, 57.0, 57.0, 64.0, 51.0], "unit": "score", "name": "json-parse-financial", "value": 57.0}, {"lowerIsBetter": true, "replicates": [62.0, 61.0, 67.0, 70.0, 74.0, 71.0, 66.0, 65.0, 65.0, 64.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 66.5}, {"lowerIsBetter": true, "replicates": [80.0, 81.0, 80.0, 79.0, 81.0, 81.0, 78.0, 80.0, 78.0, 85.0], "unit": "score", "name": "stanford-crypto-aes", "value": 80.3}, {"lowerIsBetter": true, "replicates": [119.0, 101.0, 107.0, 116.0, 126.0, 130.0, 122.0, 117.0, 116.0, 117.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 117.1}, {"lowerIsBetter": true, "replicates": [134.0, 121.0, 132.0, 131.0, 132.0, 122.0, 132.0, 130.0, 153.0, 129.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 131.6}, {"lowerIsBetter": true, "replicates": [66.0, 67.0, 82.0, 97.0, 92.0, 91.0, 70.0, 65.0, 65.0, 65.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 76.0}], "name": "kraken", "value": 1403.0999999999997}]} 17:05:24 INFO - Return code: 0 17:05:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 17:05:24 INFO - # TBPL SUCCESS # 17:05:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:05:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:05:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:05:24 INFO - 2016-05-30 17:05:24.102 screenresolution[1682:10625] starting screenresolution argv=screenresolution get 17:05:24 INFO - 2016-05-30 17:05:24.104 screenresolution[1682:10625] Display 0: 1600x1200x32@60 17:05:24 INFO - 2016-05-30 17:05:24.110 screenresolution[1683:10628] starting screenresolution argv=screenresolution list 17:05:24 INFO - Available Modes on Display 0 17:05:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:05:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:05:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:05:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:05:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:05:24 INFO - Intel Iris: 17:05:24 INFO - Chipset Model: Intel Iris 17:05:24 INFO - Type: GPU 17:05:24 INFO - Bus: Built-In 17:05:24 INFO - VRAM (Dynamic, Max): 1536 MB 17:05:24 INFO - Vendor: Intel (0x8086) 17:05:24 INFO - Device ID: 0x0a2e 17:05:24 INFO - Revision ID: 0x0009 17:05:24 INFO - Displays: 17:05:24 INFO - Display: 17:05:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:05:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:05:24 INFO - Display Serial Number: 1600x1200 60 17:05:24 INFO - Main Display: Yes 17:05:24 INFO - Mirror: Off 17:05:24 INFO - Online: Yes 17:05:24 INFO - Rotation: Supported 17:05:24 INFO - Return code: 0 17:05:24 INFO - Running post-action listener: _resource_record_post_action 17:05:24 INFO - Running post-run listener: _resource_record_post_run 17:05:25 INFO - Total resource usage - Wall time: 1191s; CPU: 53.0%; Read bytes: 31612416; Write bytes: 509875200; Read time: 200; Write time: 1530 17:05:25 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 200780288; Write bytes: 198641664; Read time: 17958; Write time: 403 17:05:25 INFO - run-tests - Wall time: 1170s; CPU: 52.0%; Read bytes: 29826560; Write bytes: 300436480; Read time: 183; Write time: 1092 17:05:25 INFO - Running post-run listener: _upload_blobber_files 17:05:25 INFO - Blob upload gear active. 17:05:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:05:25 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 17:05:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:05:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:05:25 INFO - (blobuploader) - INFO - Open directory for files ... 17:05:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:05:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:05:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:05:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:05:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:05:26 INFO - (blobuploader) - INFO - Done attempting. 17:05:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:05:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:05:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:05:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:05:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:05:28 INFO - (blobuploader) - INFO - Done attempting. 17:05:28 INFO - (blobuploader) - INFO - Iteration through files over. 17:05:28 INFO - Return code: 0 17:05:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:05:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:05:28 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1ec4b46ee1502703ed2cd84afee1de6e690824a7bf11c320ec477d2e9c7c21dd92eaa5d8cd227e888023d0184383928175b796172cb4e45515fbcd3fdf66a1c5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/78f58cabaa39bf7b115a7d726d9981330d49fe36b1086e7af7df0fd202a5bddbbf2985ec6d04a65b518250abc7f81f7b94574928b681c7ac5fa342535a5ff6f2"} 17:05:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:05:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:05:28 INFO - Contents: 17:05:28 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1ec4b46ee1502703ed2cd84afee1de6e690824a7bf11c320ec477d2e9c7c21dd92eaa5d8cd227e888023d0184383928175b796172cb4e45515fbcd3fdf66a1c5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/78f58cabaa39bf7b115a7d726d9981330d49fe36b1086e7af7df0fd202a5bddbbf2985ec6d04a65b518250abc7f81f7b94574928b681c7ac5fa342535a5ff6f2"} 17:05:28 INFO - Running post-run listener: copy_logs_to_upload_dir 17:05:28 INFO - Copying logs to upload dir... 17:05:28 INFO - mkdir: /builds/slave/test/build/upload/logs 17:05:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1392.231203 ========= master_lag: 2.41 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 14 secs) (at 2016-05-30 17:05:30.888065) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 17:05:30.891168) ========= 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.YoC7mw0Wj1/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.iTm12pZbDD/Listeners TMPDIR=/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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-Beta/sha512/1ec4b46ee1502703ed2cd84afee1de6e690824a7bf11c320ec477d2e9c7c21dd92eaa5d8cd227e888023d0184383928175b796172cb4e45515fbcd3fdf66a1c5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/78f58cabaa39bf7b115a7d726d9981330d49fe36b1086e7af7df0fd202a5bddbbf2985ec6d04a65b518250abc7f81f7b94574928b681c7ac5fa342535a5ff6f2"} build_url:https://queue.taskcluster.net/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011000 build_url: 'https://queue.taskcluster.net/v1/task/Z9FS8jZSToutaq0dr_odkg/artifacts/public/build/firefox-47.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1ec4b46ee1502703ed2cd84afee1de6e690824a7bf11c320ec477d2e9c7c21dd92eaa5d8cd227e888023d0184383928175b796172cb4e45515fbcd3fdf66a1c5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/78f58cabaa39bf7b115a7d726d9981330d49fe36b1086e7af7df0fd202a5bddbbf2985ec6d04a65b518250abc7f81f7b94574928b681c7ac5fa342535a5ff6f2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-30 17:05:30.921073) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 17:05:30.921389) ========= 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.YoC7mw0Wj1/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.iTm12pZbDD/Listeners TMPDIR=/var/folders/fh/x511n7yj7hb7h81n2245hgqc00000w/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.005096 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-30 17:05:30.971337) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 17:05:30.972045) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-30 17:05:30.972696) ========= ========= Total master_lag: 2.56 =========