builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0268 starttime: 1470157789.96 results: success (0) buildid: 20160802080456 builduid: 0e2ef1b8c2424ce5ba5e3d92b055cc31 revision: ea6e87bbd03e976ddb00f625afe40ee5167a8a35 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-02 10:09:49.962424) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-02 10:09:49.962896) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-02 10:09:49.963235) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-02 10:09:50.001577) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-08-02 10:09:50.002068) ========= 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.YKHvipvj7K/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.9AfHCpyNGC/Listeners TMPDIR=/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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-08-02 10:09:50-- 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% 6.57M=0.002s 2016-08-02 10:09:50 (6.57 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.096945 ========= master_lag: 3.12 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-08-02 10:09:53.220461) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 10:09:53.220788) ========= 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.YKHvipvj7K/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.9AfHCpyNGC/Listeners TMPDIR=/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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.020241 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 10:09:53.262251) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-02 10:09:53.262569) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev ea6e87bbd03e976ddb00f625afe40ee5167a8a35 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev ea6e87bbd03e976ddb00f625afe40ee5167a8a35 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YKHvipvj7K/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.9AfHCpyNGC/Listeners TMPDIR=/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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-08-02 10:09:53,347 truncating revision to first 12 chars 2016-08-02 10:09:53,347 Setting DEBUG logging. 2016-08-02 10:09:53,347 attempt 1/10 2016-08-02 10:09:53,347 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/ea6e87bbd03e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-02 10:09:54,424 unpacking tar archive at: mozilla-central-ea6e87bbd03e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.370241 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-02 10:09:54.661100) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-02 10:09:54.661934) ========= script_repo_revision: ea6e87bbd03e976ddb00f625afe40ee5167a8a35 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-02 10:09:54.663229) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-02 10:09:54.663846) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-02 10:09:54.682587) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-08-02 10:09:54.683094) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.YKHvipvj7K/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.9AfHCpyNGC/Listeners TMPDIR=/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 10:09:54 INFO - MultiFileLogger online at 20160802 10:09:54 in /builds/slave/test 10:09:54 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 10:09:54 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:09:54 INFO - {'append_to_log': False, 10:09:54 INFO - 'base_work_dir': '/builds/slave/test', 10:09:54 INFO - 'blob_upload_branch': 'Firefox', 10:09:54 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:09:54 INFO - 'branch': 'Firefox', 10:09:54 INFO - 'buildbot_json_path': 'buildprops.json', 10:09:54 INFO - 'config_files': ('talos/mac_config.py',), 10:09:54 INFO - 'default_actions': ('clobber', 10:09:54 INFO - 'read-buildbot-config', 10:09:54 INFO - 'download-and-extract', 10:09:54 INFO - 'populate-webroot', 10:09:54 INFO - 'create-virtualenv', 10:09:54 INFO - 'install', 10:09:54 INFO - 'run-tests'), 10:09:54 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:09:54 INFO - 'download_minidump_stackwalk': True, 10:09:54 INFO - 'download_symbols': 'ondemand', 10:09:54 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:09:54 INFO - 'tooltool.py': '/tools/tooltool.py', 10:09:54 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:09:54 INFO - '/tools/misc-python/virtualenv.py')}, 10:09:54 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:09:54 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:09:54 INFO - 'installer_path': 'installer.exe', 10:09:54 INFO - 'log_level': 'info', 10:09:54 INFO - 'log_name': 'talos', 10:09:54 INFO - 'log_to_console': True, 10:09:54 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:09:54 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:09:54 INFO - 'opt_config_files': (), 10:09:54 INFO - 'pip_index': False, 10:09:54 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:09:54 INFO - 'cmd': ('bash', 10:09:54 INFO - '-c', 10:09:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:09:54 INFO - 'enabled': True, 10:09:54 INFO - 'halt_on_failure': False, 10:09:54 INFO - 'name': 'check_screen_resolution'},), 10:09:54 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:09:54 INFO - 'cmd': ('bash', 10:09:54 INFO - '-c', 10:09:54 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:09:54 INFO - 'enabled': True, 10:09:54 INFO - 'halt_on_failure': False, 10:09:54 INFO - 'name': 'check_screen_resolution'},), 10:09:54 INFO - 'run_cmd_checks_enabled': True, 10:09:54 INFO - 'sps_profile': False, 10:09:54 INFO - 'sps_profile_interval': 0, 10:09:54 INFO - 'suite': 'dromaeojs', 10:09:54 INFO - 'system_bits': '32', 10:09:54 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:09:54 INFO - 'title': 't-yosemite-r7-0268', 10:09:54 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:09:54 INFO - 'use_talos_json': True, 10:09:54 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:09:54 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:09:54 INFO - 'work_dir': 'build'} 10:09:54 INFO - [mozharness: 2016-08-02 17:09:54.873530Z] Running clobber step. 10:09:54 INFO - Running pre-action listener: _resource_record_pre_action 10:09:54 INFO - Running main action method: clobber 10:09:54 INFO - rmtree: /builds/slave/test/build 10:09:54 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:09:56 INFO - Running post-action listener: _resource_record_post_action 10:09:56 INFO - [mozharness: 2016-08-02 17:09:56.814348Z] Finished clobber step (success) 10:09:56 INFO - [mozharness: 2016-08-02 17:09:56.814516Z] Running read-buildbot-config step. 10:09:56 INFO - Running pre-action listener: _resource_record_pre_action 10:09:56 INFO - Running main action method: read_buildbot_config 10:09:56 INFO - Using buildbot properties: 10:09:56 INFO - { 10:09:56 INFO - "project": "", 10:09:56 INFO - "product": "firefox", 10:09:56 INFO - "script_repo_revision": "production", 10:09:56 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 10:09:56 INFO - "repository": "", 10:09:56 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 10:09:56 INFO - "buildid": "20160802080456", 10:09:56 INFO - "pgo_build": "False", 10:09:56 INFO - "basedir": "/builds/slave/test", 10:09:56 INFO - "buildnumber": 6, 10:09:56 INFO - "slavename": "t-yosemite-r7-0268", 10:09:56 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 10:09:56 INFO - "platform": "yosemite_r7", 10:09:56 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 10:09:56 INFO - "branch": "mozilla-central", 10:09:56 INFO - "repo_path": "mozilla-central", 10:09:56 INFO - "revision": "ea6e87bbd03e976ddb00f625afe40ee5167a8a35", 10:09:56 INFO - "stage_platform": "macosx64", 10:09:56 INFO - "builduid": "0e2ef1b8c2424ce5ba5e3d92b055cc31", 10:09:56 INFO - "slavebuilddir": "test" 10:09:56 INFO - } 10:09:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/aO2sMXSrSZipSz9mCLFKgg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 10:09:56 INFO - Running post-action listener: _resource_record_post_action 10:09:56 INFO - [mozharness: 2016-08-02 17:09:56.818058Z] Finished read-buildbot-config step (success) 10:09:56 INFO - [mozharness: 2016-08-02 17:09:56.818197Z] Running download-and-extract step. 10:09:56 INFO - Running pre-action listener: _resource_record_pre_action 10:09:56 INFO - Running main action method: download_and_extract 10:09:56 INFO - mkdir: /builds/slave/test/build/tests 10:09: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')]} 10:09:56 INFO - https://queue.taskcluster.net/v1/task/aO2sMXSrSZipSz9mCLFKgg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 10:09:56 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aO2sMXSrSZipSz9mCLFKgg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 10:09:56 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aO2sMXSrSZipSz9mCLFKgg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 10:09:56 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aO2sMXSrSZipSz9mCLFKgg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 10:09:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aO2sMXSrSZipSz9mCLFKgg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 10:09:58 INFO - Downloaded 1199 bytes. 10:09:58 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 10:09:58 INFO - Using the following test package requirements: 10:09:58 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 10:09:58 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:09:58 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 10:09:58 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:09:58 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 10:09:58 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:09:58 INFO - u'jsshell-mac.zip'], 10:09:58 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:09:58 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 10:09:58 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 10:09:58 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:09:58 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 10:09:58 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:09:58 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 10:09:58 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:09:58 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 10:09:58 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:09:58 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 10:09:58 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:09:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:09:58 INFO - https://queue.taskcluster.net/v1/task/aO2sMXSrSZipSz9mCLFKgg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:09:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aO2sMXSrSZipSz9mCLFKgg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 10:09:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aO2sMXSrSZipSz9mCLFKgg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 10:09:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aO2sMXSrSZipSz9mCLFKgg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 10:09:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aO2sMXSrSZipSz9mCLFKgg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:09:59 INFO - Downloaded 16306114 bytes. 10:09:59 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:09:59 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 10:10:02 INFO - Return code: 0 10:10:02 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 10:10:02 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')]} 10:10:02 INFO - https://queue.taskcluster.net/v1/task/aO2sMXSrSZipSz9mCLFKgg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 10:10:02 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aO2sMXSrSZipSz9mCLFKgg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 10:10:02 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aO2sMXSrSZipSz9mCLFKgg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 10:10:02 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aO2sMXSrSZipSz9mCLFKgg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 10:10:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aO2sMXSrSZipSz9mCLFKgg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:10:03 INFO - Downloaded 16306114 bytes. 10:10:03 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:10:03 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 10:10:06 INFO - Return code: 0 10:10:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:10:06 INFO - https://queue.taskcluster.net/v1/task/aO2sMXSrSZipSz9mCLFKgg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 10:10:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aO2sMXSrSZipSz9mCLFKgg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 10:10:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aO2sMXSrSZipSz9mCLFKgg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 10:10:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aO2sMXSrSZipSz9mCLFKgg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 10:10:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aO2sMXSrSZipSz9mCLFKgg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:10:07 INFO - Downloaded 14413723 bytes. 10:10:07 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 10:10:07 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 10:10:08 INFO - Return code: 0 10:10:08 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')]} 10:10:08 INFO - https://queue.taskcluster.net/v1/task/aO2sMXSrSZipSz9mCLFKgg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 10:10:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aO2sMXSrSZipSz9mCLFKgg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 10:10:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aO2sMXSrSZipSz9mCLFKgg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 10:10:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aO2sMXSrSZipSz9mCLFKgg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 10:10:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aO2sMXSrSZipSz9mCLFKgg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 10:10:11 INFO - Downloaded 111229170 bytes. 10:10:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aO2sMXSrSZipSz9mCLFKgg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 10:10:11 INFO - mkdir: /builds/slave/test/properties 10:10:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:10:11 INFO - Writing to file /builds/slave/test/properties/build_url 10:10:11 INFO - Contents: 10:10:11 INFO - build_url:https://queue.taskcluster.net/v1/task/aO2sMXSrSZipSz9mCLFKgg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 10:10:12 INFO - Running post-action listener: _resource_record_post_action 10:10:12 INFO - Running post-action listener: set_extra_try_arguments 10:10:12 INFO - [mozharness: 2016-08-02 17:10:12.187423Z] Finished download-and-extract step (success) 10:10:12 INFO - [mozharness: 2016-08-02 17:10:12.187571Z] Running populate-webroot step. 10:10:12 INFO - Running pre-action listener: _resource_record_pre_action 10:10:12 INFO - Running main action method: populate_webroot 10:10:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:10:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:10:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:10:12 INFO - '--tppagecycles', 10:10:12 INFO - '7'], 10:10:12 INFO - 'tests': ['tsvgm']}}, 10:10:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:10:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:10:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:10:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:10:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:10:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:10:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:10:12 INFO - 'talos_options': ['--disable-e10s'], 10:10:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:10:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:10:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:10:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:10:12 INFO - 'talos_options': ['--disable-e10s'], 10:10:12 INFO - 'tests': ['damp', 'tps']}, 10:10:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:10:12 INFO - 'tests': ['damp', 'tps']}, 10:10:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:10:12 INFO - 'tests': ['dromaeo_dom']}, 10:10:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:10:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:10:12 INFO - 'tests': ['basic_compositor_video']}, 10:10:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:10:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:10:12 INFO - 'tests': ['a11yr', 10:10:12 INFO - 'ts_paint', 10:10:12 INFO - 'tpaint', 10:10:12 INFO - 'sessionrestore', 10:10:12 INFO - 'sessionrestore_no_auto_restore', 10:10:12 INFO - 'tabpaint']}, 10:10:12 INFO - 'other-e10s': {'tests': ['a11yr', 10:10:12 INFO - 'ts_paint', 10:10:12 INFO - 'tpaint', 10:10:12 INFO - 'sessionrestore', 10:10:12 INFO - 'sessionrestore_no_auto_restore', 10:10:12 INFO - 'tabpaint']}, 10:10:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:10:12 INFO - 'ts_paint', 10:10:12 INFO - 'tpaint', 10:10:12 INFO - 'sessionrestore', 10:10:12 INFO - 'sessionrestore_no_auto_restore', 10:10:12 INFO - 'tabpaint']}, 10:10:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:10:12 INFO - 'ts_paint', 10:10:12 INFO - 'tpaint', 10:10:12 INFO - 'sessionrestore', 10:10:12 INFO - 'sessionrestore_no_auto_restore', 10:10:12 INFO - 'tabpaint']}, 10:10:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:10:12 INFO - 'tests': ['a11yr', 10:10:12 INFO - 'ts_paint', 10:10:12 INFO - 'tpaint', 10:10:12 INFO - 'sessionrestore', 10:10:12 INFO - 'sessionrestore_no_auto_restore', 10:10:12 INFO - 'tabpaint']}, 10:10:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:10:12 INFO - 'tests': ['a11yr', 10:10:12 INFO - 'ts_paint', 10:10:12 INFO - 'tpaint', 10:10:12 INFO - 'sessionrestore', 10:10:12 INFO - 'sessionrestore_no_auto_restore', 10:10:12 INFO - 'tabpaint']}, 10:10:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:10:12 INFO - 'tests': ['tsvgx', 10:10:12 INFO - 'tsvgr_opacity', 10:10:12 INFO - 'tart', 10:10:12 INFO - 'tscrollx', 10:10:12 INFO - 'cart']}, 10:10:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:10:12 INFO - 'tsvgr_opacity', 10:10:12 INFO - 'tart', 10:10:12 INFO - 'tscrollx', 10:10:12 INFO - 'cart']}, 10:10:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:10:12 INFO - 'talos_options': ['--disable-e10s'], 10:10:12 INFO - 'tests': ['tp5o']}, 10:10:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:10:12 INFO - 'tests': ['tp5o']}, 10:10:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:10:12 INFO - 'talos_options': ['--disable-e10s', 10:10:12 INFO - '--xperf_path', 10:10:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:10:12 INFO - 'tests': ['tp5n']}, 10:10:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:10:12 INFO - 'talos_options': ['--xperf_path', 10:10:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:10:12 INFO - 'tests': ['tp5n']}}, 10:10:12 INFO - 'talos.zip': {'path': '', 10:10:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:10:12 INFO - Running post-action listener: _resource_record_post_action 10:10:12 INFO - [mozharness: 2016-08-02 17:10:12.206666Z] Finished populate-webroot step (success) 10:10:12 INFO - [mozharness: 2016-08-02 17:10:12.206772Z] Running create-virtualenv step. 10:10:12 INFO - Running pre-action listener: _resource_record_pre_action 10:10:12 INFO - Running main action method: create_virtualenv 10:10:12 INFO - Creating virtualenv /builds/slave/test/build/venv 10:10:12 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 10:10:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:10:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:10:12 INFO - Using real prefix '/tools/python27' 10:10:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:10:13 INFO - Installing distribute.............................................................................................................................................................................................done. 10:10:15 INFO - Installing pip.................done. 10:10:15 INFO - Return code: 0 10:10:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:10:15 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')]} 10:10:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:10:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:15 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')]} 10:10:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:10:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:15 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 0x107c3ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cd3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc10849ccb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ba2d30>, '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 0x107907db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b9ab20>, '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.YKHvipvj7K/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.9AfHCpyNGC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 10:10:15 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 10:10:15 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 10:10:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YKHvipvj7K/Render', 10:10:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:10:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:10:15 INFO - 'HOME': '/Users/cltbld', 10:10:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:10:15 INFO - 'LOGNAME': 'cltbld', 10:10:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:10:15 INFO - 'MOZ_NO_REMOTE': '1', 10:10:15 INFO - 'NO_EM_RESTART': '1', 10:10:15 INFO - 'PAGER': '/bin/cat', 10:10:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:10:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:10:15 INFO - 'PWD': '/builds/slave/test', 10:10:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:10:15 INFO - 'SHELL': '/bin/bash', 10:10:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9AfHCpyNGC/Listeners', 10:10:15 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 10:10:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:10:15 INFO - 'USER': 'cltbld', 10:10:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:10:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:10:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:10:15 INFO - 'XPC_FLAGS': '0x0', 10:10:15 INFO - 'XPC_SERVICE_NAME': '0', 10:10:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:10:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:10:16 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 10:10:16 INFO - Cleaning up... 10:10:16 INFO - Return code: 0 10:10:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:10: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')]} 10:10:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:10:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10: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')]} 10:10:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:10:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:16 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>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107c3ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cd3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc10849ccb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ba2d30>, '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 0x107907db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b9ab20>, '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.YKHvipvj7K/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.9AfHCpyNGC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 10:10:16 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>=3.1.1'] in /builds/slave/test/build 10:10:16 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>=3.1.1 10:10:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YKHvipvj7K/Render', 10:10:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:10:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:10:16 INFO - 'HOME': '/Users/cltbld', 10:10:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:10:16 INFO - 'LOGNAME': 'cltbld', 10:10:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:10:16 INFO - 'MOZ_NO_REMOTE': '1', 10:10:16 INFO - 'NO_EM_RESTART': '1', 10:10:16 INFO - 'PAGER': '/bin/cat', 10:10:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:10:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:10:16 INFO - 'PWD': '/builds/slave/test', 10:10:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:10:16 INFO - 'SHELL': '/bin/bash', 10:10:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9AfHCpyNGC/Listeners', 10:10:16 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 10:10:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:10:16 INFO - 'USER': 'cltbld', 10:10:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:10:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:10:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:10:16 INFO - 'XPC_FLAGS': '0x0', 10:10:16 INFO - 'XPC_SERVICE_NAME': '0', 10:10:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:10:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:10:16 INFO - Downloading/unpacking psutil>=3.1.1 10:10:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:10:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:10:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:10:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:10:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:10:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:10:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:10:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:10:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:10:19 INFO - Installing collected packages: psutil 10:10:19 INFO - Running setup.py install for psutil 10:10:19 INFO - building 'psutil._psutil_osx' extension 10:10:19 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 10:10:19 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 10:10:19 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 10:10:19 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 10:10:20 INFO - building 'psutil._psutil_posix' extension 10:10:20 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 10:10:20 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:10:20 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:10:20 INFO - ^ 10:10:20 INFO - 1 warning generated. 10:10:20 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 10:10:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:10:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:10:20 INFO - Successfully installed psutil 10:10:20 INFO - Cleaning up... 10:10:20 INFO - Return code: 0 10:10:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:10: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')]} 10:10:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:10:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10: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')]} 10:10:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:10:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107c3ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cd3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc10849ccb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ba2d30>, '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 0x107907db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b9ab20>, '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.YKHvipvj7K/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.9AfHCpyNGC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 10:10: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:10: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 mozsystemmonitor==0.3 10:10:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YKHvipvj7K/Render', 10:10:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:10:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:10:20 INFO - 'HOME': '/Users/cltbld', 10:10:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:10:20 INFO - 'LOGNAME': 'cltbld', 10:10:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:10:20 INFO - 'MOZ_NO_REMOTE': '1', 10:10:20 INFO - 'NO_EM_RESTART': '1', 10:10:20 INFO - 'PAGER': '/bin/cat', 10:10:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:10:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:10:20 INFO - 'PWD': '/builds/slave/test', 10:10:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:10:20 INFO - 'SHELL': '/bin/bash', 10:10:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9AfHCpyNGC/Listeners', 10:10:20 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 10:10:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:10:20 INFO - 'USER': 'cltbld', 10:10:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:10:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:10:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:10:20 INFO - 'XPC_FLAGS': '0x0', 10:10:20 INFO - 'XPC_SERVICE_NAME': '0', 10:10:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:10:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:10:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:10: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 10:10: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 10:10: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 10:10: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 10:10: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 10:10: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 10:10:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:10:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:10:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:10:23 INFO - Installing collected packages: mozsystemmonitor 10:10:23 INFO - Running setup.py install for mozsystemmonitor 10:10:23 INFO - Successfully installed mozsystemmonitor 10:10:23 INFO - Cleaning up... 10:10:23 INFO - Return code: 0 10:10:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:10:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:10:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:10:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:10:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:10:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:23 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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107c3ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cd3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc10849ccb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ba2d30>, '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 0x107907db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b9ab20>, '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.YKHvipvj7K/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.9AfHCpyNGC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 10:10:23 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==2.5.1'] in /builds/slave/test/build 10:10:23 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==2.5.1 10:10:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YKHvipvj7K/Render', 10:10:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:10:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:10:23 INFO - 'HOME': '/Users/cltbld', 10:10:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:10:23 INFO - 'LOGNAME': 'cltbld', 10:10:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:10:23 INFO - 'MOZ_NO_REMOTE': '1', 10:10:23 INFO - 'NO_EM_RESTART': '1', 10:10:23 INFO - 'PAGER': '/bin/cat', 10:10:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:10:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:10:23 INFO - 'PWD': '/builds/slave/test', 10:10:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:10:23 INFO - 'SHELL': '/bin/bash', 10:10:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9AfHCpyNGC/Listeners', 10:10:23 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 10:10:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:10:23 INFO - 'USER': 'cltbld', 10:10:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:10:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:10:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:10:23 INFO - 'XPC_FLAGS': '0x0', 10:10:23 INFO - 'XPC_SERVICE_NAME': '0', 10:10:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:10:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:10:23 INFO - Downloading/unpacking jsonschema==2.5.1 10:10: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 10:10: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 10:10: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 10:10: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 10:10: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 10:10: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 10:10:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:10:26 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:10:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:10:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:10:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:10:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:10:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:10:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:10:26 INFO - Downloading functools32-3.2.3-2.tar.gz 10:10:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:10:26 INFO - warning: no files found matching '*.txt' 10:10:26 INFO - no previously-included directories found matching 'build' 10:10:26 INFO - no previously-included directories found matching 'dist' 10:10:26 INFO - no previously-included directories found matching '.git*' 10:10:26 INFO - Installing collected packages: jsonschema, functools32 10:10:26 INFO - Running setup.py install for functools32 10:10:26 INFO - warning: no files found matching '*.txt' 10:10:26 INFO - no previously-included directories found matching 'build' 10:10:26 INFO - no previously-included directories found matching 'dist' 10:10:26 INFO - no previously-included directories found matching '.git*' 10:10:26 INFO - Successfully installed jsonschema functools32 10:10:26 INFO - Cleaning up... 10:10:26 INFO - Return code: 0 10:10:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:10:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:10:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:10:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:10:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:10:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107c3ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cd3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc10849ccb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ba2d30>, '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 0x107907db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b9ab20>, '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.YKHvipvj7K/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.9AfHCpyNGC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 10:10:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:10:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:10:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YKHvipvj7K/Render', 10:10:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:10:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:10:26 INFO - 'HOME': '/Users/cltbld', 10:10:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:10:26 INFO - 'LOGNAME': 'cltbld', 10:10:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:10:26 INFO - 'MOZ_NO_REMOTE': '1', 10:10:26 INFO - 'NO_EM_RESTART': '1', 10:10:26 INFO - 'PAGER': '/bin/cat', 10:10:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:10:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:10:26 INFO - 'PWD': '/builds/slave/test', 10:10:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:10:26 INFO - 'SHELL': '/bin/bash', 10:10:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9AfHCpyNGC/Listeners', 10:10:26 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 10:10:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:10:26 INFO - 'USER': 'cltbld', 10:10:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:10:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:10:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:10:26 INFO - 'XPC_FLAGS': '0x0', 10:10:26 INFO - 'XPC_SERVICE_NAME': '0', 10:10:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:10:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:10:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:10:27 INFO - Cleaning up... 10:10:27 INFO - Return code: 0 10:10:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:10:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:10:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:10:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:10:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:10:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:27 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x107c3ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cd3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc10849ccb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ba2d30>, '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 0x107907db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b9ab20>, '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.YKHvipvj7K/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.9AfHCpyNGC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 10:10:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:10:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:10:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YKHvipvj7K/Render', 10:10:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:10:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:10:27 INFO - 'HOME': '/Users/cltbld', 10:10:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:10:27 INFO - 'LOGNAME': 'cltbld', 10:10:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:10:27 INFO - 'MOZ_NO_REMOTE': '1', 10:10:27 INFO - 'NO_EM_RESTART': '1', 10:10:27 INFO - 'PAGER': '/bin/cat', 10:10:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:10:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:10:27 INFO - 'PWD': '/builds/slave/test', 10:10:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:10:27 INFO - 'SHELL': '/bin/bash', 10:10:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9AfHCpyNGC/Listeners', 10:10:27 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 10:10:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:10:27 INFO - 'USER': 'cltbld', 10:10:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:10:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:10:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:10:27 INFO - 'XPC_FLAGS': '0x0', 10:10:27 INFO - 'XPC_SERVICE_NAME': '0', 10:10:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:10:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:10:27 INFO - Downloading/unpacking blobuploader==1.2.4 10:10:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:10:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:10:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:10:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:10:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:10:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:10:29 INFO - Downloading blobuploader-1.2.4.tar.gz 10:10:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:10:29 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:10:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:10:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:10:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:10:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:10:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:10:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:10:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:10:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:10:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:10:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:10:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:10:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:10:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:10:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:10:30 INFO - Downloading docopt-0.6.1.tar.gz 10:10:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:10:31 INFO - Installing collected packages: blobuploader, requests, docopt 10:10:31 INFO - Running setup.py install for blobuploader 10:10:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:10:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:10:31 INFO - Running setup.py install for requests 10:10:31 INFO - Running setup.py install for docopt 10:10:31 INFO - Successfully installed blobuploader requests docopt 10:10:31 INFO - Cleaning up... 10:10:31 INFO - Return code: 0 10:10:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:10:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:10:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:10:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:10:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:10:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:31 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107c3ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cd3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc10849ccb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ba2d30>, '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 0x107907db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b9ab20>, '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.YKHvipvj7K/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.9AfHCpyNGC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 10:10:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:10:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:10:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YKHvipvj7K/Render', 10:10:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:10:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:10:31 INFO - 'HOME': '/Users/cltbld', 10:10:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:10:31 INFO - 'LOGNAME': 'cltbld', 10:10:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:10:31 INFO - 'MOZ_NO_REMOTE': '1', 10:10:31 INFO - 'NO_EM_RESTART': '1', 10:10:31 INFO - 'PAGER': '/bin/cat', 10:10:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:10:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:10:31 INFO - 'PWD': '/builds/slave/test', 10:10:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:10:31 INFO - 'SHELL': '/bin/bash', 10:10:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9AfHCpyNGC/Listeners', 10:10:31 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 10:10:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:10:31 INFO - 'USER': 'cltbld', 10:10:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:10:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:10:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:10:31 INFO - 'XPC_FLAGS': '0x0', 10:10:31 INFO - 'XPC_SERVICE_NAME': '0', 10:10:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:10:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:10:32 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-AQK993-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:10:32 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-EIvbwI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:10:32 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-mcUfOK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:10:32 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-s79GdY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:10:32 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-poRr1f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:10:32 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-eDEXML-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:10:32 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-NUU7dO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:10:32 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-nNKxRX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:10:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:10:32 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-5pNIPw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:10:33 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-Ha_oDh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:10:33 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-TG3PhN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:10:33 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-vwlPLe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:10:33 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-RKL16H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:10:33 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-3RQFbH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:10:33 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-nHOI4O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:10:33 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-5LyFy0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:10:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:10:33 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-YN7M5N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:10:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:10:33 INFO - Running setup.py install for manifestparser 10:10:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:10:34 INFO - Running setup.py install for mozcrash 10:10:34 INFO - Running setup.py install for mozdebug 10:10:34 INFO - Running setup.py install for mozdevice 10:10:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:10:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:10:34 INFO - Running setup.py install for mozfile 10:10:34 INFO - Running setup.py install for mozhttpd 10:10:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:10:34 INFO - Running setup.py install for mozinfo 10:10:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:10:34 INFO - Running setup.py install for mozInstall 10:10:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:10:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:10:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:10:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:10:35 INFO - Running setup.py install for mozleak 10:10:35 INFO - Running setup.py install for mozlog 10:10:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:10:35 INFO - Running setup.py install for moznetwork 10:10:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:10:35 INFO - Running setup.py install for mozprocess 10:10:35 INFO - Running setup.py install for mozprofile 10:10:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:10:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:10:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:10:35 INFO - Running setup.py install for mozrunner 10:10:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:10:36 INFO - Running setup.py install for mozscreenshot 10:10:36 INFO - Running setup.py install for moztest 10:10:36 INFO - Running setup.py install for mozversion 10:10:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:10:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:10:36 INFO - Cleaning up... 10:10:36 INFO - Return code: 0 10:10:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:10:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:10:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:10:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:10:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:10:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107c3ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cd3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc10849ccb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ba2d30>, '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 0x107907db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b9ab20>, '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.YKHvipvj7K/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.9AfHCpyNGC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 10:10:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:10:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:10:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YKHvipvj7K/Render', 10:10:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:10:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:10:36 INFO - 'HOME': '/Users/cltbld', 10:10:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:10:36 INFO - 'LOGNAME': 'cltbld', 10:10:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:10:36 INFO - 'MOZ_NO_REMOTE': '1', 10:10:36 INFO - 'NO_EM_RESTART': '1', 10:10:36 INFO - 'PAGER': '/bin/cat', 10:10:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:10:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:10:36 INFO - 'PWD': '/builds/slave/test', 10:10:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:10:36 INFO - 'SHELL': '/bin/bash', 10:10:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9AfHCpyNGC/Listeners', 10:10:36 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 10:10:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:10:36 INFO - 'USER': 'cltbld', 10:10:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:10:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:10:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:10:36 INFO - 'XPC_FLAGS': '0x0', 10:10:36 INFO - 'XPC_SERVICE_NAME': '0', 10:10:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:10:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:10:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:10:36 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-2UZnov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:10:36 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 10:10:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:10:36 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-wCDNw9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:10:36 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:10:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:10:36 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-Uq7Wls-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:10:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:10:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:10:37 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-bZEBvO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:10:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:10:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:10:37 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-vgeHiJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:10:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 10:10:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:10:37 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-jUrwTo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:10:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 10:10:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:10:37 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-BKZpkW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:10:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 10:10:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:10:37 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-8J7Pyc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:10:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 10:10:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:10:37 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-5r33MD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:10:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 10:10:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:10:37 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-mnrASS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:10:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 10:10:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:10:37 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-vJseB9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:10:37 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 10:10:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:10:37 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-3opgmk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:10:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 10:10:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:10:38 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-_ZfPiK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:10:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 10:10:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:10:38 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-HbeywY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:10:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 10:10:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:10:38 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-OKOaEI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:10:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 10:10:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:10:38 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-758Rth-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:10:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 10:10:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:10:38 INFO - Running setup.py (path:/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/pip-ftPIqE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:10:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 10:10:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:10:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:10:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:10:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:10:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:10:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:10: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 10:10: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 10:10: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 10:10: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 10:10: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 10:10: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 10:10:40 INFO - Downloading blessings-1.6.tar.gz 10:10:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:10:41 INFO - Installing collected packages: blessings 10:10:41 INFO - Running setup.py install for blessings 10:10:41 INFO - Successfully installed blessings 10:10:41 INFO - Cleaning up... 10:10:41 INFO - Return code: 0 10:10:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:10:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:10:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:10:41 INFO - Reading from file tmpfile_stdout 10:10:41 INFO - Current package versions: 10:10:41 INFO - blessings == 1.6 10:10:41 INFO - blobuploader == 1.2.4 10:10:41 INFO - docopt == 0.6.1 10:10:41 INFO - functools32 == 3.2.3-2 10:10:41 INFO - jsonschema == 2.5.1 10:10:41 INFO - manifestparser == 1.1 10:10:41 INFO - mozInstall == 1.12 10:10:41 INFO - mozcrash == 0.17 10:10:41 INFO - mozdebug == 0.1 10:10:41 INFO - mozdevice == 0.48 10:10:41 INFO - mozfile == 1.2 10:10:41 INFO - mozhttpd == 0.7 10:10:41 INFO - mozinfo == 0.9 10:10:41 INFO - mozleak == 0.1 10:10:41 INFO - mozlog == 3.3 10:10:41 INFO - moznetwork == 0.27 10:10:41 INFO - mozprocess == 0.23 10:10:41 INFO - mozprofile == 0.28 10:10:41 INFO - mozrunner == 6.12 10:10:41 INFO - mozscreenshot == 0.1 10:10:41 INFO - mozsystemmonitor == 0.3 10:10:41 INFO - moztest == 0.7 10:10:41 INFO - mozversion == 1.4 10:10:41 INFO - psutil == 3.1.1 10:10:41 INFO - requests == 1.2.3 10:10:41 INFO - wsgiref == 0.1.2 10:10:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:10:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:10:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:10:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:10:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:10:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107c3ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cd3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc10849ccb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ba2d30>, '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 0x107907db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b9ab20>, '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.YKHvipvj7K/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.9AfHCpyNGC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 10:10:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 10:10:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:10:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YKHvipvj7K/Render', 10:10:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:10:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:10:41 INFO - 'HOME': '/Users/cltbld', 10:10:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:10:41 INFO - 'LOGNAME': 'cltbld', 10:10:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:10:41 INFO - 'MOZ_NO_REMOTE': '1', 10:10:41 INFO - 'NO_EM_RESTART': '1', 10:10:41 INFO - 'PAGER': '/bin/cat', 10:10:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:10:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:10:41 INFO - 'PWD': '/builds/slave/test', 10:10:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:10:41 INFO - 'SHELL': '/bin/bash', 10:10:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9AfHCpyNGC/Listeners', 10:10:41 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 10:10:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:10:41 INFO - 'USER': 'cltbld', 10:10:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:10:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:10:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:10:41 INFO - 'XPC_FLAGS': '0x0', 10:10:41 INFO - 'XPC_SERVICE_NAME': '0', 10:10:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:10:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:10:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:10:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 10:10:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 10:10:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:10:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 10:10:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 10:10:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 10:10:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 10:10:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 10:10:41 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:10:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:10:41 INFO - Cleaning up... 10:10:41 INFO - Return code: 0 10:10:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:10:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:10:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:10:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 10:10:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:10:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:10:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x107c3ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107cd3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc10849ccb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ba2d30>, '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 0x107907db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b9ab20>, '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.YKHvipvj7K/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.9AfHCpyNGC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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 10:10:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:10:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:10:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YKHvipvj7K/Render', 10:10:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:10:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:10:41 INFO - 'HOME': '/Users/cltbld', 10:10:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:10:41 INFO - 'LOGNAME': 'cltbld', 10:10:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:10:41 INFO - 'MOZ_NO_REMOTE': '1', 10:10:41 INFO - 'NO_EM_RESTART': '1', 10:10:41 INFO - 'PAGER': '/bin/cat', 10:10:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:10:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:10:41 INFO - 'PWD': '/builds/slave/test', 10:10:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:10:41 INFO - 'SHELL': '/bin/bash', 10:10:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9AfHCpyNGC/Listeners', 10:10:41 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 10:10:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:10:41 INFO - 'USER': 'cltbld', 10:10:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:10:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:10:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:10:41 INFO - 'XPC_FLAGS': '0x0', 10:10:41 INFO - 'XPC_SERVICE_NAME': '0', 10:10:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:10:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:10:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:10:42 INFO - Cleaning up... 10:10:42 INFO - Return code: 0 10:10:42 INFO - Running post-action listener: _resource_record_post_action 10:10:42 INFO - Running post-action listener: _start_resource_monitoring 10:10:42 INFO - Starting resource monitoring. 10:10:42 INFO - [mozharness: 2016-08-02 17:10:42.170903Z] Finished create-virtualenv step (success) 10:10:42 INFO - [mozharness: 2016-08-02 17:10:42.171851Z] Running install step. 10:10:42 INFO - Running pre-action listener: _resource_record_pre_action 10:10:42 INFO - Running main action method: install 10:10:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:10:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:10:42 INFO - Reading from file tmpfile_stdout 10:10:42 INFO - Detecting whether we're running mozinstall >=1.0... 10:10:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:10:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:10:42 INFO - Reading from file tmpfile_stdout 10:10:42 INFO - Output received: 10:10:42 INFO - Usage: mozinstall [options] installer 10:10:42 INFO - Options: 10:10:42 INFO - -h, --help show this help message and exit 10:10:42 INFO - -d DEST, --destination=DEST 10:10:42 INFO - Directory to install application into. [default: 10:10:42 INFO - "/builds/slave/test"] 10:10:42 INFO - --app=APP Application being installed. [default: firefox] 10:10:42 INFO - mkdir: /builds/slave/test/build/application 10:10:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:10:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 10:11:16 INFO - Reading from file tmpfile_stdout 10:11:16 INFO - Output received: 10:11:16 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:11:16 INFO - Running post-action listener: _resource_record_post_action 10:11:16 INFO - [mozharness: 2016-08-02 17:11:16.750904Z] Finished install step (success) 10:11:16 INFO - [mozharness: 2016-08-02 17:11:16.751065Z] Running run-tests step. 10:11:16 INFO - Running pre-action listener: _resource_record_pre_action 10:11:16 INFO - Running main action method: run_tests 10:11:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:11:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:11:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:11:16 INFO - 2016-08-02 10:11:16.765 screenresolution[1931:65125] starting screenresolution argv=screenresolution get 10:11:16 INFO - 2016-08-02 10:11:16.768 screenresolution[1931:65125] Display 0: 1600x1200x32@60 10:11:16 INFO - 2016-08-02 10:11:16.773 screenresolution[1932:65128] starting screenresolution argv=screenresolution list 10:11:16 INFO - Available Modes on Display 0 10:11:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:11:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:11:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:11:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:11:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:11:17 INFO - Intel Iris: 10:11:17 INFO - Chipset Model: Intel Iris 10:11:17 INFO - Type: GPU 10:11:17 INFO - Bus: Built-In 10:11:17 INFO - VRAM (Dynamic, Max): 1536 MB 10:11:17 INFO - Vendor: Intel (0x8086) 10:11:17 INFO - Device ID: 0x0a2e 10:11:17 INFO - Revision ID: 0x0009 10:11:17 INFO - Displays: 10:11:17 INFO - Display: 10:11:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:11:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:11:17 INFO - Display Serial Number: 1600x1200 60 10:11:17 INFO - Main Display: Yes 10:11:17 INFO - Mirror: Off 10:11:17 INFO - Online: Yes 10:11:17 INFO - Rotation: Supported 10:11:17 INFO - Return code: 0 10:11:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:11:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:11:17 INFO - Python 2.7.3 10:11:17 INFO - Return code: 0 10:11:17 INFO - grabbing minidump binary from tooltool 10:11: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')]} 10:11:17 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107ba2d30>, '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 0x107907db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107b9ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:11:17 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 10:11:17 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 10:11:17 INFO - Calling ['/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'] with output_timeout 600 10:11:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:11:17 INFO - Return code: 0 10:11:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:11:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:11:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:11:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:11:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:11:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aO2sMXSrSZipSz9mCLFKgg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0268', '--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 10:11:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/aO2sMXSrSZipSz9mCLFKgg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0268 --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 10:11:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.YKHvipvj7K/Render', 10:11:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:11:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:11:17 INFO - 'HOME': '/Users/cltbld', 10:11:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:11:17 INFO - 'LOGNAME': 'cltbld', 10:11:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:11:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:11:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:11:17 INFO - 'MOZ_NO_REMOTE': '1', 10:11:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:11:17 INFO - 'NO_EM_RESTART': '1', 10:11:17 INFO - 'PAGER': '/bin/cat', 10:11:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:11:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:11:17 INFO - 'PWD': '/builds/slave/test', 10:11:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:11:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:11:17 INFO - 'SHELL': '/bin/bash', 10:11:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9AfHCpyNGC/Listeners', 10:11:17 INFO - 'TMPDIR': '/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/', 10:11:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:11:17 INFO - 'USER': 'cltbld', 10:11:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:11:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:11:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:11:17 INFO - 'XPC_FLAGS': '0x0', 10:11:17 INFO - 'XPC_SERVICE_NAME': '0', 10:11:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:11:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aO2sMXSrSZipSz9mCLFKgg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0268', '--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 10:11:17 INFO - mozversion application_buildid: 20160802080456 10:11:17 INFO - mozversion application_changeset: ea6e87bbd03e976ddb00f625afe40ee5167a8a35 10:11:17 INFO - mozversion application_display_name: Nightly 10:11:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:11:17 INFO - mozversion application_name: Firefox 10:11:17 INFO - mozversion application_remotingname: firefox 10:11:17 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:11:17 INFO - mozversion application_vendor: Mozilla 10:11:17 INFO - mozversion application_version: 51.0a1 10:11:17 INFO - mozversion platform_buildid: 20160802080456 10:11:17 INFO - mozversion platform_changeset: ea6e87bbd03e976ddb00f625afe40ee5167a8a35 10:11:17 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:11:17 INFO - mozversion platform_version: 51.0a1 10:11:17 INFO - using testdate: 1470157877 10:11:17 INFO - actual date: 1470157877 10:11:17 INFO - starting webserver on 'localhost:54350' 10:11:17 INFO - SUITE-START | Running 2 tests 10:11:17 INFO - TEST-START | dromaeo_css 10:11:17 INFO - operating with platform_type : mac_ 10:11:17 INFO - Initialising browser for dromaeo_css test... 10:11:17 INFO - TEST-INFO | started process 1942 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/tmp4AcjKF/profile http://localhost:54350/getInfo.html) 10:11:19 INFO - PROCESS | 1942 | __metrics Screen width/height:1600/1200 10:11:19 INFO - PROCESS | 1942 | colorDepth:24 10:11:19 INFO - PROCESS | 1942 | Browser inner width/height: 1024/689 10:11:19 INFO - PROCESS | 1942 | __metrics 10:11:19 INFO - PROCESS | 1942 | 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" 10:11:19 INFO - PROCESS | 1942 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:11:20 INFO - TEST-INFO | 1942: exit 0 10:11:20 INFO - Browser initialized. 10:11:20 INFO - Running cycle 1/1 for dromaeo_css test... 10:11:20 INFO - TEST-INFO | started process 1944 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/tmp4AcjKF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:11:32 INFO - PROCESS | 1944 | RSS: Main: 243167232 10:11:32 INFO - PROCESS | 1944 | 10:12:23 INFO - PROCESS | 1944 | 1470157943479 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] 10:14:35 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:54350/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54350/tests/dromaeo/cssquery-ext.html) 10:14:36 INFO - PROCESS | 1944 | RSS: Main: 388038656 10:14:36 INFO - PROCESS | 1944 | 10:17:40 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:54350/tests/dromaeo/cssquery-ext.html (next: http://localhost:54350/tests/dromaeo/cssquery-jquery.html) 10:17:40 INFO - PROCESS | 1944 | RSS: Main: 402599936 10:17:40 INFO - PROCESS | 1944 | 10:20:43 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:54350/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54350/tests/dromaeo/cssquery-mootools.html) 10:20:43 INFO - PROCESS | 1944 | RSS: Main: 377421824 10:20:43 INFO - PROCESS | 1944 | 10:23:45 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:54350/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54350/tests/dromaeo/cssquery-prototype.html) 10:23:46 INFO - PROCESS | 1944 | RSS: Main: 345714688 10:23:46 INFO - PROCESS | 1944 | 10:26:49 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:54350/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54350/tests/dromaeo/cssquery-yui.html) 10:26:50 INFO - PROCESS | 1944 | RSS: Main: 343146496 10:26:50 INFO - PROCESS | 1944 | 10:29:53 INFO - PROCESS | 1944 | Cycle 1(1): loaded http://localhost:54350/tests/dromaeo/cssquery-yui.html (next: http://localhost:54350/tests/dromaeo/cssquery-dojo.html) 10:29:53 INFO - PROCESS | 1944 | RSS: Main: 543027200 10:29:53 INFO - PROCESS | 1944 | 10:29:53 INFO - PROCESS | 1944 | __start_tp_report 10:29:53 INFO - PROCESS | 1944 | _x_x_mozilla_page_load 10:29:53 INFO - PROCESS | 1944 | _x_x_mozilla_page_load_details 10:29:53 INFO - PROCESS | 1944 | |i|pagename|runs| 10:29:53 INFO - PROCESS | 1944 | |0;dojo.html;3317.071220058384;3321.983390083051;3325.6508066653;3337.36590047891;3380.2056516718567;5533.142362933741;5571.498565129137;5650.8587285317835;5676.375598684152;5683.374828768838;5619.4942455179025;5653.689047102408;5704.629199102066;5711.7715291388295;5735.1970724098655;5066.822661206858;5096.490350964911;5162.638615296931;5186.7147306898105;5245.659032162916;5231.398389185235;5241.213817927303;5247.553957913563;5262.973685131569;5279.524842764151;5081.949180508184;5132.692038477703;5136.152534831748;5159.32928719266;5256.763445644936;5179.326687530597;5184.766685499161;5186.611004174702;5190.584753219771;5200.583953283766;5099;5137.357830271216;5156.871078223075;5168.992046550924;5175.042617115846;5677.886442271131;5679.716014199274;5684.658920464744;5684.715764211773;5710.600257981943;6089.056196289538;6103.847403814896;6128.816135515942;6148.323684394713;6167.599106058092;6214.533909956771;6219.595726277777;6222.097795819626;6237.189165408513;6239.188905442309;5805.274340707411;5858.824235272906;5863.237779088734;5865.384134665798;5867.325257595388;6185.195924529782;6228.501719862491;6250.624962502265;6254.718537665816;6290.087937249074;6143.815685529441;6144;6149.985252433298;6159.5688301818445;6161.921663708833;6067.886465396542;6260.154879091265;6261.530385221126;6263.874722505524;6268.028455589391;6067.605605635619;6121.84695382619;6166.8899598071885;6173.197484327009;6176.073588961692;6166.383361663798;6170.876582468325;6173;6194.659293738888;6196.256449226122;4949.183384741549;4950.70295782247;4960.975195124073;4964.379452568429;4982.501749825061;4321.157374312042;4446.510883802779;4464.665150113755;4532.229520981441;4549.6360291176625;5608.607397482218;5645.745941432645;5662.150677398423;5717;5734.139879018181;6247.625142491465;6258.749650013949;6259.968700156561;6260.186175797117;6273.059041143865;5555.944440555623;5647.7176141193595;5662.156028510253;5671.489565939085;5697.9430205698245;5300.09898317287;5351.411344752074;5400.432954539717;5406.567474602101;5408.378036525771;6265.27949285838;6280.623162610258;6288.622682639057;6293.992961126198;6300.338464461349;6138.140660307471;6242.406971337716;6252.3122456530655;6254.781082662085;6256.687165641791;6176.9073463897175;6197.411245931629;6205.348438414082;6303.747850085945;6307.085472606354;6146.279041868284;6163.7842675506145;6172.290186628596;6173.444390004921;6194.876102478015;5014.924776128434;6248.4376406123665;6253.937460625336;6254.249490061222;6278.811635650938;5896.14505896634;5971.552133589911;6018.4884284834525;6107.114468401968;6116.755329786936;6007.789727359522;6025.78909738157;6067.969660151847;6092.329843717276;6129.111278864629;5901.557383196363;5909.084091965752;5912.881742365043;5916.023856063702;5970.432808883149;5841.386654401226;5909.320428150818;5921.082232254007;6101.725422355916;6108.018205539365;5783.595148339576;5843.795467158629;5853.239078919713;5872.383399742966;5917.112433135064 10:29:53 INFO - PROCESS | 1944 | |1;ext.html;452.2154062701215;459.59325996493095;509.24377299709926;529.7695502456431;549.2009126720621;31007.379852402966;41293.58706412939;42046.789766051166;42091.57908420915;42182.94542636428;43447.78276108615;43781.68654940349;43904.78047609766;43912;43921;9627.56675949584;9787.902120978806;9800.803983920317;9814.558344874498;9830.311878168532;8676.305895528343;8853.601587928559;11701.659668066379;11917.165798394115;11942.402879856016;12000.69998250042;12051.57819476314;12073.3963301835;12081.275123492618;12089.818652720216;10426.530806113667;11893.2269402489;11926.807319268091;11952.223105498202;12002.639920802347;11479.368634725086;11627.767444651145;11632.185746997713;11715.94142029293;11722.472488738027;40299.59700403017;40545.797271013755;40574.39138412926;40590;40706;3750.2124553843723;3772.8868133956025;3985.7608543487195;4227.260229459832;4432.22436073689;26777;32582.022539323858;34395.828020859735;34413;34425.31149376999;3192.6488086310487;3363.041533163061;3399.6260411354733;3411.113110591264;3438.914027149341;432139;436524;526954;529032;531124;69341;69971;70195.54315598929;70209;70266;74141;74478.64846081939;74491;74647;74756;17361.65276694459;17495.03777292236;17497.21262543163;17498.650026999643;17539.824601754073;12116.69708257283;13132.671683207996;13200;13210.207387556777;13222.537211197754;17440.651186976444;18082.76855459726;18172.36396726108;21050.78949210519;21494.677579836316;8702.521361325062;8718.17177368149;8722.563871806384;8769.123087691307;8774.25418839393;852.9061803201643;938.887333519982;945.8675785390051;971.0338213477494;1017.7353887989176;26442.73557264441;28666.283342916176;30090.84954575213;30915.845420772752;30978.225544361572;911.284641556375;1071.6463567022863;1088.7495875948575;1114.5430373546808;1148.7645032768262;13594.660133496543;15985;16024.11867347307;16230.543874683488;16360.93653912492;581677;585113;587762;588456;588654;60712.39287607068;60945.39054609397;60993;61004.38995610076;61134.38865611287;1204.6265657646159;1284.6659868434274;1312.8949684025447;1313.7175507265983;1426.4650755966513;22105.889470552545;25407.618885716358;27850.303742406602;31360.372792544476;31471.370572587963;49681;50209.497905020486;50263.49736502588;50278.245826311904;50297;577115;581484;581972;582016;582262;27638.894444222005;27988.860055700407;28203.858980704965;28820.991265305613;43281.56718432775;48085;48458.27312590244;48547.75726121488;48778.75610621924;48785;36413.817930910176;36835.07912302214;40003;40460.1907961845;41061.38407923824;39630.40554391629;39942.400863986484;40391.1921761569;41084.58915410808;41276.1744765109;39001;39216.803915980236;39288.2142357157;39412;39576.80211598924;26176.8691156543;27981;35692.92921212368;35881;36045.45931810972;22963.196288129835;25870.48259034771;26107.477850443265;26367.353850905252;26368.270992804475 10:29:53 INFO - PROCESS | 1944 | |2;jquery.html;361.08645127826577;379.18664464723184;381.10630571310276;388.1751278533117;411.1632827196655;15325.927185097047;15647;20676.793232067714;21155;21189.04649290782;20497.282595109173;21015.264465743665;21235;21516.031778569963;21919.890400548014;5437.918431223535;5841.211436456067;5999.910001349983;6069.423404776539;6171.4445699887;4180.184863951531;6028.156058151841;6121.296050954131;6123.142760013601;6477.578957367779;6698.263191048981;6752.729890804362;6783.559068660546;6784.151981002375;6812.080369150151;5878.970605146968;6221.097940798604;6261.96869015652;6266.342034086447;6737;5832.6500409975115;6017.833579911228;6107.72515236811;6127.203463549755;6138.570300078996;13633.863661363359;13646.522371716943;13889.236092014935;13897.096688715303;13899.930500347535;2232.743234528034;2273.1134857405623;2326.802221811131;2467.629855521668;2690.4081102157493;9820.263480238938;9988.800223995479;10212.029857163558;10224.744381390525;10225.897741022569;1781.7060185069447;1821.3261093395529;1867.5424520992308;1913.8277555019977;1920.0975541495613;94113;111684;126668.36665816612;130420;130645;11091.500882460308;11291.209615326849;11425.028872545761;11468.827967580326;11521.596744114084;14432.783508247385;14454.85545144556;14479.565613031626;14481.348339324755;14523.636909077357;8415.326773858076;8568.67185586235;8601.741947741577;8662.826743465095;8673.436226645248;8658.696945606873;8676.045634980148;8679.26226270774;8816.8677469838;8850.247728942983;3844.4041173618134;4425.800838962255;4496.595306422438;4498.8425405110665;4572.5198854119835;1862.8789128706592;1921.2027008791474;1938.6898096304806;1939.7187407825786;1985.672364059914;643.3952085040141;747.5925620536821;865.4119690368245;879.5953861223909;896.4536175098034;21596.51611098887;21781.87434409842;21796.186484759466;21829.783855665526;21992.580816110214;840.5293035899915;908.6229214875752;927.6845872403416;944.4994153098869;1037.7509397744639;9129.680461183827;10806.567737290577;10932.836007459895;11311.264767790068;11328.32030078198;575803;603772;613618;614589;614804;105531;138372;141898;142588.5741142596;143273;961.1205746741792;978.320067553058;1005.3615953869346;1006.0643601450721;1006.2302338710892;15386.230688465308;19550.511237219183;19635.11641976086;20434.849775996623;20657.793422065588;41103.972400689025;55403.44596553983;56192.157117644085;57293.14060289182;57337.713311433174;48924;75814.24185758072;77289;77515.22484775305;77726;5561;5876.382979787061;8154.409890071379;8420.957895210484;8675.652973880975;11336.433178340885;12612.684682883002;12729.55446559366;13376.174937661577;13866.584002479942;11559.479823407799;11769.823452648046;11852.636946751234;11941.52233910634;12002.039836813208;11318.207725459139;11504.654860354203;11605.709857253636;11771.293722376684;11833.704157396134;10453.372797632166;10711.25021248505;10828.620998265023;12021.398930053287;12037.819432708691;7036.261192574706;8149.918500814916;8273.627686753991;8380;8461.492310461392;8484.109168537214;10845.349279043283;11370;11637.825432618349;11740 10:29:53 INFO - PROCESS | 1944 | |3;mootools.html;1514.6364872430613;1539.7613369927662;1740.4778566430075;1852.6479968805918;1900.6578815813157;8143.959280203606;8720.52037137957;8728.869066963985;8731.257843083338;8748;7665.386769058476;7869.095054068784;8296.751097467071;8507.872381914276;8535.78660533487;2275.670027845957;2310.722713274409;2314.4445333120098;2319.2694301295037;2321.8955147018423;2341.6487526870937;2352.4589344450787;2395.413123784675;2397.2568503763887;2397.6763136976547;2347.8473899196583;2372.8220383471307;2383.261189031397;2383.6305372667266;2384.5707772600927;2336.100601268507;2358.445765245166;2359.976400235993;2368.253999990015;2369.383960170368;1935.5741736817877;1956.7456230690061;2348.953020939589;2356.917507887216;2363.7990770784504;8528.189821966904;8610.655573777041;8641.870371944426;8652.913470865275;8677.305815534743;5135.8202462914;5305.442928492491;5309.973450132725;5322.600804939645;5345.224942383372;3433.9141521461916;3502.036939841564;3649.627741967716;3650.0509867434394;3652.2695460907657;1057.0909018244304;1090.983635245472;1095.3209010413516;1111.8776934537195;1114.6043154680135;11315.660530184108;11421.714457138554;11434.656960291204;11437.485313160845;11468.598599049077;4481.395011673382;4555.9772201138785;4752.358431611758;4761.523847615211;4776.522347765265;9685.70942871715;9688.370255933341;9702.90297097034;9707.46608936501;9714.319997600242;5280.9207861882105;5288.8413347599635;5289.259503669489;5293.894122117536;5304.761285742151;4030.118527284177;4297.871063868089;4449.532799056117;4459.331100334975;4855.514448555116;3054.5570892220503;3694.8706795262574;3789.77261364319;3806.7335286530233;4044.2518134145275;1064.6539874540806;1278.0478543485162;1301.7331447053332;1307.9542216022394;1308.672831792052;897.4480694372913;917.4862077236769;926.9258459323238;931.3852857114272;944.9716508504756;5607.43925607436;5919.389172604708;5981;6034.155218269446;6107.328193898667;421.15978622648015;431.2151883571869;439.2444994609267;441.8431456832778;444.7820567921695;3934.390169523728;3950.1876662275017;4047.7976101195413;4052.63526282636;4111.753294802322;9970.501474926225;10432.895671043345;10434.634787782392;10442.63450779219;10448.68653940383;10416.479176041319;10418.322809017389;10419.479026048668;10422.166226701846;10424.843627345595;7109.573425594481;7941.086775020831;7995.080565734898;8017.51675939953;8159.75520734367;5039.815643323743;5655.88688226225;5764.942350576441;5803.448672376117;6015.187949626745;8483.703070392507;9470.479123648265;9644.903550964682;9647.1317581418;9787.265955053543;9534.761630959281;9814.803703926023;9914.504274786375;10180.185585153318;10186.236032297755;7368.889466657897;7542.698292068475;7554.697812087455;7555.73554925575;7557.886631700419;7919.683212671659;8141.470804397932;8227.259546640831;8323.209295116954;8325.916740832514;7289.234630363735;7314.524555903849;7365.668544915598;7397.519161254501;7508.361789247975;7255.020572222682;7279.59962202084;7280.635968201675;7313.780586582198;7353.338199562065;6894.896576551456;6908.3091690830515;6917.100776898971;6930.306969303231;6999.440044796303;4279.507856596531;4886.951130488792;4921.753912304442;4958.876028099182;5040.344755181803;4199.832006719819;4879.219324907997;4979.526944940225;4984.900301994012;5063.1645778446145 10:29:53 INFO - PROCESS | 1944 | |4;prototype.html;300.5041681225978;311.5155932524923;312.7013701914671;318.27115904578517;321.10411950657664;5270.578353731702;5294.226434144556;5304.257403963444;5324.707141107242;5329.681588509001;4714.787834547445;5225.738713064351;5254.7635356408955;5289.841304760864;5323.520883120518;3119.953200701991;3761.3229618668697;3816.3971132124575;3931.2334094851517;3949.328614135604;3286.474164133739;3612.945805812915;3990.2019596080754;4033.6571391431617;4041.6564592009568;3611.819409029538;3824.5984171661994;3958.802059897008;3983.800809959505;4031.3751368537924;3694.3534881395917;3760.7845292274983;3798.4682144499793;3934.547527034399;4021.6983726220506;3260.722838558702;3481.651834816523;3498.4402495600834;3523.524324216249;3525.2596954639557;4988.975055124701;5057.291979122926;5059.367579052619;5082.186850103965;5122.282880396748;3737.570179429346;4439.622632076277;4562.760440087542;4610.400647915783;4630.467496237909;4201.516825565069;5132.974335128338;5468.058839112137;5471.5896307777075;5494.313210848644;3043.4217498675403;3540.0087975366937;3849.749766265184;3863.2273545291055;3896.4544963705102;4902.803887844518;5196.402413722432;5853;5911.793087241969;5952.255968003999;3389.5593572835865;3900.3759398496104;3903.9609603904164;3926.9173698646428;3932.5477570079806;3341.4486609709124;3817.961820381761;3979.5423526294267;3998.7000974926996;3999.9800000999808;3794.317022935898;4603.355530225771;4605.148047611203;4605.746683932349;4619.284010978304;5194.97402512985;5511.3937466878615;5566.304211973504;5617.9719101404235;5620.381758006616;1228.6129869091387;1319.9142055766345;1324.0202250334876;1334.3928512526777;1339.5378594384922;722.9096362954631;805.476440313789;823.5264722784423;827.8385714785559;829.2702421868717;4047.291723948297;4305.526392096866;4400.779961001937;4603;4887.437944636341;5078.669886457368;5415.296011518478;5603.327600687943;5611.719414029364;5700.857478563069;4753.691010084333;5068;5079.3650793650795;5095.643304968617;5191.740412979336;2726.8772905218916;2812.395335002984;2960.8667609957174;2967.3916847046303;2983.030515082607;4850.417949846041;5367.624266301399;5419.945800542023;5844.912326315109;5900.881982360329;5121.4622464641425;5238.423773384925;5828;5829.813819817568;5936.762529498772;4395.736255824597;5140.612034750521;5216.852292495645;5316.308879845596;5922.644641321534;4466.838621958249;5369.140937449989;5726.141078838041;5842.328132264844;5863.706814659336;4998.001998001998;5059.760358712139;5264.684118952875;5639;5700.771969121189;4994.150994331045;5651.689157096399;5945.762169513344;5980.611260268069;5997.91003134962;3788.5643151037984;3839.155385815116;3883.902902427462;3887.7667339959694;3887.8056097195595;5293.12663410533;5375.3280839895015;5561.721913904208;5621.606487545832;5835.970820146042;5524.779008839601;5526.92225016134;5527.9220551993585;5529.861753456195;5537.809370985257;4572.376806233872;5489.670619762827;5540;5599.32808063035;5602.993859390555;4996.752111127796;5157.432682404858;5426.4573542646995;5440.1023831069015;5498.642588231752;4606.976965115153;4835.564799167951;5303.655262407931;5311.150215965427;5436.130219164915;4879.536444037811;5209.6874187548865;5364.865878353073;5386.542143917654;5412.269343638716 10:29:53 INFO - PROCESS | 1944 | |5;yui.html;729.1942403643973;740.3189066059228;743.8437928035119;783.9216078392161;825.9345444376754;15698.764518532216;16592.17039148044;16607.418740344092;16625.9193152445;16627.91686041568;14117.92941035296;14921.552353429415;15095.396184152649;15183.772243416357;15243;877.806882485852;881.6693739847557;896.1844721303615;897.6813231302891;899.4558292233203;876.5660997806095;876.8597024476085;878.6704985630389;879.5338470610586;879.7668617816283;933.1601558597249;933.169479163545;933.5192375926404;934.042606328512;934.103260869566;925.8935222449437;926.8146370725882;928.0441145620022;928.3130483442238;929.5677509957834;573.5354362966011;690.3579670906055;691.5815931361504;692.4495026453981;692.6363659078974;13269.933650331783;13303.60089197316;13305.53430629933;13333.599992000158;13368.732625347438;12183.939080304543;12190.634280971584;12293.754124917452;12332.321722305269;12543.498260069586;10936.945315273453;11201.887981120248;11255.155863310203;11276.43617819114;11748.588799392066;3276.950845737316;3760.360738674432;3763.981180094082;3799.2401519695895;3834.118152824866;94121;121707;122315;123260.1510977336;124859.12711309337;8666;8844.690435834844;8939.508327041945;9022.729318120468;9225.72322830316;18928.24287028531;18989.525261868286;19149.234030638618;19206;19380.612387752168;8209.917900820916;8230;8251.793705157299;8308.6676532938;8453.746387608382;4221.134667393177;4277.893052673646;4316.374125751749;4359.760213188305;4494.07871386365;3603.7117030637487;3704.68510176638;3734.7945862977253;3750;3758.229562939591;625.0873724362391;634.403660559073;634.9301576826546;639.7313128486047;650.8860949333942;563.844942640777;635.5101209951677;638.5976834594269;639.8656282180795;648.3451713769127;8897.510636914902;8934.86597701035;9190.494522801177;9334.966136005825;9465.810683786423;639.5011890725242;662.3806740697368;676.7318994004455;713.4791602130474;713.6360456167392;4467.910641787211;4670.224838816752;4774.546418090275;4784.569388755029;4867.464578896319;96260;118670;152495;153110.46889531185;154644;67508;86779.13220867836;86871.13128868757;133556.33221834022;134781;88056;98413.50793246132;100839;107817;109237.36143957847;9583.233341332816;9774.462404567817;9787.706368808947;9855.901440985785;10106.797864042825;60200.19399418024;62398.37601624107;63860;64896.35103648903;67377.6631116861;67599.66200168968;96136.55795163217;98578.507107464;99250.5037474808;102859.48570257101;13907;13925.79111313311;14377.353019113956;14604.342804574033;14886.77669834975;14906.254687265377;15010.249487525798;15060.171690557125;15101.47144849925;15142.54572362831;13856.030077894853;14189.219592922489;14202;14238.50165244253;14704.77942830837;11670.88329116698;13340.799888001493;14003.789943150658;14120.858791411954;14455.349509271897;13643.931780341034;13649.317534123453;13724.176549407068;13808.723825523633;13853.376598053314;9653.903460965581;11202.271852329572;11289.20975531705;11898.167128300936;11902.880971190178;10062;11481.483333249857;11592.1305902056;11759.706007349885;11760.82358764602 10:29:53 INFO - PROCESS | 1944 | __end_tp_report 10:29:53 INFO - PROCESS | 1944 | __start_cc_report 10:29:53 INFO - PROCESS | 1944 | _x_x_mozilla_cycle_collect,1454 10:29:53 INFO - PROCESS | 1944 | __end_cc_report 10:29:53 INFO - PROCESS | 1944 | __startTimestamp1470158993750__endTimestamp 10:29:53 INFO - PROCESS | 1944 | 10:29:53 INFO - PROCESS | 1944 | ------- Summary: start ------- 10:29:53 INFO - PROCESS | 1944 | Number of tests: 6 10:29:53 INFO - PROCESS | 1944 | 10:29:53 INFO - PROCESS | 1944 | [#0] dojo.html Cycles:165 Average:5712.76 Median:5901.56 stddev:631.12 (10.7%) stddev-sans-first:604.43 10:29:53 INFO - PROCESS | 1944 | Values: 3317.1 3322.0 3325.7 3337.4 3380.2 5533.1 5571.5 5650.9 5676.4 5683.4 5619.5 5653.7 5704.6 5711.8 5735.2 5066.8 5096.5 5162.6 5186.7 5245.7 5231.4 5241.2 5247.6 5263.0 5279.5 5081.9 5132.7 5136.2 5159.3 5256.8 5179.3 5184.8 5186.6 5190.6 5200.6 5099.0 5137.4 5156.9 5169.0 5175.0 5677.9 5679.7 5684.7 5684.7 5710.6 6089.1 6103.8 6128.8 6148.3 6167.6 6214.5 6219.6 6222.1 6237.2 6239.2 5805.3 5858.8 5863.2 5865.4 5867.3 6185.2 6228.5 6250.6 6254.7 6290.1 6143.8 6144.0 6150.0 6159.6 6161.9 6067.9 6260.2 6261.5 6263.9 6268.0 6067.6 6121.8 6166.9 6173.2 6176.1 6166.4 6170.9 6173.0 6194.7 6196.3 4949.2 4950.7 4961.0 4964.4 4982.5 4321.2 4446.5 4464.7 4532.2 4549.6 5608.6 5645.7 5662.2 5717.0 5734.1 6247.6 6258.7 6260.0 6260.2 6273.1 5555.9 5647.7 5662.2 5671.5 5697.9 5300.1 5351.4 5400.4 5406.6 5408.4 6265.3 6280.6 6288.6 6294.0 6300.3 6138.1 6242.4 6252.3 6254.8 6256.7 6176.9 6197.4 6205.3 6303.7 6307.1 6146.3 6163.8 6172.3 6173.4 6194.9 5014.9 6248.4 6253.9 6254.2 6278.8 5896.1 5971.6 6018.5 6107.1 6116.8 6007.8 6025.8 6068.0 6092.3 6129.1 5901.6 5909.1 5912.9 5916.0 5970.4 5841.4 5909.3 5921.1 6101.7 6108.0 5783.6 5843.8 5853.2 5872.4 5917.1 10:29:53 INFO - PROCESS | 1944 | 10:29:53 INFO - PROCESS | 1944 | [#1] ext.html Cycles:180 Average:70140.15 Median:39494.40 stddev:147963.02 (374.6%) stddev-sans-first:148285.58 10:29:53 INFO - PROCESS | 1944 | Values: 452.2 459.6 509.2 529.8 549.2 31007.4 41293.6 42046.8 42091.6 42182.9 43447.8 43781.7 43904.8 43912.0 43921.0 9627.6 9787.9 9800.8 9814.6 9830.3 8676.3 8853.6 11701.7 11917.2 11942.4 12000.7 12051.6 12073.4 12081.3 12089.8 10426.5 11893.2 11926.8 11952.2 12002.6 11479.4 11627.8 11632.2 11715.9 11722.5 40299.6 40545.8 40574.4 40590.0 40706.0 3750.2 3772.9 3985.8 4227.3 4432.2 26777.0 32582.0 34395.8 34413.0 34425.3 3192.6 3363.0 3399.6 3411.1 3438.9 432139.0 436524.0 526954.0 529032.0 531124.0 69341.0 69971.0 70195.5 70209.0 70266.0 74141.0 74478.6 74491.0 74647.0 74756.0 17361.7 17495.0 17497.2 17498.7 17539.8 12116.7 13132.7 13200.0 13210.2 13222.5 17440.7 18082.8 18172.4 21050.8 21494.7 8702.5 8718.2 8722.6 8769.1 8774.3 852.9 938.9 945.9 971.0 1017.7 26442.7 28666.3 30090.8 30915.8 30978.2 911.3 1071.6 1088.7 1114.5 1148.8 13594.7 15985.0 16024.1 16230.5 16360.9 581677.0 585113.0 587762.0 588456.0 588654.0 60712.4 60945.4 60993.0 61004.4 61134.4 1204.6 1284.7 1312.9 1313.7 1426.5 22105.9 25407.6 27850.3 31360.4 31471.4 49681.0 50209.5 50263.5 50278.2 50297.0 577115.0 581484.0 581972.0 582016.0 582262.0 27638.9 27988.9 28203.9 28821.0 43281.6 48085.0 48458.3 48547.8 48778.8 48785.0 36413.8 36835.1 40003.0 40460.2 41061.4 39630.4 39942.4 40391.2 41084.6 41276.2 39001.0 39216.8 39288.2 39412.0 39576.8 26176.9 27981.0 35692.9 35881.0 36045.5 22963.2 25870.5 26107.5 26367.4 26368.3 10:29:53 INFO - PROCESS | 1944 | 10:29:53 INFO - PROCESS | 1944 | [#2] jquery.html Cycles:180 Average:34979.77 Median:2299.96 stddev:101093.77 (4395.5%) stddev-sans-first:101343.94 10:29:53 INFO - PROCESS | 1944 | Values: 361.1 379.2 381.1 388.2 411.2 15325.9 15647.0 20676.8 21155.0 21189.0 20497.3 21015.3 21235.0 21516.0 21919.9 5437.9 5841.2 5999.9 6069.4 6171.4 4180.2 6028.2 6121.3 6123.1 6477.6 6698.3 6752.7 6783.6 6784.2 6812.1 5879.0 6221.1 6262.0 6266.3 6737.0 5832.7 6017.8 6107.7 6127.2 6138.6 13633.9 13646.5 13889.2 13897.1 13899.9 2232.7 2273.1 2326.8 2467.6 2690.4 9820.3 9988.8 10212.0 10224.7 10225.9 1781.7 1821.3 1867.5 1913.8 1920.1 94113.0 111684.0 126668.4 130420.0 130645.0 11091.5 11291.2 11425.0 11468.8 11521.6 14432.8 14454.9 14479.6 14481.3 14523.6 8415.3 8568.7 8601.7 8662.8 8673.4 8658.7 8676.0 8679.3 8816.9 8850.2 3844.4 4425.8 4496.6 4498.8 4572.5 1862.9 1921.2 1938.7 1939.7 1985.7 643.4 747.6 865.4 879.6 896.5 21596.5 21781.9 21796.2 21829.8 21992.6 840.5 908.6 927.7 944.5 1037.8 9129.7 10806.6 10932.8 11311.3 11328.3 575803.0 603772.0 613618.0 614589.0 614804.0 105531.0 138372.0 141898.0 142588.6 143273.0 961.1 978.3 1005.4 1006.1 1006.2 15386.2 19550.5 19635.1 20434.8 20657.8 41104.0 55403.4 56192.2 57293.1 57337.7 48924.0 75814.2 77289.0 77515.2 77726.0 5561.0 5876.4 8154.4 8421.0 8675.7 11336.4 12612.7 12729.6 13376.2 13866.6 11559.5 11769.8 11852.6 11941.5 12002.0 11318.2 11504.7 11605.7 11771.3 11833.7 10453.4 10711.3 10828.6 12021.4 12037.8 7036.3 8149.9 8273.6 8380.0 8461.5 8484.1 10845.3 11370.0 11637.8 11740.0 10:29:53 INFO - PROCESS | 1944 | 10:29:53 INFO - PROCESS | 1944 | [#3] mootools.html Cycles:180 Average:5582.23 Median:4940.31 stddev:3125.07 (63.3%) stddev-sans-first:3118.88 10:29:53 INFO - PROCESS | 1944 | Values: 1514.6 1539.8 1740.5 1852.6 1900.7 8144.0 8720.5 8728.9 8731.3 8748.0 7665.4 7869.1 8296.8 8507.9 8535.8 2275.7 2310.7 2314.4 2319.3 2321.9 2341.6 2352.5 2395.4 2397.3 2397.7 2347.8 2372.8 2383.3 2383.6 2384.6 2336.1 2358.4 2360.0 2368.3 2369.4 1935.6 1956.7 2349.0 2356.9 2363.8 8528.2 8610.7 8641.9 8652.9 8677.3 5135.8 5305.4 5310.0 5322.6 5345.2 3433.9 3502.0 3649.6 3650.1 3652.3 1057.1 1091.0 1095.3 1111.9 1114.6 11315.7 11421.7 11434.7 11437.5 11468.6 4481.4 4556.0 4752.4 4761.5 4776.5 9685.7 9688.4 9702.9 9707.5 9714.3 5280.9 5288.8 5289.3 5293.9 5304.8 4030.1 4297.9 4449.5 4459.3 4855.5 3054.6 3694.9 3789.8 3806.7 4044.3 1064.7 1278.0 1301.7 1308.0 1308.7 897.4 917.5 926.9 931.4 945.0 5607.4 5919.4 5981.0 6034.2 6107.3 421.2 431.2 439.2 441.8 444.8 3934.4 3950.2 4047.8 4052.6 4111.8 9970.5 10432.9 10434.6 10442.6 10448.7 10416.5 10418.3 10419.5 10422.2 10424.8 7109.6 7941.1 7995.1 8017.5 8159.8 5039.8 5655.9 5764.9 5803.4 6015.2 8483.7 9470.5 9644.9 9647.1 9787.3 9534.8 9814.8 9914.5 10180.2 10186.2 7368.9 7542.7 7554.7 7555.7 7557.9 7919.7 8141.5 8227.3 8323.2 8325.9 7289.2 7314.5 7365.7 7397.5 7508.4 7255.0 7279.6 7280.6 7313.8 7353.3 6894.9 6908.3 6917.1 6930.3 6999.4 4279.5 4887.0 4921.8 4958.9 5040.3 4199.8 4879.2 4979.5 4984.9 5063.2 10:29:53 INFO - PROCESS | 1944 | 10:29:53 INFO - PROCESS | 1944 | [#4] prototype.html Cycles:180 Average:4431.77 Median:5058.33 stddev:1368.15 (27.0%) stddev-sans-first:1336.39 10:29:53 INFO - PROCESS | 1944 | Values: 300.5 311.5 312.7 318.3 321.1 5270.6 5294.2 5304.3 5324.7 5329.7 4714.8 5225.7 5254.8 5289.8 5323.5 3120.0 3761.3 3816.4 3931.2 3949.3 3286.5 3612.9 3990.2 4033.7 4041.7 3611.8 3824.6 3958.8 3983.8 4031.4 3694.4 3760.8 3798.5 3934.5 4021.7 3260.7 3481.7 3498.4 3523.5 3525.3 4989.0 5057.3 5059.4 5082.2 5122.3 3737.6 4439.6 4562.8 4610.4 4630.5 4201.5 5133.0 5468.1 5471.6 5494.3 3043.4 3540.0 3849.7 3863.2 3896.5 4902.8 5196.4 5853.0 5911.8 5952.3 3389.6 3900.4 3904.0 3926.9 3932.5 3341.4 3818.0 3979.5 3998.7 4000.0 3794.3 4603.4 4605.1 4605.7 4619.3 5195.0 5511.4 5566.3 5618.0 5620.4 1228.6 1319.9 1324.0 1334.4 1339.5 722.9 805.5 823.5 827.8 829.3 4047.3 4305.5 4400.8 4603.0 4887.4 5078.7 5415.3 5603.3 5611.7 5700.9 4753.7 5068.0 5079.4 5095.6 5191.7 2726.9 2812.4 2960.9 2967.4 2983.0 4850.4 5367.6 5419.9 5844.9 5900.9 5121.5 5238.4 5828.0 5829.8 5936.8 4395.7 5140.6 5216.9 5316.3 5922.6 4466.8 5369.1 5726.1 5842.3 5863.7 4998.0 5059.8 5264.7 5639.0 5700.8 4994.2 5651.7 5945.8 5980.6 5997.9 3788.6 3839.2 3883.9 3887.8 3887.8 5293.1 5375.3 5561.7 5621.6 5836.0 5524.8 5526.9 5527.9 5529.9 5537.8 4572.4 5489.7 5540.0 5599.3 5603.0 4996.8 5157.4 5426.5 5440.1 5498.6 4607.0 4835.6 5303.7 5311.2 5436.1 4879.5 5209.7 5364.9 5386.5 5412.3 10:29:53 INFO - PROCESS | 1944 | 10:29:53 INFO - PROCESS | 1944 | [#5] yui.html Cycles:180 Average:23670.51 Median:4297.13 stddev:37262.03 (867.1%) stddev-sans-first:37326.75 10:29:53 INFO - PROCESS | 1944 | Values: 729.2 740.3 743.8 783.9 825.9 15698.8 16592.2 16607.4 16625.9 16627.9 14117.9 14921.6 15095.4 15183.8 15243.0 877.8 881.7 896.2 897.7 899.5 876.6 876.9 878.7 879.5 879.8 933.2 933.2 933.5 934.0 934.1 925.9 926.8 928.0 928.3 929.6 573.5 690.4 691.6 692.4 692.6 13269.9 13303.6 13305.5 13333.6 13368.7 12183.9 12190.6 12293.8 12332.3 12543.5 10936.9 11201.9 11255.2 11276.4 11748.6 3277.0 3760.4 3764.0 3799.2 3834.1 94121.0 121707.0 122315.0 123260.2 124859.1 8666.0 8844.7 8939.5 9022.7 9225.7 18928.2 18989.5 19149.2 19206.0 19380.6 8209.9 8230.0 8251.8 8308.7 8453.7 4221.1 4277.9 4316.4 4359.8 4494.1 3603.7 3704.7 3734.8 3750.0 3758.2 625.1 634.4 634.9 639.7 650.9 563.8 635.5 638.6 639.9 648.3 8897.5 8934.9 9190.5 9335.0 9465.8 639.5 662.4 676.7 713.5 713.6 4467.9 4670.2 4774.5 4784.6 4867.5 96260.0 118670.0 152495.0 153110.5 154644.0 67508.0 86779.1 86871.1 133556.3 134781.0 88056.0 98413.5 100839.0 107817.0 109237.4 9583.2 9774.5 9787.7 9855.9 10106.8 60200.2 62398.4 63860.0 64896.4 67377.7 67599.7 96136.6 98578.5 99250.5 102859.5 13907.0 13925.8 14377.4 14604.3 14886.8 14906.3 15010.2 15060.2 15101.5 15142.5 13856.0 14189.2 14202.0 14238.5 14704.8 11670.9 13340.8 14003.8 14120.9 14455.3 13643.9 13649.3 13724.2 13808.7 13853.4 9653.9 11202.3 11289.2 11898.2 11902.9 10062.0 11481.5 11592.1 11759.7 11760.8 10:29:53 INFO - PROCESS | 1944 | -------- Summary: end -------- 10:29:53 INFO - PROCESS | 1944 | 10:29:54 INFO - TEST-INFO | 1944: exit 0 10:29:54 INFO - TEST-OK | dromaeo_css | took 1117051ms 10:29:54 INFO - TEST-START | kraken 10:29:54 INFO - operating with platform_type : mac_ 10:29:54 INFO - Initialising browser for kraken test... 10:29:54 INFO - TEST-INFO | started process 1947 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/tmpRbXTlJ/profile http://localhost:54350/getInfo.html) 10:29:56 INFO - PROCESS | 1947 | __metrics Screen width/height:1600/1200 10:29:56 INFO - PROCESS | 1947 | colorDepth:24 10:29:56 INFO - PROCESS | 1947 | Browser inner width/height: 1024/689 10:29:56 INFO - PROCESS | 1947 | __metrics 10:29:56 INFO - PROCESS | 1947 | 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" 10:29:56 INFO - PROCESS | 1947 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:29:57 INFO - TEST-INFO | 1947: exit 0 10:29:57 INFO - Browser initialized. 10:29:57 INFO - Running cycle 1/1 for kraken test... 10:29:57 INFO - TEST-INFO | started process 1948 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/tmpRbXTlJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:30:09 INFO - PROCESS | 1948 | RSS: Main: 243146752 10:30:09 INFO - PROCESS | 1948 | 10:30:12 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:54350/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54350/tests/kraken/driver.html?testName=audio-beat-detection) 10:30:13 INFO - PROCESS | 1948 | RSS: Main: 294076416 10:30:13 INFO - PROCESS | 1948 | 10:30:15 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:54350/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54350/tests/kraken/driver.html?testName=audio-dft) 10:30:16 INFO - PROCESS | 1948 | RSS: Main: 302952448 10:30:16 INFO - PROCESS | 1948 | 10:30:19 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:54350/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54350/tests/kraken/driver.html?testName=audio-fft) 10:30:20 INFO - PROCESS | 1948 | RSS: Main: 398249984 10:30:20 INFO - PROCESS | 1948 | 10:30:22 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:54350/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54350/tests/kraken/driver.html?testName=audio-oscillator) 10:30:23 INFO - PROCESS | 1948 | RSS: Main: 302239744 10:30:23 INFO - PROCESS | 1948 | 10:30:25 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:54350/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54350/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:30:25 INFO - PROCESS | 1948 | RSS: Main: 306536448 10:30:25 INFO - PROCESS | 1948 | 10:30:28 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:54350/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54350/tests/kraken/driver.html?testName=imaging-darkroom) 10:30:29 INFO - PROCESS | 1948 | RSS: Main: 344055808 10:30:29 INFO - PROCESS | 1948 | 10:30:32 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:54350/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54350/tests/kraken/driver.html?testName=imaging-desaturate) 10:30:32 INFO - PROCESS | 1948 | RSS: Main: 345743360 10:30:32 INFO - PROCESS | 1948 | 10:30:35 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:54350/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54350/tests/kraken/driver.html?testName=json-parse-financial) 10:30:36 INFO - PROCESS | 1948 | RSS: Main: 343212032 10:30:36 INFO - PROCESS | 1948 | 10:30:37 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:54350/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54350/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:30:38 INFO - PROCESS | 1948 | RSS: Main: 314789888 10:30:38 INFO - PROCESS | 1948 | 10:30:40 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:54350/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54350/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:30:41 INFO - PROCESS | 1948 | RSS: Main: 321474560 10:30:41 INFO - PROCESS | 1948 | 10:30:43 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:54350/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54350/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:30:43 INFO - PROCESS | 1948 | RSS: Main: 317054976 10:30:43 INFO - PROCESS | 1948 | 10:30:46 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:54350/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54350/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:30:47 INFO - PROCESS | 1948 | RSS: Main: 324833280 10:30:47 INFO - PROCESS | 1948 | 10:30:50 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:54350/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54350/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:30:51 INFO - PROCESS | 1948 | RSS: Main: 327368704 10:30:51 INFO - PROCESS | 1948 | 10:30:52 INFO - PROCESS | 1948 | Cycle 1(1): loaded http://localhost:54350/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54350/tests/kraken/driver.html?testName=ai-astar) 10:30:52 INFO - PROCESS | 1948 | RSS: Main: 371023872 10:30:52 INFO - PROCESS | 1948 | 10:30:52 INFO - PROCESS | 1948 | __start_tp_report 10:30:52 INFO - PROCESS | 1948 | _x_x_mozilla_page_load 10:30:52 INFO - PROCESS | 1948 | _x_x_mozilla_page_load_details 10:30:52 INFO - PROCESS | 1948 | |i|pagename|runs| 10:30:52 INFO - PROCESS | 1948 | |0;ai-astar;102;114;113;113;114;121;165;156;112;113 10:30:53 INFO - PROCESS | 1948 | |1;audio-beat-detection;149;114;109;164;110;106;130;107;143;107 10:30:53 INFO - PROCESS | 1948 | |2;audio-dft;186;198;262;195;184;186;185;185;181;184 10:30:53 INFO - PROCESS | 1948 | |3;audio-fft;77;70;83;131;81;61;63;57;98;56 10:30:53 INFO - PROCESS | 1948 | |4;audio-oscillator;84;68;102;70;70;69;96;70;67;100 10:30:53 INFO - PROCESS | 1948 | |5;imaging-gaussian-blur;87;85;88;85;88;84;88;86;88;85 10:30:53 INFO - PROCESS | 1948 | |6;imaging-darkroom;95;94;95;95;95;93;95;95;94;94 10:30:53 INFO - PROCESS | 1948 | |7;imaging-desaturate;83;84;84;83;84;101;136;144;84;84 10:30:53 INFO - PROCESS | 1948 | |8;json-parse-financial;55;54;54;52;54;53;54;53;52;52 10:30:53 INFO - PROCESS | 1948 | |9;json-stringify-tinderbox;67;73;73;74;73;71;70;70;71;69 10:30:53 INFO - PROCESS | 1948 | |10;stanford-crypto-aes;83;86;83;84;83;82;81;80;82;83 10:30:53 INFO - PROCESS | 1948 | |11;stanford-crypto-ccm;137;139;138;139;140;143;145;149;234;138 10:30:53 INFO - PROCESS | 1948 | |12;stanford-crypto-pbkdf2;165;166;164;166;165;165;167;162;165;164 10:30:53 INFO - PROCESS | 1948 | |13;stanford-crypto-sha256-iterative;66;67;68;66;67;65;65;66;67;69 10:30:53 INFO - PROCESS | 1948 | __end_tp_report 10:30:53 INFO - PROCESS | 1948 | __start_cc_report 10:30:53 INFO - PROCESS | 1948 | _x_x_mozilla_cycle_collect,2071 10:30:53 INFO - PROCESS | 1948 | __end_cc_report 10:30:53 INFO - PROCESS | 1948 | __startTimestamp1470159052998__endTimestamp 10:30:53 INFO - PROCESS | 1948 | 10:30:53 INFO - PROCESS | 1948 | ------- Summary: start ------- 10:30:53 INFO - PROCESS | 1948 | Number of tests: 14 10:30:53 INFO - PROCESS | 1948 | 10:30:53 INFO - PROCESS | 1948 | [#0] ai-astar Cycles:10 Average:122.30 Median:114.00 stddev:20.75 (18.2%) stddev-sans-first:20.67 10:30:53 INFO - PROCESS | 1948 | Values: 102.0 114.0 113.0 113.0 114.0 121.0 165.0 156.0 112.0 113.0 10:30:53 INFO - PROCESS | 1948 | 10:30:53 INFO - PROCESS | 1948 | [#1] audio-beat-detection Cycles:10 Average:123.90 Median:122.00 stddev:21.20 (17.4%) stddev-sans-first:20.45 10:30:53 INFO - PROCESS | 1948 | Values: 149.0 114.0 109.0 164.0 110.0 106.0 130.0 107.0 143.0 107.0 10:30:53 INFO - PROCESS | 1948 | 10:30:53 INFO - PROCESS | 1948 | [#2] audio-dft Cycles:10 Average:194.60 Median:186.00 stddev:24.26 (13.0%) stddev-sans-first:25.53 10:30:53 INFO - PROCESS | 1948 | Values: 186.0 198.0 262.0 195.0 184.0 186.0 185.0 185.0 181.0 184.0 10:30:53 INFO - PROCESS | 1948 | 10:30:53 INFO - PROCESS | 1948 | [#3] audio-fft Cycles:10 Average:77.70 Median:73.50 stddev:22.96 (31.2%) stddev-sans-first:24.36 10:30:53 INFO - PROCESS | 1948 | Values: 77.0 70.0 83.0 131.0 81.0 61.0 63.0 57.0 98.0 56.0 10:30:53 INFO - PROCESS | 1948 | 10:30:53 INFO - PROCESS | 1948 | [#4] audio-oscillator Cycles:10 Average:79.60 Median:70.00 stddev:14.49 (20.7%) stddev-sans-first:15.28 10:30:53 INFO - PROCESS | 1948 | Values: 84.0 68.0 102.0 70.0 70.0 69.0 96.0 70.0 67.0 100.0 10:30:53 INFO - PROCESS | 1948 | 10:30:53 INFO - PROCESS | 1948 | [#5] imaging-gaussian-blur Cycles:10 Average:86.40 Median:87.50 stddev:1.58 (1.8%) stddev-sans-first:1.66 10:30:53 INFO - PROCESS | 1948 | Values: 87.0 85.0 88.0 85.0 88.0 84.0 88.0 86.0 88.0 85.0 10:30:53 INFO - PROCESS | 1948 | 10:30:53 INFO - PROCESS | 1948 | [#6] imaging-darkroom Cycles:10 Average:94.50 Median:95.00 stddev:0.71 (0.7%) stddev-sans-first:0.73 10:30:53 INFO - PROCESS | 1948 | Values: 95.0 94.0 95.0 95.0 95.0 93.0 95.0 95.0 94.0 94.0 10:30:53 INFO - PROCESS | 1948 | 10:30:53 INFO - PROCESS | 1948 | [#7] imaging-desaturate Cycles:10 Average:96.70 Median:84.00 stddev:23.53 (28.0%) stddev-sans-first:24.43 10:30:53 INFO - PROCESS | 1948 | Values: 83.0 84.0 84.0 83.0 84.0 101.0 136.0 144.0 84.0 84.0 10:30:53 INFO - PROCESS | 1948 | 10:30:53 INFO - PROCESS | 1948 | [#8] json-parse-financial Cycles:10 Average:53.30 Median:54.00 stddev:1.06 (2.0%) stddev-sans-first:0.93 10:30:53 INFO - PROCESS | 1948 | Values: 55.0 54.0 54.0 52.0 54.0 53.0 54.0 53.0 52.0 52.0 10:30:53 INFO - PROCESS | 1948 | 10:30:53 INFO - PROCESS | 1948 | [#9] json-stringify-tinderbox Cycles:10 Average:71.10 Median:72.00 stddev:2.18 (3.0%) stddev-sans-first:1.74 10:30:53 INFO - PROCESS | 1948 | Values: 67.0 73.0 73.0 74.0 73.0 71.0 70.0 70.0 71.0 69.0 10:30:53 INFO - PROCESS | 1948 | 10:30:53 INFO - PROCESS | 1948 | [#10] stanford-crypto-aes Cycles:10 Average:82.70 Median:83.00 stddev:1.64 (2.0%) stddev-sans-first:1.73 10:30:53 INFO - PROCESS | 1948 | Values: 83.0 86.0 83.0 84.0 83.0 82.0 81.0 80.0 82.0 83.0 10:30:53 INFO - PROCESS | 1948 | 10:30:53 INFO - PROCESS | 1948 | [#11] stanford-crypto-ccm Cycles:10 Average:150.20 Median:141.50 stddev:29.68 (21.0%) stddev-sans-first:31.10 10:30:53 INFO - PROCESS | 1948 | Values: 137.0 139.0 138.0 139.0 140.0 143.0 145.0 149.0 234.0 138.0 10:30:53 INFO - PROCESS | 1948 | 10:30:53 INFO - PROCESS | 1948 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.90 Median:165.00 stddev:1.37 (0.8%) stddev-sans-first:1.45 10:30:53 INFO - PROCESS | 1948 | Values: 165.0 166.0 164.0 166.0 165.0 165.0 167.0 162.0 165.0 164.0 10:30:53 INFO - PROCESS | 1948 | 10:30:53 INFO - PROCESS | 1948 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:67.00 stddev:1.26 (1.9%) stddev-sans-first:1.32 10:30:53 INFO - PROCESS | 1948 | Values: 66.0 67.0 68.0 66.0 67.0 65.0 65.0 66.0 67.0 69.0 10:30:53 INFO - PROCESS | 1948 | -------- Summary: end -------- 10:30:53 INFO - PROCESS | 1948 | 10:30:53 INFO - TEST-INFO | 1948: exit 0 10:30:53 INFO - TEST-OK | kraken | took 59497ms 10:30:53 INFO - SUITE-END | took 1176s 10:30:54 INFO - Completed test suite (00:19:36) 10:30:54 INFO - javascript benchmark 10:30:54 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3317.071220058384, 3321.983390083051, 3325.6508066653, 3337.36590047891, 3380.2056516718567, 5533.142362933741, 5571.498565129137, 5650.8587285317835, 5676.375598684152, 5683.374828768838, 5619.4942455179025, 5653.689047102408, 5704.629199102066, 5711.7715291388295, 5735.1970724098655, 5066.822661206858, 5096.490350964911, 5162.638615296931, 5186.7147306898105, 5245.659032162916, 5231.398389185235, 5241.213817927303, 5247.553957913563, 5262.973685131569, 5279.524842764151, 5081.949180508184, 5132.692038477703, 5136.152534831748, 5159.32928719266, 5256.763445644936, 5179.326687530597, 5184.766685499161, 5186.611004174702, 5190.584753219771, 5200.583953283766, 5099.0, 5137.357830271216, 5156.871078223075, 5168.992046550924, 5175.042617115846, 5677.886442271131, 5679.716014199274, 5684.658920464744, 5684.715764211773, 5710.600257981943, 6089.056196289538, 6103.847403814896, 6128.816135515942, 6148.323684394713, 6167.599106058092, 6214.533909956771, 6219.595726277777, 6222.097795819626, 6237.189165408513, 6239.188905442309, 5805.274340707411, 5858.824235272906, 5863.237779088734, 5865.384134665798, 5867.325257595388, 6185.195924529782, 6228.501719862491, 6250.624962502265, 6254.718537665816, 6290.087937249074, 6143.815685529441, 6144.0, 6149.985252433298, 6159.5688301818445, 6161.921663708833, 6067.886465396542, 6260.154879091265, 6261.530385221126, 6263.874722505524, 6268.028455589391, 6067.605605635619, 6121.84695382619, 6166.8899598071885, 6173.197484327009, 6176.073588961692, 6166.383361663798, 6170.876582468325, 6173.0, 6194.659293738888, 6196.256449226122, 4949.183384741549, 4950.70295782247, 4960.975195124073, 4964.379452568429, 4982.501749825061, 4321.157374312042, 4446.510883802779, 4464.665150113755, 4532.229520981441, 4549.6360291176625, 5608.607397482218, 5645.745941432645, 5662.150677398423, 5717.0, 5734.139879018181, 6247.625142491465, 6258.749650013949, 6259.968700156561, 6260.186175797117, 6273.059041143865, 5555.944440555623, 5647.7176141193595, 5662.156028510253, 5671.489565939085, 5697.9430205698245, 5300.09898317287, 5351.411344752074, 5400.432954539717, 5406.567474602101, 5408.378036525771, 6265.27949285838, 6280.623162610258, 6288.622682639057, 6293.992961126198, 6300.338464461349, 6138.140660307471, 6242.406971337716, 6252.3122456530655, 6254.781082662085, 6256.687165641791, 6176.9073463897175, 6197.411245931629, 6205.348438414082, 6303.747850085945, 6307.085472606354, 6146.279041868284, 6163.7842675506145, 6172.290186628596, 6173.444390004921, 6194.876102478015, 5014.924776128434, 6248.4376406123665, 6253.937460625336, 6254.249490061222, 6278.811635650938, 5896.14505896634, 5971.552133589911, 6018.4884284834525, 6107.114468401968, 6116.755329786936, 6007.789727359522, 6025.78909738157, 6067.969660151847, 6092.329843717276, 6129.111278864629, 5901.557383196363, 5909.084091965752, 5912.881742365043, 5916.023856063702, 5970.432808883149, 5841.386654401226, 5909.320428150818, 5921.082232254007, 6101.725422355916, 6108.018205539365, 5783.595148339576, 5843.795467158629, 5853.239078919713, 5872.383399742966, 5917.112433135064], "value": 5672.306859836013, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [452.2154062701215, 459.59325996493095, 509.24377299709926, 529.7695502456431, 549.2009126720621, 31007.379852402966, 41293.58706412939, 42046.789766051166, 42091.57908420915, 42182.94542636428, 43447.78276108615, 43781.68654940349, 43904.78047609766, 43912.0, 43921.0, 9627.56675949584, 9787.902120978806, 9800.803983920317, 9814.558344874498, 9830.311878168532, 8676.305895528343, 8853.601587928559, 11701.659668066379, 11917.165798394115, 11942.402879856016, 12000.69998250042, 12051.57819476314, 12073.3963301835, 12081.275123492618, 12089.818652720216, 10426.530806113667, 11893.2269402489, 11926.807319268091, 11952.223105498202, 12002.639920802347, 11479.368634725086, 11627.767444651145, 11632.185746997713, 11715.94142029293, 11722.472488738027, 40299.59700403017, 40545.797271013755, 40574.39138412926, 40590.0, 40706.0, 3750.2124553843723, 3772.8868133956025, 3985.7608543487195, 4227.260229459832, 4432.22436073689, 26777.0, 32582.022539323858, 34395.828020859735, 34413.0, 34425.31149376999, 3192.6488086310487, 3363.041533163061, 3399.6260411354733, 3411.113110591264, 3438.914027149341, 432139.0, 436524.0, 526954.0, 529032.0, 531124.0, 69341.0, 69971.0, 70195.54315598929, 70209.0, 70266.0, 74141.0, 74478.64846081939, 74491.0, 74647.0, 74756.0, 17361.65276694459, 17495.03777292236, 17497.21262543163, 17498.650026999643, 17539.824601754073, 12116.69708257283, 13132.671683207996, 13200.0, 13210.207387556777, 13222.537211197754, 17440.651186976444, 18082.76855459726, 18172.36396726108, 21050.78949210519, 21494.677579836316, 8702.521361325062, 8718.17177368149, 8722.563871806384, 8769.123087691307, 8774.25418839393, 852.9061803201643, 938.887333519982, 945.8675785390051, 971.0338213477494, 1017.7353887989176, 26442.73557264441, 28666.283342916176, 30090.84954575213, 30915.845420772752, 30978.225544361572, 911.284641556375, 1071.6463567022863, 1088.7495875948575, 1114.5430373546808, 1148.7645032768262, 13594.660133496543, 15985.0, 16024.11867347307, 16230.543874683488, 16360.93653912492, 581677.0, 585113.0, 587762.0, 588456.0, 588654.0, 60712.39287607068, 60945.39054609397, 60993.0, 61004.38995610076, 61134.38865611287, 1204.6265657646159, 1284.6659868434274, 1312.8949684025447, 1313.7175507265983, 1426.4650755966513, 22105.889470552545, 25407.618885716358, 27850.303742406602, 31360.372792544476, 31471.370572587963, 49681.0, 50209.497905020486, 50263.49736502588, 50278.245826311904, 50297.0, 577115.0, 581484.0, 581972.0, 582016.0, 582262.0, 27638.894444222005, 27988.860055700407, 28203.858980704965, 28820.991265305613, 43281.56718432775, 48085.0, 48458.27312590244, 48547.75726121488, 48778.75610621924, 48785.0, 36413.817930910176, 36835.07912302214, 40003.0, 40460.1907961845, 41061.38407923824, 39630.40554391629, 39942.400863986484, 40391.1921761569, 41084.58915410808, 41276.1744765109, 39001.0, 39216.803915980236, 39288.2142357157, 39412.0, 39576.80211598924, 26176.8691156543, 27981.0, 35692.92921212368, 35881.0, 36045.45931810972, 22963.196288129835, 25870.48259034771, 26107.477850443265, 26367.353850905252, 26368.270992804475], "value": 20925.457050142635, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [361.08645127826577, 379.18664464723184, 381.10630571310276, 388.1751278533117, 411.1632827196655, 15325.927185097047, 15647.0, 20676.793232067714, 21155.0, 21189.04649290782, 20497.282595109173, 21015.264465743665, 21235.0, 21516.031778569963, 21919.890400548014, 5437.918431223535, 5841.211436456067, 5999.910001349983, 6069.423404776539, 6171.4445699887, 4180.184863951531, 6028.156058151841, 6121.296050954131, 6123.142760013601, 6477.578957367779, 6698.263191048981, 6752.729890804362, 6783.559068660546, 6784.151981002375, 6812.080369150151, 5878.970605146968, 6221.097940798604, 6261.96869015652, 6266.342034086447, 6737.0, 5832.6500409975115, 6017.833579911228, 6107.72515236811, 6127.203463549755, 6138.570300078996, 13633.863661363359, 13646.522371716943, 13889.236092014935, 13897.096688715303, 13899.930500347535, 2232.743234528034, 2273.1134857405623, 2326.802221811131, 2467.629855521668, 2690.4081102157493, 9820.263480238938, 9988.800223995479, 10212.029857163558, 10224.744381390525, 10225.897741022569, 1781.7060185069447, 1821.3261093395529, 1867.5424520992308, 1913.8277555019977, 1920.0975541495613, 94113.0, 111684.0, 126668.36665816612, 130420.0, 130645.0, 11091.500882460308, 11291.209615326849, 11425.028872545761, 11468.827967580326, 11521.596744114084, 14432.783508247385, 14454.85545144556, 14479.565613031626, 14481.348339324755, 14523.636909077357, 8415.326773858076, 8568.67185586235, 8601.741947741577, 8662.826743465095, 8673.436226645248, 8658.696945606873, 8676.045634980148, 8679.26226270774, 8816.8677469838, 8850.247728942983, 3844.4041173618134, 4425.800838962255, 4496.595306422438, 4498.8425405110665, 4572.5198854119835, 1862.8789128706592, 1921.2027008791474, 1938.6898096304806, 1939.7187407825786, 1985.672364059914, 643.3952085040141, 747.5925620536821, 865.4119690368245, 879.5953861223909, 896.4536175098034, 21596.51611098887, 21781.87434409842, 21796.186484759466, 21829.783855665526, 21992.580816110214, 840.5293035899915, 908.6229214875752, 927.6845872403416, 944.4994153098869, 1037.7509397744639, 9129.680461183827, 10806.567737290577, 10932.836007459895, 11311.264767790068, 11328.32030078198, 575803.0, 603772.0, 613618.0, 614589.0, 614804.0, 105531.0, 138372.0, 141898.0, 142588.5741142596, 143273.0, 961.1205746741792, 978.320067553058, 1005.3615953869346, 1006.0643601450721, 1006.2302338710892, 15386.230688465308, 19550.511237219183, 19635.11641976086, 20434.849775996623, 20657.793422065588, 41103.972400689025, 55403.44596553983, 56192.157117644085, 57293.14060289182, 57337.713311433174, 48924.0, 75814.24185758072, 77289.0, 77515.22484775305, 77726.0, 5561.0, 5876.382979787061, 8154.409890071379, 8420.957895210484, 8675.652973880975, 11336.433178340885, 12612.684682883002, 12729.55446559366, 13376.174937661577, 13866.584002479942, 11559.479823407799, 11769.823452648046, 11852.636946751234, 11941.52233910634, 12002.039836813208, 11318.207725459139, 11504.654860354203, 11605.709857253636, 11771.293722376684, 11833.704157396134, 10453.372797632166, 10711.25021248505, 10828.620998265023, 12021.398930053287, 12037.819432708691, 7036.261192574706, 8149.918500814916, 8273.627686753991, 8380.0, 8461.492310461392, 8484.109168537214, 10845.349279043283, 11370.0, 11637.825432618349, 11740.0], "value": 9458.142279694097, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1514.6364872430613, 1539.7613369927662, 1740.4778566430075, 1852.6479968805918, 1900.6578815813157, 8143.959280203606, 8720.52037137957, 8728.869066963985, 8731.257843083338, 8748.0, 7665.386769058476, 7869.095054068784, 8296.751097467071, 8507.872381914276, 8535.78660533487, 2275.670027845957, 2310.722713274409, 2314.4445333120098, 2319.2694301295037, 2321.8955147018423, 2341.6487526870937, 2352.4589344450787, 2395.413123784675, 2397.2568503763887, 2397.6763136976547, 2347.8473899196583, 2372.8220383471307, 2383.261189031397, 2383.6305372667266, 2384.5707772600927, 2336.100601268507, 2358.445765245166, 2359.976400235993, 2368.253999990015, 2369.383960170368, 1935.5741736817877, 1956.7456230690061, 2348.953020939589, 2356.917507887216, 2363.7990770784504, 8528.189821966904, 8610.655573777041, 8641.870371944426, 8652.913470865275, 8677.305815534743, 5135.8202462914, 5305.442928492491, 5309.973450132725, 5322.600804939645, 5345.224942383372, 3433.9141521461916, 3502.036939841564, 3649.627741967716, 3650.0509867434394, 3652.2695460907657, 1057.0909018244304, 1090.983635245472, 1095.3209010413516, 1111.8776934537195, 1114.6043154680135, 11315.660530184108, 11421.714457138554, 11434.656960291204, 11437.485313160845, 11468.598599049077, 4481.395011673382, 4555.9772201138785, 4752.358431611758, 4761.523847615211, 4776.522347765265, 9685.70942871715, 9688.370255933341, 9702.90297097034, 9707.46608936501, 9714.319997600242, 5280.9207861882105, 5288.8413347599635, 5289.259503669489, 5293.894122117536, 5304.761285742151, 4030.118527284177, 4297.871063868089, 4449.532799056117, 4459.331100334975, 4855.514448555116, 3054.5570892220503, 3694.8706795262574, 3789.77261364319, 3806.7335286530233, 4044.2518134145275, 1064.6539874540806, 1278.0478543485162, 1301.7331447053332, 1307.9542216022394, 1308.672831792052, 897.4480694372913, 917.4862077236769, 926.9258459323238, 931.3852857114272, 944.9716508504756, 5607.43925607436, 5919.389172604708, 5981.0, 6034.155218269446, 6107.328193898667, 421.15978622648015, 431.2151883571869, 439.2444994609267, 441.8431456832778, 444.7820567921695, 3934.390169523728, 3950.1876662275017, 4047.7976101195413, 4052.63526282636, 4111.753294802322, 9970.501474926225, 10432.895671043345, 10434.634787782392, 10442.63450779219, 10448.68653940383, 10416.479176041319, 10418.322809017389, 10419.479026048668, 10422.166226701846, 10424.843627345595, 7109.573425594481, 7941.086775020831, 7995.080565734898, 8017.51675939953, 8159.75520734367, 5039.815643323743, 5655.88688226225, 5764.942350576441, 5803.448672376117, 6015.187949626745, 8483.703070392507, 9470.479123648265, 9644.903550964682, 9647.1317581418, 9787.265955053543, 9534.761630959281, 9814.803703926023, 9914.504274786375, 10180.185585153318, 10186.236032297755, 7368.889466657897, 7542.698292068475, 7554.697812087455, 7555.73554925575, 7557.886631700419, 7919.683212671659, 8141.470804397932, 8227.259546640831, 8323.209295116954, 8325.916740832514, 7289.234630363735, 7314.524555903849, 7365.668544915598, 7397.519161254501, 7508.361789247975, 7255.020572222682, 7279.59962202084, 7280.635968201675, 7313.780586582198, 7353.338199562065, 6894.896576551456, 6908.3091690830515, 6917.100776898971, 6930.306969303231, 6999.440044796303, 4279.507856596531, 4886.951130488792, 4921.753912304442, 4958.876028099182, 5040.344755181803, 4199.832006719819, 4879.219324907997, 4979.526944940225, 4984.900301994012, 5063.1645778446145], "value": 4428.626600646724, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.5041681225978, 311.5155932524923, 312.7013701914671, 318.27115904578517, 321.10411950657664, 5270.578353731702, 5294.226434144556, 5304.257403963444, 5324.707141107242, 5329.681588509001, 4714.787834547445, 5225.738713064351, 5254.7635356408955, 5289.841304760864, 5323.520883120518, 3119.953200701991, 3761.3229618668697, 3816.3971132124575, 3931.2334094851517, 3949.328614135604, 3286.474164133739, 3612.945805812915, 3990.2019596080754, 4033.6571391431617, 4041.6564592009568, 3611.819409029538, 3824.5984171661994, 3958.802059897008, 3983.800809959505, 4031.3751368537924, 3694.3534881395917, 3760.7845292274983, 3798.4682144499793, 3934.547527034399, 4021.6983726220506, 3260.722838558702, 3481.651834816523, 3498.4402495600834, 3523.524324216249, 3525.2596954639557, 4988.975055124701, 5057.291979122926, 5059.367579052619, 5082.186850103965, 5122.282880396748, 3737.570179429346, 4439.622632076277, 4562.760440087542, 4610.400647915783, 4630.467496237909, 4201.516825565069, 5132.974335128338, 5468.058839112137, 5471.5896307777075, 5494.313210848644, 3043.4217498675403, 3540.0087975366937, 3849.749766265184, 3863.2273545291055, 3896.4544963705102, 4902.803887844518, 5196.402413722432, 5853.0, 5911.793087241969, 5952.255968003999, 3389.5593572835865, 3900.3759398496104, 3903.9609603904164, 3926.9173698646428, 3932.5477570079806, 3341.4486609709124, 3817.961820381761, 3979.5423526294267, 3998.7000974926996, 3999.9800000999808, 3794.317022935898, 4603.355530225771, 4605.148047611203, 4605.746683932349, 4619.284010978304, 5194.97402512985, 5511.3937466878615, 5566.304211973504, 5617.9719101404235, 5620.381758006616, 1228.6129869091387, 1319.9142055766345, 1324.0202250334876, 1334.3928512526777, 1339.5378594384922, 722.9096362954631, 805.476440313789, 823.5264722784423, 827.8385714785559, 829.2702421868717, 4047.291723948297, 4305.526392096866, 4400.779961001937, 4603.0, 4887.437944636341, 5078.669886457368, 5415.296011518478, 5603.327600687943, 5611.719414029364, 5700.857478563069, 4753.691010084333, 5068.0, 5079.3650793650795, 5095.643304968617, 5191.740412979336, 2726.8772905218916, 2812.395335002984, 2960.8667609957174, 2967.3916847046303, 2983.030515082607, 4850.417949846041, 5367.624266301399, 5419.945800542023, 5844.912326315109, 5900.881982360329, 5121.4622464641425, 5238.423773384925, 5828.0, 5829.813819817568, 5936.762529498772, 4395.736255824597, 5140.612034750521, 5216.852292495645, 5316.308879845596, 5922.644641321534, 4466.838621958249, 5369.140937449989, 5726.141078838041, 5842.328132264844, 5863.706814659336, 4998.001998001998, 5059.760358712139, 5264.684118952875, 5639.0, 5700.771969121189, 4994.150994331045, 5651.689157096399, 5945.762169513344, 5980.611260268069, 5997.91003134962, 3788.5643151037984, 3839.155385815116, 3883.902902427462, 3887.7667339959694, 3887.8056097195595, 5293.12663410533, 5375.3280839895015, 5561.721913904208, 5621.606487545832, 5835.970820146042, 5524.779008839601, 5526.92225016134, 5527.9220551993585, 5529.861753456195, 5537.809370985257, 4572.376806233872, 5489.670619762827, 5540.0, 5599.32808063035, 5602.993859390555, 4996.752111127796, 5157.432682404858, 5426.4573542646995, 5440.1023831069015, 5498.642588231752, 4606.976965115153, 4835.564799167951, 5303.655262407931, 5311.150215965427, 5436.130219164915, 4879.536444037811, 5209.6874187548865, 5364.865878353073, 5386.542143917654, 5412.269343638716], "value": 3996.7555779273666, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [729.1942403643973, 740.3189066059228, 743.8437928035119, 783.9216078392161, 825.9345444376754, 15698.764518532216, 16592.17039148044, 16607.418740344092, 16625.9193152445, 16627.91686041568, 14117.92941035296, 14921.552353429415, 15095.396184152649, 15183.772243416357, 15243.0, 877.806882485852, 881.6693739847557, 896.1844721303615, 897.6813231302891, 899.4558292233203, 876.5660997806095, 876.8597024476085, 878.6704985630389, 879.5338470610586, 879.7668617816283, 933.1601558597249, 933.169479163545, 933.5192375926404, 934.042606328512, 934.103260869566, 925.8935222449437, 926.8146370725882, 928.0441145620022, 928.3130483442238, 929.5677509957834, 573.5354362966011, 690.3579670906055, 691.5815931361504, 692.4495026453981, 692.6363659078974, 13269.933650331783, 13303.60089197316, 13305.53430629933, 13333.599992000158, 13368.732625347438, 12183.939080304543, 12190.634280971584, 12293.754124917452, 12332.321722305269, 12543.498260069586, 10936.945315273453, 11201.887981120248, 11255.155863310203, 11276.43617819114, 11748.588799392066, 3276.950845737316, 3760.360738674432, 3763.981180094082, 3799.2401519695895, 3834.118152824866, 94121.0, 121707.0, 122315.0, 123260.1510977336, 124859.12711309337, 8666.0, 8844.690435834844, 8939.508327041945, 9022.729318120468, 9225.72322830316, 18928.24287028531, 18989.525261868286, 19149.234030638618, 19206.0, 19380.612387752168, 8209.917900820916, 8230.0, 8251.793705157299, 8308.6676532938, 8453.746387608382, 4221.134667393177, 4277.893052673646, 4316.374125751749, 4359.760213188305, 4494.07871386365, 3603.7117030637487, 3704.68510176638, 3734.7945862977253, 3750.0, 3758.229562939591, 625.0873724362391, 634.403660559073, 634.9301576826546, 639.7313128486047, 650.8860949333942, 563.844942640777, 635.5101209951677, 638.5976834594269, 639.8656282180795, 648.3451713769127, 8897.510636914902, 8934.86597701035, 9190.494522801177, 9334.966136005825, 9465.810683786423, 639.5011890725242, 662.3806740697368, 676.7318994004455, 713.4791602130474, 713.6360456167392, 4467.910641787211, 4670.224838816752, 4774.546418090275, 4784.569388755029, 4867.464578896319, 96260.0, 118670.0, 152495.0, 153110.46889531185, 154644.0, 67508.0, 86779.13220867836, 86871.13128868757, 133556.33221834022, 134781.0, 88056.0, 98413.50793246132, 100839.0, 107817.0, 109237.36143957847, 9583.233341332816, 9774.462404567817, 9787.706368808947, 9855.901440985785, 10106.797864042825, 60200.19399418024, 62398.37601624107, 63860.0, 64896.35103648903, 67377.6631116861, 67599.66200168968, 96136.55795163217, 98578.507107464, 99250.5037474808, 102859.48570257101, 13907.0, 13925.79111313311, 14377.353019113956, 14604.342804574033, 14886.77669834975, 14906.254687265377, 15010.249487525798, 15060.171690557125, 15101.47144849925, 15142.54572362831, 13856.030077894853, 14189.219592922489, 14202.0, 14238.50165244253, 14704.77942830837, 11670.88329116698, 13340.799888001493, 14003.789943150658, 14120.858791411954, 14455.349509271897, 13643.931780341034, 13649.317534123453, 13724.176549407068, 13808.723825523633, 13853.376598053314, 9653.903460965581, 11202.271852329572, 11289.20975531705, 11898.167128300936, 11902.880971190178, 10062.0, 11481.483333249857, 11592.1305902056, 11759.706007349885, 11760.82358764602], "value": 7756.712819078362, "unit": "score"}], "name": "dromaeo_css", "value": 7322.46818080785, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 114.0, 113.0, 113.0, 114.0, 121.0, 165.0, 156.0, 112.0, 113.0], "value": 122.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 114.0, 109.0, 164.0, 110.0, 106.0, 130.0, 107.0, 143.0, 107.0], "value": 123.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 198.0, 262.0, 195.0, 184.0, 186.0, 185.0, 185.0, 181.0, 184.0], "value": 194.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 70.0, 83.0, 131.0, 81.0, 61.0, 63.0, 57.0, 98.0, 56.0], "value": 77.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 68.0, 102.0, 70.0, 70.0, 69.0, 96.0, 70.0, 67.0, 100.0], "value": 79.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 88.0, 85.0, 88.0, 84.0, 88.0, 86.0, 88.0, 85.0], "value": 86.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 93.0, 95.0, 95.0, 94.0, 94.0], "value": 94.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 84.0, 83.0, 84.0, 101.0, 136.0, 144.0, 84.0, 84.0], "value": 96.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 54.0, 52.0, 54.0, 53.0, 54.0, 53.0, 52.0, 52.0], "value": 53.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 73.0, 73.0, 74.0, 73.0, 71.0, 70.0, 70.0, 71.0, 69.0], "value": 71.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 83.0, 84.0, 83.0, 82.0, 81.0, 80.0, 82.0, 83.0], "value": 82.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 139.0, 138.0, 139.0, 140.0, 143.0, 145.0, 149.0, 234.0, 138.0], "value": 150.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 166.0, 164.0, 166.0, 165.0, 165.0, 167.0, 162.0, 165.0, 164.0], "value": 164.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 68.0, 66.0, 67.0, 65.0, 65.0, 66.0, 67.0, 69.0], "value": 66.6, "unit": "score"}], "name": "kraken", "value": 1464.5, "alertThreshold": 2.0}]} 10:30:54 INFO - Return code: 0 10:30:54 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:30:54 INFO - # TBPL SUCCESS # 10:30:54 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:30:54 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:30:54 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:30:54 INFO - 2016-08-02 10:30:54.310 screenresolution[1953:67180] starting screenresolution argv=screenresolution get 10:30:54 INFO - 2016-08-02 10:30:54.314 screenresolution[1953:67180] Display 0: 1600x1200x32@60 10:30:54 INFO - 2016-08-02 10:30:54.320 screenresolution[1954:67183] starting screenresolution argv=screenresolution list 10:30:54 INFO - Available Modes on Display 0 10:30:54 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:30:54 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:30:54 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:30:54 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:30:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:30:54 INFO - Intel Iris: 10:30:54 INFO - Chipset Model: Intel Iris 10:30:54 INFO - Type: GPU 10:30:54 INFO - Bus: Built-In 10:30:54 INFO - VRAM (Dynamic, Max): 1536 MB 10:30:54 INFO - Vendor: Intel (0x8086) 10:30:54 INFO - Device ID: 0x0a2e 10:30:54 INFO - Revision ID: 0x0009 10:30:54 INFO - Displays: 10:30:54 INFO - Display: 10:30:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:30:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:30:54 INFO - Display Serial Number: 1600x1200 60 10:30:54 INFO - Main Display: Yes 10:30:54 INFO - Mirror: Off 10:30:54 INFO - Online: Yes 10:30:54 INFO - Rotation: Supported 10:30:54 INFO - Return code: 0 10:30:54 INFO - Running post-action listener: _resource_record_post_action 10:30:54 INFO - [mozharness: 2016-08-02 17:30:54.559817Z] Finished run-tests step (success) 10:30:54 INFO - Running post-run listener: _resource_record_post_run 10:30:54 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 380928; Write bytes: 700290048; Read time: 2; Write time: 1967 10:30:54 INFO - TinderboxPrint: CPU usage
53.3% 10:30:54 INFO - TinderboxPrint: I/O read bytes / time
380,928 / 2 10:30:54 INFO - TinderboxPrint: I/O write bytes / time
700,290,048 / 1,967 10:30:54 INFO - TinderboxPrint: CPU idle
2,261.9 (46.7%) 10:30:54 INFO - TinderboxPrint: CPU system
966.3 (19.9%) 10:30:54 INFO - TinderboxPrint: CPU user
1,617.4 (33.4%) 10:30:54 INFO - TinderboxPrint: Swap in / out
403,337,216 / 0 10:30:54 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 392954368; Write bytes: 387033088; Read time: 29489; Write time: 826 10:30:54 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 380928; Write bytes: 291444224; Read time: 2; Write time: 1078 10:30:55 INFO - Running post-run listener: _upload_blobber_files 10:30:55 INFO - Blob upload gear active. 10:30:55 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:30:55 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 10:30:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:30:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:30:55 INFO - (blobuploader) - INFO - Open directory for files ... 10:30:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:30:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:30:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:30:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:30:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:30:56 INFO - (blobuploader) - INFO - Done attempting. 10:30:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:30:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:30:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:30:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:30:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:30:57 INFO - (blobuploader) - INFO - Done attempting. 10:30:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:30:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:30:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:30:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:30:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:30:59 INFO - (blobuploader) - INFO - Done attempting. 10:30:59 INFO - (blobuploader) - INFO - Iteration through files over. 10:30:59 INFO - Return code: 0 10:30:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:30:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:30:59 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/572923cee5158b4f72121afbedd62f71b133c7e966a72b693531a702510d1f445f820f4263800d29fae168f5cb36b2ce19e3ef134441bc3e5a37c8a816dc3d3d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/879226fd1b33811067f7b75b73a74dbe113fdd917924d5d674e108dc78c470908d356f6dd266f7df11f98ce354d69c382f8e1a4c135f43e36dec29f8a7f0f2dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef1515229316a9ee13532cb763d5e0d268f4dc96cb68e163133a2e02fa5f0d6ef2d9c1a70962b28632e74a0bb157c972f7565cc1f83484d9b3d4ec8c428974df"} 10:30:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:30:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:30:59 INFO - Contents: 10:30:59 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/572923cee5158b4f72121afbedd62f71b133c7e966a72b693531a702510d1f445f820f4263800d29fae168f5cb36b2ce19e3ef134441bc3e5a37c8a816dc3d3d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/879226fd1b33811067f7b75b73a74dbe113fdd917924d5d674e108dc78c470908d356f6dd266f7df11f98ce354d69c382f8e1a4c135f43e36dec29f8a7f0f2dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef1515229316a9ee13532cb763d5e0d268f4dc96cb68e163133a2e02fa5f0d6ef2d9c1a70962b28632e74a0bb157c972f7565cc1f83484d9b3d4ec8c428974df"} 10:30:59 INFO - Running post-run listener: copy_logs_to_upload_dir 10:30:59 INFO - Copying logs to upload dir... 10:30:59 INFO - mkdir: /builds/slave/test/build/upload/logs 10:30:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1265.016547 ========= master_lag: 4.11 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 9 secs) (at 2016-08-02 10:31:03.805662) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-02 10:31:03.809288) ========= 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.YKHvipvj7K/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.9AfHCpyNGC/Listeners TMPDIR=/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/572923cee5158b4f72121afbedd62f71b133c7e966a72b693531a702510d1f445f820f4263800d29fae168f5cb36b2ce19e3ef134441bc3e5a37c8a816dc3d3d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/879226fd1b33811067f7b75b73a74dbe113fdd917924d5d674e108dc78c470908d356f6dd266f7df11f98ce354d69c382f8e1a4c135f43e36dec29f8a7f0f2dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef1515229316a9ee13532cb763d5e0d268f4dc96cb68e163133a2e02fa5f0d6ef2d9c1a70962b28632e74a0bb157c972f7565cc1f83484d9b3d4ec8c428974df"} build_url:https://queue.taskcluster.net/v1/task/aO2sMXSrSZipSz9mCLFKgg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011154 build_url: 'https://queue.taskcluster.net/v1/task/aO2sMXSrSZipSz9mCLFKgg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/572923cee5158b4f72121afbedd62f71b133c7e966a72b693531a702510d1f445f820f4263800d29fae168f5cb36b2ce19e3ef134441bc3e5a37c8a816dc3d3d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/879226fd1b33811067f7b75b73a74dbe113fdd917924d5d674e108dc78c470908d356f6dd266f7df11f98ce354d69c382f8e1a4c135f43e36dec29f8a7f0f2dc", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef1515229316a9ee13532cb763d5e0d268f4dc96cb68e163133a2e02fa5f0d6ef2d9c1a70962b28632e74a0bb157c972f7565cc1f83484d9b3d4ec8c428974df"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-02 10:31:03.843547) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 10:31:03.843837) ========= 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.YKHvipvj7K/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.9AfHCpyNGC/Listeners TMPDIR=/var/folders/lh/qp12vhwd15354byzrjs4wrjw00000w/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.005225 ========= master_lag: 0.06 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-02 10:31:03.905573) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-02 10:31:03.905868) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-02 10:31:03.906233) ========= ========= Total master_lag: 7.36 =========