builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0080 starttime: 1461025345.25 results: success (0) buildid: 20160418150822 builduid: e56a1568f67c48ca9db916e39d26da30 revision: f128e25d78d3e0633a4f668a4485e8f4b8a25549 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-18 17:22:25.247589) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-18 17:22:25.248029) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 9 secs) (at 2016-04-18 17:22:25.248364) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 9 secs) (at 2016-04-18 17:22:34.791204) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-04-18 17:22:34.791503) ========= 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.d10dE1jgQY/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.CiVQQgOTzQ/Listeners TMPDIR=/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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-04-18 17:22:34-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 35.8M=0s 2016-04-18 17:22:34 (35.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.118191 ========= master_lag: 5.28 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 5 secs) (at 2016-04-18 17:22:40.184784) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 17:22:40.185082) ========= 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.d10dE1jgQY/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.CiVQQgOTzQ/Listeners TMPDIR=/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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.020836 ========= master_lag: 0.62 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 17:22:40.825883) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 8 secs) (at 2016-04-18 17:22:40.826209) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f128e25d78d3e0633a4f668a4485e8f4b8a25549 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev f128e25d78d3e0633a4f668a4485e8f4b8a25549 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.d10dE1jgQY/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.CiVQQgOTzQ/Listeners TMPDIR=/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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-04-18 17:22:40,906 truncating revision to first 12 chars 2016-04-18 17:22:40,907 Setting DEBUG logging. 2016-04-18 17:22:40,907 attempt 1/10 2016-04-18 17:22:40,907 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f128e25d78d3?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-18 17:22:41,979 unpacking tar archive at: mozilla-central-f128e25d78d3/testing/mozharness/ program finished with exit code 0 elapsedTime=1.326241 ========= master_lag: 7.64 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 8 secs) (at 2016-04-18 17:22:49.787817) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-18 17:22:49.788107) ========= script_repo_revision: f128e25d78d3e0633a4f668a4485e8f4b8a25549 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-18 17:22:49.788488) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 6 secs) (at 2016-04-18 17:22:49.792804) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 6 secs) (at 2016-04-18 17:22:56.465685) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-04-18 17:22:56.466022) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.d10dE1jgQY/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.CiVQQgOTzQ/Listeners TMPDIR=/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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 17:22:56 INFO - MultiFileLogger online at 20160418 17:22:56 in /builds/slave/test 17:22:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 17:22:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:22:56 INFO - {'append_to_log': False, 17:22:56 INFO - 'base_work_dir': '/builds/slave/test', 17:22:56 INFO - 'blob_upload_branch': 'Firefox', 17:22:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:22:56 INFO - 'branch': 'Firefox', 17:22:56 INFO - 'buildbot_json_path': 'buildprops.json', 17:22:56 INFO - 'config_files': ('talos/mac_config.py',), 17:22:56 INFO - 'default_actions': ('clobber', 17:22:56 INFO - 'read-buildbot-config', 17:22:56 INFO - 'download-and-extract', 17:22:56 INFO - 'populate-webroot', 17:22:56 INFO - 'create-virtualenv', 17:22:56 INFO - 'install', 17:22:56 INFO - 'run-tests'), 17:22:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:22:56 INFO - 'download_minidump_stackwalk': True, 17:22:56 INFO - 'download_symbols': 'ondemand', 17:22:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:22:56 INFO - 'tooltool.py': '/tools/tooltool.py', 17:22:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:22:56 INFO - '/tools/misc-python/virtualenv.py')}, 17:22:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:22:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:22:56 INFO - 'installer_path': 'installer.exe', 17:22:56 INFO - 'log_level': 'info', 17:22:56 INFO - 'log_name': 'talos', 17:22:56 INFO - 'log_to_console': True, 17:22:56 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:22:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:22:56 INFO - 'opt_config_files': (), 17:22:56 INFO - 'pip_index': False, 17:22:56 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:22:56 INFO - 'cmd': ('bash', 17:22:56 INFO - '-c', 17:22:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:22:56 INFO - 'enabled': True, 17:22:56 INFO - 'halt_on_failure': False, 17:22:56 INFO - 'name': 'check_screen_resolution'},), 17:22:56 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:22:56 INFO - 'cmd': ('bash', 17:22:56 INFO - '-c', 17:22:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:22:56 INFO - 'enabled': True, 17:22:56 INFO - 'halt_on_failure': False, 17:22:56 INFO - 'name': 'check_screen_resolution'},), 17:22:56 INFO - 'run_cmd_checks_enabled': True, 17:22:56 INFO - 'sps_profile': False, 17:22:56 INFO - 'sps_profile_interval': 0, 17:22:56 INFO - 'suite': 'dromaeojs', 17:22:56 INFO - 'system_bits': '32', 17:22:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:22:56 INFO - 'title': 't-yosemite-r7-0080', 17:22:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:22:56 INFO - 'use_talos_json': True, 17:22:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:22:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:22:56 INFO - 'work_dir': 'build'} 17:22:56 INFO - ##### 17:22:56 INFO - ##### Running clobber step. 17:22:56 INFO - ##### 17:22:56 INFO - Running pre-action listener: _resource_record_pre_action 17:22:56 INFO - Running main action method: clobber 17:22:56 INFO - rmtree: /builds/slave/test/build 17:22:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:22:58 INFO - Running post-action listener: _resource_record_post_action 17:22:58 INFO - ##### 17:22:58 INFO - ##### Running read-buildbot-config step. 17:22:58 INFO - ##### 17:22:58 INFO - Running pre-action listener: _resource_record_pre_action 17:22:58 INFO - Running main action method: read_buildbot_config 17:22:58 INFO - Using buildbot properties: 17:22:58 INFO - { 17:22:58 INFO - "project": "", 17:22:58 INFO - "product": "firefox", 17:22:58 INFO - "script_repo_revision": "production", 17:22:58 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 17:22:58 INFO - "repository": "", 17:22:58 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 17:22:58 INFO - "buildid": "20160418150822", 17:22:58 INFO - "pgo_build": "False", 17:22:58 INFO - "basedir": "/builds/slave/test", 17:22:58 INFO - "buildnumber": 220, 17:22:58 INFO - "slavename": "t-yosemite-r7-0080", 17:22:58 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 17:22:58 INFO - "platform": "yosemite_r7", 17:22:58 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 17:22:58 INFO - "branch": "mozilla-central", 17:22:58 INFO - "repo_path": "mozilla-central", 17:22:58 INFO - "revision": "f128e25d78d3e0633a4f668a4485e8f4b8a25549", 17:22:58 INFO - "stage_platform": "macosx64", 17:22:58 INFO - "builduid": "e56a1568f67c48ca9db916e39d26da30", 17:22:58 INFO - "slavebuilddir": "test" 17:22:58 INFO - } 17:22:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/NNKBqzhKSlaM2ttH2Q8QUQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg. 17:22:58 INFO - Running post-action listener: _resource_record_post_action 17:22:58 INFO - ##### 17:22:58 INFO - ##### Running download-and-extract step. 17:22:58 INFO - ##### 17:22:58 INFO - Running pre-action listener: _resource_record_pre_action 17:22:58 INFO - Running main action method: download_and_extract 17:22:58 INFO - mkdir: /builds/slave/test/build/tests 17:22:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:22:58 INFO - https://queue.taskcluster.net/v1/task/NNKBqzhKSlaM2ttH2Q8QUQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 17:22:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NNKBqzhKSlaM2ttH2Q8QUQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 17:22:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NNKBqzhKSlaM2ttH2Q8QUQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json 17:22:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NNKBqzhKSlaM2ttH2Q8QUQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 17:22:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NNKBqzhKSlaM2ttH2Q8QUQ/artifacts/public/build/firefox-48.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json'}, attempt #1 17:22:59 INFO - Downloaded 1199 bytes. 17:22:59 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.mac.test_packages.json 17:22:59 INFO - Using the following test package requirements: 17:22:59 INFO - {u'common': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 17:22:59 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 17:22:59 INFO - u'firefox-48.0a1.en-US.mac.cppunittest.tests.zip'], 17:22:59 INFO - u'gtest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 17:22:59 INFO - u'firefox-48.0a1.en-US.mac.gtest.tests.zip'], 17:22:59 INFO - u'jittest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 17:22:59 INFO - u'jsshell-mac.zip'], 17:22:59 INFO - u'mochitest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 17:22:59 INFO - u'firefox-48.0a1.en-US.mac.mochitest.tests.zip'], 17:22:59 INFO - u'mozbase': [u'firefox-48.0a1.en-US.mac.common.tests.zip'], 17:22:59 INFO - u'reftest': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 17:22:59 INFO - u'firefox-48.0a1.en-US.mac.reftest.tests.zip'], 17:22:59 INFO - u'talos': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 17:22:59 INFO - u'firefox-48.0a1.en-US.mac.talos.tests.zip'], 17:22:59 INFO - u'web-platform': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 17:22:59 INFO - u'firefox-48.0a1.en-US.mac.web-platform.tests.zip'], 17:22:59 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.mac.common.tests.zip', 17:22:59 INFO - u'firefox-48.0a1.en-US.mac.xpcshell.tests.zip']} 17:22:59 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:22:59 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')]} 17:22:59 INFO - https://queue.taskcluster.net/v1/task/NNKBqzhKSlaM2ttH2Q8QUQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:22:59 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NNKBqzhKSlaM2ttH2Q8QUQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 17:22:59 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NNKBqzhKSlaM2ttH2Q8QUQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 17:22:59 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NNKBqzhKSlaM2ttH2Q8QUQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 17:22:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NNKBqzhKSlaM2ttH2Q8QUQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:23:00 INFO - Downloaded 18037921 bytes. 17:23:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:23:00 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 17:23:03 INFO - Return code: 0 17:23:03 INFO - Downloading packages: [u'firefox-48.0a1.en-US.mac.common.tests.zip', u'firefox-48.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 17:23:03 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')]} 17:23:03 INFO - https://queue.taskcluster.net/v1/task/NNKBqzhKSlaM2ttH2Q8QUQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 17:23:03 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NNKBqzhKSlaM2ttH2Q8QUQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 17:23:03 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NNKBqzhKSlaM2ttH2Q8QUQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip 17:23:03 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NNKBqzhKSlaM2ttH2Q8QUQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip 17:23:03 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NNKBqzhKSlaM2ttH2Q8QUQ/artifacts/public/build/firefox-48.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip'}, attempt #1 17:23:06 INFO - Downloaded 18037921 bytes. 17:23:06 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:23:06 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 17:23:10 INFO - Return code: 0 17:23:10 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')]} 17:23:10 INFO - https://queue.taskcluster.net/v1/task/NNKBqzhKSlaM2ttH2Q8QUQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 17:23:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NNKBqzhKSlaM2ttH2Q8QUQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 17:23:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NNKBqzhKSlaM2ttH2Q8QUQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip 17:23:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NNKBqzhKSlaM2ttH2Q8QUQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip 17:23:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NNKBqzhKSlaM2ttH2Q8QUQ/artifacts/public/build/firefox-48.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip'}, attempt #1 17:23:11 INFO - Downloaded 10845952 bytes. 17:23:11 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:23:11 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 17:23:11 INFO - Return code: 0 17:23:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:23:11 INFO - https://queue.taskcluster.net/v1/task/NNKBqzhKSlaM2ttH2Q8QUQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 17:23:11 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NNKBqzhKSlaM2ttH2Q8QUQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 17:23:11 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NNKBqzhKSlaM2ttH2Q8QUQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 17:23:11 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NNKBqzhKSlaM2ttH2Q8QUQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg 17:23:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/NNKBqzhKSlaM2ttH2Q8QUQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg'}, attempt #1 17:23:14 INFO - Downloaded 109963739 bytes. 17:23:14 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NNKBqzhKSlaM2ttH2Q8QUQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 17:23:14 INFO - mkdir: /builds/slave/test/properties 17:23:14 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:23:14 INFO - Writing to file /builds/slave/test/properties/build_url 17:23:14 INFO - Contents: 17:23:14 INFO - build_url:https://queue.taskcluster.net/v1/task/NNKBqzhKSlaM2ttH2Q8QUQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg 17:23:15 INFO - Running post-action listener: _resource_record_post_action 17:23:15 INFO - Running post-action listener: set_extra_try_arguments 17:23:15 INFO - ##### 17:23:15 INFO - ##### Running populate-webroot step. 17:23:15 INFO - ##### 17:23:15 INFO - Running pre-action listener: _resource_record_pre_action 17:23:15 INFO - Running main action method: populate_webroot 17:23:15 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:23:15 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:23:15 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:23:15 INFO - '--tppagecycles', 17:23:15 INFO - '7'], 17:23:15 INFO - 'tests': ['tsvgm']}}, 17:23:15 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:23:15 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:23:15 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:23:15 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:23:15 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:23:15 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:23:15 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:23:15 INFO - 'talos_options': ['--disable-e10s'], 17:23:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:23:15 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:23:15 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:23:15 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:23:15 INFO - 'talos_options': ['--disable-e10s'], 17:23:15 INFO - 'tests': ['damp', 'tps']}, 17:23:15 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:23:15 INFO - 'tests': ['damp', 'tps']}, 17:23:15 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:23:15 INFO - 'tests': ['dromaeo_dom']}, 17:23:15 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:23:15 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:23:15 INFO - 'tests': ['a11yr', 17:23:15 INFO - 'ts_paint', 17:23:15 INFO - 'tpaint', 17:23:15 INFO - 'sessionrestore', 17:23:15 INFO - 'sessionrestore_no_auto_restore', 17:23:15 INFO - 'tabpaint']}, 17:23:15 INFO - 'other-e10s': {'tests': ['a11yr', 17:23:15 INFO - 'ts_paint', 17:23:15 INFO - 'tpaint', 17:23:15 INFO - 'sessionrestore', 17:23:15 INFO - 'sessionrestore_no_auto_restore', 17:23:15 INFO - 'tabpaint']}, 17:23:15 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:23:15 INFO - 'ts_paint', 17:23:15 INFO - 'tpaint', 17:23:15 INFO - 'sessionrestore', 17:23:15 INFO - 'sessionrestore_no_auto_restore', 17:23:15 INFO - 'tabpaint']}, 17:23:15 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:23:15 INFO - 'ts_paint', 17:23:15 INFO - 'tpaint', 17:23:15 INFO - 'sessionrestore', 17:23:15 INFO - 'sessionrestore_no_auto_restore', 17:23:15 INFO - 'tabpaint']}, 17:23:15 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:23:15 INFO - 'tests': ['a11yr', 17:23:15 INFO - 'ts_paint', 17:23:15 INFO - 'tpaint', 17:23:15 INFO - 'sessionrestore', 17:23:15 INFO - 'sessionrestore_no_auto_restore', 17:23:15 INFO - 'tabpaint']}, 17:23:15 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:23:15 INFO - 'tests': ['a11yr', 17:23:15 INFO - 'ts_paint', 17:23:15 INFO - 'tpaint', 17:23:15 INFO - 'sessionrestore', 17:23:15 INFO - 'sessionrestore_no_auto_restore', 17:23:15 INFO - 'tabpaint']}, 17:23:15 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:23:15 INFO - 'tests': ['tsvgx', 17:23:15 INFO - 'tsvgr_opacity', 17:23:15 INFO - 'tart', 17:23:15 INFO - 'tscrollx', 17:23:15 INFO - 'cart']}, 17:23:15 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:23:15 INFO - 'tsvgr_opacity', 17:23:15 INFO - 'tart', 17:23:15 INFO - 'tscrollx', 17:23:15 INFO - 'cart']}, 17:23:15 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:23:15 INFO - 'talos_options': ['--disable-e10s'], 17:23:15 INFO - 'tests': ['tp5o']}, 17:23:15 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:23:15 INFO - 'tests': ['tp5o']}, 17:23:15 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:23:15 INFO - 'talos_options': ['--disable-e10s', 17:23:15 INFO - '--xperf_path', 17:23:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:23:15 INFO - 'tests': ['tp5n']}, 17:23:15 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:23:15 INFO - 'talos_options': ['--xperf_path', 17:23:15 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:23:15 INFO - 'tests': ['tp5n']}}, 17:23:15 INFO - 'talos.zip': {'path': '', 17:23:15 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:23:15 INFO - Running post-action listener: _resource_record_post_action 17:23:15 INFO - ##### 17:23:15 INFO - ##### Running create-virtualenv step. 17:23:15 INFO - ##### 17:23:15 INFO - Running pre-action listener: _resource_record_pre_action 17:23:15 INFO - Running main action method: create_virtualenv 17:23:15 INFO - Creating virtualenv /builds/slave/test/build/venv 17:23:15 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 17:23:15 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:23:15 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:23:15 INFO - Using real prefix '/tools/python27' 17:23:15 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:23:16 INFO - Installing distribute.............................................................................................................................................................................................done. 17:23:19 INFO - Installing pip.................done. 17:23:19 INFO - Return code: 0 17:23:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:23:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:23:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:23:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:23:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:23:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:23:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:23:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10abc9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abd9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc852d58650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa68e00>, '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 0x10a891db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa63b20>, '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.d10dE1jgQY/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.CiVQQgOTzQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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 17:23:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:23:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:23:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d10dE1jgQY/Render', 17:23:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:23:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:23:19 INFO - 'HOME': '/Users/cltbld', 17:23:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:23:19 INFO - 'LOGNAME': 'cltbld', 17:23:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:23:19 INFO - 'MOZ_NO_REMOTE': '1', 17:23:19 INFO - 'NO_EM_RESTART': '1', 17:23:19 INFO - 'PAGER': '/bin/cat', 17:23:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:23:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:23:19 INFO - 'PWD': '/builds/slave/test', 17:23:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:23:19 INFO - 'SHELL': '/bin/bash', 17:23:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CiVQQgOTzQ/Listeners', 17:23:19 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 17:23:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:23:19 INFO - 'USER': 'cltbld', 17:23:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:23:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:23:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:23:19 INFO - 'XPC_FLAGS': '0x0', 17:23:19 INFO - 'XPC_SERVICE_NAME': '0', 17:23:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:23:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:23:20 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 17:23:20 INFO - Cleaning up... 17:23:20 INFO - Return code: 0 17:23:20 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 17:23:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:23:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:23:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:23:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:23:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:23:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:23:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10abc9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abd9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc852d58650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa68e00>, '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 0x10a891db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa63b20>, '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.d10dE1jgQY/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.CiVQQgOTzQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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 17:23:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 17:23:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 17:23:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d10dE1jgQY/Render', 17:23:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:23:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:23:20 INFO - 'HOME': '/Users/cltbld', 17:23:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:23:20 INFO - 'LOGNAME': 'cltbld', 17:23:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:23:20 INFO - 'MOZ_NO_REMOTE': '1', 17:23:20 INFO - 'NO_EM_RESTART': '1', 17:23:20 INFO - 'PAGER': '/bin/cat', 17:23:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:23:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:23:20 INFO - 'PWD': '/builds/slave/test', 17:23:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:23:20 INFO - 'SHELL': '/bin/bash', 17:23:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CiVQQgOTzQ/Listeners', 17:23:20 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 17:23:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:23:20 INFO - 'USER': 'cltbld', 17:23:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:23:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:23:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:23:20 INFO - 'XPC_FLAGS': '0x0', 17:23:20 INFO - 'XPC_SERVICE_NAME': '0', 17:23:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:23:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:23:20 INFO - Downloading/unpacking psutil>=0.7.1 17:23: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 17:23: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 17:23: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 17:23: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 17:23: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 17:23: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 17:23:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:23:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:23:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:23:23 INFO - Installing collected packages: psutil 17:23:23 INFO - Running setup.py install for psutil 17:23:23 INFO - building 'psutil._psutil_osx' extension 17:23:23 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 17:23:23 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 17:23:23 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 17:23:23 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 17:23:23 INFO - building 'psutil._psutil_posix' extension 17:23:23 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 17:23:23 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:23:23 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:23:23 INFO - ^ 17:23:23 INFO - 1 warning generated. 17:23:23 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 17:23:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:23:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:23:23 INFO - Successfully installed psutil 17:23:23 INFO - Cleaning up... 17:23:24 INFO - Return code: 0 17:23:24 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 17:23: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')]} 17:23:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:23:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:23: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')]} 17:23:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:23:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:23: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', '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 0x10abc9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abd9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc852d58650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa68e00>, '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 0x10a891db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa63b20>, '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.d10dE1jgQY/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.CiVQQgOTzQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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 17:23: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 17:23: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 mozsystemmonitor==0.0.0 17:23:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d10dE1jgQY/Render', 17:23:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:23:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:23:24 INFO - 'HOME': '/Users/cltbld', 17:23:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:23:24 INFO - 'LOGNAME': 'cltbld', 17:23:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:23:24 INFO - 'MOZ_NO_REMOTE': '1', 17:23:24 INFO - 'NO_EM_RESTART': '1', 17:23:24 INFO - 'PAGER': '/bin/cat', 17:23:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:23:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:23:24 INFO - 'PWD': '/builds/slave/test', 17:23:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:23:24 INFO - 'SHELL': '/bin/bash', 17:23:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CiVQQgOTzQ/Listeners', 17:23:24 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 17:23:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:23:24 INFO - 'USER': 'cltbld', 17:23:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:23:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:23:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:23:24 INFO - 'XPC_FLAGS': '0x0', 17:23:24 INFO - 'XPC_SERVICE_NAME': '0', 17:23:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:23:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:23:24 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 17:23: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 17:23: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 17:23: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 17:23: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 17:23: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 17:23: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 17:23:26 INFO - Downloading mozsystemmonitor-0.0.tar.gz 17:23:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:23:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 17:23:26 INFO - Installing collected packages: mozsystemmonitor 17:23:26 INFO - Running setup.py install for mozsystemmonitor 17:23:26 INFO - Successfully installed mozsystemmonitor 17:23:26 INFO - Cleaning up... 17:23:26 INFO - Return code: 0 17:23:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:23:26 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')]} 17:23:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:23:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:23:26 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')]} 17:23:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:23:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:23:26 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 0x10abc9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abd9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc852d58650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa68e00>, '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 0x10a891db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa63b20>, '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.d10dE1jgQY/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.CiVQQgOTzQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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 17:23:26 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 17:23:26 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 17:23:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d10dE1jgQY/Render', 17:23:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:23:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:23:26 INFO - 'HOME': '/Users/cltbld', 17:23:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:23:26 INFO - 'LOGNAME': 'cltbld', 17:23:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:23:26 INFO - 'MOZ_NO_REMOTE': '1', 17:23:26 INFO - 'NO_EM_RESTART': '1', 17:23:26 INFO - 'PAGER': '/bin/cat', 17:23:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:23:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:23:26 INFO - 'PWD': '/builds/slave/test', 17:23:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:23:26 INFO - 'SHELL': '/bin/bash', 17:23:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CiVQQgOTzQ/Listeners', 17:23:26 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 17:23:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:23:26 INFO - 'USER': 'cltbld', 17:23:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:23:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:23:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:23:26 INFO - 'XPC_FLAGS': '0x0', 17:23:26 INFO - 'XPC_SERVICE_NAME': '0', 17:23:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:23:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:23:27 INFO - Downloading/unpacking blobuploader==1.2.4 17:23: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 17:23: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 17:23: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 17:23: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 17:23: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 17:23: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 17:23:29 INFO - Downloading blobuploader-1.2.4.tar.gz 17:23:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:23:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:23:29 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 17:23:29 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 17:23:29 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 17:23:29 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 17:23:29 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 17:23:29 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 17:23:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:23:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:23: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 17:23: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 17:23: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 17:23: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 17:23: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 17:23: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 17:23:30 INFO - Downloading docopt-0.6.1.tar.gz 17:23:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:23:30 INFO - Installing collected packages: blobuploader, requests, docopt 17:23:30 INFO - Running setup.py install for blobuploader 17:23:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:23:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:23:31 INFO - Running setup.py install for requests 17:23:31 INFO - Running setup.py install for docopt 17:23:31 INFO - Successfully installed blobuploader requests docopt 17:23:31 INFO - Cleaning up... 17:23:31 INFO - Return code: 0 17:23:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:23:31 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')]} 17:23:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:23:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:23:31 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')]} 17:23:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:23:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:23:31 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 0x10abc9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abd9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc852d58650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa68e00>, '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 0x10a891db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa63b20>, '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.d10dE1jgQY/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.CiVQQgOTzQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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 17:23:31 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 17:23:31 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 17:23:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d10dE1jgQY/Render', 17:23:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:23:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:23:31 INFO - 'HOME': '/Users/cltbld', 17:23:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:23:31 INFO - 'LOGNAME': 'cltbld', 17:23:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:23:31 INFO - 'MOZ_NO_REMOTE': '1', 17:23:31 INFO - 'NO_EM_RESTART': '1', 17:23:31 INFO - 'PAGER': '/bin/cat', 17:23:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:23:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:23:31 INFO - 'PWD': '/builds/slave/test', 17:23:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:23:31 INFO - 'SHELL': '/bin/bash', 17:23:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CiVQQgOTzQ/Listeners', 17:23:31 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 17:23:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:23:31 INFO - 'USER': 'cltbld', 17:23:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:23:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:23:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:23:31 INFO - 'XPC_FLAGS': '0x0', 17:23:31 INFO - 'XPC_SERVICE_NAME': '0', 17:23:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:23:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:23:32 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-zI2Bw6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:23:32 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-98qll6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:23:32 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-_X2KSw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:23:32 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-jfqzcB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:23:32 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-baA6yZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:23:32 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-xX6xiE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:23:32 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-0lharR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:23:32 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-PUpPLM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:23:32 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-_YOY8p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:23:32 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-cb00Ds-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:23:33 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-nULRVF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:23:33 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-PWv5uF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:23:33 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-zuhzmi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:23:33 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-XoBxaK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:23:33 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-FM60_V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:23:33 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-V71jX_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:23:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:23:33 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-0ynMtw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:23:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:23:33 INFO - Running setup.py install for manifestparser 17:23:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:23:33 INFO - Running setup.py install for mozcrash 17:23:34 INFO - Running setup.py install for mozdebug 17:23:34 INFO - Running setup.py install for mozdevice 17:23:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:23:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:23:34 INFO - Running setup.py install for mozfile 17:23:34 INFO - Running setup.py install for mozhttpd 17:23:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:23:34 INFO - Running setup.py install for mozinfo 17:23:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:23:34 INFO - Running setup.py install for mozInstall 17:23:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:23:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:23:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:23:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:23:35 INFO - Running setup.py install for mozleak 17:23:35 INFO - Running setup.py install for mozlog 17:23:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:23:35 INFO - Running setup.py install for moznetwork 17:23:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:23:35 INFO - Running setup.py install for mozprocess 17:23:35 INFO - Running setup.py install for mozprofile 17:23:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:23:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:23:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:23:35 INFO - Running setup.py install for mozrunner 17:23:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:23:35 INFO - Running setup.py install for mozscreenshot 17:23:36 INFO - Running setup.py install for moztest 17:23:36 INFO - Running setup.py install for mozversion 17:23:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:23:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:23:36 INFO - Cleaning up... 17:23:36 INFO - Return code: 0 17:23:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:23:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:23:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:23:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:23:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:23:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:23:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:23:36 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 0x10abc9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abd9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc852d58650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa68e00>, '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 0x10a891db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa63b20>, '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.d10dE1jgQY/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.CiVQQgOTzQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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 17:23:36 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 17:23:36 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 17:23:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d10dE1jgQY/Render', 17:23:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:23:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:23:36 INFO - 'HOME': '/Users/cltbld', 17:23:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:23:36 INFO - 'LOGNAME': 'cltbld', 17:23:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:23:36 INFO - 'MOZ_NO_REMOTE': '1', 17:23:36 INFO - 'NO_EM_RESTART': '1', 17:23:36 INFO - 'PAGER': '/bin/cat', 17:23:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:23:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:23:36 INFO - 'PWD': '/builds/slave/test', 17:23:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:23:36 INFO - 'SHELL': '/bin/bash', 17:23:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CiVQQgOTzQ/Listeners', 17:23:36 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 17:23:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:23:36 INFO - 'USER': 'cltbld', 17:23:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:23:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:23:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:23:36 INFO - 'XPC_FLAGS': '0x0', 17:23:36 INFO - 'XPC_SERVICE_NAME': '0', 17:23:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:23:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:23:36 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-pE2S4H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:23:36 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)) 17:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:23:36 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-J_6ei5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:23:36 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)) 17:23:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:23:36 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-1aZiFH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:23:37 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)) 17:23:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:23:37 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-dDRzk6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:23:37 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)) 17:23:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:23:37 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-7Tog11-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:23:37 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)) 17:23:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:23:37 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-ZDLCtx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:23:37 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)) 17:23:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:23:37 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-RawHh7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:23:37 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)) 17:23:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:23:37 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-bACg0F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:23:37 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)) 17:23:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:23:37 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-mSCRI6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:23:37 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)) 17:23:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:23:37 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-M__4_I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:23:37 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)) 17:23:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:23:37 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-ljFSF2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:23:37 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)) 17:23:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:23:37 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-b3iafS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:23:38 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)) 17:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:23:38 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-Mo9ggU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:23:38 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)) 17:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:23:38 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-EZ1zIc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:23:38 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)) 17:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:23:38 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-TWdjHc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:23:38 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)) 17:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:23:38 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-sH5UkZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:23:38 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)) 17:23:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:23:38 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-D0mLRc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:23:38 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)) 17:23:38 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)) 17:23:38 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)) 17:23:38 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)) 17:23:38 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)) 17:23:38 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)) 17:23:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:23:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:23:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:23:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:23:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:23:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:23:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:23:40 INFO - Downloading blessings-1.6.tar.gz 17:23:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:23:41 INFO - Installing collected packages: blessings 17:23:41 INFO - Running setup.py install for blessings 17:23:41 INFO - Successfully installed blessings 17:23:41 INFO - Cleaning up... 17:23:41 INFO - Return code: 0 17:23:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:23:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:23:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:23:41 INFO - Reading from file tmpfile_stdout 17:23:41 INFO - Current package versions: 17:23:41 INFO - blessings == 1.6 17:23:41 INFO - blobuploader == 1.2.4 17:23:41 INFO - docopt == 0.6.1 17:23:41 INFO - manifestparser == 1.1 17:23:41 INFO - mozInstall == 1.12 17:23:41 INFO - mozcrash == 0.17 17:23:41 INFO - mozdebug == 0.1 17:23:41 INFO - mozdevice == 0.48 17:23:41 INFO - mozfile == 1.2 17:23:41 INFO - mozhttpd == 0.7 17:23:41 INFO - mozinfo == 0.9 17:23:41 INFO - mozleak == 0.1 17:23:41 INFO - mozlog == 3.1 17:23:41 INFO - moznetwork == 0.27 17:23:41 INFO - mozprocess == 0.22 17:23:41 INFO - mozprofile == 0.28 17:23:41 INFO - mozrunner == 6.11 17:23:41 INFO - mozscreenshot == 0.1 17:23:41 INFO - mozsystemmonitor == 0.0 17:23:41 INFO - moztest == 0.7 17:23:41 INFO - mozversion == 1.4 17:23:41 INFO - psutil == 3.1.1 17:23:41 INFO - requests == 1.2.3 17:23:41 INFO - wsgiref == 0.1.2 17:23:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:23:41 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')]} 17:23:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:23:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:23:41 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')]} 17:23:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:23:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:23:41 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 0x10abc9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abd9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc852d58650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa68e00>, '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 0x10a891db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa63b20>, '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.d10dE1jgQY/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.CiVQQgOTzQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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 17:23:41 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 17:23:41 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 17:23:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d10dE1jgQY/Render', 17:23:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:23:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:23:41 INFO - 'HOME': '/Users/cltbld', 17:23:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:23:41 INFO - 'LOGNAME': 'cltbld', 17:23:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:23:41 INFO - 'MOZ_NO_REMOTE': '1', 17:23:41 INFO - 'NO_EM_RESTART': '1', 17:23:41 INFO - 'PAGER': '/bin/cat', 17:23:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:23:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:23:41 INFO - 'PWD': '/builds/slave/test', 17:23:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:23:41 INFO - 'SHELL': '/bin/bash', 17:23:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CiVQQgOTzQ/Listeners', 17:23:41 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 17:23:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:23:41 INFO - 'USER': 'cltbld', 17:23:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:23:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:23:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:23:41 INFO - 'XPC_FLAGS': '0x0', 17:23:41 INFO - 'XPC_SERVICE_NAME': '0', 17:23:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:23:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:23:41 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)) 17:23:41 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)) 17:23:41 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)) 17:23:41 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)) 17:23:41 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)) 17:23:41 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)) 17:23:41 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)) 17:23:41 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)) 17:23:41 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)) 17:23:41 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)) 17:23:41 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)) 17:23:41 INFO - Cleaning up... 17:23:41 INFO - Return code: 0 17:23:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:23:41 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')]} 17:23:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:23:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:23:41 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')]} 17:23:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:23:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:23:41 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 0x10abc9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abd9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc852d58650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa68e00>, '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 0x10a891db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa63b20>, '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.d10dE1jgQY/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.CiVQQgOTzQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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 17:23:41 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 17:23:41 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 17:23:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d10dE1jgQY/Render', 17:23:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:23:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:23:41 INFO - 'HOME': '/Users/cltbld', 17:23:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:23:41 INFO - 'LOGNAME': 'cltbld', 17:23:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:23:41 INFO - 'MOZ_NO_REMOTE': '1', 17:23:41 INFO - 'NO_EM_RESTART': '1', 17:23:41 INFO - 'PAGER': '/bin/cat', 17:23:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:23:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:23:41 INFO - 'PWD': '/builds/slave/test', 17:23:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:23:41 INFO - 'SHELL': '/bin/bash', 17:23:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CiVQQgOTzQ/Listeners', 17:23:41 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 17:23:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:23:41 INFO - 'USER': 'cltbld', 17:23:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:23:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:23:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:23:41 INFO - 'XPC_FLAGS': '0x0', 17:23:41 INFO - 'XPC_SERVICE_NAME': '0', 17:23:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:23:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:23:42 INFO - Downloading/unpacking jsonschema 17:23:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:23:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:23:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:23:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:23:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:23:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:23:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:23:44 INFO - Downloading/unpacking functools32 (from jsonschema) 17:23:44 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 17:23:44 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 17:23:44 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 17:23:44 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 17:23:44 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 17:23:44 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 17:23:44 INFO - Downloading functools32-3.2.3-2.tar.gz 17:23:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:23:44 INFO - warning: no files found matching '*.txt' 17:23:44 INFO - no previously-included directories found matching 'build' 17:23:44 INFO - no previously-included directories found matching 'dist' 17:23:44 INFO - no previously-included directories found matching '.git*' 17:23:44 INFO - Installing collected packages: jsonschema, functools32 17:23:45 INFO - Running setup.py install for functools32 17:23:45 INFO - warning: no files found matching '*.txt' 17:23:45 INFO - no previously-included directories found matching 'build' 17:23:45 INFO - no previously-included directories found matching 'dist' 17:23:45 INFO - no previously-included directories found matching '.git*' 17:23:45 INFO - Successfully installed jsonschema functools32 17:23:45 INFO - Cleaning up... 17:23:45 INFO - Return code: 0 17:23:45 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 17:23:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:23:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:23:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:23:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:23:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:23:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:23:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10abc9a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10abd9420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc852d58650>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10aa68e00>, '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 0x10a891db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa63b20>, '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.d10dE1jgQY/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.CiVQQgOTzQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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 17:23:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 17:23:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 17:23:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d10dE1jgQY/Render', 17:23:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:23:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:23:45 INFO - 'HOME': '/Users/cltbld', 17:23:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:23:45 INFO - 'LOGNAME': 'cltbld', 17:23:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:23:45 INFO - 'MOZ_NO_REMOTE': '1', 17:23:45 INFO - 'NO_EM_RESTART': '1', 17:23:45 INFO - 'PAGER': '/bin/cat', 17:23:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:23:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:23:45 INFO - 'PWD': '/builds/slave/test', 17:23:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:23:45 INFO - 'SHELL': '/bin/bash', 17:23:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CiVQQgOTzQ/Listeners', 17:23:45 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 17:23:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:23:45 INFO - 'USER': 'cltbld', 17:23:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:23:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:23:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:23:45 INFO - 'XPC_FLAGS': '0x0', 17:23:45 INFO - 'XPC_SERVICE_NAME': '0', 17:23:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:23:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:23:45 INFO - Downloading/unpacking flake8 17:23:45 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 17:23:45 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 17:23:45 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 17:23:45 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 17:23:45 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 17:23:45 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 17:23:47 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 17:23:47 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 17:23:47 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 17:23:47 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 17:23:47 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 17:23:47 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 17:23:47 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 17:23:47 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 17:23:48 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 17:23:48 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 17:23:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:23:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:23:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:23:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:23:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:23:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:23:48 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 17:23:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:23:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:23:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:23:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:23:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:23:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:23:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 17:23:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:23:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:23:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:23:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:23:49 INFO - no previously-included directories found matching 'docs/_build' 17:23:49 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 17:23:49 INFO - Running setup.py install for pep8 17:23:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 17:23:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 17:23:49 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 17:23:49 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 17:23:49 INFO - no previously-included directories found matching 'docs/_build' 17:23:49 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 17:23:49 INFO - Successfully installed flake8 mccabe pyflakes pep8 17:23:49 INFO - Cleaning up... 17:23:49 INFO - Return code: 0 17:23:49 INFO - Running post-action listener: _resource_record_post_action 17:23:49 INFO - Running post-action listener: _start_resource_monitoring 17:23:49 INFO - Starting resource monitoring. 17:23:49 INFO - ##### 17:23:49 INFO - ##### Running install step. 17:23:49 INFO - ##### 17:23:49 INFO - Running pre-action listener: _resource_record_pre_action 17:23:49 INFO - Running main action method: install 17:23:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:23:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:23:49 INFO - Reading from file tmpfile_stdout 17:23:49 INFO - Detecting whether we're running mozinstall >=1.0... 17:23:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:23:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:23:49 INFO - Reading from file tmpfile_stdout 17:23:49 INFO - Output received: 17:23:49 INFO - Usage: mozinstall [options] installer 17:23:49 INFO - Options: 17:23:49 INFO - -h, --help show this help message and exit 17:23:49 INFO - -d DEST, --destination=DEST 17:23:49 INFO - Directory to install application into. [default: 17:23:49 INFO - "/builds/slave/test"] 17:23:49 INFO - --app=APP Application being installed. [default: firefox] 17:23:49 INFO - mkdir: /builds/slave/test/build/application 17:23:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 17:23:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 17:24:23 INFO - Reading from file tmpfile_stdout 17:24:23 INFO - Output received: 17:24:23 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:24:23 INFO - Running post-action listener: _resource_record_post_action 17:24:23 INFO - ##### 17:24:23 INFO - ##### Running run-tests step. 17:24:23 INFO - ##### 17:24:23 INFO - Running pre-action listener: _resource_record_pre_action 17:24:23 INFO - Running main action method: run_tests 17:24:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:24:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:24:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:24:23 INFO - 2016-04-18 17:24:23.509 screenresolution[2235:71841] starting screenresolution argv=screenresolution get 17:24:23 INFO - 2016-04-18 17:24:23.512 screenresolution[2235:71841] Display 0: 1600x1200x32@60 17:24:23 INFO - 2016-04-18 17:24:23.518 screenresolution[2236:71843] starting screenresolution argv=screenresolution list 17:24:23 INFO - Available Modes on Display 0 17:24:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:24:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:24:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:24:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:24:23 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:24:23 INFO - Intel Iris: 17:24:23 INFO - Chipset Model: Intel Iris 17:24:23 INFO - Type: GPU 17:24:23 INFO - Bus: Built-In 17:24:23 INFO - VRAM (Dynamic, Max): 1536 MB 17:24:23 INFO - Vendor: Intel (0x8086) 17:24:23 INFO - Device ID: 0x0a2e 17:24:23 INFO - Revision ID: 0x0009 17:24:23 INFO - Displays: 17:24:23 INFO - Display: 17:24:23 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:24:23 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:24:23 INFO - Display Serial Number: 1600x1200 60 17:24:23 INFO - Main Display: Yes 17:24:23 INFO - Mirror: Off 17:24:23 INFO - Online: Yes 17:24:23 INFO - Rotation: Supported 17:24:23 INFO - Return code: 0 17:24:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:24:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:24:23 INFO - Python 2.7.3 17:24:23 INFO - Return code: 0 17:24:23 INFO - grabbing minidump binary from tooltool 17:24:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 17:24:23 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 0x10aa68e00>, '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 0x10a891db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10aa63b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:24:23 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 17:24:23 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 17:24:23 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:24:23 INFO - Return code: 0 17:24:23 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:24:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:24:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:24:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:24:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:24:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 17:24:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 17:24:25 INFO - Return code: 0 17:24:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NNKBqzhKSlaM2ttH2Q8QUQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0080', '--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 17:24:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NNKBqzhKSlaM2ttH2Q8QUQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0080 --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 17:24:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.d10dE1jgQY/Render', 17:24:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:24:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:24:25 INFO - 'HOME': '/Users/cltbld', 17:24:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:24:25 INFO - 'LOGNAME': 'cltbld', 17:24:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:24:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:24:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:24:25 INFO - 'MOZ_NO_REMOTE': '1', 17:24:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:24:25 INFO - 'NO_EM_RESTART': '1', 17:24:25 INFO - 'PAGER': '/bin/cat', 17:24:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:24:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:24:25 INFO - 'PWD': '/builds/slave/test', 17:24:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:24:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:24:25 INFO - 'SHELL': '/bin/bash', 17:24:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.CiVQQgOTzQ/Listeners', 17:24:25 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 17:24:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:24:25 INFO - 'USER': 'cltbld', 17:24:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:24:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:24:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:24:25 INFO - 'XPC_FLAGS': '0x0', 17:24:25 INFO - 'XPC_SERVICE_NAME': '0', 17:24:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:24:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NNKBqzhKSlaM2ttH2Q8QUQ/artifacts/public/build/firefox-48.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0080', '--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 17:24:25 INFO - mozversion application_buildid: 20160418150822 17:24:25 INFO - mozversion application_changeset: f128e25d78d3e0633a4f668a4485e8f4b8a25549 17:24:25 INFO - mozversion application_display_name: Nightly 17:24:25 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:24:25 INFO - mozversion application_name: Firefox 17:24:25 INFO - mozversion application_remotingname: firefox 17:24:25 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 17:24:25 INFO - mozversion application_vendor: Mozilla 17:24:25 INFO - mozversion application_version: 48.0a1 17:24:25 INFO - mozversion platform_buildid: 20160418150822 17:24:25 INFO - mozversion platform_changeset: f128e25d78d3e0633a4f668a4485e8f4b8a25549 17:24:25 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 17:24:25 INFO - mozversion platform_version: 48.0a1 17:24:25 INFO - using testdate: 1461025465 17:24:25 INFO - actual date: 1461025465 17:24:25 INFO - starting webserver on 'localhost:54359' 17:24:25 INFO - SUITE-START | Running 2 tests 17:24:25 INFO - TEST-START | dromaeo_css 17:24:25 INFO - operating with platform_type : mac_ 17:24:25 INFO - Initialising browser for dromaeo_css test... 17:24:25 INFO - TEST-INFO | started process 2254 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/tmpSC29Nh/profile http://localhost:54359/getInfo.html) 17:24:27 INFO - PROCESS | 2254 | __metrics Screen width/height:1600/1200 17:24:27 INFO - PROCESS | 2254 | colorDepth:24 17:24:27 INFO - PROCESS | 2254 | Browser inner width/height: 1024/689 17:24:27 INFO - PROCESS | 2254 | __metrics 17:24:27 INFO - PROCESS | 2254 | 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:24:27 ERROR - PROCESS | 2254 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 17:24:28 INFO - TEST-INFO | 2254: exit 0 17:24:28 INFO - Browser initialized. 17:24:28 INFO - Running cycle 1/1 for dromaeo_css test... 17:24:28 INFO - TEST-INFO | started process 2256 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/tmpSC29Nh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:24:39 INFO - PROCESS | 2256 | RSS: Main: 237522944 17:24:39 INFO - PROCESS | 2256 | 17:25:31 INFO - PROCESS | 2256 | 1461025531678 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] 17:27:41 INFO - PROCESS | 2256 | Cycle 1(1): loaded http://localhost:54359/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54359/tests/dromaeo/cssquery-ext.html) 17:27:43 INFO - PROCESS | 2256 | RSS: Main: 365268992 17:27:43 INFO - PROCESS | 2256 | 17:30:46 INFO - PROCESS | 2256 | Cycle 1(1): loaded http://localhost:54359/tests/dromaeo/cssquery-ext.html (next: http://localhost:54359/tests/dromaeo/cssquery-jquery.html) 17:30:47 INFO - PROCESS | 2256 | RSS: Main: 370688000 17:30:47 INFO - PROCESS | 2256 | 17:33:49 INFO - PROCESS | 2256 | Cycle 1(1): loaded http://localhost:54359/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54359/tests/dromaeo/cssquery-mootools.html) 17:33:49 INFO - PROCESS | 2256 | RSS: Main: 376762368 17:33:49 INFO - PROCESS | 2256 | 17:36:52 INFO - PROCESS | 2256 | Cycle 1(1): loaded http://localhost:54359/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54359/tests/dromaeo/cssquery-prototype.html) 17:36:52 INFO - PROCESS | 2256 | RSS: Main: 377925632 17:36:52 INFO - PROCESS | 2256 | 17:39:56 INFO - PROCESS | 2256 | Cycle 1(1): loaded http://localhost:54359/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54359/tests/dromaeo/cssquery-yui.html) 17:39:56 INFO - PROCESS | 2256 | RSS: Main: 346861568 17:39:56 INFO - PROCESS | 2256 | 17:42:59 INFO - PROCESS | 2256 | Cycle 1(1): loaded http://localhost:54359/tests/dromaeo/cssquery-yui.html (next: http://localhost:54359/tests/dromaeo/cssquery-dojo.html) 17:42:59 INFO - PROCESS | 2256 | RSS: Main: 392998912 17:42:59 INFO - PROCESS | 2256 | 17:42:59 INFO - PROCESS | 2256 | __start_tp_report 17:42:59 INFO - PROCESS | 2256 | _x_x_mozilla_page_load 17:42:59 INFO - PROCESS | 2256 | _x_x_mozilla_page_load_details 17:42:59 INFO - PROCESS | 2256 | |i|pagename|runs| 17:42:59 INFO - PROCESS | 2256 | |0;dojo.html;3390.0507857799817;3457.8962631121058;3465.306938612275;3492.91267718307;3525.206828463596;5685.829425117243;5759.222504961829;5789.044807606745;5794.130880367941;5796.594238403308;5711.914321285174;5749.59752817303;5756.079027355624;5759.654420734749;5765.3369862465825;5210.2445145453885;5237;5256.684598924057;5280.683159010472;5323.361196656407;5324.015057214408;5332.306800115979;5334.359876814788;5344.412114667383;5345.278387417708;5248.0815857225025;5306.416294207634;5307.177387504924;5325.680459172443;5395.028894798935;5294.655847369909;5308.336457942757;5310.893782124374;5319.867003324909;5348.304720386363;5089.262057001751;5240.161574148156;5242.79028838846;5266.499682530153;5272.182811664198;5630.15547667849;5650.6892120933335;5664.971675141598;5718.571107166979;5732.713364331808;6246.6876656167005;6274.937250627527;6283.528735344822;6304.558680892339;6306.527010474233;5288.81489147878;6274.278457977345;6281.371862813682;6284.905726414107;6297.748090076391;5717.599768016241;5826.44648758363;5831.533477321804;5901.704914754245;5935.109733539917;6268.583865842609;6302.8424289392215;6339;6361.777337793155;6364.172657554488;6237;6237;6241.21984751906;6244.000959846494;6245.375462453809;6144.972159187458;6221.06683997404;6275.905861412082;6300.905486417708;6317.3366796486625;6214.4407003369915;6248.968755156286;6271.843203919939;6272.1219029335925;6274.341194174637;6145.907811382833;6281.214848143982;6286.088517164985;6298.937010629835;6309.747610095545;4910.680805747615;4930.778114984762;4970.726610036482;5016.698998060128;5024.145895197752;4376.059147283349;4433.113377324548;4433.290673492225;4447.177272204653;4457.04173602677;5637.323521177403;5692.800751973744;5726.885462290814;5735.082386818172;5771.278590176228;6199.659018754011;6215.627062376272;6270.780522681775;6275.811725648238;6293.937060629335;5702.942970570324;5703.771849125988;5715.31416230055;5734.025215713339;5743.655380677173;5321.73391330432;5411.810586629449;5417.187421886749;5419.972900135474;5420.430854760272;6260.217472815898;6313.674128432896;6378.2983871774995;6470.28826829039;6476.12572302752;5459.481349271813;6187.093984383176;6192.225971753531;6244.375562443719;6260;6023.5181185505935;6430.7749228776765;6461.450776683847;6474.4820414365795;6488.351164883474;5084.313617661568;6100.207047561449;6179.536534759821;6220.253569571681;6240.500759939285;4929.482404347492;5256.710880901587;6138.785142519991;6287.937120628735;6295.803797278686;6179.443850053517;6180.598261113013;6190.938090619216;6192.566520343533;6193.225846769154;5978.133170690313;6127.14220009208;6153.107799369156;6163.3528479509005;6197.4732147767945;6021.849453763691;6065.522372461252;6067.848303792441;6075.513958883367;6136.631802091889;6000.609960352563;6193.938060619336;6214.5028397727165;6214.5960512566535;6223.688815559113;5927.377625349276;5976.551758618208;5988.700564971821;5997.860406522746;6001.3698561652145 17:42:59 INFO - PROCESS | 2256 | |1;ext.html;497.4453484364934;497.46522488325047;499.4181778228364;502.5728131088573;532.4489153725892;29897.953571624996;31062;38188.99999999996;39083;39096.609033909655;41251.96870078242;41405.96485087867;41696.79151604238;41775.58224417755;41807.790961045226;9361.812763744738;9632.036796320383;9633.325667203264;9634.855477167848;9655.565499552536;8613.655453781841;8654.187389098568;8746.256568191711;8893.199612034916;8909.688160914337;11499.367534785584;11841.467133978991;11878.7624247515;11880.940595297012;11893.286402815815;11273.830892536578;11386.658400248005;11422.257553259094;11426.257293275994;11429.828552571718;7460.552366858006;11320.660380188607;11447.370394628291;11592.768144637144;11725.648230553097;37915.62084379148;38418.61581384178;38435.807820960996;38457;38538.80730596357;3139.2779660677943;3172.1752344390393;3191.9680803191904;3265.6407795142513;3365.9902029391083;26388.86805565979;27719;27750.583741243896;28255.43489130206;28330.716692833015;2726.768224700903;2759.3377589378606;2806.5930440086277;2887.13385984206;2943.1759107449734;448302;449191;450143.7492812515;450162.74918625195;450204;63750;64125.35874641194;64192;64204.18468614779;64266;63987;63998;64033;64076.35923640704;64154.6792266045;15114.773278400833;16503.422380216634;16708.41520546775;16711.080890551137;16712;11636;11689.824652630217;11707;12698.30159341245;13043.673908152372;14168.64578385528;15824.892257541862;16008.359665613478;17142.657146857244;17339.21973511195;6554.967225163844;6613.603183808987;6743.897807008831;6768.661566921733;6773.153355830521;810.546094187257;832.5421018439833;982.2093214961882;1078.8273876179774;1154.630518234157;24636.876815615808;28185.731642076156;28418.857905710753;28590.714092858805;28649.427011459655;934.2105920497169;1102.4322473925934;1120.6638008597388;1140.2246472398683;1226.5952235762177;15275.541733748005;15320.770188447183;15397.307121179574;15457.227138643248;15561.377544898078;609203;615631.921840388;616325;616354;617042;57067.71466142643;58350.70824645935;58554.70722646359;58592.12111818326;58719;1325.7613629546775;1374.8693874081946;1450.158907833475;1484.2282013352997;1509.7131545006414;28414.00550980706;28534.714652853207;29326.706732932398;29599.26001849971;29927.700722992493;49638.050672906546;50247.74380640513;50307.74846125746;50374;50411;370884;427084;427465;428768;429119;34238.97283081512;38312.616873830906;38817.6118238814;39538.6622805929;39620.801895990335;40941.38587921243;41380.58619413767;44932;45103.7744811287;45245.321320179566;35960.92117236487;36706.4494032595;37162.25675486421;37482.812585936896;37580.624193757714;35184.824075879456;35378.93863184108;35643.82178089093;36056;36071;34251.14372140716;34549;34696.479552807235;35200.47199292064;35354;30567.694323056483;31433.21416964594;31824.36351273008;32428.67571324351;32525.674743252264;23869.761302386753;24260.1508947186;24381.39046523851;24572.771361432213;24644.630330545413 17:42:59 INFO - PROCESS | 2256 | |2;jquery.html;399.2135493078635;399.6463130129835;400.3714168755052;400.7635495057915;413.36961134270234;13505.839065749482;18558.814411855878;19570.70643940342;19576.804231957693;19920.203191872344;19940.202391904306;20237.59524809503;21409.67885481719;21522.56954860902;21737.782622173774;4818.277258411232;6186;6190.195274614294;6190.876182476348;6197.194364732579;5265.841514866724;6074.301455332649;6185.845353866167;6194.473469755077;6749.156355455568;6652.93347066528;6679.732810687567;6691.498137639672;6702;6717.596944183365;5645.266115405012;6020.488258498033;6033.637981721111;6047.909281360783;6664.067030615718;5554.944450555483;5810.680412577307;5811.506021988136;5817.650940943515;5825.980453420634;24607.63088553673;25333.61999570008;25352;25508.744912551007;25937.481250375076;2161.297578287047;2275.385645875621;2311.4221444638843;2317.860928344288;2601.752833480816;9322.48726320052;9359.438433694;9445.574949127305;9840.212782977345;10228.130608898253;1707.6072503324306;1718.3556166437586;1723.0953749281603;1725.9482215533428;1771.3888708395712;109810.45094774554;114034.42982785116;114122;114948.2757758626;115015;9448.858267126;11697;11702;11749.706257343634;11904.345261010554;13611.319434028457;14185.858141418454;14255.78616320756;15238.238088095552;15247.23763811805;8436.873446898302;8788.8681669775;8852.513111778913;8933.28533717301;9006.819863602692;7554.715698331187;8618.956905215558;8663.610137543825;8777.034526202113;8777.122287771299;4475.328700694922;4707.976460117678;4709.387779588632;4718.976405117953;4768.3085952537385;1754.754334393186;1911.6176764647125;1920.26216274272;1973.1712680673863;1984.186483541741;907.5235501361706;963.5375019990444;988.7972965542046;1013.7009582173276;1058.6030238660503;20171;20551.383458496268;20711.275105371238;21108.577828443344;21216.151353945977;874.6501399440148;913.8263729891299;932.7574830543972;938.5213541094091;945.8061097475002;10847.895796564604;10873;11798.941005294919;11899.107566932515;11927.463264153133;603974;611303;616924;619440;621978;83628.58185709032;88261.55869220741;129741;131158.68841311464;133207;974.1670871404989;1051.3166441812882;1052.826283663187;1058.846467262258;1074.752806854428;18376.63246735031;18503.167357468676;18579.16393762311;18590.721139183195;18592.7211091831;41209;43437.13125737404;44546.77726611346;47810.857270984445;50659.49340506548;75568.24431755612;76164.61917690375;77778;78991.60504197443;80016.19983800087;6439.8068057958335;7841.176676448891;8028.514724775935;8100.83798324042;8370.455920365157;9172.587233574639;11830.704232394259;12497.000239980962;12875.742485150431;12918.966482681551;9502.85745713801;10648.89351106479;10696.62561810333;10795.784084318113;11144.721381965515;10416.375017498975;10494.10800081971;10588.417637030037;10913.836292455779;11207.66377008691;10377.896221037692;10507.527161277609;11336.716582085513;11571.958523732905;11610.303381797119;8187.549684767157;8191.262786349257;8350.123237060021;8420.873686894824;8484.236418722097;9632.95183524078;10245.897541024495;10644.787104258026;11105.944470277596;11437 17:42:59 INFO - PROCESS | 2256 | |3;mootools.html;1354.7900075488303;1585.2153184173835;1605.9116748578826;1865.7294692269622;1994.4914046918025;7192.244814294502;7998.200179981999;8845.690400835972;8870.645174193025;8881.555922203888;8472.703455379064;8705.651773929036;8708.21626053655;8713.390062695615;8724.43291186074;2437.0495506752336;2465.0509553821817;2467.7655622715815;2468.4816188600416;2472.925812225636;2430.9513809723794;2488.688913885764;2496.4383013821835;2502.099244272061;2504.611785173301;2434.026389444228;2460.2619214235747;2481.1192026830395;2481.6029435290357;2497.313238859319;2420.987895060522;2445.40087678519;2454.7299797022315;2477.467344520937;2481.019997101135;2425.4057755849753;2454.9754502454925;2464.9014039438403;2468.136152346682;2472.2336075816374;7059.682314295869;8652.394332396734;8798.296136309144;8816.426932249447;8827.293816494664;5227.242049902781;5447.210154527611;5450.8909821803345;5453.072977593819;5474.987127381447;3443.7589368744198;3465.5148279240925;3531.382008148589;3556.751027428081;3564.055525285801;852.6802449081595;1010.6765834932825;1011.1405305490348;1019.9592016319339;1040.9739756506071;11411.942940285246;11745.941270293679;11746.353950532717;11752.764944701144;11779.234349767237;4542.205114105019;4551.180787458289;4679.9298010529865;4684.414448193976;4760.214564596803;9183.219426348835;9195.90804091964;9207.907920920705;9208.401453905475;9209.309301802392;4733.816545863534;4831.637627177975;5261.3686357637325;5270.156774915995;5275.525202731696;3428.3486137633768;4460.464744230648;4611.631069514431;4854.417469903634;4942.258661200849;3739.439084137401;3804.6766024888193;3823.6941044716355;3837.0599203195397;3848.4804551385205;1210.5883999440232;1213.4478812140455;1214.3806658603999;1223.8776122387867;1231.5812623707802;811.5779794506824;846.3695978509935;892.3262936482914;910.7996240827008;943.7640589852537;5652.97173514138;5764.538836893039;5866.2960444746905;5917.112433134978;6000.799840032011;880.1198801198801;912.2656261709255;926.0461724423855;937.5593471068587;963.1861077389734;3673.0633688409284;3716.108134047809;3878.2825177342283;3899.3854879465475;3930.449737036817;10280;10563.47182640865;10757.731056723487;10764.946175269073;10767.19246056549;10694.62568810088;10839.729006774895;10847.349159050482;10847.783044339069;10876.347419154876;7797.805054873674;8172.713955011546;8249.876251856227;8347.749567512985;8359.623816928253;5773.336066352424;5848.859472402841;5967.283925928743;6028.367021462684;6076.696165191811;8320.916790832014;9638.855417168608;9829.655962041294;9954.402735835873;10137.493125343852;8644.654213831376;9227.907720922705;10101.98980101984;10213.029762172582;10331.638392656221;6221.47117495018;6550.984597387413;6899.068625735662;7167.3907717842485;7171.175314838861;8285.751427457186;8384.32925365957;8390.24487796077;8407.159284071544;8422.83154336922;7177.712891484282;7402.777916662509;7418.851622967619;7539.736109236151;7565.6973721050545;7312.707491700486;7321.853562928605;7387.445941554298;7393.408527317694;7454.142773581108;6685.6322902240845;6820.420264277592;6826;6838.623875686885;6840.042394064735;4690.202665546797;4757;4781.832635857728;4857.757112144449;4964.553190212898;4328.956710432855;4481.641468682433;4788.928166077442;4857.101436234308;5063.974680126576 17:42:59 INFO - PROCESS | 2256 | |4;prototype.html;316.90492852144365;317.1073428299337;323.422690497462;327.18694045297434;331.3852803050343;5076.9084646800975;5846.0061789495785;5920.703964801759;5937.58436909416;5980.700964951757;4911.435184953732;5100.387953445592;5879.206307148534;5900.43945825146;5914.852128696784;3156.5738625285635;4193.433886425331;4376.781160941956;4476.910461790762;4526.117407105616;3602.3695853225713;4360.345948107777;4491.842785502508;4500.369948207253;4500.752458614775;3666.3400587894166;4492.415985921842;4493.46078470584;4497.751124437781;4540.682152249344;4182.184474027551;4221.176870510251;4385.561443855589;4427.203103441379;4460.8884777880485;3271.4765637497885;3439.7420193485586;3842.0405593147416;3856.6529012388737;3878.168936676242;4725;4729.50340214276;4751.572358487718;5004.570954705076;5768.625039372428;4595.149897269016;4824.852940441505;4895.657303988723;5189.138455240314;5198.324217851655;5573.219749235111;5830.650160990312;6286.779962701329;6292.653904035276;6298.0951809877715;3632.673059424638;3753.2681127180213;4084.326086195774;4370.2570563004365;4374.934375984363;5491.533219676332;6297.590656607306;6608.9339106608795;6644.368784965421;6664.93335066648;4056.634902858757;4063.309237429644;4386.232409328355;4416.624586910084;4461.107778444324;4456.732596044248;4474.082813023322;4474.865754027384;4502.3246513023305;4514.841980530666;4471.910561788746;4900.387451568554;5087.592992560586;5194.740287974743;5214.348206474191;5150.574867712929;5759.712014399263;6295.96852015737;6319.33646967071;6355.362524552352;1472.9263536823116;1529.839366866463;1550.6743583847292;1557.3381312941954;1560.8907376483764;917.5823352919755;955.8948515663271;984.5520288268821;1011.3931641015479;1035.254616675992;4903.754812259373;5693.491224825425;5848.4151584841175;5852.356240813507;5864.70676466175;6545.345465453512;6572.408483236436;6573.924167016385;6583.572067815577;6669.233038200669;5054.671446355975;5811.832551259574;5843.883122337529;5852.678102704307;5892.970535147297;3022.259546411143;3146.2134466383404;3397.473391624302;3458.6195518492946;3459.7405194610506;5731.656100633975;6464.644444555594;6802.863942721118;6860.5197636164985;6922.238553759083;6409.647469389135;6618.669066546653;6666.433353165035;6669.466442684574;6725.226598941184;5737.741801618937;6397;6813.659317034128;6820;6832.624205668736;6563.835904102436;6569.540132190701;6570.901436478553;6593.3736295051895;6635.270120286668;5685.204071430086;5693;6301.936980630135;6498.588241173422;6511.413972742286;5576.86057848557;6534.771283005072;6630.204375474781;6829.658517074027;6875.621840798604;3600.4779307000863;4162.688753042782;4187.183499217624;4256.851010214627;4269.359596060615;5368.812091576776;5727.627704199213;5823.854403639943;6322.430981211529;6527.836804079936;5351.929614077278;5746.942530574641;6036.517078633787;6280.560360774702;6364.968175159094;5871.882562348644;6329.525285603506;6361.332060133805;6362.554621176473;6369.90445143314;5697.515711164598;5878.559108066826;5983.700814959322;6073.969630151821;6216.813495595139;6261.843453913689;6296.842578935563;6320.5891617044745;6359.554831161774;6411.006294024433;5221.843344699665;5837.328707198726;6216.974199257253;6221.626702397871;6224.657643829632 17:42:59 INFO - PROCESS | 2256 | |5;yui.html;604.4197570332481;647.7635662983013;677.2313424263463;719.9028131202286;731.5245090691053;14669;14887.627809304773;15119.470818521353;15166.2416879156;15773.290201940912;14392.856071439282;14468.782968255458;14500.782488262657;14511.854881451209;14518.927405362958;741.3365038290726;761.9657115429819;900.6127365232979;931.4550987672203;940.6237504998021;927.3462209142539;935.6584846531035;936.489613160829;938.6620816506087;938.8403971324857;988.3723835364559;988.658912675126;989.1789814454022;991.2317953585956;991.8660980767576;759.4190444310079;939.0281059103863;988.980220395588;993.1657407777501;995.5320999130397;722.6495149852317;726.3462883404925;726.5676922231264;727.058459295214;728.2389902551846;12488.687782805411;15118.39526418942;15119.54641360761;15129.773053404208;15161.696766064617;12228.816567751492;12374.381280936008;12379.876201238052;12416.130870839044;12734.299613521252;8270.83458330836;9424.387414818015;9624.422534647943;9679.516024198761;10110.848337274947;4018.9397159042637;4027.597240275978;4090.8568200112854;4103.794810259505;4128.256913755523;80858.19141808565;81621.18378816196;81865.59067204625;82003.58998204971;82202;9246.121618446372;9260.629574817067;9344.2524598032;9454.810903781887;9844.753881153028;21837.45406364822;21839.344819655435;21850.12599496034;21878.23426180108;21882.452938676342;7226.74706385274;7246.782596522007;7259.89110163348;7326.120865496175;7332.376747976375;3343.063942096217;3741.607131251234;3898.785566793797;3901.31726947783;3925.8822235332987;3457.4986626938953;3597.2805438912324;3598.2443686825536;3599.766015209003;3606.1886075632774;539.5899116671279;639.6897504710212;651.3031056769212;659.8713250916121;708.125465050662;490.872373182975;582.9358770535238;604.4759054711025;609.7378127405169;613.4846728747873;6569.441597464173;7515.924840751522;7842.137364889858;8171;8463.407561470762;558.4025093150372;563.0681222576642;587.1938185948029;589.3664310865836;597.2534332084894;3435.793852368866;3770.5663848657205;3791.431285307171;3805.5243094613174;3899.454076429302;93700.06299936914;94240.46076313878;97586;98975;99075;59238.70380648069;79343;80171.59914200509;80210.5989470049;80305;68059.65970170118;70589;71003;71494.9275760864;72135.27864721391;7509.1739908608915;7626.10068200103;7669.769906902802;7670.463067585216;7766.495177813424;37674.05814854651;42145.78927105345;43650;44280;44869.55130448654;61599.692001539705;62249.68875155595;64931.67534162299;66268;66455.66772166108;10235.283530152818;10380.688579342632;10532.473376331305;10567.63013294531;10584.153267738408;10321.122704570196;10509.211809114195;10523.368597884151;10569.26015178961;11584.42077896085;10241.436720980417;10425.739356516147;10624.787504250025;10934.179936504635;11197.552097916318;9152.764376809311;10153.746156346151;10649.627263045757;10663.89336106629;10683.57265709363;9555.808883822423;10198.031187037219;10333.431661258703;10581.788364232518;10605.787884242427;8667.17661822126;8814.074522175079;8815.955920220358;8835.293176545989;8935.553222338987;8775.254103401026;8777.166169213915;8880.289576833968;9110.407823491452;9138.040505746802 17:42:59 INFO - PROCESS | 2256 | __end_tp_report 17:42:59 INFO - PROCESS | 2256 | __start_cc_report 17:42:59 INFO - PROCESS | 2256 | _x_x_mozilla_cycle_collect,1274 17:42:59 INFO - PROCESS | 2256 | __end_cc_report 17:42:59 INFO - PROCESS | 2256 | __startTimestamp1461026579634__endTimestamp 17:42:59 INFO - PROCESS | 2256 | 17:42:59 INFO - PROCESS | 2256 | ------- Summary: start ------- 17:42:59 INFO - PROCESS | 2256 | Number of tests: 6 17:42:59 INFO - PROCESS | 2256 | 17:42:59 INFO - PROCESS | 2256 | [#0] dojo.html Cycles:165 Average:5765.13 Median:5978.13 stddev:635.09 (10.6%) stddev-sans-first:609.10 17:42:59 INFO - PROCESS | 2256 | Values: 3390.1 3457.9 3465.3 3492.9 3525.2 5685.8 5759.2 5789.0 5794.1 5796.6 5711.9 5749.6 5756.1 5759.7 5765.3 5210.2 5237.0 5256.7 5280.7 5323.4 5324.0 5332.3 5334.4 5344.4 5345.3 5248.1 5306.4 5307.2 5325.7 5395.0 5294.7 5308.3 5310.9 5319.9 5348.3 5089.3 5240.2 5242.8 5266.5 5272.2 5630.2 5650.7 5665.0 5718.6 5732.7 6246.7 6274.9 6283.5 6304.6 6306.5 5288.8 6274.3 6281.4 6284.9 6297.7 5717.6 5826.4 5831.5 5901.7 5935.1 6268.6 6302.8 6339.0 6361.8 6364.2 6237.0 6237.0 6241.2 6244.0 6245.4 6145.0 6221.1 6275.9 6300.9 6317.3 6214.4 6249.0 6271.8 6272.1 6274.3 6145.9 6281.2 6286.1 6298.9 6309.7 4910.7 4930.8 4970.7 5016.7 5024.1 4376.1 4433.1 4433.3 4447.2 4457.0 5637.3 5692.8 5726.9 5735.1 5771.3 6199.7 6215.6 6270.8 6275.8 6293.9 5702.9 5703.8 5715.3 5734.0 5743.7 5321.7 5411.8 5417.2 5420.0 5420.4 6260.2 6313.7 6378.3 6470.3 6476.1 5459.5 6187.1 6192.2 6244.4 6260.0 6023.5 6430.8 6461.5 6474.5 6488.4 5084.3 6100.2 6179.5 6220.3 6240.5 4929.5 5256.7 6138.8 6287.9 6295.8 6179.4 6180.6 6190.9 6192.6 6193.2 5978.1 6127.1 6153.1 6163.4 6197.5 6021.8 6065.5 6067.8 6075.5 6136.6 6000.6 6193.9 6214.5 6214.6 6223.7 5927.4 5976.6 5988.7 5997.9 6001.4 17:42:59 INFO - PROCESS | 2256 | 17:42:59 INFO - PROCESS | 2256 | [#1] ext.html Cycles:180 Average:63664.52 Median:35802.37 stddev:133686.13 (373.4%) stddev-sans-first:133977.03 17:42:59 INFO - PROCESS | 2256 | Values: 497.4 497.5 499.4 502.6 532.4 29898.0 31062.0 38189.0 39083.0 39096.6 41252.0 41406.0 41696.8 41775.6 41807.8 9361.8 9632.0 9633.3 9634.9 9655.6 8613.7 8654.2 8746.3 8893.2 8909.7 11499.4 11841.5 11878.8 11880.9 11893.3 11273.8 11386.7 11422.3 11426.3 11429.8 7460.6 11320.7 11447.4 11592.8 11725.6 37915.6 38418.6 38435.8 38457.0 38538.8 3139.3 3172.2 3192.0 3265.6 3366.0 26388.9 27719.0 27750.6 28255.4 28330.7 2726.8 2759.3 2806.6 2887.1 2943.2 448302.0 449191.0 450143.7 450162.7 450204.0 63750.0 64125.4 64192.0 64204.2 64266.0 63987.0 63998.0 64033.0 64076.4 64154.7 15114.8 16503.4 16708.4 16711.1 16712.0 11636.0 11689.8 11707.0 12698.3 13043.7 14168.6 15824.9 16008.4 17142.7 17339.2 6555.0 6613.6 6743.9 6768.7 6773.2 810.5 832.5 982.2 1078.8 1154.6 24636.9 28185.7 28418.9 28590.7 28649.4 934.2 1102.4 1120.7 1140.2 1226.6 15275.5 15320.8 15397.3 15457.2 15561.4 609203.0 615631.9 616325.0 616354.0 617042.0 57067.7 58350.7 58554.7 58592.1 58719.0 1325.8 1374.9 1450.2 1484.2 1509.7 28414.0 28534.7 29326.7 29599.3 29927.7 49638.1 50247.7 50307.7 50374.0 50411.0 370884.0 427084.0 427465.0 428768.0 429119.0 34239.0 38312.6 38817.6 39538.7 39620.8 40941.4 41380.6 44932.0 45103.8 45245.3 35960.9 36706.4 37162.3 37482.8 37580.6 35184.8 35378.9 35643.8 36056.0 36071.0 34251.1 34549.0 34696.5 35200.5 35354.0 30567.7 31433.2 31824.4 32428.7 32525.7 23869.8 24260.2 24381.4 24572.8 24644.6 17:42:59 INFO - PROCESS | 2256 | 17:42:59 INFO - PROCESS | 2256 | [#2] jquery.html Cycles:180 Average:34673.11 Median:1500.48 stddev:102054.20 (6801.4%) stddev-sans-first:102308.04 17:42:59 INFO - PROCESS | 2256 | Values: 399.2 399.6 400.4 400.8 413.4 13505.8 18558.8 19570.7 19576.8 19920.2 19940.2 20237.6 21409.7 21522.6 21737.8 4818.3 6186.0 6190.2 6190.9 6197.2 5265.8 6074.3 6185.8 6194.5 6749.2 6652.9 6679.7 6691.5 6702.0 6717.6 5645.3 6020.5 6033.6 6047.9 6664.1 5554.9 5810.7 5811.5 5817.7 5826.0 24607.6 25333.6 25352.0 25508.7 25937.5 2161.3 2275.4 2311.4 2317.9 2601.8 9322.5 9359.4 9445.6 9840.2 10228.1 1707.6 1718.4 1723.1 1725.9 1771.4 109810.5 114034.4 114122.0 114948.3 115015.0 9448.9 11697.0 11702.0 11749.7 11904.3 13611.3 14185.9 14255.8 15238.2 15247.2 8436.9 8788.9 8852.5 8933.3 9006.8 7554.7 8619.0 8663.6 8777.0 8777.1 4475.3 4708.0 4709.4 4719.0 4768.3 1754.8 1911.6 1920.3 1973.2 1984.2 907.5 963.5 988.8 1013.7 1058.6 20171.0 20551.4 20711.3 21108.6 21216.2 874.7 913.8 932.8 938.5 945.8 10847.9 10873.0 11798.9 11899.1 11927.5 603974.0 611303.0 616924.0 619440.0 621978.0 83628.6 88261.6 129741.0 131158.7 133207.0 974.2 1051.3 1052.8 1058.8 1074.8 18376.6 18503.2 18579.2 18590.7 18592.7 41209.0 43437.1 44546.8 47810.9 50659.5 75568.2 76164.6 77778.0 78991.6 80016.2 6439.8 7841.2 8028.5 8100.8 8370.5 9172.6 11830.7 12497.0 12875.7 12919.0 9502.9 10648.9 10696.6 10795.8 11144.7 10416.4 10494.1 10588.4 10913.8 11207.7 10377.9 10507.5 11336.7 11572.0 11610.3 8187.5 8191.3 8350.1 8420.9 8484.2 9633.0 10245.9 10644.8 11105.9 11437.0 17:42:59 INFO - PROCESS | 2256 | 17:42:59 INFO - PROCESS | 2256 | [#3] mootools.html Cycles:180 Average:5613.31 Median:4857.43 stddev:3128.96 (64.4%) stddev-sans-first:3121.36 17:42:59 INFO - PROCESS | 2256 | Values: 1354.8 1585.2 1605.9 1865.7 1994.5 7192.2 7998.2 8845.7 8870.6 8881.6 8472.7 8705.7 8708.2 8713.4 8724.4 2437.0 2465.1 2467.8 2468.5 2472.9 2431.0 2488.7 2496.4 2502.1 2504.6 2434.0 2460.3 2481.1 2481.6 2497.3 2421.0 2445.4 2454.7 2477.5 2481.0 2425.4 2455.0 2464.9 2468.1 2472.2 7059.7 8652.4 8798.3 8816.4 8827.3 5227.2 5447.2 5450.9 5453.1 5475.0 3443.8 3465.5 3531.4 3556.8 3564.1 852.7 1010.7 1011.1 1020.0 1041.0 11411.9 11745.9 11746.4 11752.8 11779.2 4542.2 4551.2 4679.9 4684.4 4760.2 9183.2 9195.9 9207.9 9208.4 9209.3 4733.8 4831.6 5261.4 5270.2 5275.5 3428.3 4460.5 4611.6 4854.4 4942.3 3739.4 3804.7 3823.7 3837.1 3848.5 1210.6 1213.4 1214.4 1223.9 1231.6 811.6 846.4 892.3 910.8 943.8 5653.0 5764.5 5866.3 5917.1 6000.8 880.1 912.3 926.0 937.6 963.2 3673.1 3716.1 3878.3 3899.4 3930.4 10280.0 10563.5 10757.7 10764.9 10767.2 10694.6 10839.7 10847.3 10847.8 10876.3 7797.8 8172.7 8249.9 8347.7 8359.6 5773.3 5848.9 5967.3 6028.4 6076.7 8320.9 9638.9 9829.7 9954.4 10137.5 8644.7 9227.9 10102.0 10213.0 10331.6 6221.5 6551.0 6899.1 7167.4 7171.2 8285.8 8384.3 8390.2 8407.2 8422.8 7177.7 7402.8 7418.9 7539.7 7565.7 7312.7 7321.9 7387.4 7393.4 7454.1 6685.6 6820.4 6826.0 6838.6 6840.0 4690.2 4757.0 4781.8 4857.8 4964.6 4329.0 4481.6 4788.9 4857.1 5064.0 17:42:59 INFO - PROCESS | 2256 | 17:42:59 INFO - PROCESS | 2256 | [#4] prototype.html Cycles:180 Average:5044.90 Median:5695.50 stddev:1596.58 (28.0%) stddev-sans-first:1561.12 17:42:59 INFO - PROCESS | 2256 | Values: 316.9 317.1 323.4 327.2 331.4 5076.9 5846.0 5920.7 5937.6 5980.7 4911.4 5100.4 5879.2 5900.4 5914.9 3156.6 4193.4 4376.8 4476.9 4526.1 3602.4 4360.3 4491.8 4500.4 4500.8 3666.3 4492.4 4493.5 4497.8 4540.7 4182.2 4221.2 4385.6 4427.2 4460.9 3271.5 3439.7 3842.0 3856.7 3878.2 4725.0 4729.5 4751.6 5004.6 5768.6 4595.1 4824.9 4895.7 5189.1 5198.3 5573.2 5830.7 6286.8 6292.7 6298.1 3632.7 3753.3 4084.3 4370.3 4374.9 5491.5 6297.6 6608.9 6644.4 6664.9 4056.6 4063.3 4386.2 4416.6 4461.1 4456.7 4474.1 4474.9 4502.3 4514.8 4471.9 4900.4 5087.6 5194.7 5214.3 5150.6 5759.7 6296.0 6319.3 6355.4 1472.9 1529.8 1550.7 1557.3 1560.9 917.6 955.9 984.6 1011.4 1035.3 4903.8 5693.5 5848.4 5852.4 5864.7 6545.3 6572.4 6573.9 6583.6 6669.2 5054.7 5811.8 5843.9 5852.7 5893.0 3022.3 3146.2 3397.5 3458.6 3459.7 5731.7 6464.6 6802.9 6860.5 6922.2 6409.6 6618.7 6666.4 6669.5 6725.2 5737.7 6397.0 6813.7 6820.0 6832.6 6563.8 6569.5 6570.9 6593.4 6635.3 5685.2 5693.0 6301.9 6498.6 6511.4 5576.9 6534.8 6630.2 6829.7 6875.6 3600.5 4162.7 4187.2 4256.9 4269.4 5368.8 5727.6 5823.9 6322.4 6527.8 5351.9 5746.9 6036.5 6280.6 6365.0 5871.9 6329.5 6361.3 6362.6 6369.9 5697.5 5878.6 5983.7 6074.0 6216.8 6261.8 6296.8 6320.6 6359.6 6411.0 5221.8 5837.3 6217.0 6221.6 6224.7 17:42:59 INFO - PROCESS | 2256 | 17:42:59 INFO - PROCESS | 2256 | [#5] yui.html Cycles:180 Average:17886.63 Median:32789.72 stddev:25726.29 (78.5%) stddev-sans-first:25765.73 17:42:59 INFO - PROCESS | 2256 | Values: 604.4 647.8 677.2 719.9 731.5 14669.0 14887.6 15119.5 15166.2 15773.3 14392.9 14468.8 14500.8 14511.9 14518.9 741.3 762.0 900.6 931.5 940.6 927.3 935.7 936.5 938.7 938.8 988.4 988.7 989.2 991.2 991.9 759.4 939.0 989.0 993.2 995.5 722.6 726.3 726.6 727.1 728.2 12488.7 15118.4 15119.5 15129.8 15161.7 12228.8 12374.4 12379.9 12416.1 12734.3 8270.8 9424.4 9624.4 9679.5 10110.8 4018.9 4027.6 4090.9 4103.8 4128.3 80858.2 81621.2 81865.6 82003.6 82202.0 9246.1 9260.6 9344.3 9454.8 9844.8 21837.5 21839.3 21850.1 21878.2 21882.5 7226.7 7246.8 7259.9 7326.1 7332.4 3343.1 3741.6 3898.8 3901.3 3925.9 3457.5 3597.3 3598.2 3599.8 3606.2 539.6 639.7 651.3 659.9 708.1 490.9 582.9 604.5 609.7 613.5 6569.4 7515.9 7842.1 8171.0 8463.4 558.4 563.1 587.2 589.4 597.3 3435.8 3770.6 3791.4 3805.5 3899.5 93700.1 94240.5 97586.0 98975.0 99075.0 59238.7 79343.0 80171.6 80210.6 80305.0 68059.7 70589.0 71003.0 71494.9 72135.3 7509.2 7626.1 7669.8 7670.5 7766.5 37674.1 42145.8 43650.0 44280.0 44869.6 61599.7 62249.7 64931.7 66268.0 66455.7 10235.3 10380.7 10532.5 10567.6 10584.2 10321.1 10509.2 10523.4 10569.3 11584.4 10241.4 10425.7 10624.8 10934.2 11197.6 9152.8 10153.7 10649.6 10663.9 10683.6 9555.8 10198.0 10333.4 10581.8 10605.8 8667.2 8814.1 8816.0 8835.3 8935.6 8775.3 8777.2 8880.3 9110.4 9138.0 17:42:59 INFO - PROCESS | 2256 | -------- Summary: end -------- 17:42:59 INFO - PROCESS | 2256 | 17:43:00 INFO - TEST-INFO | 2256: exit 0 17:43:00 INFO - TEST-OK | dromaeo_css | took 1114938ms 17:43:00 INFO - TEST-START | kraken 17:43:00 INFO - operating with platform_type : mac_ 17:43:00 INFO - Initialising browser for kraken test... 17:43:00 INFO - TEST-INFO | started process 2259 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/tmp_j704s/profile http://localhost:54359/getInfo.html) 17:43:02 INFO - PROCESS | 2259 | __metrics Screen width/height:1600/1200 17:43:02 INFO - PROCESS | 2259 | colorDepth:24 17:43:02 INFO - PROCESS | 2259 | Browser inner width/height: 1024/689 17:43:02 INFO - PROCESS | 2259 | __metrics 17:43:02 INFO - PROCESS | 2259 | 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:43:02 ERROR - PROCESS | 2259 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 17:43:03 INFO - TEST-INFO | 2259: exit 0 17:43:03 INFO - Browser initialized. 17:43:03 INFO - Running cycle 1/1 for kraken test... 17:43:03 INFO - TEST-INFO | started process 2261 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/tmp_j704s/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:43:14 INFO - PROCESS | 2261 | RSS: Main: 237641728 17:43:14 INFO - PROCESS | 2261 | 17:43:17 INFO - PROCESS | 2261 | Cycle 1(1): loaded http://localhost:54359/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54359/tests/kraken/driver.html?testName=audio-beat-detection) 17:43:18 INFO - PROCESS | 2261 | RSS: Main: 285933568 17:43:18 INFO - PROCESS | 2261 | 17:43:21 INFO - PROCESS | 2261 | Cycle 1(1): loaded http://localhost:54359/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54359/tests/kraken/driver.html?testName=audio-dft) 17:43:21 INFO - PROCESS | 2261 | RSS: Main: 300396544 17:43:21 INFO - PROCESS | 2261 | 17:43:25 INFO - PROCESS | 2261 | Cycle 1(1): loaded http://localhost:54359/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54359/tests/kraken/driver.html?testName=audio-fft) 17:43:26 INFO - PROCESS | 2261 | RSS: Main: 388239360 17:43:26 INFO - PROCESS | 2261 | 17:43:28 INFO - PROCESS | 2261 | Cycle 1(1): loaded http://localhost:54359/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54359/tests/kraken/driver.html?testName=audio-oscillator) 17:43:28 INFO - PROCESS | 2261 | RSS: Main: 286892032 17:43:28 INFO - PROCESS | 2261 | 17:43:30 INFO - PROCESS | 2261 | Cycle 1(1): loaded http://localhost:54359/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54359/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:43:31 INFO - PROCESS | 2261 | RSS: Main: 290942976 17:43:31 INFO - PROCESS | 2261 | 17:43:34 INFO - PROCESS | 2261 | Cycle 1(1): loaded http://localhost:54359/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54359/tests/kraken/driver.html?testName=imaging-darkroom) 17:43:34 INFO - PROCESS | 2261 | RSS: Main: 329056256 17:43:34 INFO - PROCESS | 2261 | 17:43:37 INFO - PROCESS | 2261 | Cycle 1(1): loaded http://localhost:54359/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54359/tests/kraken/driver.html?testName=imaging-desaturate) 17:43:38 INFO - PROCESS | 2261 | RSS: Main: 329965568 17:43:38 INFO - PROCESS | 2261 | 17:43:41 INFO - PROCESS | 2261 | Cycle 1(1): loaded http://localhost:54359/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54359/tests/kraken/driver.html?testName=json-parse-financial) 17:43:41 INFO - PROCESS | 2261 | RSS: Main: 328863744 17:43:41 INFO - PROCESS | 2261 | 17:43:43 INFO - PROCESS | 2261 | Cycle 1(1): loaded http://localhost:54359/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54359/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:43:44 INFO - PROCESS | 2261 | RSS: Main: 320143360 17:43:44 INFO - PROCESS | 2261 | 17:43:45 INFO - PROCESS | 2261 | Cycle 1(1): loaded http://localhost:54359/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54359/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:43:46 INFO - PROCESS | 2261 | RSS: Main: 326516736 17:43:46 INFO - PROCESS | 2261 | 17:43:48 INFO - PROCESS | 2261 | Cycle 1(1): loaded http://localhost:54359/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54359/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:43:49 INFO - PROCESS | 2261 | RSS: Main: 329744384 17:43:49 INFO - PROCESS | 2261 | 17:43:52 INFO - PROCESS | 2261 | Cycle 1(1): loaded http://localhost:54359/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54359/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:43:52 INFO - PROCESS | 2261 | RSS: Main: 335032320 17:43:52 INFO - PROCESS | 2261 | 17:43:55 INFO - PROCESS | 2261 | Cycle 1(1): loaded http://localhost:54359/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54359/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:43:56 INFO - PROCESS | 2261 | RSS: Main: 332406784 17:43:56 INFO - PROCESS | 2261 | 17:43:58 INFO - PROCESS | 2261 | Cycle 1(1): loaded http://localhost:54359/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54359/tests/kraken/driver.html?testName=ai-astar) 17:43:58 INFO - PROCESS | 2261 | RSS: Main: 396414976 17:43:58 INFO - PROCESS | 2261 | 17:43:58 INFO - PROCESS | 2261 | __start_tp_report 17:43:58 INFO - PROCESS | 2261 | _x_x_mozilla_page_load 17:43:58 INFO - PROCESS | 2261 | _x_x_mozilla_page_load_details 17:43:58 INFO - PROCESS | 2261 | |i|pagename|runs| 17:43:58 INFO - PROCESS | 2261 | |0;ai-astar;112;110;110;112;111;113;120;113;112;112 17:43:58 INFO - PROCESS | 2261 | |1;audio-beat-detection;162;121;146;174;134;178;168;105;251;147 17:43:58 INFO - PROCESS | 2261 | |2;audio-dft;188;183;187;187;185;184;184;185;185;182 17:43:58 INFO - PROCESS | 2261 | |3;audio-fft;79;73;85;95;56;72;82;93;82;59 17:43:58 INFO - PROCESS | 2261 | |4;audio-oscillator;89;100;137;68;115;115;129;80;77;123 17:43:58 INFO - PROCESS | 2261 | |5;imaging-gaussian-blur;90;86;87;88;90;84;94;85;90;85 17:43:58 INFO - PROCESS | 2261 | |6;imaging-darkroom;94;93;93;93;93;93;94;108;150;101 17:43:58 INFO - PROCESS | 2261 | |7;imaging-desaturate;84;83;83;86;83;88;80;84;85;84 17:43:58 INFO - PROCESS | 2261 | |8;json-parse-financial;59;60;59;58;56;59;59;74;60;58 17:43:58 INFO - PROCESS | 2261 | |9;json-stringify-tinderbox;66;70;76;81;72;72;72;71;70;70 17:43:58 INFO - PROCESS | 2261 | |10;stanford-crypto-aes;81;82;82;82;81;103;106;77;83;83 17:43:58 INFO - PROCESS | 2261 | |11;stanford-crypto-ccm;139;143;138;137;138;137;155;148;172;140 17:43:58 INFO - PROCESS | 2261 | |12;stanford-crypto-pbkdf2;170;174;228;194;173;165;171;168;171;172 17:43:58 INFO - PROCESS | 2261 | |13;stanford-crypto-sha256-iterative;70;68;69;68;68;68;67;71;67;67 17:43:58 INFO - PROCESS | 2261 | __end_tp_report 17:43:58 INFO - PROCESS | 2261 | __start_cc_report 17:43:58 INFO - PROCESS | 2261 | _x_x_mozilla_cycle_collect,1976 17:43:58 INFO - PROCESS | 2261 | __end_cc_report 17:43:58 INFO - PROCESS | 2261 | __startTimestamp1461026638469__endTimestamp 17:43:58 INFO - PROCESS | 2261 | 17:43:58 INFO - PROCESS | 2261 | ------- Summary: start ------- 17:43:58 INFO - PROCESS | 2261 | Number of tests: 14 17:43:58 INFO - PROCESS | 2261 | 17:43:58 INFO - PROCESS | 2261 | [#0] ai-astar Cycles:10 Average:112.50 Median:112.00 stddev:2.84 (2.5%) stddev-sans-first:3.00 17:43:58 INFO - PROCESS | 2261 | Values: 112.0 110.0 110.0 112.0 111.0 113.0 120.0 113.0 112.0 112.0 17:43:58 INFO - PROCESS | 2261 | 17:43:58 INFO - PROCESS | 2261 | [#1] audio-beat-detection Cycles:10 Average:158.60 Median:165.00 stddev:40.05 (24.3%) stddev-sans-first:42.46 17:43:58 INFO - PROCESS | 2261 | Values: 162.0 121.0 146.0 174.0 134.0 178.0 168.0 105.0 251.0 147.0 17:43:58 INFO - PROCESS | 2261 | 17:43:58 INFO - PROCESS | 2261 | [#2] audio-dft Cycles:10 Average:185.00 Median:185.00 stddev:1.89 (1.0%) stddev-sans-first:1.66 17:43:58 INFO - PROCESS | 2261 | Values: 188.0 183.0 187.0 187.0 185.0 184.0 184.0 185.0 185.0 182.0 17:43:58 INFO - PROCESS | 2261 | 17:43:58 INFO - PROCESS | 2261 | [#3] audio-fft Cycles:10 Average:77.60 Median:82.00 stddev:12.91 (15.7%) stddev-sans-first:13.68 17:43:58 INFO - PROCESS | 2261 | Values: 79.0 73.0 85.0 95.0 56.0 72.0 82.0 93.0 82.0 59.0 17:43:58 INFO - PROCESS | 2261 | 17:43:58 INFO - PROCESS | 2261 | [#4] audio-oscillator Cycles:10 Average:103.30 Median:102.50 stddev:23.93 (23.3%) stddev-sans-first:24.82 17:43:58 INFO - PROCESS | 2261 | Values: 89.0 100.0 137.0 68.0 115.0 115.0 129.0 80.0 77.0 123.0 17:43:58 INFO - PROCESS | 2261 | 17:43:58 INFO - PROCESS | 2261 | [#5] imaging-gaussian-blur Cycles:10 Average:87.90 Median:89.00 stddev:3.11 (3.5%) stddev-sans-first:3.20 17:43:58 INFO - PROCESS | 2261 | Values: 90.0 86.0 87.0 88.0 90.0 84.0 94.0 85.0 90.0 85.0 17:43:58 INFO - PROCESS | 2261 | 17:43:58 INFO - PROCESS | 2261 | [#6] imaging-darkroom Cycles:10 Average:101.20 Median:93.00 stddev:17.85 (19.2%) stddev-sans-first:18.74 17:43:58 INFO - PROCESS | 2261 | Values: 94.0 93.0 93.0 93.0 93.0 93.0 94.0 108.0 150.0 101.0 17:43:58 INFO - PROCESS | 2261 | 17:43:58 INFO - PROCESS | 2261 | [#7] imaging-desaturate Cycles:10 Average:84.00 Median:84.00 stddev:2.11 (2.5%) stddev-sans-first:2.24 17:43:58 INFO - PROCESS | 2261 | Values: 84.0 83.0 83.0 86.0 83.0 88.0 80.0 84.0 85.0 84.0 17:43:58 INFO - PROCESS | 2261 | 17:43:58 INFO - PROCESS | 2261 | [#8] json-parse-financial Cycles:10 Average:60.20 Median:59.00 stddev:4.98 (8.4%) stddev-sans-first:5.27 17:43:58 INFO - PROCESS | 2261 | Values: 59.0 60.0 59.0 58.0 56.0 59.0 59.0 74.0 60.0 58.0 17:43:58 INFO - PROCESS | 2261 | 17:43:58 INFO - PROCESS | 2261 | [#9] json-stringify-tinderbox Cycles:10 Average:72.00 Median:72.00 stddev:4.03 (5.6%) stddev-sans-first:3.64 17:43:58 INFO - PROCESS | 2261 | Values: 66.0 70.0 76.0 81.0 72.0 72.0 72.0 71.0 70.0 70.0 17:43:58 INFO - PROCESS | 2261 | 17:43:58 INFO - PROCESS | 2261 | [#10] stanford-crypto-aes Cycles:10 Average:86.00 Median:82.00 stddev:9.92 (12.1%) stddev-sans-first:10.36 17:43:58 INFO - PROCESS | 2261 | Values: 81.0 82.0 82.0 82.0 81.0 103.0 106.0 77.0 83.0 83.0 17:43:58 INFO - PROCESS | 2261 | 17:43:58 INFO - PROCESS | 2261 | [#11] stanford-crypto-ccm Cycles:10 Average:144.70 Median:141.50 stddev:11.20 (7.9%) stddev-sans-first:11.68 17:43:58 INFO - PROCESS | 2261 | Values: 139.0 143.0 138.0 137.0 138.0 137.0 155.0 148.0 172.0 140.0 17:43:58 INFO - PROCESS | 2261 | 17:43:58 INFO - PROCESS | 2261 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.60 Median:172.50 stddev:19.03 (11.0%) stddev-sans-first:19.93 17:43:58 INFO - PROCESS | 2261 | Values: 170.0 174.0 228.0 194.0 173.0 165.0 171.0 168.0 171.0 172.0 17:43:58 INFO - PROCESS | 2261 | 17:43:58 INFO - PROCESS | 2261 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.30 Median:68.00 stddev:1.34 (2.0%) stddev-sans-first:1.27 17:43:58 INFO - PROCESS | 2261 | Values: 70.0 68.0 69.0 68.0 68.0 68.0 67.0 71.0 67.0 67.0 17:43:58 INFO - PROCESS | 2261 | -------- Summary: end -------- 17:43:58 INFO - PROCESS | 2261 | 17:43:59 INFO - TEST-INFO | 2261: exit 0 17:43:59 INFO - TEST-OK | kraken | took 59073ms 17:43:59 INFO - SUITE-END | took 1174s 17:43:59 INFO - Completed test suite (00:19:34) 17:43:59 INFO - javascript benchmark 17:43:59 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3390.0507857799817, 3457.8962631121058, 3465.306938612275, 3492.91267718307, 3525.206828463596, 5685.829425117243, 5759.222504961829, 5789.044807606745, 5794.130880367941, 5796.594238403308, 5711.914321285174, 5749.59752817303, 5756.079027355624, 5759.654420734749, 5765.3369862465825, 5210.2445145453885, 5237.0, 5256.684598924057, 5280.683159010472, 5323.361196656407, 5324.015057214408, 5332.306800115979, 5334.359876814788, 5344.412114667383, 5345.278387417708, 5248.0815857225025, 5306.416294207634, 5307.177387504924, 5325.680459172443, 5395.028894798935, 5294.655847369909, 5308.336457942757, 5310.893782124374, 5319.867003324909, 5348.304720386363, 5089.262057001751, 5240.161574148156, 5242.79028838846, 5266.499682530153, 5272.182811664198, 5630.15547667849, 5650.6892120933335, 5664.971675141598, 5718.571107166979, 5732.713364331808, 6246.6876656167005, 6274.937250627527, 6283.528735344822, 6304.558680892339, 6306.527010474233, 5288.81489147878, 6274.278457977345, 6281.371862813682, 6284.905726414107, 6297.748090076391, 5717.599768016241, 5826.44648758363, 5831.533477321804, 5901.704914754245, 5935.109733539917, 6268.583865842609, 6302.8424289392215, 6339.0, 6361.777337793155, 6364.172657554488, 6237.0, 6237.0, 6241.21984751906, 6244.000959846494, 6245.375462453809, 6144.972159187458, 6221.06683997404, 6275.905861412082, 6300.905486417708, 6317.3366796486625, 6214.4407003369915, 6248.968755156286, 6271.843203919939, 6272.1219029335925, 6274.341194174637, 6145.907811382833, 6281.214848143982, 6286.088517164985, 6298.937010629835, 6309.747610095545, 4910.680805747615, 4930.778114984762, 4970.726610036482, 5016.698998060128, 5024.145895197752, 4376.059147283349, 4433.113377324548, 4433.290673492225, 4447.177272204653, 4457.04173602677, 5637.323521177403, 5692.800751973744, 5726.885462290814, 5735.082386818172, 5771.278590176228, 6199.659018754011, 6215.627062376272, 6270.780522681775, 6275.811725648238, 6293.937060629335, 5702.942970570324, 5703.771849125988, 5715.31416230055, 5734.025215713339, 5743.655380677173, 5321.73391330432, 5411.810586629449, 5417.187421886749, 5419.972900135474, 5420.430854760272, 6260.217472815898, 6313.674128432896, 6378.2983871774995, 6470.28826829039, 6476.12572302752, 5459.481349271813, 6187.093984383176, 6192.225971753531, 6244.375562443719, 6260.0, 6023.5181185505935, 6430.7749228776765, 6461.450776683847, 6474.4820414365795, 6488.351164883474, 5084.313617661568, 6100.207047561449, 6179.536534759821, 6220.253569571681, 6240.500759939285, 4929.482404347492, 5256.710880901587, 6138.785142519991, 6287.937120628735, 6295.803797278686, 6179.443850053517, 6180.598261113013, 6190.938090619216, 6192.566520343533, 6193.225846769154, 5978.133170690313, 6127.14220009208, 6153.107799369156, 6163.3528479509005, 6197.4732147767945, 6021.849453763691, 6065.522372461252, 6067.848303792441, 6075.513958883367, 6136.631802091889, 6000.609960352563, 6193.938060619336, 6214.5028397727165, 6214.5960512566535, 6223.688815559113, 5927.377625349276, 5976.551758618208, 5988.700564971821, 5997.860406522746, 6001.3698561652145], "value": 5726.684263391873, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [497.4453484364934, 497.46522488325047, 499.4181778228364, 502.5728131088573, 532.4489153725892, 29897.953571624996, 31062.0, 38188.99999999996, 39083.0, 39096.609033909655, 41251.96870078242, 41405.96485087867, 41696.79151604238, 41775.58224417755, 41807.790961045226, 9361.812763744738, 9632.036796320383, 9633.325667203264, 9634.855477167848, 9655.565499552536, 8613.655453781841, 8654.187389098568, 8746.256568191711, 8893.199612034916, 8909.688160914337, 11499.367534785584, 11841.467133978991, 11878.7624247515, 11880.940595297012, 11893.286402815815, 11273.830892536578, 11386.658400248005, 11422.257553259094, 11426.257293275994, 11429.828552571718, 7460.552366858006, 11320.660380188607, 11447.370394628291, 11592.768144637144, 11725.648230553097, 37915.62084379148, 38418.61581384178, 38435.807820960996, 38457.0, 38538.80730596357, 3139.2779660677943, 3172.1752344390393, 3191.9680803191904, 3265.6407795142513, 3365.9902029391083, 26388.86805565979, 27719.0, 27750.583741243896, 28255.43489130206, 28330.716692833015, 2726.768224700903, 2759.3377589378606, 2806.5930440086277, 2887.13385984206, 2943.1759107449734, 448302.0, 449191.0, 450143.7492812515, 450162.74918625195, 450204.0, 63750.0, 64125.35874641194, 64192.0, 64204.18468614779, 64266.0, 63987.0, 63998.0, 64033.0, 64076.35923640704, 64154.6792266045, 15114.773278400833, 16503.422380216634, 16708.41520546775, 16711.080890551137, 16712.0, 11636.0, 11689.824652630217, 11707.0, 12698.30159341245, 13043.673908152372, 14168.64578385528, 15824.892257541862, 16008.359665613478, 17142.657146857244, 17339.21973511195, 6554.967225163844, 6613.603183808987, 6743.897807008831, 6768.661566921733, 6773.153355830521, 810.546094187257, 832.5421018439833, 982.2093214961882, 1078.8273876179774, 1154.630518234157, 24636.876815615808, 28185.731642076156, 28418.857905710753, 28590.714092858805, 28649.427011459655, 934.2105920497169, 1102.4322473925934, 1120.6638008597388, 1140.2246472398683, 1226.5952235762177, 15275.541733748005, 15320.770188447183, 15397.307121179574, 15457.227138643248, 15561.377544898078, 609203.0, 615631.921840388, 616325.0, 616354.0, 617042.0, 57067.71466142643, 58350.70824645935, 58554.70722646359, 58592.12111818326, 58719.0, 1325.7613629546775, 1374.8693874081946, 1450.158907833475, 1484.2282013352997, 1509.7131545006414, 28414.00550980706, 28534.714652853207, 29326.706732932398, 29599.26001849971, 29927.700722992493, 49638.050672906546, 50247.74380640513, 50307.74846125746, 50374.0, 50411.0, 370884.0, 427084.0, 427465.0, 428768.0, 429119.0, 34238.97283081512, 38312.616873830906, 38817.6118238814, 39538.6622805929, 39620.801895990335, 40941.38587921243, 41380.58619413767, 44932.0, 45103.7744811287, 45245.321320179566, 35960.92117236487, 36706.4494032595, 37162.25675486421, 37482.812585936896, 37580.624193757714, 35184.824075879456, 35378.93863184108, 35643.82178089093, 36056.0, 36071.0, 34251.14372140716, 34549.0, 34696.479552807235, 35200.47199292064, 35354.0, 30567.694323056483, 31433.21416964594, 31824.36351273008, 32428.67571324351, 32525.674743252264, 23869.761302386753, 24260.1508947186, 24381.39046523851, 24572.771361432213, 24644.630330545413], "value": 19529.49967655861, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [399.2135493078635, 399.6463130129835, 400.3714168755052, 400.7635495057915, 413.36961134270234, 13505.839065749482, 18558.814411855878, 19570.70643940342, 19576.804231957693, 19920.203191872344, 19940.202391904306, 20237.59524809503, 21409.67885481719, 21522.56954860902, 21737.782622173774, 4818.277258411232, 6186.0, 6190.195274614294, 6190.876182476348, 6197.194364732579, 5265.841514866724, 6074.301455332649, 6185.845353866167, 6194.473469755077, 6749.156355455568, 6652.93347066528, 6679.732810687567, 6691.498137639672, 6702.0, 6717.596944183365, 5645.266115405012, 6020.488258498033, 6033.637981721111, 6047.909281360783, 6664.067030615718, 5554.944450555483, 5810.680412577307, 5811.506021988136, 5817.650940943515, 5825.980453420634, 24607.63088553673, 25333.61999570008, 25352.0, 25508.744912551007, 25937.481250375076, 2161.297578287047, 2275.385645875621, 2311.4221444638843, 2317.860928344288, 2601.752833480816, 9322.48726320052, 9359.438433694, 9445.574949127305, 9840.212782977345, 10228.130608898253, 1707.6072503324306, 1718.3556166437586, 1723.0953749281603, 1725.9482215533428, 1771.3888708395712, 109810.45094774554, 114034.42982785116, 114122.0, 114948.2757758626, 115015.0, 9448.858267126, 11697.0, 11702.0, 11749.706257343634, 11904.345261010554, 13611.319434028457, 14185.858141418454, 14255.78616320756, 15238.238088095552, 15247.23763811805, 8436.873446898302, 8788.8681669775, 8852.513111778913, 8933.28533717301, 9006.819863602692, 7554.715698331187, 8618.956905215558, 8663.610137543825, 8777.034526202113, 8777.122287771299, 4475.328700694922, 4707.976460117678, 4709.387779588632, 4718.976405117953, 4768.3085952537385, 1754.754334393186, 1911.6176764647125, 1920.26216274272, 1973.1712680673863, 1984.186483541741, 907.5235501361706, 963.5375019990444, 988.7972965542046, 1013.7009582173276, 1058.6030238660503, 20171.0, 20551.383458496268, 20711.275105371238, 21108.577828443344, 21216.151353945977, 874.6501399440148, 913.8263729891299, 932.7574830543972, 938.5213541094091, 945.8061097475002, 10847.895796564604, 10873.0, 11798.941005294919, 11899.107566932515, 11927.463264153133, 603974.0, 611303.0, 616924.0, 619440.0, 621978.0, 83628.58185709032, 88261.55869220741, 129741.0, 131158.68841311464, 133207.0, 974.1670871404989, 1051.3166441812882, 1052.826283663187, 1058.846467262258, 1074.752806854428, 18376.63246735031, 18503.167357468676, 18579.16393762311, 18590.721139183195, 18592.7211091831, 41209.0, 43437.13125737404, 44546.77726611346, 47810.857270984445, 50659.49340506548, 75568.24431755612, 76164.61917690375, 77778.0, 78991.60504197443, 80016.19983800087, 6439.8068057958335, 7841.176676448891, 8028.514724775935, 8100.83798324042, 8370.455920365157, 9172.587233574639, 11830.704232394259, 12497.000239980962, 12875.742485150431, 12918.966482681551, 9502.85745713801, 10648.89351106479, 10696.62561810333, 10795.784084318113, 11144.721381965515, 10416.375017498975, 10494.10800081971, 10588.417637030037, 10913.836292455779, 11207.66377008691, 10377.896221037692, 10507.527161277609, 11336.716582085513, 11571.958523732905, 11610.303381797119, 8187.549684767157, 8191.262786349257, 8350.123237060021, 8420.873686894824, 8484.236418722097, 9632.95183524078, 10245.897541024495, 10644.787104258026, 11105.944470277596, 11437.0], "value": 9516.425285355564, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1354.7900075488303, 1585.2153184173835, 1605.9116748578826, 1865.7294692269622, 1994.4914046918025, 7192.244814294502, 7998.200179981999, 8845.690400835972, 8870.645174193025, 8881.555922203888, 8472.703455379064, 8705.651773929036, 8708.21626053655, 8713.390062695615, 8724.43291186074, 2437.0495506752336, 2465.0509553821817, 2467.7655622715815, 2468.4816188600416, 2472.925812225636, 2430.9513809723794, 2488.688913885764, 2496.4383013821835, 2502.099244272061, 2504.611785173301, 2434.026389444228, 2460.2619214235747, 2481.1192026830395, 2481.6029435290357, 2497.313238859319, 2420.987895060522, 2445.40087678519, 2454.7299797022315, 2477.467344520937, 2481.019997101135, 2425.4057755849753, 2454.9754502454925, 2464.9014039438403, 2468.136152346682, 2472.2336075816374, 7059.682314295869, 8652.394332396734, 8798.296136309144, 8816.426932249447, 8827.293816494664, 5227.242049902781, 5447.210154527611, 5450.8909821803345, 5453.072977593819, 5474.987127381447, 3443.7589368744198, 3465.5148279240925, 3531.382008148589, 3556.751027428081, 3564.055525285801, 852.6802449081595, 1010.6765834932825, 1011.1405305490348, 1019.9592016319339, 1040.9739756506071, 11411.942940285246, 11745.941270293679, 11746.353950532717, 11752.764944701144, 11779.234349767237, 4542.205114105019, 4551.180787458289, 4679.9298010529865, 4684.414448193976, 4760.214564596803, 9183.219426348835, 9195.90804091964, 9207.907920920705, 9208.401453905475, 9209.309301802392, 4733.816545863534, 4831.637627177975, 5261.3686357637325, 5270.156774915995, 5275.525202731696, 3428.3486137633768, 4460.464744230648, 4611.631069514431, 4854.417469903634, 4942.258661200849, 3739.439084137401, 3804.6766024888193, 3823.6941044716355, 3837.0599203195397, 3848.4804551385205, 1210.5883999440232, 1213.4478812140455, 1214.3806658603999, 1223.8776122387867, 1231.5812623707802, 811.5779794506824, 846.3695978509935, 892.3262936482914, 910.7996240827008, 943.7640589852537, 5652.97173514138, 5764.538836893039, 5866.2960444746905, 5917.112433134978, 6000.799840032011, 880.1198801198801, 912.2656261709255, 926.0461724423855, 937.5593471068587, 963.1861077389734, 3673.0633688409284, 3716.108134047809, 3878.2825177342283, 3899.3854879465475, 3930.449737036817, 10280.0, 10563.47182640865, 10757.731056723487, 10764.946175269073, 10767.19246056549, 10694.62568810088, 10839.729006774895, 10847.349159050482, 10847.783044339069, 10876.347419154876, 7797.805054873674, 8172.713955011546, 8249.876251856227, 8347.749567512985, 8359.623816928253, 5773.336066352424, 5848.859472402841, 5967.283925928743, 6028.367021462684, 6076.696165191811, 8320.916790832014, 9638.855417168608, 9829.655962041294, 9954.402735835873, 10137.493125343852, 8644.654213831376, 9227.907720922705, 10101.98980101984, 10213.029762172582, 10331.638392656221, 6221.47117495018, 6550.984597387413, 6899.068625735662, 7167.3907717842485, 7171.175314838861, 8285.751427457186, 8384.32925365957, 8390.24487796077, 8407.159284071544, 8422.83154336922, 7177.712891484282, 7402.777916662509, 7418.851622967619, 7539.736109236151, 7565.6973721050545, 7312.707491700486, 7321.853562928605, 7387.445941554298, 7393.408527317694, 7454.142773581108, 6685.6322902240845, 6820.420264277592, 6826.0, 6838.623875686885, 6840.042394064735, 4690.202665546797, 4757.0, 4781.832635857728, 4857.757112144449, 4964.553190212898, 4328.956710432855, 4481.641468682433, 4788.928166077442, 4857.101436234308, 5063.974680126576], "value": 4530.612136823813, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [316.90492852144365, 317.1073428299337, 323.422690497462, 327.18694045297434, 331.3852803050343, 5076.9084646800975, 5846.0061789495785, 5920.703964801759, 5937.58436909416, 5980.700964951757, 4911.435184953732, 5100.387953445592, 5879.206307148534, 5900.43945825146, 5914.852128696784, 3156.5738625285635, 4193.433886425331, 4376.781160941956, 4476.910461790762, 4526.117407105616, 3602.3695853225713, 4360.345948107777, 4491.842785502508, 4500.369948207253, 4500.752458614775, 3666.3400587894166, 4492.415985921842, 4493.46078470584, 4497.751124437781, 4540.682152249344, 4182.184474027551, 4221.176870510251, 4385.561443855589, 4427.203103441379, 4460.8884777880485, 3271.4765637497885, 3439.7420193485586, 3842.0405593147416, 3856.6529012388737, 3878.168936676242, 4725.0, 4729.50340214276, 4751.572358487718, 5004.570954705076, 5768.625039372428, 4595.149897269016, 4824.852940441505, 4895.657303988723, 5189.138455240314, 5198.324217851655, 5573.219749235111, 5830.650160990312, 6286.779962701329, 6292.653904035276, 6298.0951809877715, 3632.673059424638, 3753.2681127180213, 4084.326086195774, 4370.2570563004365, 4374.934375984363, 5491.533219676332, 6297.590656607306, 6608.9339106608795, 6644.368784965421, 6664.93335066648, 4056.634902858757, 4063.309237429644, 4386.232409328355, 4416.624586910084, 4461.107778444324, 4456.732596044248, 4474.082813023322, 4474.865754027384, 4502.3246513023305, 4514.841980530666, 4471.910561788746, 4900.387451568554, 5087.592992560586, 5194.740287974743, 5214.348206474191, 5150.574867712929, 5759.712014399263, 6295.96852015737, 6319.33646967071, 6355.362524552352, 1472.9263536823116, 1529.839366866463, 1550.6743583847292, 1557.3381312941954, 1560.8907376483764, 917.5823352919755, 955.8948515663271, 984.5520288268821, 1011.3931641015479, 1035.254616675992, 4903.754812259373, 5693.491224825425, 5848.4151584841175, 5852.356240813507, 5864.70676466175, 6545.345465453512, 6572.408483236436, 6573.924167016385, 6583.572067815577, 6669.233038200669, 5054.671446355975, 5811.832551259574, 5843.883122337529, 5852.678102704307, 5892.970535147297, 3022.259546411143, 3146.2134466383404, 3397.473391624302, 3458.6195518492946, 3459.7405194610506, 5731.656100633975, 6464.644444555594, 6802.863942721118, 6860.5197636164985, 6922.238553759083, 6409.647469389135, 6618.669066546653, 6666.433353165035, 6669.466442684574, 6725.226598941184, 5737.741801618937, 6397.0, 6813.659317034128, 6820.0, 6832.624205668736, 6563.835904102436, 6569.540132190701, 6570.901436478553, 6593.3736295051895, 6635.270120286668, 5685.204071430086, 5693.0, 6301.936980630135, 6498.588241173422, 6511.413972742286, 5576.86057848557, 6534.771283005072, 6630.204375474781, 6829.658517074027, 6875.621840798604, 3600.4779307000863, 4162.688753042782, 4187.183499217624, 4256.851010214627, 4269.359596060615, 5368.812091576776, 5727.627704199213, 5823.854403639943, 6322.430981211529, 6527.836804079936, 5351.929614077278, 5746.942530574641, 6036.517078633787, 6280.560360774702, 6364.968175159094, 5871.882562348644, 6329.525285603506, 6361.332060133805, 6362.554621176473, 6369.90445143314, 5697.515711164598, 5878.559108066826, 5983.700814959322, 6073.969630151821, 6216.813495595139, 6261.843453913689, 6296.842578935563, 6320.5891617044745, 6359.554831161774, 6411.006294024433, 5221.843344699665, 5837.328707198726, 6216.974199257253, 6221.626702397871, 6224.657643829632], "value": 4536.6528661349785, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [604.4197570332481, 647.7635662983013, 677.2313424263463, 719.9028131202286, 731.5245090691053, 14669.0, 14887.627809304773, 15119.470818521353, 15166.2416879156, 15773.290201940912, 14392.856071439282, 14468.782968255458, 14500.782488262657, 14511.854881451209, 14518.927405362958, 741.3365038290726, 761.9657115429819, 900.6127365232979, 931.4550987672203, 940.6237504998021, 927.3462209142539, 935.6584846531035, 936.489613160829, 938.6620816506087, 938.8403971324857, 988.3723835364559, 988.658912675126, 989.1789814454022, 991.2317953585956, 991.8660980767576, 759.4190444310079, 939.0281059103863, 988.980220395588, 993.1657407777501, 995.5320999130397, 722.6495149852317, 726.3462883404925, 726.5676922231264, 727.058459295214, 728.2389902551846, 12488.687782805411, 15118.39526418942, 15119.54641360761, 15129.773053404208, 15161.696766064617, 12228.816567751492, 12374.381280936008, 12379.876201238052, 12416.130870839044, 12734.299613521252, 8270.83458330836, 9424.387414818015, 9624.422534647943, 9679.516024198761, 10110.848337274947, 4018.9397159042637, 4027.597240275978, 4090.8568200112854, 4103.794810259505, 4128.256913755523, 80858.19141808565, 81621.18378816196, 81865.59067204625, 82003.58998204971, 82202.0, 9246.121618446372, 9260.629574817067, 9344.2524598032, 9454.810903781887, 9844.753881153028, 21837.45406364822, 21839.344819655435, 21850.12599496034, 21878.23426180108, 21882.452938676342, 7226.74706385274, 7246.782596522007, 7259.89110163348, 7326.120865496175, 7332.376747976375, 3343.063942096217, 3741.607131251234, 3898.785566793797, 3901.31726947783, 3925.8822235332987, 3457.4986626938953, 3597.2805438912324, 3598.2443686825536, 3599.766015209003, 3606.1886075632774, 539.5899116671279, 639.6897504710212, 651.3031056769212, 659.8713250916121, 708.125465050662, 490.872373182975, 582.9358770535238, 604.4759054711025, 609.7378127405169, 613.4846728747873, 6569.441597464173, 7515.924840751522, 7842.137364889858, 8171.0, 8463.407561470762, 558.4025093150372, 563.0681222576642, 587.1938185948029, 589.3664310865836, 597.2534332084894, 3435.793852368866, 3770.5663848657205, 3791.431285307171, 3805.5243094613174, 3899.454076429302, 93700.06299936914, 94240.46076313878, 97586.0, 98975.0, 99075.0, 59238.70380648069, 79343.0, 80171.59914200509, 80210.5989470049, 80305.0, 68059.65970170118, 70589.0, 71003.0, 71494.9275760864, 72135.27864721391, 7509.1739908608915, 7626.10068200103, 7669.769906902802, 7670.463067585216, 7766.495177813424, 37674.05814854651, 42145.78927105345, 43650.0, 44280.0, 44869.55130448654, 61599.692001539705, 62249.68875155595, 64931.67534162299, 66268.0, 66455.66772166108, 10235.283530152818, 10380.688579342632, 10532.473376331305, 10567.63013294531, 10584.153267738408, 10321.122704570196, 10509.211809114195, 10523.368597884151, 10569.26015178961, 11584.42077896085, 10241.436720980417, 10425.739356516147, 10624.787504250025, 10934.179936504635, 11197.552097916318, 9152.764376809311, 10153.746156346151, 10649.627263045757, 10663.89336106629, 10683.57265709363, 9555.808883822423, 10198.031187037219, 10333.431661258703, 10581.788364232518, 10605.787884242427, 8667.17661822126, 8814.074522175079, 8815.955920220358, 8835.293176545989, 8935.553222338987, 8775.254103401026, 8777.166169213915, 8880.289576833968, 9110.407823491452, 9138.040505746802], "value": 6721.423035710912, "unit": "score"}], "name": "dromaeo_css", "value": 7265.13768899844, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 110.0, 110.0, 112.0, 111.0, 113.0, 120.0, 113.0, 112.0, 112.0], "value": 112.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 121.0, 146.0, 174.0, 134.0, 178.0, 168.0, 105.0, 251.0, 147.0], "value": 158.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 183.0, 187.0, 187.0, 185.0, 184.0, 184.0, 185.0, 185.0, 182.0], "value": 185.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 73.0, 85.0, 95.0, 56.0, 72.0, 82.0, 93.0, 82.0, 59.0], "value": 77.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 100.0, 137.0, 68.0, 115.0, 115.0, 129.0, 80.0, 77.0, 123.0], "value": 103.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 86.0, 87.0, 88.0, 90.0, 84.0, 94.0, 85.0, 90.0, 85.0], "value": 87.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 93.0, 93.0, 93.0, 93.0, 94.0, 108.0, 150.0, 101.0], "value": 101.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 83.0, 86.0, 83.0, 88.0, 80.0, 84.0, 85.0, 84.0], "value": 84.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 60.0, 59.0, 58.0, 56.0, 59.0, 59.0, 74.0, 60.0, 58.0], "value": 60.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 70.0, 76.0, 81.0, 72.0, 72.0, 72.0, 71.0, 70.0, 70.0], "value": 72.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 82.0, 82.0, 81.0, 103.0, 106.0, 77.0, 83.0, 83.0], "value": 86.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 143.0, 138.0, 137.0, 138.0, 137.0, 155.0, 148.0, 172.0, 140.0], "value": 144.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 174.0, 228.0, 194.0, 173.0, 165.0, 171.0, 168.0, 171.0, 172.0], "value": 178.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 69.0, 68.0, 68.0, 68.0, 67.0, 71.0, 67.0, 67.0], "value": 68.3, "unit": "score"}], "name": "kraken", "value": 1519.9, "alertThreshold": 2.0}]} 17:43:59 INFO - Return code: 0 17:43:59 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 17:43:59 ERROR - # TBPL SUCCESS # 17:43:59 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:43:59 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:43:59 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:43:59 INFO - 2016-04-18 17:43:59.768 screenresolution[2265:73926] starting screenresolution argv=screenresolution get 17:43:59 INFO - 2016-04-18 17:43:59.771 screenresolution[2265:73926] Display 0: 1600x1200x32@60 17:43:59 INFO - 2016-04-18 17:43:59.777 screenresolution[2266:73930] starting screenresolution argv=screenresolution list 17:43:59 INFO - Available Modes on Display 0 17:43:59 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:43:59 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:43:59 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:43:59 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:44:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:44:00 INFO - Intel Iris: 17:44:00 INFO - Chipset Model: Intel Iris 17:44:00 INFO - Type: GPU 17:44:00 INFO - Bus: Built-In 17:44:00 INFO - VRAM (Dynamic, Max): 1536 MB 17:44:00 INFO - Vendor: Intel (0x8086) 17:44:00 INFO - Device ID: 0x0a2e 17:44:00 INFO - Revision ID: 0x0009 17:44:00 INFO - Displays: 17:44:00 INFO - Display: 17:44:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:44:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:44:00 INFO - Display Serial Number: 1600x1200 60 17:44:00 INFO - Main Display: Yes 17:44:00 INFO - Mirror: Off 17:44:00 INFO - Online: Yes 17:44:00 INFO - Rotation: Supported 17:44:00 INFO - Return code: 0 17:44:00 INFO - Running post-action listener: _resource_record_post_action 17:44:00 INFO - Running post-run listener: _resource_record_post_run 17:44:01 INFO - Total resource usage - Wall time: 1210s; CPU: 53.0%; Read bytes: 49152; Write bytes: 692045312; Read time: 1; Write time: 1971 17:44:01 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 375187968; Write bytes: 369690112; Read time: 27921; Write time: 692 17:44:01 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 49152; Write bytes: 294998016; Read time: 1; Write time: 1223 17:44:01 INFO - Running post-run listener: _upload_blobber_files 17:44:01 INFO - Blob upload gear active. 17:44:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:44:01 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:44:01 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:44:01 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:44:01 INFO - (blobuploader) - INFO - Open directory for files ... 17:44:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:44:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:44:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:44:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:44:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:44:02 INFO - (blobuploader) - INFO - Done attempting. 17:44:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:44:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:44:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:44:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:44:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:44:03 INFO - (blobuploader) - INFO - Done attempting. 17:44:03 INFO - (blobuploader) - INFO - Iteration through files over. 17:44:03 INFO - Return code: 0 17:44:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:44:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:44:03 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8d8929d834248388e57e26db7c64451079c59d67472b9fd9a5cf5c408363d087a5b95d07abf63a3e9836206a726637120b5b368a38eefbc52c0354815991b00", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8cc78e66305b679d0f77de4fe2e68ac728237790ea14b49f4100cab9ba412ea74a17ba6a8bb97c06ae6d733a438c44617e4cccca959c04f05987d3c9473a20dd"} 17:44:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:44:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:44:03 INFO - Contents: 17:44:03 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8d8929d834248388e57e26db7c64451079c59d67472b9fd9a5cf5c408363d087a5b95d07abf63a3e9836206a726637120b5b368a38eefbc52c0354815991b00", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8cc78e66305b679d0f77de4fe2e68ac728237790ea14b49f4100cab9ba412ea74a17ba6a8bb97c06ae6d733a438c44617e4cccca959c04f05987d3c9473a20dd"} 17:44:03 INFO - Running post-run listener: copy_logs_to_upload_dir 17:44:03 INFO - Copying logs to upload dir... 17:44:03 INFO - mkdir: /builds/slave/test/build/upload/logs 17:44:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1266.821548 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 6 secs) (at 2016-04-18 17:44:03.329962) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-18 17:44:03.333088) ========= 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.d10dE1jgQY/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.CiVQQgOTzQ/Listeners TMPDIR=/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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/Firefox/sha512/b8d8929d834248388e57e26db7c64451079c59d67472b9fd9a5cf5c408363d087a5b95d07abf63a3e9836206a726637120b5b368a38eefbc52c0354815991b00", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8cc78e66305b679d0f77de4fe2e68ac728237790ea14b49f4100cab9ba412ea74a17ba6a8bb97c06ae6d733a438c44617e4cccca959c04f05987d3c9473a20dd"} build_url:https://queue.taskcluster.net/v1/task/NNKBqzhKSlaM2ttH2Q8QUQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011370 build_url: 'https://queue.taskcluster.net/v1/task/NNKBqzhKSlaM2ttH2Q8QUQ/artifacts/public/build/firefox-48.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8d8929d834248388e57e26db7c64451079c59d67472b9fd9a5cf5c408363d087a5b95d07abf63a3e9836206a726637120b5b368a38eefbc52c0354815991b00", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/8cc78e66305b679d0f77de4fe2e68ac728237790ea14b49f4100cab9ba412ea74a17ba6a8bb97c06ae6d733a438c44617e4cccca959c04f05987d3c9473a20dd"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-18 17:44:03.360233) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 17:44:03.360521) ========= 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.d10dE1jgQY/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.CiVQQgOTzQ/Listeners TMPDIR=/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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.005167 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 17:44:03.409611) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-18 17:44:03.409909) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-18 17:44:03.410330) ========= ========= Total master_lag: 13.63 =========