builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0218 starttime: 1464377901.11 results: success (0) buildid: 20160527114145 builduid: ec1b1ec38d4d437c8b02dbf9a69d7c5e revision: 2ab4b64da78750d82f120faa25327f25cf11e579 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-27 12:38:21.109948) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-27 12:38:21.110807) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-27 12:38:21.111408) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-27 12:38:21.135217) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 12:38:21.135633) ========= 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.qVV45GqxJt/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.tw5QAbTsLM/Listeners TMPDIR=/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-27 12:38:21-- 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% 65.4M=0s 2016-05-27 12:38:21 (65.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.459731 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 12:38:21.619686) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 12:38:21.620014) ========= 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.qVV45GqxJt/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.tw5QAbTsLM/Listeners TMPDIR=/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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.025964 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 12:38:21.668820) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-27 12:38:21.669173) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 2ab4b64da78750d82f120faa25327f25cf11e579 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 2ab4b64da78750d82f120faa25327f25cf11e579 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qVV45GqxJt/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.tw5QAbTsLM/Listeners TMPDIR=/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-27 12:38:21,750 truncating revision to first 12 chars 2016-05-27 12:38:21,750 Setting DEBUG logging. 2016-05-27 12:38:21,750 attempt 1/10 2016-05-27 12:38:21,750 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/2ab4b64da787?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-27 12:38:22,486 unpacking tar archive at: mozilla-aurora-2ab4b64da787/testing/mozharness/ program finished with exit code 0 elapsedTime=0.999072 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-27 12:38:22.688362) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-27 12:38:22.688678) ========= script_repo_revision: 2ab4b64da78750d82f120faa25327f25cf11e579 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-27 12:38:22.689092) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-27 12:38:22.689420) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-27 12:38:22.705907) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 33 secs) (at 2016-05-27 12:38:22.706222) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qVV45GqxJt/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.tw5QAbTsLM/Listeners TMPDIR=/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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 12:38:22 INFO - MultiFileLogger online at 20160527 12:38:22 in /builds/slave/test 12:38:22 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 12:38:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:38:22 INFO - {'append_to_log': False, 12:38:22 INFO - 'base_work_dir': '/builds/slave/test', 12:38:22 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 12:38:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:38:22 INFO - 'branch': 'Mozilla-Aurora', 12:38:22 INFO - 'buildbot_json_path': 'buildprops.json', 12:38:22 INFO - 'config_files': ('talos/mac_config.py',), 12:38:22 INFO - 'default_actions': ('clobber', 12:38:22 INFO - 'read-buildbot-config', 12:38:22 INFO - 'download-and-extract', 12:38:22 INFO - 'populate-webroot', 12:38:22 INFO - 'create-virtualenv', 12:38:22 INFO - 'install', 12:38:22 INFO - 'run-tests'), 12:38:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:38:22 INFO - 'download_minidump_stackwalk': True, 12:38:22 INFO - 'download_symbols': 'ondemand', 12:38:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:38:22 INFO - 'tooltool.py': '/tools/tooltool.py', 12:38:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:38:22 INFO - '/tools/misc-python/virtualenv.py')}, 12:38:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:38:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:38:22 INFO - 'installer_path': 'installer.exe', 12:38:22 INFO - 'log_level': 'info', 12:38:22 INFO - 'log_name': 'talos', 12:38:22 INFO - 'log_to_console': True, 12:38:22 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:38:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:38:22 INFO - 'opt_config_files': (), 12:38:22 INFO - 'pip_index': False, 12:38:22 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:38:22 INFO - 'cmd': ('bash', 12:38:22 INFO - '-c', 12:38:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:38:22 INFO - 'enabled': True, 12:38:22 INFO - 'halt_on_failure': False, 12:38:22 INFO - 'name': 'check_screen_resolution'},), 12:38:22 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:38:22 INFO - 'cmd': ('bash', 12:38:22 INFO - '-c', 12:38:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:38:22 INFO - 'enabled': True, 12:38:22 INFO - 'halt_on_failure': False, 12:38:22 INFO - 'name': 'check_screen_resolution'},), 12:38:22 INFO - 'run_cmd_checks_enabled': True, 12:38:22 INFO - 'sps_profile': False, 12:38:22 INFO - 'sps_profile_interval': 0, 12:38:22 INFO - 'suite': 'dromaeojs', 12:38:22 INFO - 'system_bits': '32', 12:38:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:38:22 INFO - 'title': 't-yosemite-r7-0218', 12:38:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:38:22 INFO - 'use_talos_json': True, 12:38:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:38:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:38:22 INFO - 'work_dir': 'build'} 12:38:22 INFO - ##### 12:38:22 INFO - ##### Running clobber step. 12:38:22 INFO - ##### 12:38:22 INFO - Running pre-action listener: _resource_record_pre_action 12:38:22 INFO - Running main action method: clobber 12:38:22 INFO - rmtree: /builds/slave/test/build 12:38:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:38:24 INFO - Running post-action listener: _resource_record_post_action 12:38:24 INFO - ##### 12:38:24 INFO - ##### Running read-buildbot-config step. 12:38:24 INFO - ##### 12:38:24 INFO - Running pre-action listener: _resource_record_pre_action 12:38:24 INFO - Running main action method: read_buildbot_config 12:38:24 INFO - Using buildbot properties: 12:38:24 INFO - { 12:38:24 INFO - "project": "", 12:38:24 INFO - "product": "firefox", 12:38:24 INFO - "script_repo_revision": "production", 12:38:24 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 12:38:24 INFO - "repository": "", 12:38:24 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 12:38:24 INFO - "buildid": "20160527114145", 12:38:24 INFO - "pgo_build": "False", 12:38:24 INFO - "basedir": "/builds/slave/test", 12:38:24 INFO - "buildnumber": 330, 12:38:24 INFO - "slavename": "t-yosemite-r7-0218", 12:38:24 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 12:38:24 INFO - "platform": "yosemite_r7", 12:38:24 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 12:38:24 INFO - "branch": "mozilla-aurora", 12:38:24 INFO - "repo_path": "releases/mozilla-aurora", 12:38:24 INFO - "revision": "2ab4b64da78750d82f120faa25327f25cf11e579", 12:38:24 INFO - "stage_platform": "macosx64", 12:38:24 INFO - "builduid": "ec1b1ec38d4d437c8b02dbf9a69d7c5e", 12:38:24 INFO - "slavebuilddir": "test" 12:38:24 INFO - } 12:38:24 INFO - Found installer url https://queue.taskcluster.net/v1/task/ABw42i3yQWSh1_tdwbn6SA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 12:38:24 INFO - Running post-action listener: _resource_record_post_action 12:38:24 INFO - ##### 12:38:24 INFO - ##### Running download-and-extract step. 12:38:24 INFO - ##### 12:38:24 INFO - Running pre-action listener: _resource_record_pre_action 12:38:24 INFO - Running main action method: download_and_extract 12:38:24 INFO - mkdir: /builds/slave/test/build/tests 12:38: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')]} 12:38:24 INFO - https://queue.taskcluster.net/v1/task/ABw42i3yQWSh1_tdwbn6SA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 12:38:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABw42i3yQWSh1_tdwbn6SA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 12:38:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABw42i3yQWSh1_tdwbn6SA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 12:38:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABw42i3yQWSh1_tdwbn6SA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 12:38:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABw42i3yQWSh1_tdwbn6SA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 12:38:42 INFO - Downloaded 1199 bytes. 12:38:42 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 12:38:42 INFO - Using the following test package requirements: 12:38:42 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 12:38:42 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:38:42 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 12:38:42 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:38:42 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 12:38:42 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:38:42 INFO - u'jsshell-mac.zip'], 12:38:42 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:38:42 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 12:38:42 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 12:38:42 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:38:42 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 12:38:42 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:38:42 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 12:38:42 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:38:42 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 12:38:42 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 12:38:42 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 12:38:42 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 12:38:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:38:42 INFO - https://queue.taskcluster.net/v1/task/ABw42i3yQWSh1_tdwbn6SA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:38:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABw42i3yQWSh1_tdwbn6SA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:38:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABw42i3yQWSh1_tdwbn6SA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:38:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABw42i3yQWSh1_tdwbn6SA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:38:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABw42i3yQWSh1_tdwbn6SA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 12:38:43 INFO - Downloaded 18425509 bytes. 12:38:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:38:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 12:38:46 INFO - Return code: 0 12:38:46 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 12:38:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:38:46 INFO - https://queue.taskcluster.net/v1/task/ABw42i3yQWSh1_tdwbn6SA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:38:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABw42i3yQWSh1_tdwbn6SA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:38:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABw42i3yQWSh1_tdwbn6SA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:38:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABw42i3yQWSh1_tdwbn6SA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 12:38:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABw42i3yQWSh1_tdwbn6SA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 12:38:51 INFO - Downloaded 18425509 bytes. 12:38:51 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:38:51 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 12:38:54 INFO - Return code: 0 12:38:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:38:54 INFO - https://queue.taskcluster.net/v1/task/ABw42i3yQWSh1_tdwbn6SA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 12:38:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABw42i3yQWSh1_tdwbn6SA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 12:38:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABw42i3yQWSh1_tdwbn6SA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 12:38:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABw42i3yQWSh1_tdwbn6SA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 12:38:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABw42i3yQWSh1_tdwbn6SA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 12:38:55 INFO - Downloaded 10840145 bytes. 12:38:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:38:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 12:38:56 INFO - Return code: 0 12:38:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:38:56 INFO - https://queue.taskcluster.net/v1/task/ABw42i3yQWSh1_tdwbn6SA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 12:38:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABw42i3yQWSh1_tdwbn6SA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 12:38:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABw42i3yQWSh1_tdwbn6SA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 12:38:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABw42i3yQWSh1_tdwbn6SA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 12:38:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ABw42i3yQWSh1_tdwbn6SA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 12:39:08 INFO - Downloaded 114143669 bytes. 12:39:08 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ABw42i3yQWSh1_tdwbn6SA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 12:39:08 INFO - mkdir: /builds/slave/test/properties 12:39:08 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:39:08 INFO - Writing to file /builds/slave/test/properties/build_url 12:39:08 INFO - Contents: 12:39:08 INFO - build_url:https://queue.taskcluster.net/v1/task/ABw42i3yQWSh1_tdwbn6SA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 12:39:09 INFO - Running post-action listener: _resource_record_post_action 12:39:09 INFO - Running post-action listener: set_extra_try_arguments 12:39:09 INFO - ##### 12:39:09 INFO - ##### Running populate-webroot step. 12:39:09 INFO - ##### 12:39:09 INFO - Running pre-action listener: _resource_record_pre_action 12:39:09 INFO - Running main action method: populate_webroot 12:39:09 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:39:09 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:39:09 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:39:09 INFO - '--tppagecycles', 12:39:09 INFO - '7'], 12:39:09 INFO - 'tests': ['tsvgm']}}, 12:39:09 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:39:09 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:39:09 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:39:09 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:39:09 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:39:09 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:39:09 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:39:09 INFO - 'talos_options': ['--disable-e10s'], 12:39:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:39:09 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:39:09 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:39:09 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:39:09 INFO - 'talos_options': ['--disable-e10s'], 12:39:09 INFO - 'tests': ['damp', 'tps']}, 12:39:09 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:39:09 INFO - 'tests': ['damp', 'tps']}, 12:39:09 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:39:09 INFO - 'tests': ['dromaeo_dom']}, 12:39:09 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:39:09 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:39:09 INFO - 'tests': ['a11yr', 12:39:09 INFO - 'ts_paint', 12:39:09 INFO - 'tpaint', 12:39:09 INFO - 'sessionrestore', 12:39:09 INFO - 'sessionrestore_no_auto_restore', 12:39:09 INFO - 'tabpaint']}, 12:39:09 INFO - 'other-e10s': {'tests': ['a11yr', 12:39:09 INFO - 'ts_paint', 12:39:09 INFO - 'tpaint', 12:39:09 INFO - 'sessionrestore', 12:39:09 INFO - 'sessionrestore_no_auto_restore', 12:39:09 INFO - 'tabpaint']}, 12:39:09 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:39:09 INFO - 'ts_paint', 12:39:09 INFO - 'tpaint', 12:39:09 INFO - 'sessionrestore', 12:39:09 INFO - 'sessionrestore_no_auto_restore', 12:39:09 INFO - 'tabpaint']}, 12:39:09 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:39:09 INFO - 'ts_paint', 12:39:09 INFO - 'tpaint', 12:39:09 INFO - 'sessionrestore', 12:39:09 INFO - 'sessionrestore_no_auto_restore', 12:39:09 INFO - 'tabpaint']}, 12:39:09 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:39:09 INFO - 'tests': ['a11yr', 12:39:09 INFO - 'ts_paint', 12:39:09 INFO - 'tpaint', 12:39:09 INFO - 'sessionrestore', 12:39:09 INFO - 'sessionrestore_no_auto_restore', 12:39:09 INFO - 'tabpaint']}, 12:39:09 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:39:09 INFO - 'tests': ['a11yr', 12:39:09 INFO - 'ts_paint', 12:39:09 INFO - 'tpaint', 12:39:09 INFO - 'sessionrestore', 12:39:09 INFO - 'sessionrestore_no_auto_restore', 12:39:09 INFO - 'tabpaint']}, 12:39:09 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:39:09 INFO - 'tests': ['tsvgx', 12:39:09 INFO - 'tsvgr_opacity', 12:39:09 INFO - 'tart', 12:39:09 INFO - 'tscrollx', 12:39:09 INFO - 'cart']}, 12:39:09 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:39:09 INFO - 'tsvgr_opacity', 12:39:09 INFO - 'tart', 12:39:09 INFO - 'tscrollx', 12:39:09 INFO - 'cart']}, 12:39:09 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:39:09 INFO - 'talos_options': ['--disable-e10s'], 12:39:09 INFO - 'tests': ['tp5o']}, 12:39:09 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:39:09 INFO - 'tests': ['tp5o']}, 12:39:09 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:39:09 INFO - 'talos_options': ['--disable-e10s', 12:39:09 INFO - '--xperf_path', 12:39:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:39:09 INFO - 'tests': ['tp5n']}, 12:39:09 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:39:09 INFO - 'talos_options': ['--xperf_path', 12:39:09 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:39:09 INFO - 'tests': ['tp5n']}}, 12:39:09 INFO - 'talos.zip': {'path': '', 12:39:09 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:39:09 INFO - Running post-action listener: _resource_record_post_action 12:39:09 INFO - ##### 12:39:09 INFO - ##### Running create-virtualenv step. 12:39:09 INFO - ##### 12:39:09 INFO - Running pre-action listener: _resource_record_pre_action 12:39:09 INFO - Running main action method: create_virtualenv 12:39:09 INFO - Creating virtualenv /builds/slave/test/build/venv 12:39:09 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 12:39:09 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:39:09 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:39:09 INFO - Using real prefix '/tools/python27' 12:39:09 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:39:09 INFO - Installing distribute.............................................................................................................................................................................................done. 12:39:12 INFO - Installing pip.................done. 12:39:12 INFO - Return code: 0 12:39:12 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:39:12 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')]} 12:39:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:39:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:39:12 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')]} 12:39:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:39:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:39:12 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 0x1034b3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034c3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1b955d4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103352e00>, '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 0x1031bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10334db20>, '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.qVV45GqxJt/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.tw5QAbTsLM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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 12:39:12 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 12:39:12 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 12:39:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qVV45GqxJt/Render', 12:39:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:39:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:39:12 INFO - 'HOME': '/Users/cltbld', 12:39:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:39:12 INFO - 'LOGNAME': 'cltbld', 12:39:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:39:12 INFO - 'MOZ_NO_REMOTE': '1', 12:39:12 INFO - 'NO_EM_RESTART': '1', 12:39:12 INFO - 'PAGER': '/bin/cat', 12:39:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:39:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:39:12 INFO - 'PWD': '/builds/slave/test', 12:39:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:39:12 INFO - 'SHELL': '/bin/bash', 12:39:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tw5QAbTsLM/Listeners', 12:39:12 INFO - 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/', 12:39:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:39:12 INFO - 'USER': 'cltbld', 12:39:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:39:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:39:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:39:12 INFO - 'XPC_FLAGS': '0x0', 12:39:12 INFO - 'XPC_SERVICE_NAME': '0', 12:39:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:39:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:39:13 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 12:39:13 INFO - Cleaning up... 12:39:13 INFO - Return code: 0 12:39:13 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 12:39:13 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')]} 12:39:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:39:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:39:13 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')]} 12:39:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:39:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:39:13 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 0x1034b3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034c3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1b955d4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103352e00>, '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 0x1031bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10334db20>, '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.qVV45GqxJt/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.tw5QAbTsLM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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 12:39:13 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 12:39:13 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 12:39:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qVV45GqxJt/Render', 12:39:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:39:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:39:13 INFO - 'HOME': '/Users/cltbld', 12:39:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:39:13 INFO - 'LOGNAME': 'cltbld', 12:39:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:39:13 INFO - 'MOZ_NO_REMOTE': '1', 12:39:13 INFO - 'NO_EM_RESTART': '1', 12:39:13 INFO - 'PAGER': '/bin/cat', 12:39:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:39:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:39:13 INFO - 'PWD': '/builds/slave/test', 12:39:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:39:13 INFO - 'SHELL': '/bin/bash', 12:39:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tw5QAbTsLM/Listeners', 12:39:13 INFO - 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/', 12:39:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:39:13 INFO - 'USER': 'cltbld', 12:39:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:39:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:39:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:39:13 INFO - 'XPC_FLAGS': '0x0', 12:39:13 INFO - 'XPC_SERVICE_NAME': '0', 12:39:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:39:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:39:13 INFO - Downloading/unpacking psutil>=0.7.1 12:39:13 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 12:39:13 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 12:39:13 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 12:39:13 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 12:39:13 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 12:39:13 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 12:39:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:39:16 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:39:16 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:39:16 INFO - Installing collected packages: psutil 12:39:16 INFO - Running setup.py install for psutil 12:39:16 INFO - building 'psutil._psutil_osx' extension 12:39:16 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 12:39:17 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 12:39:17 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 12:39:17 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 12:39:17 INFO - building 'psutil._psutil_posix' extension 12:39:17 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 12:39:17 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:39:17 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:39:17 INFO - ^ 12:39:17 INFO - 1 warning generated. 12:39:17 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 12:39:17 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:39:17 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:39:17 INFO - Successfully installed psutil 12:39:17 INFO - Cleaning up... 12:39:17 INFO - Return code: 0 12:39:17 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 12:39:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:39:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:39:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:39:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:39:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:39:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:39:17 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 0x1034b3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034c3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1b955d4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103352e00>, '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 0x1031bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10334db20>, '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.qVV45GqxJt/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.tw5QAbTsLM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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 12:39:17 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 12:39:17 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 12:39:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qVV45GqxJt/Render', 12:39:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:39:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:39:17 INFO - 'HOME': '/Users/cltbld', 12:39:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:39:17 INFO - 'LOGNAME': 'cltbld', 12:39:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:39:17 INFO - 'MOZ_NO_REMOTE': '1', 12:39:17 INFO - 'NO_EM_RESTART': '1', 12:39:17 INFO - 'PAGER': '/bin/cat', 12:39:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:39:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:39:17 INFO - 'PWD': '/builds/slave/test', 12:39:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:39:17 INFO - 'SHELL': '/bin/bash', 12:39:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tw5QAbTsLM/Listeners', 12:39:17 INFO - 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/', 12:39:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:39:17 INFO - 'USER': 'cltbld', 12:39:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:39:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:39:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:39:17 INFO - 'XPC_FLAGS': '0x0', 12:39:17 INFO - 'XPC_SERVICE_NAME': '0', 12:39:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:39:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:39:17 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 12:39:17 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 12:39:17 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 12:39:17 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 12:39:17 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 12:39:17 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 12:39:17 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 12:39:20 INFO - Downloading mozsystemmonitor-0.0.tar.gz 12:39:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:39:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 12:39:20 INFO - Installing collected packages: mozsystemmonitor 12:39:20 INFO - Running setup.py install for mozsystemmonitor 12:39:20 INFO - Successfully installed mozsystemmonitor 12:39:20 INFO - Cleaning up... 12:39:20 INFO - Return code: 0 12:39:20 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:39: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')]} 12:39:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:39:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:39: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')]} 12:39:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:39:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:39: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', '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 0x1034b3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034c3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1b955d4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103352e00>, '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 0x1031bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10334db20>, '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.qVV45GqxJt/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.tw5QAbTsLM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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 12:39: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:39: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 blobuploader==1.2.4 12:39:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qVV45GqxJt/Render', 12:39:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:39:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:39:20 INFO - 'HOME': '/Users/cltbld', 12:39:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:39:20 INFO - 'LOGNAME': 'cltbld', 12:39:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:39:20 INFO - 'MOZ_NO_REMOTE': '1', 12:39:20 INFO - 'NO_EM_RESTART': '1', 12:39:20 INFO - 'PAGER': '/bin/cat', 12:39:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:39:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:39:20 INFO - 'PWD': '/builds/slave/test', 12:39:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:39:20 INFO - 'SHELL': '/bin/bash', 12:39:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tw5QAbTsLM/Listeners', 12:39:20 INFO - 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/', 12:39:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:39:20 INFO - 'USER': 'cltbld', 12:39:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:39:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:39:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:39:20 INFO - 'XPC_FLAGS': '0x0', 12:39:20 INFO - 'XPC_SERVICE_NAME': '0', 12:39:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:39:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:39:20 INFO - Downloading/unpacking blobuploader==1.2.4 12:39: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 12:39: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 12:39: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 12:39: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 12:39: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 12:39: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 12:39:23 INFO - Downloading blobuploader-1.2.4.tar.gz 12:39:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:39:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:39:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:39:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:39:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:39:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:39:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:39:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:39:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:39:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:39: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 12:39: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 12:39: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 12:39: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 12:39: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 12:39: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 12:39:24 INFO - Downloading docopt-0.6.1.tar.gz 12:39:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:39:24 INFO - Installing collected packages: blobuploader, requests, docopt 12:39:24 INFO - Running setup.py install for blobuploader 12:39:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:39:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:39:24 INFO - Running setup.py install for requests 12:39:25 INFO - Running setup.py install for docopt 12:39:25 INFO - Successfully installed blobuploader requests docopt 12:39:25 INFO - Cleaning up... 12:39:25 INFO - Return code: 0 12:39:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:39:25 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')]} 12:39:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:39:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:39:25 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')]} 12:39:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:39:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:39:25 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 0x1034b3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034c3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1b955d4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103352e00>, '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 0x1031bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10334db20>, '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.qVV45GqxJt/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.tw5QAbTsLM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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 12:39:25 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 12:39:25 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 12:39:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qVV45GqxJt/Render', 12:39:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:39:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:39:25 INFO - 'HOME': '/Users/cltbld', 12:39:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:39:25 INFO - 'LOGNAME': 'cltbld', 12:39:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:39:25 INFO - 'MOZ_NO_REMOTE': '1', 12:39:25 INFO - 'NO_EM_RESTART': '1', 12:39:25 INFO - 'PAGER': '/bin/cat', 12:39:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:39:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:39:25 INFO - 'PWD': '/builds/slave/test', 12:39:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:39:25 INFO - 'SHELL': '/bin/bash', 12:39:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tw5QAbTsLM/Listeners', 12:39:25 INFO - 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/', 12:39:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:39:25 INFO - 'USER': 'cltbld', 12:39:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:39:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:39:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:39:25 INFO - 'XPC_FLAGS': '0x0', 12:39:25 INFO - 'XPC_SERVICE_NAME': '0', 12:39:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:39:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:39:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:39:25 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-2pljoZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:39:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:39:25 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-i4Qo9E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:39:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:39:25 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-zkZ0G8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:39:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:39:26 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-9wDLbr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:39:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:39:26 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-tVKe8z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:39:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:39:26 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-Xqn1Id-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:39:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:39:26 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-duK4Ge-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:39:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:39:26 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-RbTmr2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:39:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:39:26 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-ESPjeT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:39:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:39:26 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-jjTOKk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:39:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:39:26 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-TmaaG8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:39:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:39:26 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-IYVcIJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:39:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:39:27 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-Bo6Hgt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:39:27 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-lE55jH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:39:27 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-Bwk00e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:39:27 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-PBiFsb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:39:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:39:27 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-VTR6c4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:39:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:39:27 INFO - Running setup.py install for manifestparser 12:39:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:39:27 INFO - Running setup.py install for mozcrash 12:39:27 INFO - Running setup.py install for mozdebug 12:39:27 INFO - Running setup.py install for mozdevice 12:39:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:39:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:39:28 INFO - Running setup.py install for mozfile 12:39:28 INFO - Running setup.py install for mozhttpd 12:39:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:39:28 INFO - Running setup.py install for mozinfo 12:39:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:39:28 INFO - Running setup.py install for mozInstall 12:39:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:39:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:39:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:39:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:39:28 INFO - Running setup.py install for mozleak 12:39:28 INFO - Running setup.py install for mozlog 12:39:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:39:29 INFO - Running setup.py install for moznetwork 12:39:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:39:29 INFO - Running setup.py install for mozprocess 12:39:29 INFO - Running setup.py install for mozprofile 12:39:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:39:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:39:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:39:29 INFO - Running setup.py install for mozrunner 12:39:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:39:29 INFO - Running setup.py install for mozscreenshot 12:39:29 INFO - Running setup.py install for moztest 12:39:29 INFO - Running setup.py install for mozversion 12:39:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:39:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:39:30 INFO - Cleaning up... 12:39:30 INFO - Return code: 0 12:39:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:39:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:39:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:39:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:39:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:39:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:39:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:39:30 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 0x1034b3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034c3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1b955d4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103352e00>, '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 0x1031bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10334db20>, '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.qVV45GqxJt/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.tw5QAbTsLM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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 12:39:30 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 12:39:30 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 12:39:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qVV45GqxJt/Render', 12:39:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:39:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:39:30 INFO - 'HOME': '/Users/cltbld', 12:39:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:39:30 INFO - 'LOGNAME': 'cltbld', 12:39:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:39:30 INFO - 'MOZ_NO_REMOTE': '1', 12:39:30 INFO - 'NO_EM_RESTART': '1', 12:39:30 INFO - 'PAGER': '/bin/cat', 12:39:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:39:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:39:30 INFO - 'PWD': '/builds/slave/test', 12:39:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:39:30 INFO - 'SHELL': '/bin/bash', 12:39:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tw5QAbTsLM/Listeners', 12:39:30 INFO - 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/', 12:39:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:39:30 INFO - 'USER': 'cltbld', 12:39:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:39:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:39:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:39:30 INFO - 'XPC_FLAGS': '0x0', 12:39:30 INFO - 'XPC_SERVICE_NAME': '0', 12:39:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:39:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:39:30 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-91OFQm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:39:30 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)) 12:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:39:30 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-PQm09D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:39:30 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)) 12:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:39:30 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-GQEkS6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:39:30 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)) 12:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:39:30 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-OPGuG5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:39:30 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)) 12:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:39:30 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-y8ZxpJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:39:30 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)) 12:39:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:39:30 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-B7YIRa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:39:31 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)) 12:39:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:39:31 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-nYTFXF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:39:31 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)) 12:39:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:39:31 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-tLpsfr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:39:31 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)) 12:39:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:39:31 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-zkan5c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:39:31 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)) 12:39:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:39:31 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-AP35ko-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:39:31 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)) 12:39:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:39:31 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-MLbwL8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:39:31 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)) 12:39:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:39:31 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-6Gn3jz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:39:31 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)) 12:39:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:39:31 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-IgEmj2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:39:31 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)) 12:39:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:39:31 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-RPPaW8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:39:32 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)) 12:39:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:39:32 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-09MoOW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:39:32 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)) 12:39:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:39:32 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-n2QsUy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:39:32 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)) 12:39:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:39:32 INFO - Running setup.py (path:/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/pip-adrTgo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:39:32 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)) 12:39:32 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)) 12:39:32 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)) 12:39:32 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)) 12:39:32 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)) 12:39:32 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)) 12:39:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:39:32 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 12:39:32 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 12:39:32 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 12:39:32 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 12:39:32 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 12:39:32 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 12:39:34 INFO - Downloading blessings-1.6.tar.gz 12:39:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:39:34 INFO - Installing collected packages: blessings 12:39:34 INFO - Running setup.py install for blessings 12:39:35 INFO - Successfully installed blessings 12:39:35 INFO - Cleaning up... 12:39:35 INFO - Return code: 0 12:39:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:39:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:39:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:39:35 INFO - Reading from file tmpfile_stdout 12:39:35 INFO - Current package versions: 12:39:35 INFO - blessings == 1.6 12:39:35 INFO - blobuploader == 1.2.4 12:39:35 INFO - docopt == 0.6.1 12:39:35 INFO - manifestparser == 1.1 12:39:35 INFO - mozInstall == 1.12 12:39:35 INFO - mozcrash == 0.17 12:39:35 INFO - mozdebug == 0.1 12:39:35 INFO - mozdevice == 0.48 12:39:35 INFO - mozfile == 1.2 12:39:35 INFO - mozhttpd == 0.7 12:39:35 INFO - mozinfo == 0.9 12:39:35 INFO - mozleak == 0.1 12:39:35 INFO - mozlog == 3.1 12:39:35 INFO - moznetwork == 0.27 12:39:35 INFO - mozprocess == 0.22 12:39:35 INFO - mozprofile == 0.28 12:39:35 INFO - mozrunner == 6.11 12:39:35 INFO - mozscreenshot == 0.1 12:39:35 INFO - mozsystemmonitor == 0.0 12:39:35 INFO - moztest == 0.7 12:39:35 INFO - mozversion == 1.4 12:39:35 INFO - psutil == 3.1.1 12:39:35 INFO - requests == 1.2.3 12:39:35 INFO - wsgiref == 0.1.2 12:39:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:39:35 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')]} 12:39:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:39:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:39:35 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')]} 12:39:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:39:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:39:35 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 0x1034b3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034c3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1b955d4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103352e00>, '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 0x1031bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10334db20>, '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.qVV45GqxJt/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.tw5QAbTsLM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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 12:39:35 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 12:39:35 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 12:39:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qVV45GqxJt/Render', 12:39:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:39:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:39:35 INFO - 'HOME': '/Users/cltbld', 12:39:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:39:35 INFO - 'LOGNAME': 'cltbld', 12:39:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:39:35 INFO - 'MOZ_NO_REMOTE': '1', 12:39:35 INFO - 'NO_EM_RESTART': '1', 12:39:35 INFO - 'PAGER': '/bin/cat', 12:39:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:39:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:39:35 INFO - 'PWD': '/builds/slave/test', 12:39:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:39:35 INFO - 'SHELL': '/bin/bash', 12:39:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tw5QAbTsLM/Listeners', 12:39:35 INFO - 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/', 12:39:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:39:35 INFO - 'USER': 'cltbld', 12:39:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:39:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:39:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:39:35 INFO - 'XPC_FLAGS': '0x0', 12:39:35 INFO - 'XPC_SERVICE_NAME': '0', 12:39:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:39:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:39:35 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)) 12:39:35 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)) 12:39:35 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)) 12:39:35 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)) 12:39:35 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)) 12:39:35 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)) 12:39:35 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)) 12:39:35 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)) 12:39:35 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)) 12:39:35 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)) 12:39:35 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)) 12:39:35 INFO - Cleaning up... 12:39:35 INFO - Return code: 0 12:39:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:39:35 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')]} 12:39:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:39:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:39:35 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')]} 12:39:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:39:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:39:35 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 0x1034b3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034c3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1b955d4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103352e00>, '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 0x1031bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10334db20>, '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.qVV45GqxJt/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.tw5QAbTsLM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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 12:39:35 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 12:39:35 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 12:39:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qVV45GqxJt/Render', 12:39:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:39:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:39:35 INFO - 'HOME': '/Users/cltbld', 12:39:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:39:35 INFO - 'LOGNAME': 'cltbld', 12:39:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:39:35 INFO - 'MOZ_NO_REMOTE': '1', 12:39:35 INFO - 'NO_EM_RESTART': '1', 12:39:35 INFO - 'PAGER': '/bin/cat', 12:39:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:39:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:39:35 INFO - 'PWD': '/builds/slave/test', 12:39:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:39:35 INFO - 'SHELL': '/bin/bash', 12:39:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tw5QAbTsLM/Listeners', 12:39:35 INFO - 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/', 12:39:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:39:35 INFO - 'USER': 'cltbld', 12:39:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:39:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:39:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:39:35 INFO - 'XPC_FLAGS': '0x0', 12:39:35 INFO - 'XPC_SERVICE_NAME': '0', 12:39:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:39:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:39:35 INFO - Downloading/unpacking jsonschema 12:39:35 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 12:39:35 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 12:39:35 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 12:39:35 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 12:39:35 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 12:39:35 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 12:39:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:39:38 INFO - Downloading/unpacking functools32 (from jsonschema) 12:39: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 12:39: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 12:39: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 12:39: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 12:39: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 12:39: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 12:39:38 INFO - Downloading functools32-3.2.3-2.tar.gz 12:39:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:39:38 INFO - warning: no files found matching '*.txt' 12:39:38 INFO - no previously-included directories found matching 'build' 12:39:38 INFO - no previously-included directories found matching 'dist' 12:39:38 INFO - no previously-included directories found matching '.git*' 12:39:38 INFO - Installing collected packages: jsonschema, functools32 12:39:38 INFO - Running setup.py install for functools32 12:39:39 INFO - warning: no files found matching '*.txt' 12:39:39 INFO - no previously-included directories found matching 'build' 12:39:39 INFO - no previously-included directories found matching 'dist' 12:39:39 INFO - no previously-included directories found matching '.git*' 12:39:39 INFO - Successfully installed jsonschema functools32 12:39:39 INFO - Cleaning up... 12:39:39 INFO - Return code: 0 12:39:39 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 12:39:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:39:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:39:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:39:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:39:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:39:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:39:39 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 0x1034b3a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1034c3420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1b955d4f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103352e00>, '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 0x1031bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10334db20>, '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.qVV45GqxJt/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.tw5QAbTsLM/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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 12:39:39 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 12:39:39 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 12:39:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qVV45GqxJt/Render', 12:39:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:39:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:39:39 INFO - 'HOME': '/Users/cltbld', 12:39:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:39:39 INFO - 'LOGNAME': 'cltbld', 12:39:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:39:39 INFO - 'MOZ_NO_REMOTE': '1', 12:39:39 INFO - 'NO_EM_RESTART': '1', 12:39:39 INFO - 'PAGER': '/bin/cat', 12:39:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:39:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:39:39 INFO - 'PWD': '/builds/slave/test', 12:39:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:39:39 INFO - 'SHELL': '/bin/bash', 12:39:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tw5QAbTsLM/Listeners', 12:39:39 INFO - 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/', 12:39:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:39:39 INFO - 'USER': 'cltbld', 12:39:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:39:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:39:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:39:39 INFO - 'XPC_FLAGS': '0x0', 12:39:39 INFO - 'XPC_SERVICE_NAME': '0', 12:39:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:39:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:39:39 INFO - Downloading/unpacking flake8 12:39:39 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 12:39:39 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 12:39:39 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 12:39:39 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 12:39:39 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 12:39:39 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 12:39:41 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 12:39:41 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 12:39:41 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 12:39:41 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 12:39:41 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 12:39:41 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 12:39:41 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 12:39:41 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 12:39:42 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 12:39:42 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 12:39: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 12:39: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 12:39: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 12:39: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 12:39: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 12:39: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 12:39:42 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 12:39: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 12:39: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 12:39: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 12:39: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 12:39: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 12:39: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 12:39:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 12:39:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:39:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:39:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:39:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:39:43 INFO - no previously-included directories found matching 'docs/_build' 12:39:43 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 12:39:43 INFO - Running setup.py install for pep8 12:39:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:39:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:39:43 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:39:43 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:39:43 INFO - no previously-included directories found matching 'docs/_build' 12:39:43 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 12:39:43 INFO - Successfully installed flake8 mccabe pyflakes pep8 12:39:43 INFO - Cleaning up... 12:39:43 INFO - Return code: 0 12:39:43 INFO - Running post-action listener: _resource_record_post_action 12:39:43 INFO - Running post-action listener: _start_resource_monitoring 12:39:43 INFO - Starting resource monitoring. 12:39:43 INFO - ##### 12:39:43 INFO - ##### Running install step. 12:39:43 INFO - ##### 12:39:43 INFO - Running pre-action listener: _resource_record_pre_action 12:39:43 INFO - Running main action method: install 12:39:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:39:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:39:43 INFO - Reading from file tmpfile_stdout 12:39:43 INFO - Detecting whether we're running mozinstall >=1.0... 12:39:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:39:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:39:43 INFO - Reading from file tmpfile_stdout 12:39:43 INFO - Output received: 12:39:43 INFO - Usage: mozinstall [options] installer 12:39:43 INFO - Options: 12:39:43 INFO - -h, --help show this help message and exit 12:39:43 INFO - -d DEST, --destination=DEST 12:39:43 INFO - Directory to install application into. [default: 12:39:43 INFO - "/builds/slave/test"] 12:39:43 INFO - --app=APP Application being installed. [default: firefox] 12:39:43 INFO - mkdir: /builds/slave/test/build/application 12:39:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:39:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 12:40:16 INFO - Reading from file tmpfile_stdout 12:40:16 INFO - Output received: 12:40:16 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 12:40:16 INFO - Running post-action listener: _resource_record_post_action 12:40:16 INFO - ##### 12:40:16 INFO - ##### Running run-tests step. 12:40:16 INFO - ##### 12:40:16 INFO - Running pre-action listener: _resource_record_pre_action 12:40:16 INFO - Running main action method: run_tests 12:40:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:40:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:40:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:40:16 INFO - 2016-05-27 12:40:16.298 screenresolution[1644:7777] starting screenresolution argv=screenresolution get 12:40:16 INFO - 2016-05-27 12:40:16.301 screenresolution[1644:7777] Display 0: 1600x1200x32@60 12:40:16 INFO - 2016-05-27 12:40:16.307 screenresolution[1645:7779] starting screenresolution argv=screenresolution list 12:40:16 INFO - Available Modes on Display 0 12:40:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:40:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:40:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:40:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:40:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:40:16 INFO - Intel Iris: 12:40:16 INFO - Chipset Model: Intel Iris 12:40:16 INFO - Type: GPU 12:40:16 INFO - Bus: Built-In 12:40:16 INFO - VRAM (Dynamic, Max): 1536 MB 12:40:16 INFO - Vendor: Intel (0x8086) 12:40:16 INFO - Device ID: 0x0a2e 12:40:16 INFO - Revision ID: 0x0009 12:40:16 INFO - Displays: 12:40:16 INFO - Display: 12:40:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:40:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:40:16 INFO - Display Serial Number: 1600x1200 60 12:40:16 INFO - Main Display: Yes 12:40:16 INFO - Mirror: Off 12:40:16 INFO - Online: Yes 12:40:16 INFO - Rotation: Supported 12:40:16 INFO - Return code: 0 12:40:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:40:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:40:16 INFO - Python 2.7.3 12:40:16 INFO - Return code: 0 12:40:16 INFO - grabbing minidump binary from tooltool 12:40:16 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')]} 12:40:16 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 0x103352e00>, '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 0x1031bbdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10334db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:40:16 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 12:40:16 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 12:40:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:40:16 INFO - Return code: 0 12:40:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:40:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:40:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:40:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:40:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:40:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 12:40:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 12:40:17 INFO - Return code: 0 12:40:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ABw42i3yQWSh1_tdwbn6SA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0218', '--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 12:40:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ABw42i3yQWSh1_tdwbn6SA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0218 --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 12:40:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qVV45GqxJt/Render', 12:40:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:40:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:40:17 INFO - 'HOME': '/Users/cltbld', 12:40:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:40:17 INFO - 'LOGNAME': 'cltbld', 12:40:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:40:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:40:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:40:17 INFO - 'MOZ_NO_REMOTE': '1', 12:40:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:40:17 INFO - 'NO_EM_RESTART': '1', 12:40:17 INFO - 'PAGER': '/bin/cat', 12:40:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:40:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:40:17 INFO - 'PWD': '/builds/slave/test', 12:40:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:40:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:40:17 INFO - 'SHELL': '/bin/bash', 12:40:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tw5QAbTsLM/Listeners', 12:40:17 INFO - 'TMPDIR': '/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/', 12:40:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:40:17 INFO - 'USER': 'cltbld', 12:40:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:40:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:40:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:40:17 INFO - 'XPC_FLAGS': '0x0', 12:40:17 INFO - 'XPC_SERVICE_NAME': '0', 12:40:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:40:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ABw42i3yQWSh1_tdwbn6SA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0218', '--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 12:40:17 INFO - mozversion application_buildid: 20160527114145 12:40:17 INFO - mozversion application_changeset: 2ab4b64da78750d82f120faa25327f25cf11e579 12:40:17 INFO - mozversion application_display_name: FirefoxDeveloperEdition 12:40:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:40:17 INFO - mozversion application_name: Firefox 12:40:17 INFO - mozversion application_remotingname: firefox-dev 12:40:17 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:40:17 INFO - mozversion application_vendor: Mozilla 12:40:17 INFO - mozversion application_version: 48.0a2 12:40:17 INFO - mozversion platform_buildid: 20160527114145 12:40:17 INFO - mozversion platform_changeset: 2ab4b64da78750d82f120faa25327f25cf11e579 12:40:17 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:40:17 INFO - mozversion platform_version: 48.0a2 12:40:17 INFO - using testdate: 1464378017 12:40:17 INFO - actual date: 1464378017 12:40:17 INFO - starting webserver on 'localhost:49275' 12:40:17 INFO - SUITE-START | Running 2 tests 12:40:17 INFO - TEST-START | dromaeo_css 12:40:17 INFO - operating with platform_type : mac_ 12:40:17 INFO - Initialising browser for dromaeo_css test... 12:40:17 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/tmpURc9Ma/profile http://localhost:49275/getInfo.html) 12:40:20 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 12:40:20 INFO - PROCESS | 1663 | colorDepth:24 12:40:20 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 12:40:20 INFO - PROCESS | 1663 | __metrics 12:40:20 INFO - PROCESS | 1663 | 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" 12:40:20 ERROR - PROCESS | 1663 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 12:40:21 INFO - TEST-INFO | 1663: exit 0 12:40:21 INFO - Browser initialized. 12:40:21 INFO - Running cycle 1/1 for dromaeo_css test... 12:40:21 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/tmpURc9Ma/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:40:33 INFO - PROCESS | 1665 | RSS: Main: 211058688 12:40:33 INFO - PROCESS | 1665 | 12:41:24 INFO - PROCESS | 1665 | 1464378084551 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] 12:43:35 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 12:43:36 INFO - PROCESS | 1665 | RSS: Main: 335597568 12:43:36 INFO - PROCESS | 1665 | 12:46:39 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 12:46:40 INFO - PROCESS | 1665 | RSS: Main: 357175296 12:46:40 INFO - PROCESS | 1665 | 12:49:42 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 12:49:43 INFO - PROCESS | 1665 | RSS: Main: 361062400 12:49:43 INFO - PROCESS | 1665 | 12:52:45 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 12:52:45 INFO - PROCESS | 1665 | RSS: Main: 359718912 12:52:45 INFO - PROCESS | 1665 | 12:55:49 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 12:55:49 INFO - PROCESS | 1665 | RSS: Main: 324259840 12:55:49 INFO - PROCESS | 1665 | 12:58:52 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 12:58:52 INFO - PROCESS | 1665 | RSS: Main: 367419392 12:58:52 INFO - PROCESS | 1665 | 12:58:52 INFO - PROCESS | 1665 | __start_tp_report 12:58:52 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 12:58:52 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 12:58:52 INFO - PROCESS | 1665 | |i|pagename|runs| 12:58:52 INFO - PROCESS | 1665 | |0;dojo.html;3368.27582069855;3424.982875085624;3443.1392151962;3453.8963831085075;3496.4230901901187;5661.264035675362;5661.745221465034;5667.801626943047;5671.177679236508;5696.914546281809;5658.377578466376;5682.687452190128;5701.116326969316;5706.971465142678;5731.398203188668;5049.621278404116;5080.77136528857;5115.667481613702;5203.609729270301;5221;5187.844364669066;5207.817726379578;5210.609204309673;5210.687358758468;5211.791528338862;5131.204663277179;5175.844724658266;5201.609879259052;5204.42751297357;5205.661631993928;5192.948070519285;5234.581233501311;5236.633435659505;5248.81629142982;5275.57795376369;4995.425526064477;5039.672421292605;5091.79632814687;5154.587632989352;5159.793608255665;5582.27430434041;5586.636868603528;5604.775808967636;5626.071698169797;5629.408912064216;6047.516198704093;6195.132681424604;6204.906926396107;6219;6223.782167624111;6063.896013346911;6130.734471166807;6179.677430591871;6183.9381606183815;6187.504999600022;5767.423257674199;5796.246487956539;5810.244668193108;5819.88360232793;5825.38833422493;6238.968805155945;6243.875122497525;6271.686415679198;6278.434940855345;6285.497160227171;5975.701214939236;6054.462194567704;6087.391260873966;6122;6168.383161683885;6170.938290617126;6175.104609831549;6189.566730328924;6196.039613859859;6208.099825525273;6135.53983451234;6175.84560385985;6197.225346831646;6197.25632924043;6215.564910456224;6017.789377371771;6115.818436018264;6115.879294474695;6137.631742095399;6183.5671502994355;4855;4864.683795553277;4888.413390393175;4893.143699852511;4893.21708526634;4305.913881722348;4379.408779814684;4391.187630288407;4446.133004064176;4470.932936005963;5628.04323265046;5653.349864765604;5667.829965101054;5694.743736531866;5706.629069110494;6116.571839971249;6184.103305020747;6186.1648677428875;6198.194234749544;6200.131981522591;5602.4957753802355;5662.37713851476;5665.915011274834;5706.400827913092;5710.229119068956;5320.787168513293;5326.600504962066;5338.252644629755;5338.599605029638;5345.224942383333;5121;6195.535334849995;6274.811755647429;6324.146240257598;6363.045543168377;6142.897836592492;6149.938500615115;6211.409916057967;6215.005599104122;6225.470834979077;6302.558820882494;6302.842428939313;6312.210973628296;6317.368263173646;6321.810345689637;6020.30766461845;6115.027386288435;6161.599496032743;6166.445019948226;6167.506599472121;5368.973155134199;6122.154209354628;6176.536759742948;6180;6199.380061993765;5364.463553644604;6103.786367477117;6104.877902441837;6118.877622447437;6133.6319820810895;5937.554683398849;5997.940020599738;6066.241719785027;6131;6142.355052719578;5889.411058894248;5932.466078052996;5948.791792287422;5991.64050156992;5999.460048595647;6039.033754599243;6064.878702425838;6091.208142941389;6096.695165241809;6101.572889897842;5772.682502462237;5876.059830427112;5947.226860508106;5957.463828255478;5975.581709280309 12:58:52 INFO - PROCESS | 1665 | |1;ext.html;511.19231614049795;516.212775517336;527.7097596322022;573.9225204597378;589.767042018403;30610.479794823576;36331.273374532524;37948.05129871751;38807.223855522876;38882.33411990565;41177.17645647085;41728.58271417285;41752.16495670085;41774;41846.79076604613;8534.530600816952;9400.106989835955;9613.807723845519;9623.615055397811;9636.807263854718;8590.02429441229;8713.869291960626;8716.171963663473;8719.433236839584;11468.254563453349;11321.773564528705;11395.886041139607;11402.543898244101;11415.429228538582;11495.942520287428;11409.771804563903;11426.885731142665;11520.481578328996;11525.53897844081;11531.07751379896;8641.827163456695;11271.929166729147;11469.369184694839;11675.357855317954;11688.240264382788;37942.81028594839;38353.80823095867;38503;38530.807345963374;38531;2706.8240564363255;3175.237942893712;3274.5743053403144;3440.6215316315183;3522.383582872987;27374;27628;27871;28206.717932820615;28244.293892652848;2697.973020269792;2703.1349968010245;2738.945221095587;2817;2825.519661657523;446713;449241;449769;449882;450707;58368.416315837145;59730.80538389295;59773.70113149406;59784.70107649434;59811;62140.689296553224;62157.37842621516;62227.377726222156;62273;62301;16598.087105209088;17133.485995420157;17142;17143.828561714472;17147.657046859244;13412.32938353098;13491.25798081115;13542.25517596522;13554.457821687021;13585.184888906697;13900.51348202808;14256.429742810378;14259.00186986901;14280.642983925485;14300.499482518062;5585.078462053797;6209.975354066529;6214.8135555933395;6230.72886734459;6231;983.5524836199655;1013.6973988424838;1037.0044757033181;1060.6711919304885;1069.0805906920043;25877.611835822478;29000.854995724887;29668.851655742015;30871.50070210063;31859.362812743617;953.2040745977187;1088.967330980072;1159.0437888741822;1175.3653027365126;1178.5639313454076;13438.932805335911;13576.456941722417;13706.349403887756;13854.376553055137;13890;566707;572131;573193;573822;574217;49417;50355.74822125866;55729.164062539094;56606.716966414904;56841.43158568361;1486.851314868526;1491.5674454408534;1497.6255936015996;1499.1679617812001;1511.909285442877;24522.14172503954;28277.29306767265;28350.574741379307;28891.85554072216;28970.855145724137;47300.763496183674;47324;47737;47770.805729857035;47784.99999999861;490206;605434;629948;638880;640829;24669.62995555032;24895;25143.622845657697;25245.495090098462;25275.98896044211;41268.79365603153;41329.58670413378;42284.36573451462;42290.788546058306;42456.575434245264;34572.481412778325;34765.65234347624;35439;35605.64394356127;36956;35941.124749760194;36021.27957440889;36072.4589131158;36432.45351319679;36669.89990300295;35922.10194745152;36160.095997599215;37107.70123045788;37156.628433716396;37171.25657486781;31805.36389272248;32475.35049299048;32616;32752.344953100328;32916.34167316592;22002;24344.026238950242;24658.136965206133;24772.132975345776;25580 12:58:52 INFO - PROCESS | 1665 | |2;jquery.html;398.1916709080567;399.8100902071516;401.867383763358;404.35303514376983;410.37008192424616;20264;20871.478213044662;20937.58124837504;21023.159073637074;21656.783432165674;20631.865247411388;21437;21501.462463438424;21512.247071352507;21798.673019904712;5399.946000539993;5523.530499907512;5986.401359864022;6160.414760597759;6179.721912513947;6072.605280656765;6086.969565152191;6132.233470816148;6139.969300153493;6142.5085993120665;5525.033119204143;5530.364008139055;5543.196236545698;5613.158026296068;6068.059450785136;5007.474215207405;5244.344456942882;5532.1425179097105;5535.391106978229;5542.140968149944;5318.654287471302;5396.19057141428;5407.026735187665;5482.945170548283;5483.506484416422;25516.851741671668;26695.73304266952;26718.46563068747;26746.598801018;26920.326991825164;2069.606774712815;2145.8605190662556;2159.88120653364;2237.44063984004;2792.966602357141;9960.153386962116;9962.70111896637;10368.118709909666;10478.109360704273;12045.518179272818;1710.9056566259046;1742.7821522309712;1794.5872449336596;1838.6782313095289;1848.9075546222634;101398;114780.85219147785;116275;119328;122005.16992245124;11798.823017654742;11826.34955077479;11861.940690296493;11915.463804128834;11930;15512.45706400293;15547.844521554865;15548.533543993472;15555.844441555666;15574.454894078654;8904.154105359981;9020.729378118667;9023.684171053981;9164.816703665889;9176.816463670822;9065.82144321234;9073.637054517745;9075.727728168165;9171.541422928827;9207.539623018822;4566.13243483737;4703.459102203222;4774.761261936889;4801.207800712913;4868;1935.2355819451238;1974.3879397386856;1999.4501512084003;2019.5153163240716;2036.7372229217979;891.4250308550949;907.8547432410783;918.2653876898454;995.6465454763454;1023.4933707814679;19915;20971.37085887426;21027.369178924655;21135.365939021853;21167.470813229484;929.6281487405092;944.8771659684197;945.3571571331561;973.6640858903667;1053.1101219469535;10982.176336774774;11182.776344473228;11193.72015699614;11211.103111751041;11226.21416500837;481676;489258;498419;499838;500061;134129;142323;143116;144084;148165;991.9504024798875;1045.1012129568717;1053.794510070529;1054.2146101154535;1055.076807793181;15130.697386051997;18087.909560452114;18765.249390024244;18899.338523151622;19168.712469312693;51581;53654.65863353448;54795.17807232815;55391.72304138453;57315;74548.88176677245;77670.6116469414;79509;81473.59263203647;81543;7364.926350736424;8270.586470676562;8277.95861020691;8480.830383392175;8562.357823163164;8992.50541220213;12161.695957600778;12734.172278801849;13121.540746073842;13133.868661313647;11032.117430605373;11270.718232044264;11412.885871141514;11462.025727813229;11616.825747613962;11049.779004420026;11076.446177691245;11089.778204436028;11167.1066314693;11412.02997745201;10932.125429965743;11441.599544015919;11664.241824281395;11800.17398782077;11860.703482413008;7579.355754760907;8159.551224682699;8257.83484330298;8446.66213351459;8631.525266110173;9379.108984646447;9619.124270767134;11302.982731553871;11515.078793696652;11729.824052639387 12:58:52 INFO - PROCESS | 1665 | |3;mootools.html;1398.23796031163;1537.4772577323708;1573.087609186672;1748.676494848453;1873.8969356685386;7691.346235569969;7866.449348545604;8471.36464765142;8525.360597955163;8704.216620504154;8389.496630202177;8648.697295594655;8662.393632445732;8669.003064647568;8693.913060869389;1847.3534263007907;2127.8936053197353;2196.176433837311;2395.544846479172;2419.370963549481;2202.196198387593;2207.878566678832;2371.027878569787;2436.2691192642224;2440.6461063145834;2390.306811024801;2390.509945461185;2390.95218095638;2391.0555330644384;2392.9162479313227;2300.0799680127916;2381.6189409694366;2385.2009576791797;2391.5934291170543;2392.0431827269144;2091.006771783391;2343.4961483281004;2410.2648692148887;2416.685830841997;2418.8548687078833;8579.356548258906;8653.826923461558;8663.653453861902;8665.39342246043;8670.826583468359;5177.974110129463;5348.545373643245;5380.677159370451;5383.407825139231;5389.676619402849;3405.8126803025825;3471.930561388783;3531.770434921722;3535.2399234164777;3551.6803487686234;1012.3773879064322;1035.730710015394;1037.4968140451874;1043.4209013997229;1059.8887116852698;11795.292282462993;11798;11805.350705711182;11874.406279686067;11879.40602969848;4251.44731184944;4627.976860115745;4754.9286760698615;4780.259059845729;4824.131656301899;9932.851007234898;9942.254330925209;9958;9958.651447199314;9965.35225210359;5366.356037275552;5383.569314454834;5425.972870135624;5426.972865135649;5436.103042997861;4316.6546676265825;4889.755512224375;4947.356843610308;4947.505249475095;4955.554000140005;3693.7045036397026;3929.0766669832565;3995.220931918248;4028.7582745035393;4032.858849940238;1306.4839388441512;1315.651352391617;1326.9251905956014;1327.7875539913568;1336.1648969394128;837.417994493821;850.5109562001874;856.2037305306125;922.796984663373;928.832810094176;5613.691246981455;5736.168255602997;5857.824265271964;5910.379410161957;5921.763129474859;935.9532023398802;964.0455948610964;967.2552134856121;976.0776066616979;1002.674130907458;3759.342115129841;3856.672182864488;3889.8833035008997;3906.4335671328067;3963.980180099081;8608.612612432456;8976.955115224382;10507;10649.414282214553;10774.676759697222;10537.209709271836;10631.255812093234;10638.94680526608;10720.571177152982;10815.513301901434;8235.629396677165;8259.8348033039;8371.497710137412;8376.664933402595;8479.236868681724;5132.538071573576;5438.564914806885;5454.645448045864;5558.24963629921;5609.467100625434;9460.337776355589;9595.184409325286;9699.6120155193;9751.073648003428;9766.951165244129;9012.459252444874;9352.719418417459;9375.96864344908;9926.751831203988;9932.801343972935;6675.83310417224;7349.55902645843;7406.7037318506655;7473.663685134291;7483.775486735407;7542.698292068256;7930.484518506279;8098.392620553364;8173.18268173178;8177.713780017671;7214.134303883568;7330;7347.08161479815;7425.888611670937;7478.775636730907;7222.711091556489;7236.38490728294;7277.490575659653;7350.70597176107;7363.447741419308;6765.458763299022;6868.7252509901045;6877.105976223059;6901.206361268318;6967.198772141269;4744.833930812405;4759.976200118977;4776.761161941959;4948.292838970549;4955.578775803953;4805.37530121082;4824.445188803333;4904.754762261801;4928.778204980713;4930.457649658606 12:58:52 INFO - PROCESS | 1665 | |4;prototype.html;314.25364758698095;319.3325948767076;326.1617642658367;331.01687986679553;332.3535723018728;6698.196216454026;6707.664616769161;6805.319468053204;6812.148481439814;6817.863642727142;6368.49052075834;6587.769428070019;6626.271110177877;6627.403533681968;6719.126513553243;4780.139574876521;4860.63545234108;4861.902761944759;4865.975670121645;4928.940277840264;4861.781219845116;4884.633652476061;4895.069936712013;4926;4942.876428089308;4895.9510404895855;4911.330147749764;4916.336294600237;4923.606111511071;4931.186354251544;4654.301854721785;4737.620990320766;4869.488703686133;4881.707097574157;4913.557779799818;4159.272127377727;4174.624283814441;4176;4177.26897792885;4185.22573323936;6345.650989195592;6392.20097487814;6405.135306733625;6411.1024456576115;6514;5886.293644762613;5896.498797602209;5899.085641725539;5900.321463031763;5904.261967254093;6195.791820594985;6802.4217941475035;7145.499815012951;7158.642067896636;7169.139703235593;4434.268345722952;4618.722876627413;4620.996352370952;4633.467151277612;4640.327152562893;6482.481401487869;7270.963645181793;7383.187849336622;7520.435967302419;7566.583837888914;3970.1662650843646;4659.8136074557315;4678.157931572349;4683.110209060268;4693.601043911238;4677.579017888406;4732.1718699227495;4747.477777444478;4752.976235118871;4767.308740232646;5683.204351390808;5697.6296540724725;5716.628419152742;5720.713964301768;5739.540836733051;5818;6875.656217189121;7119.540494198677;7228.349448549656;7265.673044713;1353.343628340254;1643.1127191316555;1654.123314643241;1656.9088700121608;1657.7513372993906;970.5292932927524;976.3409698453515;1022.9897701023043;1024.7489365105596;1046.3930920066343;6237.8148151851;6481.805545833632;6491.902621460682;6496.252930912911;6578.868422631521;5568.44315568448;7401.037865077506;7405.296496832792;7426.220246874001;7499.375218673421;6737.9326206737305;6740.224874139439;6745.696443660047;6748.9876518522615;6764.357386048416;3901.258760835489;3961.425593288957;3966.2464131814863;3969.285528604879;3972.106276087857;7565.243475652391;7832.255935686101;7836;7868.409869259828;7876.975993120857;6744;7307.684616769033;7750.186230445835;7765.961170194226;7790.065192176975;6333.493320534346;6853.732059568995;7849.136594974549;7877.921220787719;7880.921190788019;6740.9325906740305;7283.890741638986;7446.813829654302;7520.736774212876;7553.546787192786;7387.482876198829;7406.962965185139;7429.108506979197;7435.553866768011;7445.7766267012075;7813.921860781319;7826.960865195638;7830.1386847447875;7840;7873;4880.46314905353;4951.10880041596;4953.356063711694;4975;4977.900441991067;7157.928420715934;7352;7420.517666351886;7426.628668566658;7428.517146385684;7159.928400715926;7201.783946481614;7210.89183662235;7222.133343998544;7233.204347521873;7187.5687458752645;7198.964005179941;7202.89195662055;7208.71165153388;7224.13310402755;7017.122859642544;7021.929780702128;7026.508144429841;7029.226785053538;7033.050538177281;6929.89605155913;7209.801010691789;7217.458690598121;7246.673899674422;7254.746083887038;6758.256591774999;6897.862042759217;7004.4396448283;7009.228984811769;7076.681549330189 12:58:52 INFO - PROCESS | 1665 | |5;yui.html;596.9910451343228;670.8088194864463;725.9310365515278;750.8535835512074;754.7282978127873;15059.322330495126;15160.924195379035;15380.384784608603;15396.307166177536;15416.845831541696;13973.441062357493;14145.787813182811;14341.569752907404;14376.640583985405;14377.78433323501;729.237946346067;731.9670614822319;898.5641963647643;907.8093600343935;924.1820988425225;909.727081875438;912.2975309012056;912.7763697894026;913.8218047480744;914.0356923445762;965.652365148546;967.2165545907802;967.5549247346229;967.6177909725654;970.1171933540479;758.2758465665277;938.652698501552;956.1538038835611;956.2493442647558;958.3004406783018;609.6860117039728;701.3687681087011;707.4057791455202;707.8195060259652;707.8230442389403;14917.925410372878;14984.850151498455;14990.400383984628;14991.475298364614;15001.024933379296;11424.771504569946;11576.131790115774;11951.581694640647;12182.208156469802;12509;10040.447775372424;10073.647422340184;10082.848757268646;10224.539895704711;10285.331453455501;3450.070121362002;3450.102973226979;3568.1971556399862;3589.74871758977;3605.2068544920076;77431;78731.6063419685;78768;78802.60598696969;79104.64924429212;9167.679131230509;9480.952595237117;9538.236941044699;9606.423614583147;9744.512774361254;20711;20879.478013049797;20908;20919.47701307449;21443.142274309164;7378.225286344964;7414.073240844895;7454.888176677354;7574.183235088354;7649.120351159577;4032.2338755636333;4040.41413994975;4075.042365044212;4096.365063415175;4341.9131617367475;3554.964450355515;3587.121155316964;3625.093726568358;3627.365211088049;3642.1805093853673;638.218182726166;643.4466358931315;650.9967450162719;675.0515525686418;690.3786592067079;557.4064265099705;590.5246276747259;591.7071049830362;602.3885755957706;611.541343992006;7801.29788319054;8044.959775201203;8079.636416361278;8154.75535733929;8198.795030124294;573.2089716191712;595.4551585299403;597.5040716205539;605.2672185084348;614.4500671898626;3245.334706385185;3590;3777.320082385142;3807.029207552112;3887.3002859485;83811;84507.57746211352;84958.57520712356;84993.15006849976;85127.5743621278;54316.72841635766;73005;73186.26813731795;73629.15927101758;73950;63752.68123659352;64371.678141609926;66343.33656663468;66939.33060669429;68171.29571760746;8242.464239824392;8289.212524810375;8335.291500222549;8480.406371553932;8542.44474109181;38997.805010974764;46402.30396543987;46571.835704106314;47984.80037999078;48686;64873.67563162154;65969.68060638856;66523;66706;66987.66506167439;10675.145988321072;10739.08717758968;11037.834432483358;11339.886601133883;11371;11636.94181529087;11758.3532905691;11908.52365905354;11975.880241197476;12014;11384.37385943781;11459.025982791556;11472.827907581226;11730.472128754056;11754.118441117122;11198.272112312674;11307.717307067389;11369.829452558053;11734.647960561197;11778.352190629597;10811.729706757395;10999.285046471954;11000.449977500934;11290.096792256436;11899.286042837457;9291.442513449214;9298.256139508689;9478.146966773023;9551.14039736427;9764.218862490841;8249.670013199404;8901.376903616685;9431.858522122311;9457.621695132118;9494.261244319678 12:58:52 INFO - PROCESS | 1665 | __end_tp_report 12:58:52 INFO - PROCESS | 1665 | __start_cc_report 12:58:52 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,1254 12:58:52 INFO - PROCESS | 1665 | __end_cc_report 12:58:52 INFO - PROCESS | 1665 | __startTimestamp1464379132794__endTimestamp 12:58:52 INFO - PROCESS | 1665 | 12:58:52 INFO - PROCESS | 1665 | ------- Summary: start ------- 12:58:52 INFO - PROCESS | 1665 | Number of tests: 6 12:58:52 INFO - PROCESS | 1665 | 12:58:52 INFO - PROCESS | 1665 | [#0] dojo.html Cycles:165 Average:5696.97 Median:5937.55 stddev:623.69 (10.5%) stddev-sans-first:598.25 12:58:52 INFO - PROCESS | 1665 | Values: 3368.3 3425.0 3443.1 3453.9 3496.4 5661.3 5661.7 5667.8 5671.2 5696.9 5658.4 5682.7 5701.1 5707.0 5731.4 5049.6 5080.8 5115.7 5203.6 5221.0 5187.8 5207.8 5210.6 5210.7 5211.8 5131.2 5175.8 5201.6 5204.4 5205.7 5192.9 5234.6 5236.6 5248.8 5275.6 4995.4 5039.7 5091.8 5154.6 5159.8 5582.3 5586.6 5604.8 5626.1 5629.4 6047.5 6195.1 6204.9 6219.0 6223.8 6063.9 6130.7 6179.7 6183.9 6187.5 5767.4 5796.2 5810.2 5819.9 5825.4 6239.0 6243.9 6271.7 6278.4 6285.5 5975.7 6054.5 6087.4 6122.0 6168.4 6170.9 6175.1 6189.6 6196.0 6208.1 6135.5 6175.8 6197.2 6197.3 6215.6 6017.8 6115.8 6115.9 6137.6 6183.6 4855.0 4864.7 4888.4 4893.1 4893.2 4305.9 4379.4 4391.2 4446.1 4470.9 5628.0 5653.3 5667.8 5694.7 5706.6 6116.6 6184.1 6186.2 6198.2 6200.1 5602.5 5662.4 5665.9 5706.4 5710.2 5320.8 5326.6 5338.3 5338.6 5345.2 5121.0 6195.5 6274.8 6324.1 6363.0 6142.9 6149.9 6211.4 6215.0 6225.5 6302.6 6302.8 6312.2 6317.4 6321.8 6020.3 6115.0 6161.6 6166.4 6167.5 5369.0 6122.2 6176.5 6180.0 6199.4 5364.5 6103.8 6104.9 6118.9 6133.6 5937.6 5997.9 6066.2 6131.0 6142.4 5889.4 5932.5 5948.8 5991.6 5999.5 6039.0 6064.9 6091.2 6096.7 6101.6 5772.7 5876.1 5947.2 5957.5 5975.6 12:58:52 INFO - PROCESS | 1665 | 12:58:52 INFO - PROCESS | 1665 | [#1] ext.html Cycles:180 Average:66844.14 Median:19480.69 stddev:145863.06 (748.8%) stddev-sans-first:146187.22 12:58:52 INFO - PROCESS | 1665 | Values: 511.2 516.2 527.7 573.9 589.8 30610.5 36331.3 37948.1 38807.2 38882.3 41177.2 41728.6 41752.2 41774.0 41846.8 8534.5 9400.1 9613.8 9623.6 9636.8 8590.0 8713.9 8716.2 8719.4 11468.3 11321.8 11395.9 11402.5 11415.4 11495.9 11409.8 11426.9 11520.5 11525.5 11531.1 8641.8 11271.9 11469.4 11675.4 11688.2 37942.8 38353.8 38503.0 38530.8 38531.0 2706.8 3175.2 3274.6 3440.6 3522.4 27374.0 27628.0 27871.0 28206.7 28244.3 2698.0 2703.1 2738.9 2817.0 2825.5 446713.0 449241.0 449769.0 449882.0 450707.0 58368.4 59730.8 59773.7 59784.7 59811.0 62140.7 62157.4 62227.4 62273.0 62301.0 16598.1 17133.5 17142.0 17143.8 17147.7 13412.3 13491.3 13542.3 13554.5 13585.2 13900.5 14256.4 14259.0 14280.6 14300.5 5585.1 6210.0 6214.8 6230.7 6231.0 983.6 1013.7 1037.0 1060.7 1069.1 25877.6 29000.9 29668.9 30871.5 31859.4 953.2 1089.0 1159.0 1175.4 1178.6 13438.9 13576.5 13706.3 13854.4 13890.0 566707.0 572131.0 573193.0 573822.0 574217.0 49417.0 50355.7 55729.2 56606.7 56841.4 1486.9 1491.6 1497.6 1499.2 1511.9 24522.1 28277.3 28350.6 28891.9 28970.9 47300.8 47324.0 47737.0 47770.8 47785.0 490206.0 605434.0 629948.0 638880.0 640829.0 24669.6 24895.0 25143.6 25245.5 25276.0 41268.8 41329.6 42284.4 42290.8 42456.6 34572.5 34765.7 35439.0 35605.6 36956.0 35941.1 36021.3 36072.5 36432.5 36669.9 35922.1 36160.1 37107.7 37156.6 37171.3 31805.4 32475.4 32616.0 32752.3 32916.3 22002.0 24344.0 24658.1 24772.1 25580.0 12:58:52 INFO - PROCESS | 1665 | 12:58:52 INFO - PROCESS | 1665 | [#2] jquery.html Cycles:180 Average:32646.05 Median:26106.29 stddev:83971.10 (321.7%) stddev-sans-first:84171.76 12:58:52 INFO - PROCESS | 1665 | Values: 398.2 399.8 401.9 404.4 410.4 20264.0 20871.5 20937.6 21023.2 21656.8 20631.9 21437.0 21501.5 21512.2 21798.7 5399.9 5523.5 5986.4 6160.4 6179.7 6072.6 6087.0 6132.2 6140.0 6142.5 5525.0 5530.4 5543.2 5613.2 6068.1 5007.5 5244.3 5532.1 5535.4 5542.1 5318.7 5396.2 5407.0 5482.9 5483.5 25516.9 26695.7 26718.5 26746.6 26920.3 2069.6 2145.9 2159.9 2237.4 2793.0 9960.2 9962.7 10368.1 10478.1 12045.5 1710.9 1742.8 1794.6 1838.7 1848.9 101398.0 114780.9 116275.0 119328.0 122005.2 11798.8 11826.3 11861.9 11915.5 11930.0 15512.5 15547.8 15548.5 15555.8 15574.5 8904.2 9020.7 9023.7 9164.8 9176.8 9065.8 9073.6 9075.7 9171.5 9207.5 4566.1 4703.5 4774.8 4801.2 4868.0 1935.2 1974.4 1999.5 2019.5 2036.7 891.4 907.9 918.3 995.6 1023.5 19915.0 20971.4 21027.4 21135.4 21167.5 929.6 944.9 945.4 973.7 1053.1 10982.2 11182.8 11193.7 11211.1 11226.2 481676.0 489258.0 498419.0 499838.0 500061.0 134129.0 142323.0 143116.0 144084.0 148165.0 992.0 1045.1 1053.8 1054.2 1055.1 15130.7 18087.9 18765.2 18899.3 19168.7 51581.0 53654.7 54795.2 55391.7 57315.0 74548.9 77670.6 79509.0 81473.6 81543.0 7364.9 8270.6 8278.0 8480.8 8562.4 8992.5 12161.7 12734.2 13121.5 13133.9 11032.1 11270.7 11412.9 11462.0 11616.8 11049.8 11076.4 11089.8 11167.1 11412.0 10932.1 11441.6 11664.2 11800.2 11860.7 7579.4 8159.6 8257.8 8446.7 8631.5 9379.1 9619.1 11303.0 11515.1 11729.8 12:58:52 INFO - PROCESS | 1665 | 12:58:52 INFO - PROCESS | 1665 | [#3] mootools.html Cycles:180 Average:5614.14 Median:5263.26 stddev:3125.12 (59.4%) stddev-sans-first:3117.83 12:58:52 INFO - PROCESS | 1665 | Values: 1398.2 1537.5 1573.1 1748.7 1873.9 7691.3 7866.4 8471.4 8525.4 8704.2 8389.5 8648.7 8662.4 8669.0 8693.9 1847.4 2127.9 2196.2 2395.5 2419.4 2202.2 2207.9 2371.0 2436.3 2440.6 2390.3 2390.5 2391.0 2391.1 2392.9 2300.1 2381.6 2385.2 2391.6 2392.0 2091.0 2343.5 2410.3 2416.7 2418.9 8579.4 8653.8 8663.7 8665.4 8670.8 5178.0 5348.5 5380.7 5383.4 5389.7 3405.8 3471.9 3531.8 3535.2 3551.7 1012.4 1035.7 1037.5 1043.4 1059.9 11795.3 11798.0 11805.4 11874.4 11879.4 4251.4 4628.0 4754.9 4780.3 4824.1 9932.9 9942.3 9958.0 9958.7 9965.4 5366.4 5383.6 5426.0 5427.0 5436.1 4316.7 4889.8 4947.4 4947.5 4955.6 3693.7 3929.1 3995.2 4028.8 4032.9 1306.5 1315.7 1326.9 1327.8 1336.2 837.4 850.5 856.2 922.8 928.8 5613.7 5736.2 5857.8 5910.4 5921.8 936.0 964.0 967.3 976.1 1002.7 3759.3 3856.7 3889.9 3906.4 3964.0 8608.6 8977.0 10507.0 10649.4 10774.7 10537.2 10631.3 10638.9 10720.6 10815.5 8235.6 8259.8 8371.5 8376.7 8479.2 5132.5 5438.6 5454.6 5558.2 5609.5 9460.3 9595.2 9699.6 9751.1 9767.0 9012.5 9352.7 9376.0 9926.8 9932.8 6675.8 7349.6 7406.7 7473.7 7483.8 7542.7 7930.5 8098.4 8173.2 8177.7 7214.1 7330.0 7347.1 7425.9 7478.8 7222.7 7236.4 7277.5 7350.7 7363.4 6765.5 6868.7 6877.1 6901.2 6967.2 4744.8 4760.0 4776.8 4948.3 4955.6 4805.4 4824.4 4904.8 4928.8 4930.5 12:58:52 INFO - PROCESS | 1665 | 12:58:52 INFO - PROCESS | 1665 | [#4] prototype.html Cycles:180 Average:5836.15 Median:6626.84 stddev:1873.45 (28.3%) stddev-sans-first:1832.28 12:58:52 INFO - PROCESS | 1665 | Values: 314.3 319.3 326.2 331.0 332.4 6698.2 6707.7 6805.3 6812.1 6817.9 6368.5 6587.8 6626.3 6627.4 6719.1 4780.1 4860.6 4861.9 4866.0 4928.9 4861.8 4884.6 4895.1 4926.0 4942.9 4896.0 4911.3 4916.3 4923.6 4931.2 4654.3 4737.6 4869.5 4881.7 4913.6 4159.3 4174.6 4176.0 4177.3 4185.2 6345.7 6392.2 6405.1 6411.1 6514.0 5886.3 5896.5 5899.1 5900.3 5904.3 6195.8 6802.4 7145.5 7158.6 7169.1 4434.3 4618.7 4621.0 4633.5 4640.3 6482.5 7271.0 7383.2 7520.4 7566.6 3970.2 4659.8 4678.2 4683.1 4693.6 4677.6 4732.2 4747.5 4753.0 4767.3 5683.2 5697.6 5716.6 5720.7 5739.5 5818.0 6875.7 7119.5 7228.3 7265.7 1353.3 1643.1 1654.1 1656.9 1657.8 970.5 976.3 1023.0 1024.7 1046.4 6237.8 6481.8 6491.9 6496.3 6578.9 5568.4 7401.0 7405.3 7426.2 7499.4 6737.9 6740.2 6745.7 6749.0 6764.4 3901.3 3961.4 3966.2 3969.3 3972.1 7565.2 7832.3 7836.0 7868.4 7877.0 6744.0 7307.7 7750.2 7766.0 7790.1 6333.5 6853.7 7849.1 7877.9 7880.9 6740.9 7283.9 7446.8 7520.7 7553.5 7387.5 7407.0 7429.1 7435.6 7445.8 7813.9 7827.0 7830.1 7840.0 7873.0 4880.5 4951.1 4953.4 4975.0 4977.9 7157.9 7352.0 7420.5 7426.6 7428.5 7159.9 7201.8 7210.9 7222.1 7233.2 7187.6 7199.0 7202.9 7208.7 7224.1 7017.1 7021.9 7026.5 7029.2 7033.1 6929.9 7209.8 7217.5 7246.7 7254.7 6758.3 6897.9 7004.4 7009.2 7076.7 12:58:52 INFO - PROCESS | 1665 | 12:58:52 INFO - PROCESS | 1665 | [#5] yui.html Cycles:180 Average:17477.76 Median:610.61 stddev:23989.73 (3928.8%) stddev-sans-first:24023.54 12:58:52 INFO - PROCESS | 1665 | Values: 597.0 670.8 725.9 750.9 754.7 15059.3 15160.9 15380.4 15396.3 15416.8 13973.4 14145.8 14341.6 14376.6 14377.8 729.2 732.0 898.6 907.8 924.2 909.7 912.3 912.8 913.8 914.0 965.7 967.2 967.6 967.6 970.1 758.3 938.7 956.2 956.2 958.3 609.7 701.4 707.4 707.8 707.8 14917.9 14984.9 14990.4 14991.5 15001.0 11424.8 11576.1 11951.6 12182.2 12509.0 10040.4 10073.6 10082.8 10224.5 10285.3 3450.1 3450.1 3568.2 3589.7 3605.2 77431.0 78731.6 78768.0 78802.6 79104.6 9167.7 9481.0 9538.2 9606.4 9744.5 20711.0 20879.5 20908.0 20919.5 21443.1 7378.2 7414.1 7454.9 7574.2 7649.1 4032.2 4040.4 4075.0 4096.4 4341.9 3555.0 3587.1 3625.1 3627.4 3642.2 638.2 643.4 651.0 675.1 690.4 557.4 590.5 591.7 602.4 611.5 7801.3 8045.0 8079.6 8154.8 8198.8 573.2 595.5 597.5 605.3 614.5 3245.3 3590.0 3777.3 3807.0 3887.3 83811.0 84507.6 84958.6 84993.2 85127.6 54316.7 73005.0 73186.3 73629.2 73950.0 63752.7 64371.7 66343.3 66939.3 68171.3 8242.5 8289.2 8335.3 8480.4 8542.4 38997.8 46402.3 46571.8 47984.8 48686.0 64873.7 65969.7 66523.0 66706.0 66987.7 10675.1 10739.1 11037.8 11339.9 11371.0 11636.9 11758.4 11908.5 11975.9 12014.0 11384.4 11459.0 11472.8 11730.5 11754.1 11198.3 11307.7 11369.8 11734.6 11778.4 10811.7 10999.3 11000.4 11290.1 11899.3 9291.4 9298.3 9478.1 9551.1 9764.2 8249.7 8901.4 9431.9 9457.6 9494.3 12:58:52 INFO - PROCESS | 1665 | -------- Summary: end -------- 12:58:52 INFO - PROCESS | 1665 | 12:58:53 INFO - TEST-INFO | 1665: exit 0 12:58:53 INFO - TEST-OK | dromaeo_css | took 1115381ms 12:58:53 INFO - TEST-START | kraken 12:58:53 INFO - operating with platform_type : mac_ 12:58:53 INFO - Initialising browser for kraken test... 12:58:53 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/tmppXJGBZ/profile http://localhost:49275/getInfo.html) 12:58:55 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 12:58:55 INFO - PROCESS | 1671 | colorDepth:24 12:58:55 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 12:58:55 INFO - PROCESS | 1671 | __metrics 12:58:55 INFO - PROCESS | 1671 | 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" 12:58:55 ERROR - PROCESS | 1671 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 12:58:56 INFO - TEST-INFO | 1671: exit 0 12:58:56 INFO - Browser initialized. 12:58:56 INFO - Running cycle 1/1 for kraken test... 12:58:56 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/tmppXJGBZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:59:08 INFO - PROCESS | 1673 | RSS: Main: 211214336 12:59:08 INFO - PROCESS | 1673 | 12:59:10 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 12:59:11 INFO - PROCESS | 1673 | RSS: Main: 272306176 12:59:11 INFO - PROCESS | 1673 | 12:59:14 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 12:59:15 INFO - PROCESS | 1673 | RSS: Main: 286531584 12:59:15 INFO - PROCESS | 1673 | 12:59:18 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 12:59:19 INFO - PROCESS | 1673 | RSS: Main: 372830208 12:59:19 INFO - PROCESS | 1673 | 12:59:21 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 12:59:21 INFO - PROCESS | 1673 | RSS: Main: 279949312 12:59:21 INFO - PROCESS | 1673 | 12:59:23 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:59:24 INFO - PROCESS | 1673 | RSS: Main: 281337856 12:59:24 INFO - PROCESS | 1673 | 12:59:27 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 12:59:28 INFO - PROCESS | 1673 | RSS: Main: 317771776 12:59:28 INFO - PROCESS | 1673 | 12:59:31 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 12:59:31 INFO - PROCESS | 1673 | RSS: Main: 319279104 12:59:31 INFO - PROCESS | 1673 | 12:59:34 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 12:59:35 INFO - PROCESS | 1673 | RSS: Main: 317968384 12:59:35 INFO - PROCESS | 1673 | 12:59:36 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:59:37 INFO - PROCESS | 1673 | RSS: Main: 309788672 12:59:37 INFO - PROCESS | 1673 | 12:59:39 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:59:39 INFO - PROCESS | 1673 | RSS: Main: 316174336 12:59:39 INFO - PROCESS | 1673 | 12:59:42 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:59:42 INFO - PROCESS | 1673 | RSS: Main: 319074304 12:59:42 INFO - PROCESS | 1673 | 12:59:45 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:59:46 INFO - PROCESS | 1673 | RSS: Main: 324292608 12:59:46 INFO - PROCESS | 1673 | 12:59:48 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:59:49 INFO - PROCESS | 1673 | RSS: Main: 322166784 12:59:49 INFO - PROCESS | 1673 | 12:59:51 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 12:59:51 INFO - PROCESS | 1673 | RSS: Main: 386502656 12:59:51 INFO - PROCESS | 1673 | 12:59:51 INFO - PROCESS | 1673 | __start_tp_report 12:59:51 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 12:59:51 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 12:59:51 INFO - PROCESS | 1673 | |i|pagename|runs| 12:59:51 INFO - PROCESS | 1673 | |0;ai-astar;113;113;121;113;113;112;114;113;113;133 12:59:51 INFO - PROCESS | 1673 | |1;audio-beat-detection;175;145;178;166;119;145;141;157;179;146 12:59:51 INFO - PROCESS | 1673 | |2;audio-dft;184;184;241;215;184;183;191;185;182;183 12:59:51 INFO - PROCESS | 1673 | |3;audio-fft;69;79;73;150;57;72;83;91;144;55 12:59:51 INFO - PROCESS | 1673 | |4;audio-oscillator;93;91;140;77;78;81;126;81;79;126 12:59:51 INFO - PROCESS | 1673 | |5;imaging-gaussian-blur;104;128;133;73;130;128;132;88;90;87 12:59:51 INFO - PROCESS | 1673 | |6;imaging-darkroom;96;95;96;96;96;95;96;96;95;95 12:59:51 INFO - PROCESS | 1673 | |7;imaging-desaturate;85;85;84;98;114;86;84;86;94;130 12:59:51 INFO - PROCESS | 1673 | |8;json-parse-financial;61;64;65;61;59;64;64;80;61;59 12:59:51 INFO - PROCESS | 1673 | |9;json-stringify-tinderbox;63;67;69;82;79;73;69;70;69;69 12:59:51 INFO - PROCESS | 1673 | |10;stanford-crypto-aes;80;81;81;82;80;80;89;103;80;83 12:59:51 INFO - PROCESS | 1673 | |11;stanford-crypto-ccm;130;131;118;128;113;138;133;133;146;147 12:59:51 INFO - PROCESS | 1673 | |12;stanford-crypto-pbkdf2;172;169;166;171;166;167;165;170;208;203 12:59:51 INFO - PROCESS | 1673 | |13;stanford-crypto-sha256-iterative;69;70;69;67;67;69;68;70;66;66 12:59:51 INFO - PROCESS | 1673 | __end_tp_report 12:59:51 INFO - PROCESS | 1673 | __start_cc_report 12:59:51 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,2010 12:59:51 INFO - PROCESS | 1673 | __end_cc_report 12:59:51 INFO - PROCESS | 1673 | __startTimestamp1464379191580__endTimestamp 12:59:51 INFO - PROCESS | 1673 | 12:59:51 INFO - PROCESS | 1673 | ------- Summary: start ------- 12:59:51 INFO - PROCESS | 1673 | Number of tests: 14 12:59:51 INFO - PROCESS | 1673 | 12:59:51 INFO - PROCESS | 1673 | [#0] ai-astar Cycles:10 Average:115.80 Median:113.00 stddev:6.56 (5.8%) stddev-sans-first:6.88 12:59:51 INFO - PROCESS | 1673 | Values: 113.0 113.0 121.0 113.0 113.0 112.0 114.0 113.0 113.0 133.0 12:59:51 INFO - PROCESS | 1673 | 12:59:51 INFO - PROCESS | 1673 | [#1] audio-beat-detection Cycles:10 Average:155.10 Median:161.50 stddev:19.44 (12.0%) stddev-sans-first:19.24 12:59:51 INFO - PROCESS | 1673 | Values: 175.0 145.0 178.0 166.0 119.0 145.0 141.0 157.0 179.0 146.0 12:59:51 INFO - PROCESS | 1673 | 12:59:51 INFO - PROCESS | 1673 | [#2] audio-dft Cycles:10 Average:193.20 Median:184.50 stddev:19.49 (10.6%) stddev-sans-first:20.39 12:59:51 INFO - PROCESS | 1673 | Values: 184.0 184.0 241.0 215.0 184.0 183.0 191.0 185.0 182.0 183.0 12:59:51 INFO - PROCESS | 1673 | 12:59:51 INFO - PROCESS | 1673 | [#3] audio-fft Cycles:10 Average:87.30 Median:72.50 stddev:33.30 (45.9%) stddev-sans-first:34.66 12:59:51 INFO - PROCESS | 1673 | Values: 69.0 79.0 73.0 150.0 57.0 72.0 83.0 91.0 144.0 55.0 12:59:51 INFO - PROCESS | 1673 | 12:59:51 INFO - PROCESS | 1673 | [#4] audio-oscillator Cycles:10 Average:97.20 Median:80.00 stddev:23.99 (30.0%) stddev-sans-first:25.40 12:59:51 INFO - PROCESS | 1673 | Values: 93.0 91.0 140.0 77.0 78.0 81.0 126.0 81.0 79.0 126.0 12:59:51 INFO - PROCESS | 1673 | 12:59:51 INFO - PROCESS | 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:109.30 Median:103.00 stddev:23.27 (22.6%) stddev-sans-first:24.60 12:59:51 INFO - PROCESS | 1673 | Values: 104.0 128.0 133.0 73.0 130.0 128.0 132.0 88.0 90.0 87.0 12:59:51 INFO - PROCESS | 1673 | 12:59:51 INFO - PROCESS | 1673 | [#6] imaging-darkroom Cycles:10 Average:95.60 Median:96.00 stddev:0.52 (0.5%) stddev-sans-first:0.53 12:59:51 INFO - PROCESS | 1673 | Values: 96.0 95.0 96.0 96.0 96.0 95.0 96.0 96.0 95.0 95.0 12:59:51 INFO - PROCESS | 1673 | 12:59:51 INFO - PROCESS | 1673 | [#7] imaging-desaturate Cycles:10 Average:94.60 Median:85.50 stddev:15.63 (18.3%) stddev-sans-first:16.19 12:59:51 INFO - PROCESS | 1673 | Values: 85.0 85.0 84.0 98.0 114.0 86.0 84.0 86.0 94.0 130.0 12:59:51 INFO - PROCESS | 1673 | 12:59:51 INFO - PROCESS | 1673 | [#8] json-parse-financial Cycles:10 Average:63.80 Median:64.00 stddev:6.09 (9.5%) stddev-sans-first:6.37 12:59:51 INFO - PROCESS | 1673 | Values: 61.0 64.0 65.0 61.0 59.0 64.0 64.0 80.0 61.0 59.0 12:59:51 INFO - PROCESS | 1673 | 12:59:51 INFO - PROCESS | 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:71.00 Median:69.50 stddev:5.64 (8.1%) stddev-sans-first:5.18 12:59:51 INFO - PROCESS | 1673 | Values: 63.0 67.0 69.0 82.0 79.0 73.0 69.0 70.0 69.0 69.0 12:59:51 INFO - PROCESS | 1673 | 12:59:51 INFO - PROCESS | 1673 | [#10] stanford-crypto-aes Cycles:10 Average:83.90 Median:81.00 stddev:7.25 (8.9%) stddev-sans-first:7.55 12:59:51 INFO - PROCESS | 1673 | Values: 80.0 81.0 81.0 82.0 80.0 80.0 89.0 103.0 80.0 83.0 12:59:51 INFO - PROCESS | 1673 | 12:59:51 INFO - PROCESS | 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:131.70 Median:133.00 stddev:10.73 (8.1%) stddev-sans-first:11.36 12:59:51 INFO - PROCESS | 1673 | Values: 130.0 131.0 118.0 128.0 113.0 138.0 133.0 133.0 146.0 147.0 12:59:51 INFO - PROCESS | 1673 | 12:59:51 INFO - PROCESS | 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.70 Median:170.50 stddev:15.92 (9.3%) stddev-sans-first:16.83 12:59:51 INFO - PROCESS | 1673 | Values: 172.0 169.0 166.0 171.0 166.0 167.0 165.0 170.0 208.0 203.0 12:59:51 INFO - PROCESS | 1673 | 12:59:51 INFO - PROCESS | 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:69.00 stddev:1.52 (2.2%) stddev-sans-first:1.58 12:59:51 INFO - PROCESS | 1673 | Values: 69.0 70.0 69.0 67.0 67.0 69.0 68.0 70.0 66.0 66.0 12:59:51 INFO - PROCESS | 1673 | -------- Summary: end -------- 12:59:51 INFO - PROCESS | 1673 | 12:59:52 INFO - TEST-INFO | 1673: exit 0 12:59:52 INFO - TEST-OK | kraken | took 59001ms 12:59:52 INFO - SUITE-END | took 1174s 12:59:52 INFO - Completed test suite (00:19:34) 12:59:52 INFO - javascript benchmark 12:59:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3368.27582069855, 3424.982875085624, 3443.1392151962, 3453.8963831085075, 3496.4230901901187, 5661.264035675362, 5661.745221465034, 5667.801626943047, 5671.177679236508, 5696.914546281809, 5658.377578466376, 5682.687452190128, 5701.116326969316, 5706.971465142678, 5731.398203188668, 5049.621278404116, 5080.77136528857, 5115.667481613702, 5203.609729270301, 5221.0, 5187.844364669066, 5207.817726379578, 5210.609204309673, 5210.687358758468, 5211.791528338862, 5131.204663277179, 5175.844724658266, 5201.609879259052, 5204.42751297357, 5205.661631993928, 5192.948070519285, 5234.581233501311, 5236.633435659505, 5248.81629142982, 5275.57795376369, 4995.425526064477, 5039.672421292605, 5091.79632814687, 5154.587632989352, 5159.793608255665, 5582.27430434041, 5586.636868603528, 5604.775808967636, 5626.071698169797, 5629.408912064216, 6047.516198704093, 6195.132681424604, 6204.906926396107, 6219.0, 6223.782167624111, 6063.896013346911, 6130.734471166807, 6179.677430591871, 6183.9381606183815, 6187.504999600022, 5767.423257674199, 5796.246487956539, 5810.244668193108, 5819.88360232793, 5825.38833422493, 6238.968805155945, 6243.875122497525, 6271.686415679198, 6278.434940855345, 6285.497160227171, 5975.701214939236, 6054.462194567704, 6087.391260873966, 6122.0, 6168.383161683885, 6170.938290617126, 6175.104609831549, 6189.566730328924, 6196.039613859859, 6208.099825525273, 6135.53983451234, 6175.84560385985, 6197.225346831646, 6197.25632924043, 6215.564910456224, 6017.789377371771, 6115.818436018264, 6115.879294474695, 6137.631742095399, 6183.5671502994355, 4855.0, 4864.683795553277, 4888.413390393175, 4893.143699852511, 4893.21708526634, 4305.913881722348, 4379.408779814684, 4391.187630288407, 4446.133004064176, 4470.932936005963, 5628.04323265046, 5653.349864765604, 5667.829965101054, 5694.743736531866, 5706.629069110494, 6116.571839971249, 6184.103305020747, 6186.1648677428875, 6198.194234749544, 6200.131981522591, 5602.4957753802355, 5662.37713851476, 5665.915011274834, 5706.400827913092, 5710.229119068956, 5320.787168513293, 5326.600504962066, 5338.252644629755, 5338.599605029638, 5345.224942383333, 5121.0, 6195.535334849995, 6274.811755647429, 6324.146240257598, 6363.045543168377, 6142.897836592492, 6149.938500615115, 6211.409916057967, 6215.005599104122, 6225.470834979077, 6302.558820882494, 6302.842428939313, 6312.210973628296, 6317.368263173646, 6321.810345689637, 6020.30766461845, 6115.027386288435, 6161.599496032743, 6166.445019948226, 6167.506599472121, 5368.973155134199, 6122.154209354628, 6176.536759742948, 6180.0, 6199.380061993765, 5364.463553644604, 6103.786367477117, 6104.877902441837, 6118.877622447437, 6133.6319820810895, 5937.554683398849, 5997.940020599738, 6066.241719785027, 6131.0, 6142.355052719578, 5889.411058894248, 5932.466078052996, 5948.791792287422, 5991.64050156992, 5999.460048595647, 6039.033754599243, 6064.878702425838, 6091.208142941389, 6096.695165241809, 6101.572889897842, 5772.682502462237, 5876.059830427112, 5947.226860508106, 5957.463828255478, 5975.581709280309], "value": 5658.416960550694, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [511.19231614049795, 516.212775517336, 527.7097596322022, 573.9225204597378, 589.767042018403, 30610.479794823576, 36331.273374532524, 37948.05129871751, 38807.223855522876, 38882.33411990565, 41177.17645647085, 41728.58271417285, 41752.16495670085, 41774.0, 41846.79076604613, 8534.530600816952, 9400.106989835955, 9613.807723845519, 9623.615055397811, 9636.807263854718, 8590.02429441229, 8713.869291960626, 8716.171963663473, 8719.433236839584, 11468.254563453349, 11321.773564528705, 11395.886041139607, 11402.543898244101, 11415.429228538582, 11495.942520287428, 11409.771804563903, 11426.885731142665, 11520.481578328996, 11525.53897844081, 11531.07751379896, 8641.827163456695, 11271.929166729147, 11469.369184694839, 11675.357855317954, 11688.240264382788, 37942.81028594839, 38353.80823095867, 38503.0, 38530.807345963374, 38531.0, 2706.8240564363255, 3175.237942893712, 3274.5743053403144, 3440.6215316315183, 3522.383582872987, 27374.0, 27628.0, 27871.0, 28206.717932820615, 28244.293892652848, 2697.973020269792, 2703.1349968010245, 2738.945221095587, 2817.0, 2825.519661657523, 446713.0, 449241.0, 449769.0, 449882.0, 450707.0, 58368.416315837145, 59730.80538389295, 59773.70113149406, 59784.70107649434, 59811.0, 62140.689296553224, 62157.37842621516, 62227.377726222156, 62273.0, 62301.0, 16598.087105209088, 17133.485995420157, 17142.0, 17143.828561714472, 17147.657046859244, 13412.32938353098, 13491.25798081115, 13542.25517596522, 13554.457821687021, 13585.184888906697, 13900.51348202808, 14256.429742810378, 14259.00186986901, 14280.642983925485, 14300.499482518062, 5585.078462053797, 6209.975354066529, 6214.8135555933395, 6230.72886734459, 6231.0, 983.5524836199655, 1013.6973988424838, 1037.0044757033181, 1060.6711919304885, 1069.0805906920043, 25877.611835822478, 29000.854995724887, 29668.851655742015, 30871.50070210063, 31859.362812743617, 953.2040745977187, 1088.967330980072, 1159.0437888741822, 1175.3653027365126, 1178.5639313454076, 13438.932805335911, 13576.456941722417, 13706.349403887756, 13854.376553055137, 13890.0, 566707.0, 572131.0, 573193.0, 573822.0, 574217.0, 49417.0, 50355.74822125866, 55729.164062539094, 56606.716966414904, 56841.43158568361, 1486.851314868526, 1491.5674454408534, 1497.6255936015996, 1499.1679617812001, 1511.909285442877, 24522.14172503954, 28277.29306767265, 28350.574741379307, 28891.85554072216, 28970.855145724137, 47300.763496183674, 47324.0, 47737.0, 47770.805729857035, 47784.99999999861, 490206.0, 605434.0, 629948.0, 638880.0, 640829.0, 24669.62995555032, 24895.0, 25143.622845657697, 25245.495090098462, 25275.98896044211, 41268.79365603153, 41329.58670413378, 42284.36573451462, 42290.788546058306, 42456.575434245264, 34572.481412778325, 34765.65234347624, 35439.0, 35605.64394356127, 36956.0, 35941.124749760194, 36021.27957440889, 36072.4589131158, 36432.45351319679, 36669.89990300295, 35922.10194745152, 36160.095997599215, 37107.70123045788, 37156.628433716396, 37171.25657486781, 31805.36389272248, 32475.35049299048, 32616.0, 32752.344953100328, 32916.34167316592, 22002.0, 24344.026238950242, 24658.136965206133, 24772.132975345776, 25580.0], "value": 19364.97348402422, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [398.1916709080567, 399.8100902071516, 401.867383763358, 404.35303514376983, 410.37008192424616, 20264.0, 20871.478213044662, 20937.58124837504, 21023.159073637074, 21656.783432165674, 20631.865247411388, 21437.0, 21501.462463438424, 21512.247071352507, 21798.673019904712, 5399.946000539993, 5523.530499907512, 5986.401359864022, 6160.414760597759, 6179.721912513947, 6072.605280656765, 6086.969565152191, 6132.233470816148, 6139.969300153493, 6142.5085993120665, 5525.033119204143, 5530.364008139055, 5543.196236545698, 5613.158026296068, 6068.059450785136, 5007.474215207405, 5244.344456942882, 5532.1425179097105, 5535.391106978229, 5542.140968149944, 5318.654287471302, 5396.19057141428, 5407.026735187665, 5482.945170548283, 5483.506484416422, 25516.851741671668, 26695.73304266952, 26718.46563068747, 26746.598801018, 26920.326991825164, 2069.606774712815, 2145.8605190662556, 2159.88120653364, 2237.44063984004, 2792.966602357141, 9960.153386962116, 9962.70111896637, 10368.118709909666, 10478.109360704273, 12045.518179272818, 1710.9056566259046, 1742.7821522309712, 1794.5872449336596, 1838.6782313095289, 1848.9075546222634, 101398.0, 114780.85219147785, 116275.0, 119328.0, 122005.16992245124, 11798.823017654742, 11826.34955077479, 11861.940690296493, 11915.463804128834, 11930.0, 15512.45706400293, 15547.844521554865, 15548.533543993472, 15555.844441555666, 15574.454894078654, 8904.154105359981, 9020.729378118667, 9023.684171053981, 9164.816703665889, 9176.816463670822, 9065.82144321234, 9073.637054517745, 9075.727728168165, 9171.541422928827, 9207.539623018822, 4566.13243483737, 4703.459102203222, 4774.761261936889, 4801.207800712913, 4868.0, 1935.2355819451238, 1974.3879397386856, 1999.4501512084003, 2019.5153163240716, 2036.7372229217979, 891.4250308550949, 907.8547432410783, 918.2653876898454, 995.6465454763454, 1023.4933707814679, 19915.0, 20971.37085887426, 21027.369178924655, 21135.365939021853, 21167.470813229484, 929.6281487405092, 944.8771659684197, 945.3571571331561, 973.6640858903667, 1053.1101219469535, 10982.176336774774, 11182.776344473228, 11193.72015699614, 11211.103111751041, 11226.21416500837, 481676.0, 489258.0, 498419.0, 499838.0, 500061.0, 134129.0, 142323.0, 143116.0, 144084.0, 148165.0, 991.9504024798875, 1045.1012129568717, 1053.794510070529, 1054.2146101154535, 1055.076807793181, 15130.697386051997, 18087.909560452114, 18765.249390024244, 18899.338523151622, 19168.712469312693, 51581.0, 53654.65863353448, 54795.17807232815, 55391.72304138453, 57315.0, 74548.88176677245, 77670.6116469414, 79509.0, 81473.59263203647, 81543.0, 7364.926350736424, 8270.586470676562, 8277.95861020691, 8480.830383392175, 8562.357823163164, 8992.50541220213, 12161.695957600778, 12734.172278801849, 13121.540746073842, 13133.868661313647, 11032.117430605373, 11270.718232044264, 11412.885871141514, 11462.025727813229, 11616.825747613962, 11049.779004420026, 11076.446177691245, 11089.778204436028, 11167.1066314693, 11412.02997745201, 10932.125429965743, 11441.599544015919, 11664.241824281395, 11800.17398782077, 11860.703482413008, 7579.355754760907, 8159.551224682699, 8257.83484330298, 8446.66213351459, 8631.525266110173, 9379.108984646447, 9619.124270767134, 11302.982731553871, 11515.078793696652, 11729.824052639387], "value": 9679.845835318769, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1398.23796031163, 1537.4772577323708, 1573.087609186672, 1748.676494848453, 1873.8969356685386, 7691.346235569969, 7866.449348545604, 8471.36464765142, 8525.360597955163, 8704.216620504154, 8389.496630202177, 8648.697295594655, 8662.393632445732, 8669.003064647568, 8693.913060869389, 1847.3534263007907, 2127.8936053197353, 2196.176433837311, 2395.544846479172, 2419.370963549481, 2202.196198387593, 2207.878566678832, 2371.027878569787, 2436.2691192642224, 2440.6461063145834, 2390.306811024801, 2390.509945461185, 2390.95218095638, 2391.0555330644384, 2392.9162479313227, 2300.0799680127916, 2381.6189409694366, 2385.2009576791797, 2391.5934291170543, 2392.0431827269144, 2091.006771783391, 2343.4961483281004, 2410.2648692148887, 2416.685830841997, 2418.8548687078833, 8579.356548258906, 8653.826923461558, 8663.653453861902, 8665.39342246043, 8670.826583468359, 5177.974110129463, 5348.545373643245, 5380.677159370451, 5383.407825139231, 5389.676619402849, 3405.8126803025825, 3471.930561388783, 3531.770434921722, 3535.2399234164777, 3551.6803487686234, 1012.3773879064322, 1035.730710015394, 1037.4968140451874, 1043.4209013997229, 1059.8887116852698, 11795.292282462993, 11798.0, 11805.350705711182, 11874.406279686067, 11879.40602969848, 4251.44731184944, 4627.976860115745, 4754.9286760698615, 4780.259059845729, 4824.131656301899, 9932.851007234898, 9942.254330925209, 9958.0, 9958.651447199314, 9965.35225210359, 5366.356037275552, 5383.569314454834, 5425.972870135624, 5426.972865135649, 5436.103042997861, 4316.6546676265825, 4889.755512224375, 4947.356843610308, 4947.505249475095, 4955.554000140005, 3693.7045036397026, 3929.0766669832565, 3995.220931918248, 4028.7582745035393, 4032.858849940238, 1306.4839388441512, 1315.651352391617, 1326.9251905956014, 1327.7875539913568, 1336.1648969394128, 837.417994493821, 850.5109562001874, 856.2037305306125, 922.796984663373, 928.832810094176, 5613.691246981455, 5736.168255602997, 5857.824265271964, 5910.379410161957, 5921.763129474859, 935.9532023398802, 964.0455948610964, 967.2552134856121, 976.0776066616979, 1002.674130907458, 3759.342115129841, 3856.672182864488, 3889.8833035008997, 3906.4335671328067, 3963.980180099081, 8608.612612432456, 8976.955115224382, 10507.0, 10649.414282214553, 10774.676759697222, 10537.209709271836, 10631.255812093234, 10638.94680526608, 10720.571177152982, 10815.513301901434, 8235.629396677165, 8259.8348033039, 8371.497710137412, 8376.664933402595, 8479.236868681724, 5132.538071573576, 5438.564914806885, 5454.645448045864, 5558.24963629921, 5609.467100625434, 9460.337776355589, 9595.184409325286, 9699.6120155193, 9751.073648003428, 9766.951165244129, 9012.459252444874, 9352.719418417459, 9375.96864344908, 9926.751831203988, 9932.801343972935, 6675.83310417224, 7349.55902645843, 7406.7037318506655, 7473.663685134291, 7483.775486735407, 7542.698292068256, 7930.484518506279, 8098.392620553364, 8173.18268173178, 8177.713780017671, 7214.134303883568, 7330.0, 7347.08161479815, 7425.888611670937, 7478.775636730907, 7222.711091556489, 7236.38490728294, 7277.490575659653, 7350.70597176107, 7363.447741419308, 6765.458763299022, 6868.7252509901045, 6877.105976223059, 6901.206361268318, 6967.198772141269, 4744.833930812405, 4759.976200118977, 4776.761161941959, 4948.292838970549, 4955.578775803953, 4805.37530121082, 4824.445188803333, 4904.754762261801, 4928.778204980713, 4930.457649658606], "value": 4530.297775211271, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.25364758698095, 319.3325948767076, 326.1617642658367, 331.01687986679553, 332.3535723018728, 6698.196216454026, 6707.664616769161, 6805.319468053204, 6812.148481439814, 6817.863642727142, 6368.49052075834, 6587.769428070019, 6626.271110177877, 6627.403533681968, 6719.126513553243, 4780.139574876521, 4860.63545234108, 4861.902761944759, 4865.975670121645, 4928.940277840264, 4861.781219845116, 4884.633652476061, 4895.069936712013, 4926.0, 4942.876428089308, 4895.9510404895855, 4911.330147749764, 4916.336294600237, 4923.606111511071, 4931.186354251544, 4654.301854721785, 4737.620990320766, 4869.488703686133, 4881.707097574157, 4913.557779799818, 4159.272127377727, 4174.624283814441, 4176.0, 4177.26897792885, 4185.22573323936, 6345.650989195592, 6392.20097487814, 6405.135306733625, 6411.1024456576115, 6514.0, 5886.293644762613, 5896.498797602209, 5899.085641725539, 5900.321463031763, 5904.261967254093, 6195.791820594985, 6802.4217941475035, 7145.499815012951, 7158.642067896636, 7169.139703235593, 4434.268345722952, 4618.722876627413, 4620.996352370952, 4633.467151277612, 4640.327152562893, 6482.481401487869, 7270.963645181793, 7383.187849336622, 7520.435967302419, 7566.583837888914, 3970.1662650843646, 4659.8136074557315, 4678.157931572349, 4683.110209060268, 4693.601043911238, 4677.579017888406, 4732.1718699227495, 4747.477777444478, 4752.976235118871, 4767.308740232646, 5683.204351390808, 5697.6296540724725, 5716.628419152742, 5720.713964301768, 5739.540836733051, 5818.0, 6875.656217189121, 7119.540494198677, 7228.349448549656, 7265.673044713, 1353.343628340254, 1643.1127191316555, 1654.123314643241, 1656.9088700121608, 1657.7513372993906, 970.5292932927524, 976.3409698453515, 1022.9897701023043, 1024.7489365105596, 1046.3930920066343, 6237.8148151851, 6481.805545833632, 6491.902621460682, 6496.252930912911, 6578.868422631521, 5568.44315568448, 7401.037865077506, 7405.296496832792, 7426.220246874001, 7499.375218673421, 6737.9326206737305, 6740.224874139439, 6745.696443660047, 6748.9876518522615, 6764.357386048416, 3901.258760835489, 3961.425593288957, 3966.2464131814863, 3969.285528604879, 3972.106276087857, 7565.243475652391, 7832.255935686101, 7836.0, 7868.409869259828, 7876.975993120857, 6744.0, 7307.684616769033, 7750.186230445835, 7765.961170194226, 7790.065192176975, 6333.493320534346, 6853.732059568995, 7849.136594974549, 7877.921220787719, 7880.921190788019, 6740.9325906740305, 7283.890741638986, 7446.813829654302, 7520.736774212876, 7553.546787192786, 7387.482876198829, 7406.962965185139, 7429.108506979197, 7435.553866768011, 7445.7766267012075, 7813.921860781319, 7826.960865195638, 7830.1386847447875, 7840.0, 7873.0, 4880.46314905353, 4951.10880041596, 4953.356063711694, 4975.0, 4977.900441991067, 7157.928420715934, 7352.0, 7420.517666351886, 7426.628668566658, 7428.517146385684, 7159.928400715926, 7201.783946481614, 7210.89183662235, 7222.133343998544, 7233.204347521873, 7187.5687458752645, 7198.964005179941, 7202.89195662055, 7208.71165153388, 7224.13310402755, 7017.122859642544, 7021.929780702128, 7026.508144429841, 7029.226785053538, 7033.050538177281, 6929.89605155913, 7209.801010691789, 7217.458690598121, 7246.673899674422, 7254.746083887038, 6758.256591774999, 6897.862042759217, 7004.4396448283, 7009.228984811769, 7076.681549330189], "value": 5197.511296511778, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [596.9910451343228, 670.8088194864463, 725.9310365515278, 750.8535835512074, 754.7282978127873, 15059.322330495126, 15160.924195379035, 15380.384784608603, 15396.307166177536, 15416.845831541696, 13973.441062357493, 14145.787813182811, 14341.569752907404, 14376.640583985405, 14377.78433323501, 729.237946346067, 731.9670614822319, 898.5641963647643, 907.8093600343935, 924.1820988425225, 909.727081875438, 912.2975309012056, 912.7763697894026, 913.8218047480744, 914.0356923445762, 965.652365148546, 967.2165545907802, 967.5549247346229, 967.6177909725654, 970.1171933540479, 758.2758465665277, 938.652698501552, 956.1538038835611, 956.2493442647558, 958.3004406783018, 609.6860117039728, 701.3687681087011, 707.4057791455202, 707.8195060259652, 707.8230442389403, 14917.925410372878, 14984.850151498455, 14990.400383984628, 14991.475298364614, 15001.024933379296, 11424.771504569946, 11576.131790115774, 11951.581694640647, 12182.208156469802, 12509.0, 10040.447775372424, 10073.647422340184, 10082.848757268646, 10224.539895704711, 10285.331453455501, 3450.070121362002, 3450.102973226979, 3568.1971556399862, 3589.74871758977, 3605.2068544920076, 77431.0, 78731.6063419685, 78768.0, 78802.60598696969, 79104.64924429212, 9167.679131230509, 9480.952595237117, 9538.236941044699, 9606.423614583147, 9744.512774361254, 20711.0, 20879.478013049797, 20908.0, 20919.47701307449, 21443.142274309164, 7378.225286344964, 7414.073240844895, 7454.888176677354, 7574.183235088354, 7649.120351159577, 4032.2338755636333, 4040.41413994975, 4075.042365044212, 4096.365063415175, 4341.9131617367475, 3554.964450355515, 3587.121155316964, 3625.093726568358, 3627.365211088049, 3642.1805093853673, 638.218182726166, 643.4466358931315, 650.9967450162719, 675.0515525686418, 690.3786592067079, 557.4064265099705, 590.5246276747259, 591.7071049830362, 602.3885755957706, 611.541343992006, 7801.29788319054, 8044.959775201203, 8079.636416361278, 8154.75535733929, 8198.795030124294, 573.2089716191712, 595.4551585299403, 597.5040716205539, 605.2672185084348, 614.4500671898626, 3245.334706385185, 3590.0, 3777.320082385142, 3807.029207552112, 3887.3002859485, 83811.0, 84507.57746211352, 84958.57520712356, 84993.15006849976, 85127.5743621278, 54316.72841635766, 73005.0, 73186.26813731795, 73629.15927101758, 73950.0, 63752.68123659352, 64371.678141609926, 66343.33656663468, 66939.33060669429, 68171.29571760746, 8242.464239824392, 8289.212524810375, 8335.291500222549, 8480.406371553932, 8542.44474109181, 38997.805010974764, 46402.30396543987, 46571.835704106314, 47984.80037999078, 48686.0, 64873.67563162154, 65969.68060638856, 66523.0, 66706.0, 66987.66506167439, 10675.145988321072, 10739.08717758968, 11037.834432483358, 11339.886601133883, 11371.0, 11636.94181529087, 11758.3532905691, 11908.52365905354, 11975.880241197476, 12014.0, 11384.37385943781, 11459.025982791556, 11472.827907581226, 11730.472128754056, 11754.118441117122, 11198.272112312674, 11307.717307067389, 11369.829452558053, 11734.647960561197, 11778.352190629597, 10811.729706757395, 10999.285046471954, 11000.449977500934, 11290.096792256436, 11899.286042837457, 9291.442513449214, 9298.256139508689, 9478.146966773023, 9551.14039736427, 9764.218862490841, 8249.670013199404, 8901.376903616685, 9431.858522122311, 9457.621695132118, 9494.261244319678], "value": 6794.078096790031, "unit": "score"}], "name": "dromaeo_css", "value": 7440.664094720944, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 121.0, 113.0, 113.0, 112.0, 114.0, 113.0, 113.0, 133.0], "value": 115.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 145.0, 178.0, 166.0, 119.0, 145.0, 141.0, 157.0, 179.0, 146.0], "value": 155.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 241.0, 215.0, 184.0, 183.0, 191.0, 185.0, 182.0, 183.0], "value": 193.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 79.0, 73.0, 150.0, 57.0, 72.0, 83.0, 91.0, 144.0, 55.0], "value": 87.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 91.0, 140.0, 77.0, 78.0, 81.0, 126.0, 81.0, 79.0, 126.0], "value": 97.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 128.0, 133.0, 73.0, 130.0, 128.0, 132.0, 88.0, 90.0, 87.0], "value": 109.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 96.0, 96.0, 95.0, 96.0, 96.0, 95.0, 95.0], "value": 95.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 84.0, 98.0, 114.0, 86.0, 84.0, 86.0, 94.0, 130.0], "value": 94.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 65.0, 61.0, 59.0, 64.0, 64.0, 80.0, 61.0, 59.0], "value": 63.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 67.0, 69.0, 82.0, 79.0, 73.0, 69.0, 70.0, 69.0, 69.0], "value": 71.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 81.0, 81.0, 82.0, 80.0, 80.0, 89.0, 103.0, 80.0, 83.0], "value": 83.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [130.0, 131.0, 118.0, 128.0, 113.0, 138.0, 133.0, 133.0, 146.0, 147.0], "value": 131.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 169.0, 166.0, 171.0, 166.0, 167.0, 165.0, 170.0, 208.0, 203.0], "value": 175.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 70.0, 69.0, 67.0, 67.0, 69.0, 68.0, 70.0, 66.0, 66.0], "value": 68.1, "unit": "score"}], "name": "kraken", "value": 1542.3000000000002, "alertThreshold": 2.0}]} 12:59:52 INFO - Return code: 0 12:59:52 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 12:59:52 ERROR - # TBPL SUCCESS # 12:59:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:59:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:59:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:59:52 INFO - 2016-05-27 12:59:52.870 screenresolution[1677:10031] starting screenresolution argv=screenresolution get 12:59:52 INFO - 2016-05-27 12:59:52.872 screenresolution[1677:10031] Display 0: 1600x1200x32@60 12:59:52 INFO - 2016-05-27 12:59:52.878 screenresolution[1678:10034] starting screenresolution argv=screenresolution list 12:59:52 INFO - Available Modes on Display 0 12:59:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:59:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:59:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:59:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:59:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:59:53 INFO - Intel Iris: 12:59:53 INFO - Chipset Model: Intel Iris 12:59:53 INFO - Type: GPU 12:59:53 INFO - Bus: Built-In 12:59:53 INFO - VRAM (Dynamic, Max): 1536 MB 12:59:53 INFO - Vendor: Intel (0x8086) 12:59:53 INFO - Device ID: 0x0a2e 12:59:53 INFO - Revision ID: 0x0009 12:59:53 INFO - Displays: 12:59:53 INFO - Display: 12:59:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:59:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:59:53 INFO - Display Serial Number: 1600x1200 60 12:59:53 INFO - Main Display: Yes 12:59:53 INFO - Mirror: Off 12:59:53 INFO - Online: Yes 12:59:53 INFO - Rotation: Supported 12:59:53 INFO - Return code: 0 12:59:53 INFO - Running post-action listener: _resource_record_post_action 12:59:53 INFO - Running post-run listener: _resource_record_post_run 12:59:54 INFO - Total resource usage - Wall time: 1209s; CPU: 52.0%; Read bytes: 31436288; Write bytes: 651816960; Read time: 194; Write time: 1852 12:59:54 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 346862080; Write bytes: 346367488; Read time: 27461; Write time: 747 12:59:54 INFO - run-tests - Wall time: 1177s; CPU: 52.0%; Read bytes: 30514688; Write bytes: 295492096; Read time: 184; Write time: 1070 12:59:54 INFO - Running post-run listener: _upload_blobber_files 12:59:54 INFO - Blob upload gear active. 12:59:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:59:54 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 12:59:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:59:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:59:54 INFO - (blobuploader) - INFO - Open directory for files ... 12:59:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:59:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:59:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:59:55 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:59:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:59:55 INFO - (blobuploader) - INFO - Done attempting. 12:59:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:59:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:59:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:59:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:59:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:59:56 INFO - (blobuploader) - INFO - Done attempting. 12:59:56 INFO - (blobuploader) - INFO - Iteration through files over. 12:59:56 INFO - Return code: 0 12:59:56 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:59:56 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:59:56 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cd50b8f7de5d9f447933ef230acbbf4b34f72aefbe4fa3f0b2c87d1fbdbeaa761398381606ace2aa52226f9e820ad6fc660cdfef1d9b3d7f2307cb05a0ebaa42", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/446185c45c2cbf2858d37ee8d7190498475248e7c48280681cdb0653d4121f704c3b8f6e41541b7b3fe78a587d733a648bbfefab4a345e0ef1bc3f04f1b513bf"} 12:59:56 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:59:56 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:59:56 INFO - Contents: 12:59:56 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cd50b8f7de5d9f447933ef230acbbf4b34f72aefbe4fa3f0b2c87d1fbdbeaa761398381606ace2aa52226f9e820ad6fc660cdfef1d9b3d7f2307cb05a0ebaa42", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/446185c45c2cbf2858d37ee8d7190498475248e7c48280681cdb0653d4121f704c3b8f6e41541b7b3fe78a587d733a648bbfefab4a345e0ef1bc3f04f1b513bf"} 12:59:56 INFO - Running post-run listener: copy_logs_to_upload_dir 12:59:56 INFO - Copying logs to upload dir... 12:59:56 INFO - mkdir: /builds/slave/test/build/upload/logs 12:59:56 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1293.587290 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 33 secs) (at 2016-05-27 12:59:56.367482) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-27 12:59:56.371787) ========= 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.qVV45GqxJt/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.tw5QAbTsLM/Listeners TMPDIR=/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cd50b8f7de5d9f447933ef230acbbf4b34f72aefbe4fa3f0b2c87d1fbdbeaa761398381606ace2aa52226f9e820ad6fc660cdfef1d9b3d7f2307cb05a0ebaa42", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/446185c45c2cbf2858d37ee8d7190498475248e7c48280681cdb0653d4121f704c3b8f6e41541b7b3fe78a587d733a648bbfefab4a345e0ef1bc3f04f1b513bf"} build_url:https://queue.taskcluster.net/v1/task/ABw42i3yQWSh1_tdwbn6SA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011498 build_url: 'https://queue.taskcluster.net/v1/task/ABw42i3yQWSh1_tdwbn6SA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cd50b8f7de5d9f447933ef230acbbf4b34f72aefbe4fa3f0b2c87d1fbdbeaa761398381606ace2aa52226f9e820ad6fc660cdfef1d9b3d7f2307cb05a0ebaa42", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/446185c45c2cbf2858d37ee8d7190498475248e7c48280681cdb0653d4121f704c3b8f6e41541b7b3fe78a587d733a648bbfefab4a345e0ef1bc3f04f1b513bf"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-27 12:59:56.408568) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 12:59:56.408979) ========= 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.qVV45GqxJt/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.tw5QAbTsLM/Listeners TMPDIR=/var/folders/k4/xd58rgkx0dnbrdjb1pv1qrj800000w/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.005163 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-27 12:59:56.460238) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-27 12:59:56.463637) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-27 12:59:56.464010) ========= ========= Total master_lag: 0.21 =========