builder: larch_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0340 starttime: 1464834368.24 results: success (0) buildid: 20160601171646 builduid: 53752fd2ea174b3482276bb52bef4de6 revision: 279597150b922a9b762de1dc88fbf083a31a4f6f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-01 19:26:08.243491) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-01 19:26:08.244282) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-01 19:26:08.244577) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-01 19:26:08.340938) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 19:26:08.341248) ========= 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.T6eEChsRCL/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.gD2XAiyyWv/Listeners TMPDIR=/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-06-01 19:26:08-- 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% 18.5M=0.001s 2016-06-01 19:26:08 (18.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.488578 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 19:26:08.851828) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 19:26:08.852164) ========= 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.T6eEChsRCL/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.gD2XAiyyWv/Listeners TMPDIR=/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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.026186 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 19:26:08.903731) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-01 19:26:08.904136) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 279597150b922a9b762de1dc88fbf083a31a4f6f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev 279597150b922a9b762de1dc88fbf083a31a4f6f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.T6eEChsRCL/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.gD2XAiyyWv/Listeners TMPDIR=/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-06-01 19:26:08,986 truncating revision to first 12 chars 2016-06-01 19:26:08,986 Setting DEBUG logging. 2016-06-01 19:26:08,986 attempt 1/10 2016-06-01 19:26:08,986 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/279597150b92?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-01 19:26:09,957 unpacking tar archive at: larch-279597150b92/testing/mozharness/ program finished with exit code 0 elapsedTime=1.234279 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-01 19:26:10.158101) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-01 19:26:10.158419) ========= script_repo_revision: 279597150b922a9b762de1dc88fbf083a31a4f6f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-01 19:26:10.158928) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-01 19:26:10.159270) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-01 19:26:10.175732) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-06-01 19:26:10.176211) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Larch', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.T6eEChsRCL/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.gD2XAiyyWv/Listeners TMPDIR=/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 19:26:10 INFO - MultiFileLogger online at 20160601 19:26:10 in /builds/slave/test 19:26:10 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 19:26:10 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:26:10 INFO - {'append_to_log': False, 19:26:10 INFO - 'base_work_dir': '/builds/slave/test', 19:26:10 INFO - 'blob_upload_branch': 'Larch', 19:26:10 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:26:10 INFO - 'branch': 'Larch', 19:26:10 INFO - 'buildbot_json_path': 'buildprops.json', 19:26:10 INFO - 'config_files': ('talos/mac_config.py',), 19:26:10 INFO - 'default_actions': ('clobber', 19:26:10 INFO - 'read-buildbot-config', 19:26:10 INFO - 'download-and-extract', 19:26:10 INFO - 'populate-webroot', 19:26:10 INFO - 'create-virtualenv', 19:26:10 INFO - 'install', 19:26:10 INFO - 'run-tests'), 19:26:10 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:26:10 INFO - 'download_minidump_stackwalk': True, 19:26:10 INFO - 'download_symbols': 'ondemand', 19:26:10 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:26:10 INFO - 'tooltool.py': '/tools/tooltool.py', 19:26:10 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:26:10 INFO - '/tools/misc-python/virtualenv.py')}, 19:26:10 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:26:10 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:26:10 INFO - 'installer_path': 'installer.exe', 19:26:10 INFO - 'log_level': 'info', 19:26:10 INFO - 'log_name': 'talos', 19:26:10 INFO - 'log_to_console': True, 19:26:10 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:26:10 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:26:10 INFO - 'opt_config_files': (), 19:26:10 INFO - 'pip_index': False, 19:26:10 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:26:10 INFO - 'cmd': ('bash', 19:26:10 INFO - '-c', 19:26:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:26:10 INFO - 'enabled': True, 19:26:10 INFO - 'halt_on_failure': False, 19:26:10 INFO - 'name': 'check_screen_resolution'},), 19:26:10 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:26:10 INFO - 'cmd': ('bash', 19:26:10 INFO - '-c', 19:26:10 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:26:10 INFO - 'enabled': True, 19:26:10 INFO - 'halt_on_failure': False, 19:26:10 INFO - 'name': 'check_screen_resolution'},), 19:26:10 INFO - 'run_cmd_checks_enabled': True, 19:26:10 INFO - 'sps_profile': False, 19:26:10 INFO - 'sps_profile_interval': 0, 19:26:10 INFO - 'suite': 'dromaeojs-e10s', 19:26:10 INFO - 'system_bits': '32', 19:26:10 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:26:10 INFO - 'title': 't-yosemite-r7-0340', 19:26:10 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:26:10 INFO - 'use_talos_json': True, 19:26:10 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:26:10 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:26:10 INFO - 'work_dir': 'build'} 19:26:10 INFO - ##### 19:26:10 INFO - ##### Running clobber step. 19:26:10 INFO - ##### 19:26:10 INFO - Running pre-action listener: _resource_record_pre_action 19:26:10 INFO - Running main action method: clobber 19:26:10 INFO - rmtree: /builds/slave/test/build 19:26:10 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:26:12 INFO - Running post-action listener: _resource_record_post_action 19:26:12 INFO - ##### 19:26:12 INFO - ##### Finished clobber step (success) 19:26:12 INFO - ##### 19:26:12 INFO - ##### 19:26:12 INFO - ##### Running read-buildbot-config step. 19:26:12 INFO - ##### 19:26:12 INFO - Running pre-action listener: _resource_record_pre_action 19:26:12 INFO - Running main action method: read_buildbot_config 19:26:12 INFO - Using buildbot properties: 19:26:12 INFO - { 19:26:12 INFO - "project": "", 19:26:12 INFO - "product": "firefox", 19:26:12 INFO - "script_repo_revision": "production", 19:26:12 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs-e10s", 19:26:12 INFO - "repository": "", 19:26:12 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs-e10s", 19:26:12 INFO - "buildid": "20160601171646", 19:26:12 INFO - "pgo_build": "False", 19:26:12 INFO - "basedir": "/builds/slave/test", 19:26:12 INFO - "buildnumber": 1, 19:26:12 INFO - "slavename": "t-yosemite-r7-0340", 19:26:12 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 19:26:12 INFO - "platform": "yosemite_r7", 19:26:12 INFO - "scheduler": "tests-larch-macosx64-talos", 19:26:12 INFO - "branch": "larch", 19:26:12 INFO - "repo_path": "projects/larch", 19:26:12 INFO - "revision": "279597150b922a9b762de1dc88fbf083a31a4f6f", 19:26:12 INFO - "stage_platform": "macosx64", 19:26:12 INFO - "builduid": "53752fd2ea174b3482276bb52bef4de6", 19:26:12 INFO - "slavebuilddir": "test" 19:26:12 INFO - } 19:26:12 INFO - Found installer url https://queue.taskcluster.net/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 19:26:12 INFO - Running post-action listener: _resource_record_post_action 19:26:12 INFO - ##### 19:26:12 INFO - ##### Finished read-buildbot-config step (success) 19:26:12 INFO - ##### 19:26:12 INFO - ##### 19:26:12 INFO - ##### Running download-and-extract step. 19:26:12 INFO - ##### 19:26:12 INFO - Running pre-action listener: _resource_record_pre_action 19:26:12 INFO - Running main action method: download_and_extract 19:26:12 INFO - mkdir: /builds/slave/test/build/tests 19:26:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:26:12 INFO - https://queue.taskcluster.net/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 19:26:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 19:26:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 19:26:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 19:26:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 19:26:14 INFO - Downloaded 1199 bytes. 19:26:14 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 19:26:14 INFO - Using the following test package requirements: 19:26:14 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 19:26:14 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:26:14 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 19:26:14 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:26:14 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 19:26:14 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:26:14 INFO - u'jsshell-mac.zip'], 19:26:14 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:26:14 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 19:26:14 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 19:26:14 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:26:14 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 19:26:14 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:26:14 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 19:26:14 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:26:14 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 19:26:14 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 19:26:14 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 19:26:14 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:26:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:26:14 INFO - https://queue.taskcluster.net/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 19:26:14 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:26:14 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:26:14 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:26:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:26:18 INFO - Downloaded 18697063 bytes. 19:26:18 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:26:18 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 19:26:22 INFO - Return code: 0 19:26:22 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 19:26:22 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')]} 19:26:22 INFO - https://queue.taskcluster.net/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 19:26:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:26:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:26:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 19:26:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:26:30 INFO - Downloaded 18697063 bytes. 19:26:30 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:26:30 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 19:26:34 INFO - Return code: 0 19:26:34 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')]} 19:26:34 INFO - https://queue.taskcluster.net/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 19:26:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 19:26:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 19:26:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 19:26:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:26:35 INFO - Downloaded 10834063 bytes. 19:26:35 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 19:26:35 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 19:26:35 INFO - Return code: 0 19:26:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:26:35 INFO - https://queue.taskcluster.net/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 19:26:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:26:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:26:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 19:26:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 19:26:38 INFO - Downloaded 111282794 bytes. 19:26:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:26:38 INFO - mkdir: /builds/slave/test/properties 19:26:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:26:38 INFO - Writing to file /builds/slave/test/properties/build_url 19:26:38 INFO - Contents: 19:26:38 INFO - build_url:https://queue.taskcluster.net/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 19:26:39 INFO - Running post-action listener: _resource_record_post_action 19:26:39 INFO - Running post-action listener: set_extra_try_arguments 19:26:39 INFO - ##### 19:26:39 INFO - ##### Finished download-and-extract step (success) 19:26:39 INFO - ##### 19:26:39 INFO - ##### 19:26:39 INFO - ##### Running populate-webroot step. 19:26:39 INFO - ##### 19:26:39 INFO - Running pre-action listener: _resource_record_pre_action 19:26:39 INFO - Running main action method: populate_webroot 19:26:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:26:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:26:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:26:39 INFO - '--tppagecycles', 19:26:39 INFO - '7'], 19:26:39 INFO - 'tests': ['tsvgm']}}, 19:26:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:26:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:26:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:26:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:26:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:26:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:26:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:26:39 INFO - 'talos_options': ['--disable-e10s'], 19:26:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:26:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:26:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:26:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:26:39 INFO - 'talos_options': ['--disable-e10s'], 19:26:39 INFO - 'tests': ['damp', 'tps']}, 19:26:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:26:39 INFO - 'tests': ['damp', 'tps']}, 19:26:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:26:39 INFO - 'tests': ['dromaeo_dom']}, 19:26:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:26:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:26:39 INFO - 'tests': ['a11yr', 19:26:39 INFO - 'ts_paint', 19:26:39 INFO - 'tpaint', 19:26:39 INFO - 'sessionrestore', 19:26:39 INFO - 'sessionrestore_no_auto_restore', 19:26:39 INFO - 'tabpaint']}, 19:26:39 INFO - 'other-e10s': {'tests': ['a11yr', 19:26:39 INFO - 'ts_paint', 19:26:39 INFO - 'tpaint', 19:26:39 INFO - 'sessionrestore', 19:26:39 INFO - 'sessionrestore_no_auto_restore', 19:26:39 INFO - 'tabpaint']}, 19:26:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:26:39 INFO - 'ts_paint', 19:26:39 INFO - 'tpaint', 19:26:39 INFO - 'sessionrestore', 19:26:39 INFO - 'sessionrestore_no_auto_restore', 19:26:39 INFO - 'tabpaint']}, 19:26:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:26:39 INFO - 'ts_paint', 19:26:39 INFO - 'tpaint', 19:26:39 INFO - 'sessionrestore', 19:26:39 INFO - 'sessionrestore_no_auto_restore', 19:26:39 INFO - 'tabpaint']}, 19:26:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:26:39 INFO - 'tests': ['a11yr', 19:26:39 INFO - 'ts_paint', 19:26:39 INFO - 'tpaint', 19:26:39 INFO - 'sessionrestore', 19:26:39 INFO - 'sessionrestore_no_auto_restore', 19:26:39 INFO - 'tabpaint']}, 19:26:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:26:39 INFO - 'tests': ['a11yr', 19:26:39 INFO - 'ts_paint', 19:26:39 INFO - 'tpaint', 19:26:39 INFO - 'sessionrestore', 19:26:39 INFO - 'sessionrestore_no_auto_restore', 19:26:39 INFO - 'tabpaint']}, 19:26:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:26:39 INFO - 'tests': ['tsvgx', 19:26:39 INFO - 'tsvgr_opacity', 19:26:39 INFO - 'tart', 19:26:39 INFO - 'tscrollx', 19:26:39 INFO - 'cart']}, 19:26:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:26:39 INFO - 'tsvgr_opacity', 19:26:39 INFO - 'tart', 19:26:39 INFO - 'tscrollx', 19:26:39 INFO - 'cart']}, 19:26:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:26:39 INFO - 'talos_options': ['--disable-e10s'], 19:26:39 INFO - 'tests': ['tp5o']}, 19:26:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:26:39 INFO - 'tests': ['tp5o']}, 19:26:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:26:39 INFO - 'talos_options': ['--disable-e10s', 19:26:39 INFO - '--xperf_path', 19:26:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:26:39 INFO - 'tests': ['tp5n']}, 19:26:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:26:39 INFO - 'talos_options': ['--xperf_path', 19:26:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:26:39 INFO - 'tests': ['tp5n']}}, 19:26:39 INFO - 'talos.zip': {'path': '', 19:26:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:26:39 INFO - Running post-action listener: _resource_record_post_action 19:26:39 INFO - ##### 19:26:39 INFO - ##### Finished populate-webroot step (success) 19:26:39 INFO - ##### 19:26:39 INFO - ##### 19:26:39 INFO - ##### Running create-virtualenv step. 19:26:39 INFO - ##### 19:26:39 INFO - Running pre-action listener: _resource_record_pre_action 19:26:39 INFO - Running main action method: create_virtualenv 19:26:39 INFO - Creating virtualenv /builds/slave/test/build/venv 19:26:39 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 19:26:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:26:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:26:39 INFO - Using real prefix '/tools/python27' 19:26:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:26:39 INFO - Installing distribute.............................................................................................................................................................................................done. 19:26:42 INFO - Installing pip.................done. 19:26:42 INFO - Return code: 0 19:26:42 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:26:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:26:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:26:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bebfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bec5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a535674c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd63d30>, '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 0x10bb87db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd5ab20>, '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.T6eEChsRCL/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.gD2XAiyyWv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 19:26:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 19:26:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 19:26:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T6eEChsRCL/Render', 19:26:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:26:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:26:42 INFO - 'HOME': '/Users/cltbld', 19:26:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:26:42 INFO - 'LOGNAME': 'cltbld', 19:26:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:42 INFO - 'MOZ_NO_REMOTE': '1', 19:26:42 INFO - 'NO_EM_RESTART': '1', 19:26:42 INFO - 'PAGER': '/bin/cat', 19:26:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:26:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:42 INFO - 'PWD': '/builds/slave/test', 19:26:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:26:42 INFO - 'SHELL': '/bin/bash', 19:26:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gD2XAiyyWv/Listeners', 19:26:42 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 19:26:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:26:42 INFO - 'USER': 'cltbld', 19:26:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:26:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:26:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:42 INFO - 'XPC_FLAGS': '0x0', 19:26:42 INFO - 'XPC_SERVICE_NAME': '0', 19:26:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:26:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:43 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 19:26:43 INFO - Cleaning up... 19:26:43 INFO - Return code: 0 19:26:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:26:43 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')]} 19:26:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:43 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')]} 19:26:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:43 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 0x10bebfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bec5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a535674c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd63d30>, '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 0x10bb87db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd5ab20>, '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.T6eEChsRCL/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.gD2XAiyyWv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 19:26:43 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 19:26:43 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 19:26:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T6eEChsRCL/Render', 19:26:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:26:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:26:43 INFO - 'HOME': '/Users/cltbld', 19:26:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:26:43 INFO - 'LOGNAME': 'cltbld', 19:26:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:43 INFO - 'MOZ_NO_REMOTE': '1', 19:26:43 INFO - 'NO_EM_RESTART': '1', 19:26:43 INFO - 'PAGER': '/bin/cat', 19:26:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:26:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:43 INFO - 'PWD': '/builds/slave/test', 19:26:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:26:43 INFO - 'SHELL': '/bin/bash', 19:26:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gD2XAiyyWv/Listeners', 19:26:43 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 19:26:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:26:43 INFO - 'USER': 'cltbld', 19:26:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:26:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:26:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:43 INFO - 'XPC_FLAGS': '0x0', 19:26:43 INFO - 'XPC_SERVICE_NAME': '0', 19:26:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:26:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:43 INFO - Downloading/unpacking psutil>=3.1.1 19:26:43 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 19:26:43 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 19:26:43 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 19:26:43 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 19:26:43 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 19:26:43 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 19:26:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:26:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:26:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:26:46 INFO - Installing collected packages: psutil 19:26:46 INFO - Running setup.py install for psutil 19:26:46 INFO - building 'psutil._psutil_osx' extension 19:26:46 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 19:26:46 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 19:26:47 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 19:26:47 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 19:26:47 INFO - building 'psutil._psutil_posix' extension 19:26:47 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 19:26:47 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:26:47 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:26:47 INFO - ^ 19:26:47 INFO - 1 warning generated. 19:26:47 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 19:26:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:26:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:26:47 INFO - Successfully installed psutil 19:26:47 INFO - Cleaning up... 19:26:47 INFO - Return code: 0 19:26:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:26:47 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')]} 19:26:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:47 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')]} 19:26:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:47 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 0x10bebfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bec5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a535674c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd63d30>, '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 0x10bb87db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd5ab20>, '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.T6eEChsRCL/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.gD2XAiyyWv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 19:26:47 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 19:26:47 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 19:26:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T6eEChsRCL/Render', 19:26:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:26:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:26:47 INFO - 'HOME': '/Users/cltbld', 19:26:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:26:47 INFO - 'LOGNAME': 'cltbld', 19:26:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:47 INFO - 'MOZ_NO_REMOTE': '1', 19:26:47 INFO - 'NO_EM_RESTART': '1', 19:26:47 INFO - 'PAGER': '/bin/cat', 19:26:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:26:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:47 INFO - 'PWD': '/builds/slave/test', 19:26:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:26:47 INFO - 'SHELL': '/bin/bash', 19:26:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gD2XAiyyWv/Listeners', 19:26:47 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 19:26:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:26:47 INFO - 'USER': 'cltbld', 19:26:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:26:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:26:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:47 INFO - 'XPC_FLAGS': '0x0', 19:26:47 INFO - 'XPC_SERVICE_NAME': '0', 19:26:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:26:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:26:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:26:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:26:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:26:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:26:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:26:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:26:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:26:50 INFO - Installing collected packages: mozsystemmonitor 19:26:50 INFO - Running setup.py install for mozsystemmonitor 19:26:50 INFO - Successfully installed mozsystemmonitor 19:26:50 INFO - Cleaning up... 19:26:50 INFO - Return code: 0 19:26:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:26:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:26:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:26:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:50 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 0x10bebfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bec5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a535674c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd63d30>, '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 0x10bb87db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd5ab20>, '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.T6eEChsRCL/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.gD2XAiyyWv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 19:26:50 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 19:26:50 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 19:26:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T6eEChsRCL/Render', 19:26:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:26:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:26:50 INFO - 'HOME': '/Users/cltbld', 19:26:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:26:50 INFO - 'LOGNAME': 'cltbld', 19:26:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:50 INFO - 'MOZ_NO_REMOTE': '1', 19:26:50 INFO - 'NO_EM_RESTART': '1', 19:26:50 INFO - 'PAGER': '/bin/cat', 19:26:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:26:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:50 INFO - 'PWD': '/builds/slave/test', 19:26:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:26:50 INFO - 'SHELL': '/bin/bash', 19:26:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gD2XAiyyWv/Listeners', 19:26:50 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 19:26:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:26:50 INFO - 'USER': 'cltbld', 19:26:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:26:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:26:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:50 INFO - 'XPC_FLAGS': '0x0', 19:26:50 INFO - 'XPC_SERVICE_NAME': '0', 19:26:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:26:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:50 INFO - Downloading/unpacking blobuploader==1.2.4 19:26:50 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 19:26:50 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 19:26:50 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 19:26:50 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 19:26:50 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 19:26:50 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 19:26:53 INFO - Downloading blobuploader-1.2.4.tar.gz 19:26:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:26:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:26:53 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 19:26:53 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 19:26:53 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 19:26:53 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 19:26:53 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 19:26:53 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 19:26:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:26:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:26:54 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 19:26:54 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 19:26:54 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 19:26:54 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 19:26:54 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 19:26:54 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 19:26:54 INFO - Downloading docopt-0.6.1.tar.gz 19:26:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:26:54 INFO - Installing collected packages: blobuploader, requests, docopt 19:26:54 INFO - Running setup.py install for blobuploader 19:26:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:26:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:26:54 INFO - Running setup.py install for requests 19:26:55 INFO - Running setup.py install for docopt 19:26:55 INFO - Successfully installed blobuploader requests docopt 19:26:55 INFO - Cleaning up... 19:26:55 INFO - Return code: 0 19:26:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:26:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:26:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:26:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:55 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 0x10bebfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bec5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a535674c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd63d30>, '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 0x10bb87db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd5ab20>, '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.T6eEChsRCL/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.gD2XAiyyWv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 19:26:55 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 19:26:55 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 19:26:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T6eEChsRCL/Render', 19:26:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:26:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:26:55 INFO - 'HOME': '/Users/cltbld', 19:26:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:26:55 INFO - 'LOGNAME': 'cltbld', 19:26:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:55 INFO - 'MOZ_NO_REMOTE': '1', 19:26:55 INFO - 'NO_EM_RESTART': '1', 19:26:55 INFO - 'PAGER': '/bin/cat', 19:26:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:26:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:55 INFO - 'PWD': '/builds/slave/test', 19:26:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:26:55 INFO - 'SHELL': '/bin/bash', 19:26:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gD2XAiyyWv/Listeners', 19:26:55 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 19:26:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:26:55 INFO - 'USER': 'cltbld', 19:26:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:26:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:26:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:55 INFO - 'XPC_FLAGS': '0x0', 19:26:55 INFO - 'XPC_SERVICE_NAME': '0', 19:26:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:26:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:26:55 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-8a2Hxw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:26:55 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-V6ilI8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:26:55 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-5iBT3g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:26:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:26:55 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-vHBNBZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:26:56 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-r2jO4H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:26:56 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-Ie2me6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:26:56 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-kykaRS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:26:56 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-6kLg3E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:26:56 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-KnJCQr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:26:56 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-Rm2UbC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:26:56 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-HNeOPk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:26:56 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-tEwllg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:26:56 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-BlLIo3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:26:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:26:56 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-knzOVA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:26:57 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-x5tl29-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:26:57 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-9L0Ide-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:26:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:26:57 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-IRAES1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:26:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:26:57 INFO - Running setup.py install for manifestparser 19:26:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:26:57 INFO - Running setup.py install for mozcrash 19:26:57 INFO - Running setup.py install for mozdebug 19:26:57 INFO - Running setup.py install for mozdevice 19:26:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:26:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:26:57 INFO - Running setup.py install for mozfile 19:26:58 INFO - Running setup.py install for mozhttpd 19:26:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:26:58 INFO - Running setup.py install for mozinfo 19:26:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:26:58 INFO - Running setup.py install for mozInstall 19:26:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:26:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:26:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:26:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:26:58 INFO - Running setup.py install for mozleak 19:26:58 INFO - Running setup.py install for mozlog 19:26:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:26:58 INFO - Running setup.py install for moznetwork 19:26:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:26:58 INFO - Running setup.py install for mozprocess 19:26:59 INFO - Running setup.py install for mozprofile 19:26:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:26:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:26:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:26:59 INFO - Running setup.py install for mozrunner 19:26:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:26:59 INFO - Running setup.py install for mozscreenshot 19:26:59 INFO - Running setup.py install for moztest 19:26:59 INFO - Running setup.py install for mozversion 19:26:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:26:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:26:59 INFO - Cleaning up... 19:26:59 INFO - Return code: 0 19:26:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:26:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:26:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:26:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:26:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:26:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:26:59 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 0x10bebfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bec5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a535674c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd63d30>, '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 0x10bb87db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd5ab20>, '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.T6eEChsRCL/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.gD2XAiyyWv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 19:26:59 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 19:26:59 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 19:26:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T6eEChsRCL/Render', 19:26:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:26:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:26:59 INFO - 'HOME': '/Users/cltbld', 19:26:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:26:59 INFO - 'LOGNAME': 'cltbld', 19:26:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:26:59 INFO - 'MOZ_NO_REMOTE': '1', 19:26:59 INFO - 'NO_EM_RESTART': '1', 19:26:59 INFO - 'PAGER': '/bin/cat', 19:26:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:26:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:26:59 INFO - 'PWD': '/builds/slave/test', 19:26:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:26:59 INFO - 'SHELL': '/bin/bash', 19:26:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gD2XAiyyWv/Listeners', 19:26:59 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 19:26:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:26:59 INFO - 'USER': 'cltbld', 19:26:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:26:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:26:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:26:59 INFO - 'XPC_FLAGS': '0x0', 19:26:59 INFO - 'XPC_SERVICE_NAME': '0', 19:26:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:27:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:27:00 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-cdijOW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:27:00 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)) 19:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:27:00 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-tZ88Ol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:27:00 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)) 19:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:27:00 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-ghJQRE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:27:00 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)) 19:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:27:00 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-OyZLCW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:27:00 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)) 19:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:27:00 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-tsOPNv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:27:00 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)) 19:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:27:00 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-6GMwe9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:27:00 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)) 19:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:27:00 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-nfseaS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:27:00 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)) 19:27:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:27:00 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-h7V7Qj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:27:01 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)) 19:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:27:01 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-8Rv5kP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:27:01 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)) 19:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:27:01 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-2pTWty-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:27:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 19:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:27:01 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-xpVYze-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:27:01 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)) 19:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:27:01 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-ducpgk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:27:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 19:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:27:01 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-HhrzQZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:27:01 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)) 19:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:27:01 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-Q_2omj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:27:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 19:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:27:01 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-u1Pgc7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:27:01 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)) 19:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:27:01 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-zOw_zC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:27:01 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)) 19:27:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:27:01 INFO - Running setup.py (path:/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/pip-cx5lpl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:27:01 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)) 19:27:01 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)) 19:27:01 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)) 19:27:01 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)) 19:27:01 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)) 19:27:01 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)) 19:27:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:27:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:27:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:27:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:27:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:27:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:27:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:27:04 INFO - Downloading blessings-1.6.tar.gz 19:27:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:27:04 INFO - Installing collected packages: blessings 19:27:04 INFO - Running setup.py install for blessings 19:27:04 INFO - Successfully installed blessings 19:27:04 INFO - Cleaning up... 19:27:04 INFO - Return code: 0 19:27:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:27:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:27:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:27:05 INFO - Reading from file tmpfile_stdout 19:27:05 INFO - Current package versions: 19:27:05 INFO - blessings == 1.6 19:27:05 INFO - blobuploader == 1.2.4 19:27:05 INFO - docopt == 0.6.1 19:27:05 INFO - manifestparser == 1.1 19:27:05 INFO - mozInstall == 1.12 19:27:05 INFO - mozcrash == 0.17 19:27:05 INFO - mozdebug == 0.1 19:27:05 INFO - mozdevice == 0.48 19:27:05 INFO - mozfile == 1.2 19:27:05 INFO - mozhttpd == 0.7 19:27:05 INFO - mozinfo == 0.9 19:27:05 INFO - mozleak == 0.1 19:27:05 INFO - mozlog == 3.2 19:27:05 INFO - moznetwork == 0.27 19:27:05 INFO - mozprocess == 0.22 19:27:05 INFO - mozprofile == 0.28 19:27:05 INFO - mozrunner == 6.11 19:27:05 INFO - mozscreenshot == 0.1 19:27:05 INFO - mozsystemmonitor == 0.3 19:27:05 INFO - moztest == 0.7 19:27:05 INFO - mozversion == 1.4 19:27:05 INFO - psutil == 3.1.1 19:27:05 INFO - requests == 1.2.3 19:27:05 INFO - wsgiref == 0.1.2 19:27:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:27:05 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')]} 19:27:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:27:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:27:05 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')]} 19:27:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:27:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:27:05 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 0x10bebfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bec5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a535674c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd63d30>, '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 0x10bb87db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd5ab20>, '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.T6eEChsRCL/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.gD2XAiyyWv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 19:27:05 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 19:27:05 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 19:27:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T6eEChsRCL/Render', 19:27:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:27:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:27:05 INFO - 'HOME': '/Users/cltbld', 19:27:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:27:05 INFO - 'LOGNAME': 'cltbld', 19:27:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:27:05 INFO - 'MOZ_NO_REMOTE': '1', 19:27:05 INFO - 'NO_EM_RESTART': '1', 19:27:05 INFO - 'PAGER': '/bin/cat', 19:27:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:27:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:27:05 INFO - 'PWD': '/builds/slave/test', 19:27:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:27:05 INFO - 'SHELL': '/bin/bash', 19:27:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gD2XAiyyWv/Listeners', 19:27:05 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 19:27:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:27:05 INFO - 'USER': 'cltbld', 19:27:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:27:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:27:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:27:05 INFO - 'XPC_FLAGS': '0x0', 19:27:05 INFO - 'XPC_SERVICE_NAME': '0', 19:27:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:27:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:27:05 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)) 19:27:05 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)) 19:27:05 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)) 19:27:05 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)) 19:27:05 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)) 19:27:05 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)) 19:27:05 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)) 19:27:05 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)) 19:27:05 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)) 19:27:05 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)) 19:27:05 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)) 19:27:05 INFO - Cleaning up... 19:27:05 INFO - Return code: 0 19:27:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:27:05 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')]} 19:27:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:27:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:27:05 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')]} 19:27:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:27:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:27:05 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 0x10bebfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bec5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a535674c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd63d30>, '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 0x10bb87db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd5ab20>, '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.T6eEChsRCL/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.gD2XAiyyWv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 19:27:05 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 19:27:05 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 19:27:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T6eEChsRCL/Render', 19:27:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:27:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:27:05 INFO - 'HOME': '/Users/cltbld', 19:27:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:27:05 INFO - 'LOGNAME': 'cltbld', 19:27:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:27:05 INFO - 'MOZ_NO_REMOTE': '1', 19:27:05 INFO - 'NO_EM_RESTART': '1', 19:27:05 INFO - 'PAGER': '/bin/cat', 19:27:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:27:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:27:05 INFO - 'PWD': '/builds/slave/test', 19:27:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:27:05 INFO - 'SHELL': '/bin/bash', 19:27:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gD2XAiyyWv/Listeners', 19:27:05 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 19:27:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:27:05 INFO - 'USER': 'cltbld', 19:27:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:27:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:27:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:27:05 INFO - 'XPC_FLAGS': '0x0', 19:27:05 INFO - 'XPC_SERVICE_NAME': '0', 19:27:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:27:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:27:05 INFO - Downloading/unpacking jsonschema 19:27:05 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 19:27:05 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 19:27:05 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 19:27:05 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 19:27:05 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 19:27:05 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 19:27:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:27:08 INFO - Downloading/unpacking functools32 (from jsonschema) 19:27:08 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 19:27:08 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 19:27:08 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 19:27:08 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 19:27:08 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 19:27:08 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 19:27:08 INFO - Downloading functools32-3.2.3-2.tar.gz 19:27:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:27:08 INFO - warning: no files found matching '*.txt' 19:27:08 INFO - no previously-included directories found matching 'build' 19:27:08 INFO - no previously-included directories found matching 'dist' 19:27:08 INFO - no previously-included directories found matching '.git*' 19:27:08 INFO - Installing collected packages: jsonschema, functools32 19:27:08 INFO - Running setup.py install for functools32 19:27:08 INFO - warning: no files found matching '*.txt' 19:27:08 INFO - no previously-included directories found matching 'build' 19:27:08 INFO - no previously-included directories found matching 'dist' 19:27:08 INFO - no previously-included directories found matching '.git*' 19:27:08 INFO - Successfully installed jsonschema functools32 19:27:08 INFO - Cleaning up... 19:27:08 INFO - Return code: 0 19:27:08 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 19:27: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')]} 19:27:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:27:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:27: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')]} 19:27:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:27:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:27:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bebfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bec5570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a535674c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bd63d30>, '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 0x10bb87db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd5ab20>, '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.T6eEChsRCL/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.gD2XAiyyWv/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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 19:27:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 19:27:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 19:27:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T6eEChsRCL/Render', 19:27:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:27:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:27:08 INFO - 'HOME': '/Users/cltbld', 19:27:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:27:08 INFO - 'LOGNAME': 'cltbld', 19:27:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:27:08 INFO - 'MOZ_NO_REMOTE': '1', 19:27:08 INFO - 'NO_EM_RESTART': '1', 19:27:08 INFO - 'PAGER': '/bin/cat', 19:27:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:27:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:27:08 INFO - 'PWD': '/builds/slave/test', 19:27:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:27:08 INFO - 'SHELL': '/bin/bash', 19:27:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gD2XAiyyWv/Listeners', 19:27:08 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 19:27:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:27:08 INFO - 'USER': 'cltbld', 19:27:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:27:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:27:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:27:08 INFO - 'XPC_FLAGS': '0x0', 19:27:08 INFO - 'XPC_SERVICE_NAME': '0', 19:27:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:27:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:27:09 INFO - Downloading/unpacking flake8 19:27:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:27:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:27:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:27:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:27:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:27:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:27:11 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 19:27:11 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 19:27:11 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 19:27:11 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 19:27:11 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 19:27:11 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 19:27:11 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 19:27:11 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 19:27:11 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 19:27:11 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 19:27:11 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 19:27:11 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 19:27:11 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 19:27:11 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 19:27:11 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 19:27:11 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 19:27:12 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 19:27:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:27:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:27:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:27:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:27:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:27:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:27:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 19:27:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:27:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:27:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:27:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:27:12 INFO - no previously-included directories found matching 'docs/_build' 19:27:12 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 19:27:12 INFO - Running setup.py install for pep8 19:27:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:27:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:27:12 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:27:12 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:27:12 INFO - no previously-included directories found matching 'docs/_build' 19:27:12 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 19:27:12 INFO - Successfully installed flake8 mccabe pyflakes pep8 19:27:12 INFO - Cleaning up... 19:27:13 INFO - Return code: 0 19:27:13 INFO - Running post-action listener: _resource_record_post_action 19:27:13 INFO - Running post-action listener: _start_resource_monitoring 19:27:13 INFO - Starting resource monitoring. 19:27:13 INFO - ##### 19:27:13 INFO - ##### Finished create-virtualenv step (success) 19:27:13 INFO - ##### 19:27:13 INFO - ##### 19:27:13 INFO - ##### Running install step. 19:27:13 INFO - ##### 19:27:13 INFO - Running pre-action listener: _resource_record_pre_action 19:27:13 INFO - Running main action method: install 19:27:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:27:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:27:13 INFO - Reading from file tmpfile_stdout 19:27:13 INFO - Detecting whether we're running mozinstall >=1.0... 19:27:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:27:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:27:13 INFO - Reading from file tmpfile_stdout 19:27:13 INFO - Output received: 19:27:13 INFO - Usage: mozinstall [options] installer 19:27:13 INFO - Options: 19:27:13 INFO - -h, --help show this help message and exit 19:27:13 INFO - -d DEST, --destination=DEST 19:27:13 INFO - Directory to install application into. [default: 19:27:13 INFO - "/builds/slave/test"] 19:27:13 INFO - --app=APP Application being installed. [default: firefox] 19:27:13 INFO - mkdir: /builds/slave/test/build/application 19:27:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:27:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 19:27:47 INFO - Reading from file tmpfile_stdout 19:27:47 INFO - Output received: 19:27:47 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:27:47 INFO - Running post-action listener: _resource_record_post_action 19:27:47 INFO - ##### 19:27:47 INFO - ##### Finished install step (success) 19:27:47 INFO - ##### 19:27:47 INFO - ##### 19:27:47 INFO - ##### Running run-tests step. 19:27:47 INFO - ##### 19:27:47 INFO - Running pre-action listener: _resource_record_pre_action 19:27:47 INFO - Running main action method: run_tests 19:27:47 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:27:47 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:27:47 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:27:47 INFO - 2016-06-01 19:27:47.569 screenresolution[1647:7715] starting screenresolution argv=screenresolution get 19:27:47 INFO - 2016-06-01 19:27:47.571 screenresolution[1647:7715] Display 0: 1600x1200x32@60 19:27:47 INFO - 2016-06-01 19:27:47.577 screenresolution[1648:7717] starting screenresolution argv=screenresolution list 19:27:47 INFO - Available Modes on Display 0 19:27:47 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:27:47 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:27:47 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:27:47 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:27:47 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:27:47 INFO - Intel Iris: 19:27:47 INFO - Chipset Model: Intel Iris 19:27:47 INFO - Type: GPU 19:27:47 INFO - Bus: Built-In 19:27:47 INFO - VRAM (Dynamic, Max): 1536 MB 19:27:47 INFO - Vendor: Intel (0x8086) 19:27:47 INFO - Device ID: 0x0a2e 19:27:47 INFO - Revision ID: 0x0009 19:27:47 INFO - Displays: 19:27:47 INFO - Display: 19:27:47 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:27:47 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:27:47 INFO - Display Serial Number: 1600x1200 60 19:27:47 INFO - Main Display: Yes 19:27:47 INFO - Mirror: Off 19:27:47 INFO - Online: Yes 19:27:47 INFO - Rotation: Supported 19:27:47 INFO - Return code: 0 19:27:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:27:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:27:47 INFO - Python 2.7.3 19:27:47 INFO - Return code: 0 19:27:47 INFO - grabbing minidump binary from tooltool 19:27:47 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')]} 19:27:47 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 0x10bd63d30>, '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 0x10bb87db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bd5ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:27:47 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 19:27:47 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 19:27:47 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 19:27:47 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:27:47 INFO - Return code: 0 19:27:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:27:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:27:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:27:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:27:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:27:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 19:27:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 19:27:49 INFO - Return code: 0 19:27:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0340', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 19:27:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0340 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 19:27:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.T6eEChsRCL/Render', 19:27:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:27:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:27:49 INFO - 'HOME': '/Users/cltbld', 19:27:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:27:49 INFO - 'LOGNAME': 'cltbld', 19:27:49 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:27:49 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:27:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:27:49 INFO - 'MOZ_NO_REMOTE': '1', 19:27:49 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:27:49 INFO - 'NO_EM_RESTART': '1', 19:27:49 INFO - 'PAGER': '/bin/cat', 19:27:49 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:27:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:27:49 INFO - 'PWD': '/builds/slave/test', 19:27:49 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:27:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:27:49 INFO - 'SHELL': '/bin/bash', 19:27:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gD2XAiyyWv/Listeners', 19:27:49 INFO - 'TMPDIR': '/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/', 19:27:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:27:49 INFO - 'USER': 'cltbld', 19:27:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:27:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:27:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:27:49 INFO - 'XPC_FLAGS': '0x0', 19:27:49 INFO - 'XPC_SERVICE_NAME': '0', 19:27:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:27:49 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0340', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 19:27:49 INFO - mozversion application_buildid: 20160601171646 19:27:49 INFO - mozversion application_changeset: 279597150b922a9b762de1dc88fbf083a31a4f6f 19:27:49 INFO - mozversion application_display_name: Nightly 19:27:49 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:27:49 INFO - mozversion application_name: Firefox 19:27:49 INFO - mozversion application_remotingname: firefox 19:27:49 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 19:27:49 INFO - mozversion application_vendor: Mozilla 19:27:49 INFO - mozversion application_version: 49.0a1 19:27:49 INFO - mozversion platform_buildid: 20160601171646 19:27:49 INFO - mozversion platform_changeset: 279597150b922a9b762de1dc88fbf083a31a4f6f 19:27:49 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 19:27:49 INFO - mozversion platform_version: 49.0a1 19:27:49 INFO - using testdate: 1464834469 19:27:49 INFO - actual date: 1464834469 19:27:49 INFO - starting webserver on 'localhost:49279' 19:27:49 INFO - SUITE-START | Running 2 tests 19:27:49 INFO - TEST-START | dromaeo_css 19:27:49 INFO - operating with platform_type : mac_ 19:27:49 INFO - Initialising browser for dromaeo_css test... 19:27:49 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/tmpsigmv6/profile http://localhost:49279/getInfo.html) 19:27:52 INFO - PROCESS | 1666 | __metrics Screen width/height:1600/1200 19:27:52 INFO - PROCESS | 1666 | colorDepth:24 19:27:52 INFO - PROCESS | 1666 | Browser inner width/height: 1024/689 19:27:52 INFO - PROCESS | 1666 | __metrics 19:27:52 ERROR - PROCESS | 1666 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2938: TypeError: this.tabs is undefined 19:27:53 INFO - TEST-INFO | 1666: exit 0 19:27:53 INFO - Browser initialized. 19:27:53 INFO - Running cycle 1/1 for dromaeo_css test... 19:27:53 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/tmpsigmv6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:28:05 INFO - PROCESS | 1669 | RSS: Main: 252837888 19:28:05 INFO - PROCESS | 1669 | 19:28:54 INFO - PROCESS | 1669 | 1464834534704 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] 19:31:06 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49279/tests/dromaeo/cssquery-ext.html) 19:31:07 INFO - PROCESS | 1669 | RSS: Main: 266891264 19:31:07 INFO - PROCESS | 1669 | 19:34:10 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-ext.html (next: http://localhost:49279/tests/dromaeo/cssquery-jquery.html) 19:34:10 INFO - PROCESS | 1669 | RSS: Main: 278159360 19:34:10 INFO - PROCESS | 1669 | 19:37:12 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49279/tests/dromaeo/cssquery-mootools.html) 19:37:13 INFO - PROCESS | 1669 | RSS: Main: 280199168 19:37:13 INFO - PROCESS | 1669 | 19:40:15 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49279/tests/dromaeo/cssquery-prototype.html) 19:40:15 INFO - PROCESS | 1669 | RSS: Main: 282267648 19:40:15 INFO - PROCESS | 1669 | 19:43:18 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49279/tests/dromaeo/cssquery-yui.html) 19:43:19 INFO - PROCESS | 1669 | RSS: Main: 285245440 19:43:19 INFO - PROCESS | 1669 | 19:46:21 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-yui.html (next: http://localhost:49279/tests/dromaeo/cssquery-dojo.html) 19:46:22 INFO - PROCESS | 1669 | RSS: Main: 285712384 19:46:22 INFO - PROCESS | 1669 | 19:46:22 INFO - PROCESS | 1669 | __start_tp_report 19:46:22 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 19:46:22 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 19:46:22 INFO - PROCESS | 1669 | |i|pagename|runs| 19:46:22 INFO - PROCESS | 1669 | |0;dojo.html;3188.9202769930757;3461.948070778939;3478.5477887874604;3483.425234836252;3533.4523148911926;5722.141678748189;5820.156077368779;5836.795712150076;5864.266966629171;5887.381824908387;5702.173184888189;5844.1818145459665;5899.734511946962;5913.083472061827;5940.465358117768;5289.074411977917;5297.783246343454;5309.442508536606;5401.4868587484325;5430.864228394283;4405.31717583775;5366.248725178478;5387.838364849041;5401.054815407308;5472.644278121929;5226.163813789775;5239.476052394768;5320.228566857784;5361.383440904307;5393.703346315951;5332.280142180794;5348.197770334442;5349.839504814861;5357.4374690657305;5358.732063396815;4774.3077253798;5339.652922560022;5351.892962140774;5359.276497672842;5378.623496355257;5437.265969094161;5537.556995440355;5708.657480551139;5733.426657334275;5738.885222295572;6256.812295631093;6284.497240220817;6291.245050593958;6294.590851594632;6348.17473728417;5229;6271.78048768291;6330.082138089997;6341.682915854235;6343.904841427382;5923.792667256668;6012.128241405059;6017.578769486182;6032.396760323976;6051.152838602599;5391.757370918318;6343.936560634427;6373.139626150503;6384.297727249998;6387.776427825004;5223.608229382736;6113.020255720364;6149.200603921461;6275.748970041147;6288.502683087856;6232.220972378453;6253.46845518127;6293.1504246927;6303.936960630427;6323.7786677466065;4625.699329543569;5279.838435544174;6133;6222.191115155091;6260.311365749764;6242.001279795211;6267.373262673696;6288.591241569284;6313.43179113882;6337.556371053982;4950.158473059517;5046.419661738873;5070.442251352349;5094.363204599425;5100.362454693163;4157.230912281238;4319.57454040166;4519.186546421675;4535.092981403733;4572.0855828834365;5790.71046447676;5984.222051133324;5984.611000284967;5987.820365389045;5989.940100598938;6188.319284878659;6223.782167624202;6257.5932564383165;6307.085472606538;6348.460380867586;5868.207791948118;5909.113632955091;5916.467517923407;5930.792422265286;5965.642061476326;5377.032134215879;5449.2371068049715;5493.752781124859;5495.890082198333;5512.448755124536;6376.458001069961;6502.479801615954;6526.869462610626;6536.084948107174;6539.346065393423;6309.558330916975;6312.179416675803;6312.368763123651;6314.905276420949;6321.146645202839;6193.287771906288;6367.522435817425;6370.745170193326;6504.934950650433;6513.120728701565;6239.282482514569;6287.811365659038;6295.496360291258;6316.621002739666;6324.936750632619;6273.466755325849;6292.087647291026;6293.118963345044;6300.180976473029;6300.936990630218;6118.923762351488;6122.947104152957;6132.5707200495535;6181.907271391023;6249.6875656112725;5872.383399743137;6137.499563105042;6218.751249949952;6226.1905952225925;6254.562180647493;5930.11048342752;6084.300305464928;6124.38756124384;6151;6176.567640265138;6096.573239873166;6122.15420935445;6151.8926593213655;6253.437190652863;6272.891256227179;6100.944524685144;6155.353687862889;6158.168647232746;6191.938080619317;6198.132261483306 19:46:22 INFO - PROCESS | 1669 | |1;ext.html;477.84708893154203;503.1094961917405;504.722402678527;517.1079887194588;518.7872972081448;32430.351392972127;33030.50454243185;41063.79468102659;42688.573114268846;42727;43837.7808110959;44395;44417.77791111048;44427;44511;9458.243340532757;9693.563789629483;9696.030396960283;9704.078112579293;9713.805723885518;9588.760280992961;9682.177014953702;9731.95134024329;9753.853692194623;9763.560639771193;9750.219982401426;9750.658726944559;9752.707418777449;9760.07279308468;9779.951100244489;7527.435442341792;8872.245859101984;9118.726438206864;9583.760405989837;9692.563834627459;9462.52687365636;9502.334836561444;9541.904580954171;9564.713058608253;9610.327277090606;39990.00024999369;40356;40707.18585628271;40942.38586421206;41021;3487.2676737885076;3522.7181825453904;3662.688671462929;3682.558093028854;3741.4574886641285;28036.719632803615;31716.04851854448;31853.52219716706;32221.6777832221;33479;3227.4190645684002;3416.9145771355666;3428.4171690812623;3431.24512607226;3441.2429265561536;495526.0447395516;499093;507820;509417;509787.451062746;58100.70949645225;68970.65514672395;69959;70232.94650580245;74257.25742742504;71768;72122.11511539585;72678;72714.63642681752;72908.27091729121;16113.758293625606;16165.434209802836;16234.75647865283;16344;16375.59061023484;11799.292042477478;12205.755884882252;12291.508339666492;12449.377531123408;12491.312977786309;17509.7373539397;21136.682949755766;21466.677999829702;21471;21491.57016859654;7439.590822504706;8667.653293868301;8857.29141668665;8860;8971.820563588692;823.81052357958;940.3887473142375;1035.3787727363522;1203.867574566797;1225.871283515218;30230;30533;30623.69376306253;31160.688393116234;31179.68820311768;981.3866333541537;1094.7591529863416;1109.8501702270253;1118.0887576625187;1132.5130194016651;12551.623451296477;15598;15852.44516441919;16035.11806850611;16189.757153642704;706911;713430;718248;718824;720335;59064.70467647634;60187.699061505285;60786.69606652027;60790.392096079355;60836.783264335354;1416.8441471438134;1436.547487541421;1461.7295800276984;1466.3621324723779;1466.74331991901;26766.598501022112;30058.363330454693;30994.225144371572;31009.68990310068;31029.68970310268;51450.74274628603;52017.69955751136;52130.739346303024;52215;52384;642487;648638;651529.4847051658;652839;654583;42914;43763.343549847414;44857.775711121234;44863.775681121384;44886.960173584535;48686.75656621694;48785.268220976;49414.50585494099;49696.751516242184;49970.000599988525;39246.4113038299;40416.79791601023;40615.78152655425;40690.59309406868;41237.96905077277;35143.297134057684;37495;39203.41194882022;39660.60339396569;39952.40071398873;32415.837920810245;32666.836665816518;35113.824430877685;38291;38692;33011.504827427125;34023.48964765481;35055.82472087623;35619;35644.643553564136;25317.62023569611;25569.74430255748;25631;25768;26350.736492634827 19:46:22 INFO - PROCESS | 1669 | |2;jquery.html;384.6192269653043;388.3689005366279;390.7030656700907;391.48128729433506;414.12619373122703;14081.155130692166;15986.680266394666;17045.914770426145;20539.691904621443;20598.86706231157;16492.587685307844;19666.508337291576;20191.2933047343;21476.677849832264;21538;5804.767809287623;5877.265341832271;6110.694465276741;6119.969400152993;6155.969220153893;6039.063945088518;6086.665233412161;6104.542159338067;6107.847303817418;6117.724702388381;5679.602427830054;6001.309849367335;6003.819885403423;6003.849903752397;6074.301455332627;5871.765129394819;5961.433663801932;5984.132300816401;5988.131720900488;5990.221271234734;5048.091343558158;5198.844034678966;5231.136862417697;5232.607554433394;5250.212468129773;20722.48193795152;22569.435764105863;24775.00899963999;25572;25957.480850382886;2236.452069243046;2254.7060260058697;2269.3645779181857;2280.8289378296527;2334.3580515358412;15459.072455652584;15732.842671573253;15904.045757254602;16061.518154455385;16283.511494655178;1852.97220541692;1894.886306821595;1900;1920.9519762005923;1965.8722183058055;115458;118075;119187;119668;119764;10554.736131596617;10772.838407423895;10944.23390362667;11014.118870490502;11038.227324087236;11346.149038822121;13704.65738356549;13734.725305494034;13790.379432925542;13814.723705525834;8179.4274400792565;8352.624131914079;8357.331413486907;8435.620397082022;8451.281641060456;8197.221263979913;8246.752597422086;8248.381371397169;8318.625661845232;8356.373272004625;5073.061483625541;5597.552195824324;6239.377761781879;6607.471402287805;6779.694913728796;1847.1133855749047;1852.777666680007;1884.4535084825247;1908.7327774111636;1909.634611252961;942.1661829281136;991.1723710701501;1012.5747186181674;1055.3034996902009;1134.8881466136681;18480.907595462206;19337;21653;21941.68349899011;22128.557428851655;869.6390997735995;979.6571200079915;1000.1598657127902;1013.3008224325192;1027.6660085472251;11341.206115571997;11799.764004719858;11808.763824723457;11828.52685892572;11860;580001;594035;600875;601384;602637;65349.38602455943;80203;80721;113832.43083784694;125911;932.4172392254841;984.4634674102774;1004.4826914139078;1005.1205195453978;1006.7030226083323;18111.547211319823;19168;19677.704834427208;19775.70336444926;19888;38773;48765.75617121892;50062;50324.24513632225;51750.741246293524;70425;71076;71435.64282178556;71476;72518.63740681263;7060.8940865886025;7411.332980031823;7533.472656913963;7771.417143714131;8098.595070246346;11701.531938722355;12976.545820896223;13054.673633159247;13247.602571922858;13358.864496517905;11326.037286830711;11366.602168924048;11528.423578821194;11584.710382240175;11678.182527223353;11249.437528123724;11373;11441.370724610226;11515.19393642437;11705.356205408785;11432.428378581204;11617.419129043683;11791.17236828293;11818.82271765907;11949;7543.886841697488;7598.278163574451;7609.315161635479;7622;7739.5743234122665;11438.656840294805;11479.655610331703;11653.88346116528;11720.589779357682;11767.470463828977 19:46:22 INFO - PROCESS | 1669 | |3;mootools.html;1516.5601975427126;1548.78317035615;1747.4146161036053;1867.0011543824048;1925.1048262557924;7995.320397766182;8517.701880434186;8579;8965.372423930336;8984.775380615489;7551.395888328934;8667.046624871258;8767.868481972775;8841.778955526115;8849.115088491164;2342.2387723989686;2363.846349987254;2375.061850569024;2376.5128148729555;2382.892769825362;2051.569170474202;2347.1784875293683;2360.114956891166;2395.185636883459;2403.759624037591;1968.6850103983365;2343.6953196084487;2362.83460157789;2367.550165468564;2382.9761702383016;2326.6510023496444;2350.177437896731;2361.0673783855455;2361.8110551155864;2367.7868991790733;2326.639370897514;2353.9058437662475;2360.586897292984;2366.7041619797524;2380.488195038075;8765.561721913942;8834.337424693109;8842.955785221096;8851.60167792451;8868.02451730309;5215.191645294947;5220;5386.865328366822;5387.084195686703;5392.676439413648;3494.109002204422;3599.0822340303316;3672.8714494992546;3673.338799016176;3677.3196958562753;1035.2390992620417;1052.0584077250894;1075.9031687148115;1083.4474418046775;1086.342762628614;11606.709832254177;11652.475638596283;11685.065194784482;11756.470958806874;11780.587679431264;3987.940180897289;4359.673024523174;4579.610733087725;4690.319903613957;4724.385829842167;9857.112859842506;9883.604655813688;9895.109440150422;9913.900860991442;9918.702438926844;5260.079486089995;5277.102892508283;5322.2548843161985;5357.946420535823;5369.624126311199;4413.183561041217;4450.265706158512;4879.731614761151;4893.21708526634;4914.410270767531;3957.0700885291935;3979;4039.2931237033404;4074.246264441088;4097.426360309559;1123.0454114003032;1306.0409385921118;1315.1188703568632;1315.7171208190093;1317.308413083139;731.3454458259828;895.6417433026842;900.9549522523979;961.3676293503371;969.9224062075017;5866.2960444746905;5894.440028197314;5922.111683247461;5968.761249550056;6168.629882207082;441.26969864873683;444.72871548355477;445.1542070066899;454.313985881315;463.53646353646354;3978.6419222270133;4038.172174704179;4052.0275133968225;4127.9174416511505;4166.8749937501925;10252.282340236112;10689.679309620724;10760.3543787373;10761.408122553177;10808;10713.732156696145;10720.195985301134;10725.678229653124;10732.517036733365;10750.892491074988;7753.883691744629;8091.716789912325;8094.392920530866;8136.982877140357;8291.917080829235;4854.980454104678;5349.919751203657;5467.261919640798;5871.354151043467;6043;8210.712625058095;9611;10052.54763535628;10068.949655251678;10071.848922266025;8862.20240178387;9602.471864047266;10136.949315253376;10161.898381016095;10203.7449063774;7563.205863384485;7604.771856844303;7949.8807517886125;8215.34277257806;8395.076541580544;8314.501129932223;8374.748757537283;8416.747497575083;8424.789380265542;8491.36314776382;6595.1096601958125;7343.302386273295;7491.625418729151;7545.056867891513;7583.355414789805;6791.74558472085;7387.963060184879;7430.219826918095;7438.479306448497;7489.101307843093;6894;6895.689693963684;6902;6964.129483814523;7026.683799229149;4856.127638821192;4933.827316043921;5036.269740887477;5087.186050231945;5098.643094983316;5047.217681259411;5080.796768129233;5118.334616499831;5131.897362052663;5209.4530074343165 19:46:22 INFO - PROCESS | 1669 | |4;prototype.html;306.27718584141434;314.84257871064466;320.0207365461687;322.69505317474966;326.0154333911587;4670.781378845047;5312.548433383167;5447.132535899511;5661.216716734231;5703.572232082595;5067.315912351832;5580.525655319292;5598.664080155194;5637.3235211774645;5645.717714114298;3507.9298414031705;3539.7168226541817;4082.8571000015004;4221.176870510244;4256.957430425702;3392.6607339266125;4244.026527664635;4250.447441832557;4261.318189089746;4276.593723596254;4107.219628270634;4150.875473735778;4161.460259703898;4167.562405947378;4193.6645068394455;3513.9648603514147;3587.0852932502175;3625.456181572759;4027.2549578328103;4178.874633760992;3600.027992442052;3601.1897323102094;3625.2024554597947;3626.1116026573395;3667;4961.826336078256;5334.839954801362;5336.51971322579;5449.291592093042;5458.471627944182;3983.721139520235;4339.717918335298;4343.326784348431;4649.86050418488;4815.951840481621;4872.4640289568115;5461.508464238238;5800.390958949334;5909.349971503131;5938.198343223653;3198.408294465508;3634.437192007436;3815.2560250751117;3932.033983008496;3992.8802135935966;4741.90936084699;5044.798208071709;5066.670666406671;6212.534059945522;6231.626102433868;3576.445650924111;3806.9428958565645;3823.655870971626;4024.396340548941;4041.2927737645796;3862.1117143057254;3959.544652365015;4009.8396064157105;4094.1811637672586;4102.958970410317;3961.9896926283614;4202.453681021448;4753.405824271966;4774.092922344744;4777.327935222714;4987.426445958761;5314.751033507123;5939.554533410012;5946.762129514771;5982.414660115073;1262.810578413227;1352.7767918293569;1401.6846209602759;1402.5161319344809;1404.803327534146;758.3213024343293;795.777182388932;821.8191997760483;835.306695442781;846.8433339832151;4174.874753757392;4265.445492086071;4352.042550638855;4644.5616051572515;4698.95301046992;4425.402570652985;4496.7077139985795;5162.6128040397125;5208.427073021964;5231.424543300234;4087.6321131097748;4373.278409062469;4480.401367207012;4623.0966417595555;4757.881052973703;2180.9345719628436;2539.2889990802605;2639.5776675732177;2722.3466368071918;2747.845904720022;4509.909801803946;4659.483878705461;5012.122878496249;5039.571636410873;5337.759800808973;4832.565069143794;5341.866453338697;5353.973230133903;5361.463853614685;5374.813305160539;6130.394582302592;6375.330590287953;6382.266039405527;6413;6424.196975378078;4986.825461108844;5650.434956504317;5742;6134.6625935573975;6184.938150618616;5083.771230294572;5331.013762453958;5446.972765136307;6096.359882212304;6098.176746139215;5405.972970135124;5964.733401307475;6285.654289014148;6376.298607153302;6427.2967663570125;3922.2906734875055;4001.639852413297;4063.167050754529;4067.3695577185586;4074.2258970795456;5249.265102885675;5321.137601839325;5663.262246917015;5717.199592057199;5911.541748077251;5122.616893438825;5310.628256022042;5639.548836093185;5918.141869428995;5943.732532035982;5832.358440571449;5865.706714664335;5882.441168089024;5887.499562537232;5912.61567998096;5155.917257375993;5471.343438787371;5537.916931246115;5545.694986775766;5865.882682346244;5389.865253368697;5742.827715168552;5835.299764028174;5958.34458209588;5990.513936716388;5551.139573366135;5559.972200138974;5875.94124058771;5878.823635290948;5891.381404952418 19:46:22 INFO - PROCESS | 1669 | |5;yui.html;743.3012967810258;761.2919984414492;775.7944144801628;820.1019883227871;875.2953872132932;14877.107373557594;15507.534773956786;15597.766033509506;15787.28957196926;15823.841761582395;14520;14688.339024743886;14690.779638305434;14705.411783528672;14724.263786810643;704.1972151747012;869.3088994249555;885.2386947225381;893.008760276093;893.4773157702751;904.4663648447415;905.1355955062899;911.3483859040784;911.9726408207764;915.555955361649;948.2650945517211;979.4564016970578;980.0640388429068;983.0415345038601;985.8718366612367;916.8028873792167;964.1563631822156;967.1682353176262;967.4872317671575;969.0890562870914;686.5262968551683;712.8574285142992;723.4357201382877;726.5894769455293;727.6761840980765;14944.775828362583;15098.773518397233;15234.923825380913;15250.694986100216;15275.38898444061;14709.485168019175;14729.337179826827;14762.26898023869;14777.261136943218;14951.177685227309;11856.22934509254;12067.939660301643;12349.19730217533;12385.75228495434;12412.379381030913;3810.90472738181;3849.268638958589;4030.6372426481757;4037.7173597848164;4042.43405923171;97948.51025744826;98334;102038.48980755049;102672;102710.48644756728;11427.257228280136;11483.483243254073;11485.425728713531;11589.130815188895;11663.125265605113;21226.620269682422;21350;22525.662115068288;23267.18564850222;23295.7670423297;8097.959510202411;8130.430869839172;8150.673973041012;8196.221358970888;8261.17388261181;4485.372047913294;4540.02389486262;4540.886477838015;4580.129775342741;4620.768961551909;3531.540899683076;3575.4636804479014;3649.9817500912327;3651.6713495785507;3658.835352409148;689.5311188391942;693.2547511425238;729.8795698709654;730.6273800361747;735.1215297719174;478.80608353616145;541.3855274263739;627.8273474794468;644.6615526848442;716.233630015878;9542.809143817085;9730.64826270372;9845.655402060893;9862.408255504693;9969.30214884951;606;650.72344253692;664.3954001858285;687.3058211206617;709.5139829217003;4503.932441013388;4667.13657973276;4693.413323334583;4773.76131193439;4799.6400269979895;144762;147228.26385868216;147637;151998;156595;122345.38827305807;130328;135021;135324;136538;77151.22848771553;113381;116823;117987.8201217994;119044.40477797555;9258.907410925805;9280.72157835266;9293.210077143518;9329.440233586007;9335.766605834908;52222.21666674927;61271.77456450935;63353.68323158355;64416;65804.34195657982;73995;83245.16754832616;86622;86849;88578.11421885957;13229.536966206137;13316.600501984956;13327.533536326182;13596.320183990958;13666.385012674255;14281.14313141215;14612.634684132981;14770.33533490974;14983.175925324213;15076;11389.601363952223;13352.399142038827;14036.508722194674;14394.424223031381;14418.711625767635;13467.932660337028;13959.581212563638;14219.50231741884;14384.424623014962;14470.710585788014;13651.38568764388;13681.863181368059;13801.723965520432;13809.378577963815;14103.224322661941;11163.776724465628;11269.211155219384;11460.255083419552;11580.305181689126;11606.184665527073;10502.842457362993;11324.32054076758;11568.479418426023;11637.650870473899;11640.592579259684 19:46:22 INFO - PROCESS | 1669 | __end_tp_report 19:46:22 INFO - PROCESS | 1669 | __start_cc_report 19:46:22 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,435 19:46:22 INFO - PROCESS | 1669 | __end_cc_report 19:46:22 INFO - PROCESS | 1669 | __startTimestamp1464835582069__endTimestamp 19:46:22 INFO - PROCESS | 1669 | 19:46:22 INFO - PROCESS | 1669 | ------- Summary: start ------- 19:46:22 INFO - PROCESS | 1669 | Number of tests: 6 19:46:22 INFO - PROCESS | 1669 | 19:46:22 INFO - PROCESS | 1669 | [#0] dojo.html Cycles:165 Average:5807.73 Median:6084.30 stddev:660.74 (10.9%) stddev-sans-first:630.02 19:46:22 INFO - PROCESS | 1669 | Values: 3188.9 3461.9 3478.5 3483.4 3533.5 5722.1 5820.2 5836.8 5864.3 5887.4 5702.2 5844.2 5899.7 5913.1 5940.5 5289.1 5297.8 5309.4 5401.5 5430.9 4405.3 5366.2 5387.8 5401.1 5472.6 5226.2 5239.5 5320.2 5361.4 5393.7 5332.3 5348.2 5349.8 5357.4 5358.7 4774.3 5339.7 5351.9 5359.3 5378.6 5437.3 5537.6 5708.7 5733.4 5738.9 6256.8 6284.5 6291.2 6294.6 6348.2 5229.0 6271.8 6330.1 6341.7 6343.9 5923.8 6012.1 6017.6 6032.4 6051.2 5391.8 6343.9 6373.1 6384.3 6387.8 5223.6 6113.0 6149.2 6275.7 6288.5 6232.2 6253.5 6293.2 6303.9 6323.8 4625.7 5279.8 6133.0 6222.2 6260.3 6242.0 6267.4 6288.6 6313.4 6337.6 4950.2 5046.4 5070.4 5094.4 5100.4 4157.2 4319.6 4519.2 4535.1 4572.1 5790.7 5984.2 5984.6 5987.8 5989.9 6188.3 6223.8 6257.6 6307.1 6348.5 5868.2 5909.1 5916.5 5930.8 5965.6 5377.0 5449.2 5493.8 5495.9 5512.4 6376.5 6502.5 6526.9 6536.1 6539.3 6309.6 6312.2 6312.4 6314.9 6321.1 6193.3 6367.5 6370.7 6504.9 6513.1 6239.3 6287.8 6295.5 6316.6 6324.9 6273.5 6292.1 6293.1 6300.2 6300.9 6118.9 6122.9 6132.6 6181.9 6249.7 5872.4 6137.5 6218.8 6226.2 6254.6 5930.1 6084.3 6124.4 6151.0 6176.6 6096.6 6122.2 6151.9 6253.4 6272.9 6100.9 6155.4 6158.2 6191.9 6198.1 19:46:22 INFO - PROCESS | 1669 | 19:46:22 INFO - PROCESS | 1669 | [#1] ext.html Cycles:180 Average:76062.54 Median:42707.79 stddev:168560.53 (394.7%) stddev-sans-first:168937.85 19:46:22 INFO - PROCESS | 1669 | Values: 477.8 503.1 504.7 517.1 518.8 32430.4 33030.5 41063.8 42688.6 42727.0 43837.8 44395.0 44417.8 44427.0 44511.0 9458.2 9693.6 9696.0 9704.1 9713.8 9588.8 9682.2 9732.0 9753.9 9763.6 9750.2 9750.7 9752.7 9760.1 9780.0 7527.4 8872.2 9118.7 9583.8 9692.6 9462.5 9502.3 9541.9 9564.7 9610.3 39990.0 40356.0 40707.2 40942.4 41021.0 3487.3 3522.7 3662.7 3682.6 3741.5 28036.7 31716.0 31853.5 32221.7 33479.0 3227.4 3416.9 3428.4 3431.2 3441.2 495526.0 499093.0 507820.0 509417.0 509787.5 58100.7 68970.7 69959.0 70232.9 74257.3 71768.0 72122.1 72678.0 72714.6 72908.3 16113.8 16165.4 16234.8 16344.0 16375.6 11799.3 12205.8 12291.5 12449.4 12491.3 17509.7 21136.7 21466.7 21471.0 21491.6 7439.6 8667.7 8857.3 8860.0 8971.8 823.8 940.4 1035.4 1203.9 1225.9 30230.0 30533.0 30623.7 31160.7 31179.7 981.4 1094.8 1109.9 1118.1 1132.5 12551.6 15598.0 15852.4 16035.1 16189.8 706911.0 713430.0 718248.0 718824.0 720335.0 59064.7 60187.7 60786.7 60790.4 60836.8 1416.8 1436.5 1461.7 1466.4 1466.7 26766.6 30058.4 30994.2 31009.7 31029.7 51450.7 52017.7 52130.7 52215.0 52384.0 642487.0 648638.0 651529.5 652839.0 654583.0 42914.0 43763.3 44857.8 44863.8 44887.0 48686.8 48785.3 49414.5 49696.8 49970.0 39246.4 40416.8 40615.8 40690.6 41238.0 35143.3 37495.0 39203.4 39660.6 39952.4 32415.8 32666.8 35113.8 38291.0 38692.0 33011.5 34023.5 35055.8 35619.0 35644.6 25317.6 25569.7 25631.0 25768.0 26350.7 19:46:22 INFO - PROCESS | 1669 | 19:46:22 INFO - PROCESS | 1669 | [#2] jquery.html Cycles:180 Average:33768.72 Median:22035.12 stddev:98677.54 (447.8%) stddev-sans-first:98922.51 19:46:22 INFO - PROCESS | 1669 | Values: 384.6 388.4 390.7 391.5 414.1 14081.2 15986.7 17045.9 20539.7 20598.9 16492.6 19666.5 20191.3 21476.7 21538.0 5804.8 5877.3 6110.7 6120.0 6156.0 6039.1 6086.7 6104.5 6107.8 6117.7 5679.6 6001.3 6003.8 6003.8 6074.3 5871.8 5961.4 5984.1 5988.1 5990.2 5048.1 5198.8 5231.1 5232.6 5250.2 20722.5 22569.4 24775.0 25572.0 25957.5 2236.5 2254.7 2269.4 2280.8 2334.4 15459.1 15732.8 15904.0 16061.5 16283.5 1853.0 1894.9 1900.0 1921.0 1965.9 115458.0 118075.0 119187.0 119668.0 119764.0 10554.7 10772.8 10944.2 11014.1 11038.2 11346.1 13704.7 13734.7 13790.4 13814.7 8179.4 8352.6 8357.3 8435.6 8451.3 8197.2 8246.8 8248.4 8318.6 8356.4 5073.1 5597.6 6239.4 6607.5 6779.7 1847.1 1852.8 1884.5 1908.7 1909.6 942.2 991.2 1012.6 1055.3 1134.9 18480.9 19337.0 21653.0 21941.7 22128.6 869.6 979.7 1000.2 1013.3 1027.7 11341.2 11799.8 11808.8 11828.5 11860.0 580001.0 594035.0 600875.0 601384.0 602637.0 65349.4 80203.0 80721.0 113832.4 125911.0 932.4 984.5 1004.5 1005.1 1006.7 18111.5 19168.0 19677.7 19775.7 19888.0 38773.0 48765.8 50062.0 50324.2 51750.7 70425.0 71076.0 71435.6 71476.0 72518.6 7060.9 7411.3 7533.5 7771.4 8098.6 11701.5 12976.5 13054.7 13247.6 13358.9 11326.0 11366.6 11528.4 11584.7 11678.2 11249.4 11373.0 11441.4 11515.2 11705.4 11432.4 11617.4 11791.2 11818.8 11949.0 7543.9 7598.3 7609.3 7622.0 7739.6 11438.7 11479.7 11653.9 11720.6 11767.5 19:46:22 INFO - PROCESS | 1669 | 19:46:22 INFO - PROCESS | 1669 | [#3] mootools.html Cycles:180 Average:5681.92 Median:4886.47 stddev:3197.37 (65.4%) stddev-sans-first:3191.02 19:46:22 INFO - PROCESS | 1669 | Values: 1516.6 1548.8 1747.4 1867.0 1925.1 7995.3 8517.7 8579.0 8965.4 8984.8 7551.4 8667.0 8767.9 8841.8 8849.1 2342.2 2363.8 2375.1 2376.5 2382.9 2051.6 2347.2 2360.1 2395.2 2403.8 1968.7 2343.7 2362.8 2367.6 2383.0 2326.7 2350.2 2361.1 2361.8 2367.8 2326.6 2353.9 2360.6 2366.7 2380.5 8765.6 8834.3 8843.0 8851.6 8868.0 5215.2 5220.0 5386.9 5387.1 5392.7 3494.1 3599.1 3672.9 3673.3 3677.3 1035.2 1052.1 1075.9 1083.4 1086.3 11606.7 11652.5 11685.1 11756.5 11780.6 3987.9 4359.7 4579.6 4690.3 4724.4 9857.1 9883.6 9895.1 9913.9 9918.7 5260.1 5277.1 5322.3 5357.9 5369.6 4413.2 4450.3 4879.7 4893.2 4914.4 3957.1 3979.0 4039.3 4074.2 4097.4 1123.0 1306.0 1315.1 1315.7 1317.3 731.3 895.6 901.0 961.4 969.9 5866.3 5894.4 5922.1 5968.8 6168.6 441.3 444.7 445.2 454.3 463.5 3978.6 4038.2 4052.0 4127.9 4166.9 10252.3 10689.7 10760.4 10761.4 10808.0 10713.7 10720.2 10725.7 10732.5 10750.9 7753.9 8091.7 8094.4 8137.0 8291.9 4855.0 5349.9 5467.3 5871.4 6043.0 8210.7 9611.0 10052.5 10068.9 10071.8 8862.2 9602.5 10136.9 10161.9 10203.7 7563.2 7604.8 7949.9 8215.3 8395.1 8314.5 8374.7 8416.7 8424.8 8491.4 6595.1 7343.3 7491.6 7545.1 7583.4 6791.7 7388.0 7430.2 7438.5 7489.1 6894.0 6895.7 6902.0 6964.1 7026.7 4856.1 4933.8 5036.3 5087.2 5098.6 5047.2 5080.8 5118.3 5131.9 5209.5 19:46:22 INFO - PROCESS | 1669 | 19:46:22 INFO - PROCESS | 1669 | [#4] prototype.html Cycles:180 Average:4551.47 Median:4999.77 stddev:1444.09 (28.9%) stddev-sans-first:1412.55 19:46:22 INFO - PROCESS | 1669 | Values: 306.3 314.8 320.0 322.7 326.0 4670.8 5312.5 5447.1 5661.2 5703.6 5067.3 5580.5 5598.7 5637.3 5645.7 3507.9 3539.7 4082.9 4221.2 4257.0 3392.7 4244.0 4250.4 4261.3 4276.6 4107.2 4150.9 4161.5 4167.6 4193.7 3514.0 3587.1 3625.5 4027.3 4178.9 3600.0 3601.2 3625.2 3626.1 3667.0 4961.8 5334.8 5336.5 5449.3 5458.5 3983.7 4339.7 4343.3 4649.9 4816.0 4872.5 5461.5 5800.4 5909.3 5938.2 3198.4 3634.4 3815.3 3932.0 3992.9 4741.9 5044.8 5066.7 6212.5 6231.6 3576.4 3806.9 3823.7 4024.4 4041.3 3862.1 3959.5 4009.8 4094.2 4103.0 3962.0 4202.5 4753.4 4774.1 4777.3 4987.4 5314.8 5939.6 5946.8 5982.4 1262.8 1352.8 1401.7 1402.5 1404.8 758.3 795.8 821.8 835.3 846.8 4174.9 4265.4 4352.0 4644.6 4699.0 4425.4 4496.7 5162.6 5208.4 5231.4 4087.6 4373.3 4480.4 4623.1 4757.9 2180.9 2539.3 2639.6 2722.3 2747.8 4509.9 4659.5 5012.1 5039.6 5337.8 4832.6 5341.9 5354.0 5361.5 5374.8 6130.4 6375.3 6382.3 6413.0 6424.2 4986.8 5650.4 5742.0 6134.7 6184.9 5083.8 5331.0 5447.0 6096.4 6098.2 5406.0 5964.7 6285.7 6376.3 6427.3 3922.3 4001.6 4063.2 4067.4 4074.2 5249.3 5321.1 5663.3 5717.2 5911.5 5122.6 5310.6 5639.5 5918.1 5943.7 5832.4 5865.7 5882.4 5887.5 5912.6 5155.9 5471.3 5537.9 5545.7 5865.9 5389.9 5742.8 5835.3 5958.3 5990.5 5551.1 5560.0 5875.9 5878.8 5891.4 19:46:22 INFO - PROCESS | 1669 | 19:46:22 INFO - PROCESS | 1669 | [#5] yui.html Cycles:180 Average:24618.13 Median:3655.25 stddev:38974.75 (1066.3%) stddev-sans-first:39042.86 19:46:22 INFO - PROCESS | 1669 | Values: 743.3 761.3 775.8 820.1 875.3 14877.1 15507.5 15597.8 15787.3 15823.8 14520.0 14688.3 14690.8 14705.4 14724.3 704.2 869.3 885.2 893.0 893.5 904.5 905.1 911.3 912.0 915.6 948.3 979.5 980.1 983.0 985.9 916.8 964.2 967.2 967.5 969.1 686.5 712.9 723.4 726.6 727.7 14944.8 15098.8 15234.9 15250.7 15275.4 14709.5 14729.3 14762.3 14777.3 14951.2 11856.2 12067.9 12349.2 12385.8 12412.4 3810.9 3849.3 4030.6 4037.7 4042.4 97948.5 98334.0 102038.5 102672.0 102710.5 11427.3 11483.5 11485.4 11589.1 11663.1 21226.6 21350.0 22525.7 23267.2 23295.8 8098.0 8130.4 8150.7 8196.2 8261.2 4485.4 4540.0 4540.9 4580.1 4620.8 3531.5 3575.5 3650.0 3651.7 3658.8 689.5 693.3 729.9 730.6 735.1 478.8 541.4 627.8 644.7 716.2 9542.8 9730.6 9845.7 9862.4 9969.3 606.0 650.7 664.4 687.3 709.5 4503.9 4667.1 4693.4 4773.8 4799.6 144762.0 147228.3 147637.0 151998.0 156595.0 122345.4 130328.0 135021.0 135324.0 136538.0 77151.2 113381.0 116823.0 117987.8 119044.4 9258.9 9280.7 9293.2 9329.4 9335.8 52222.2 61271.8 63353.7 64416.0 65804.3 73995.0 83245.2 86622.0 86849.0 88578.1 13229.5 13316.6 13327.5 13596.3 13666.4 14281.1 14612.6 14770.3 14983.2 15076.0 11389.6 13352.4 14036.5 14394.4 14418.7 13467.9 13959.6 14219.5 14384.4 14470.7 13651.4 13681.9 13801.7 13809.4 14103.2 11163.8 11269.2 11460.3 11580.3 11606.2 10502.8 11324.3 11568.5 11637.7 11640.6 19:46:22 INFO - PROCESS | 1669 | -------- Summary: end -------- 19:46:22 INFO - PROCESS | 1669 | 19:46:22 INFO - TEST-INFO | 1669: exit 0 19:46:22 INFO - TEST-OK | dromaeo_css | took 1113433ms 19:46:22 INFO - TEST-START | kraken 19:46:22 INFO - operating with platform_type : mac_ 19:46:22 INFO - Initialising browser for kraken test... 19:46:22 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/tmpkaVujb/profile http://localhost:49279/getInfo.html) 19:46:25 INFO - PROCESS | 1678 | __metrics Screen width/height:1600/1200 19:46:25 INFO - PROCESS | 1678 | colorDepth:24 19:46:25 INFO - PROCESS | 1678 | Browser inner width/height: 1024/689 19:46:25 INFO - PROCESS | 1678 | __metrics 19:46:25 ERROR - PROCESS | 1678 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2938: TypeError: this.tabs is undefined 19:46:26 INFO - TEST-INFO | 1678: exit 0 19:46:26 INFO - Browser initialized. 19:46:26 INFO - Running cycle 1/1 for kraken test... 19:46:26 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/T/tmpkaVujb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:46:38 INFO - PROCESS | 1680 | RSS: Main: 253046784 19:46:38 INFO - PROCESS | 1680 | 19:46:41 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection) 19:46:42 INFO - PROCESS | 1680 | RSS: Main: 265580544 19:46:42 INFO - PROCESS | 1680 | 19:46:44 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-dft) 19:46:45 INFO - PROCESS | 1680 | RSS: Main: 270114816 19:46:45 INFO - PROCESS | 1680 | 19:46:49 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-fft) 19:46:49 INFO - PROCESS | 1680 | RSS: Main: 270233600 19:46:49 INFO - PROCESS | 1680 | 19:46:51 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator) 19:46:52 INFO - PROCESS | 1680 | RSS: Main: 271073280 19:46:52 INFO - PROCESS | 1680 | 19:46:54 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:46:54 INFO - PROCESS | 1680 | RSS: Main: 272232448 19:46:54 INFO - PROCESS | 1680 | 19:46:57 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom) 19:46:58 INFO - PROCESS | 1680 | RSS: Main: 272539648 19:46:58 INFO - PROCESS | 1680 | 19:47:01 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate) 19:47:02 INFO - PROCESS | 1680 | RSS: Main: 272879616 19:47:02 INFO - PROCESS | 1680 | 19:47:05 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial) 19:47:06 INFO - PROCESS | 1680 | RSS: Main: 272846848 19:47:06 INFO - PROCESS | 1680 | 19:47:07 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:47:08 INFO - PROCESS | 1680 | RSS: Main: 272666624 19:47:08 INFO - PROCESS | 1680 | 19:47:10 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:47:10 INFO - PROCESS | 1680 | RSS: Main: 272121856 19:47:10 INFO - PROCESS | 1680 | 19:47:13 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:47:13 INFO - PROCESS | 1680 | RSS: Main: 272531456 19:47:13 INFO - PROCESS | 1680 | 19:47:16 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:47:17 INFO - PROCESS | 1680 | RSS: Main: 272535552 19:47:17 INFO - PROCESS | 1680 | 19:47:20 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:47:21 INFO - PROCESS | 1680 | RSS: Main: 272457728 19:47:21 INFO - PROCESS | 1680 | 19:47:22 INFO - PROCESS | 1680 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49279/tests/kraken/driver.html?testName=ai-astar) 19:47:23 INFO - PROCESS | 1680 | RSS: Main: 273453056 19:47:23 INFO - PROCESS | 1680 | 19:47:23 INFO - PROCESS | 1680 | __start_tp_report 19:47:23 INFO - PROCESS | 1680 | _x_x_mozilla_page_load 19:47:23 INFO - PROCESS | 1680 | _x_x_mozilla_page_load_details 19:47:23 INFO - PROCESS | 1680 | |i|pagename|runs| 19:47:23 INFO - PROCESS | 1680 | |0;ai-astar;116;109;117;115;113;115;98;172;168;132 19:47:23 INFO - PROCESS | 1680 | |1;audio-beat-detection;160;143;149;149;108;140;160;149;206;138 19:47:23 INFO - PROCESS | 1680 | |2;audio-dft;197;203;267;267;186;201;181;268;284;236 19:47:23 INFO - PROCESS | 1680 | |3;audio-fft;73;81;75;146;58;58;58;61;133;61 19:47:23 INFO - PROCESS | 1680 | |4;audio-oscillator;71;73;121;71;74;80;117;71;73;115 19:47:23 INFO - PROCESS | 1680 | |5;imaging-gaussian-blur;88;86;89;85;91;85;88;123;92;132 19:47:23 INFO - PROCESS | 1680 | |6;imaging-darkroom;104;97;151;139;95;119;153;117;155;112 19:47:23 INFO - PROCESS | 1680 | |7;imaging-desaturate;94;85;146;128;95;95;99;145;75;135 19:47:23 INFO - PROCESS | 1680 | |8;json-parse-financial;56;56;57;56;56;58;58;55;58;56 19:47:23 INFO - PROCESS | 1680 | |9;json-stringify-tinderbox;65;73;76;69;76;78;76;103;96;71 19:47:23 INFO - PROCESS | 1680 | |10;stanford-crypto-aes;96;116;110;106;91;89;106;106;116;89 19:47:23 INFO - PROCESS | 1680 | |11;stanford-crypto-ccm;191;175;144;138;139;141;138;141;182;156 19:47:23 INFO - PROCESS | 1680 | |12;stanford-crypto-pbkdf2;166;162;176;231;184;171;165;181;162;225 19:47:23 INFO - PROCESS | 1680 | |13;stanford-crypto-sha256-iterative;66;69;66;68;67;68;64;68;67;77 19:47:23 INFO - PROCESS | 1680 | __end_tp_report 19:47:23 INFO - PROCESS | 1680 | __start_cc_report 19:47:23 INFO - PROCESS | 1680 | _x_x_mozilla_cycle_collect,1090 19:47:23 INFO - PROCESS | 1680 | __end_cc_report 19:47:23 INFO - PROCESS | 1680 | __startTimestamp1464835643088__endTimestamp 19:47:23 INFO - PROCESS | 1680 | 19:47:23 INFO - PROCESS | 1680 | ------- Summary: start ------- 19:47:23 INFO - PROCESS | 1680 | Number of tests: 14 19:47:23 INFO - PROCESS | 1680 | 19:47:23 INFO - PROCESS | 1680 | [#0] ai-astar Cycles:10 Average:125.50 Median:124.50 stddev:24.90 (20.0%) stddev-sans-first:26.17 19:47:23 INFO - PROCESS | 1680 | Values: 116.0 109.0 117.0 115.0 113.0 115.0 98.0 172.0 168.0 132.0 19:47:23 INFO - PROCESS | 1680 | 19:47:23 INFO - PROCESS | 1680 | [#1] audio-beat-detection Cycles:10 Average:150.20 Median:149.00 stddev:24.48 (16.4%) stddev-sans-first:25.71 19:47:23 INFO - PROCESS | 1680 | Values: 160.0 143.0 149.0 149.0 108.0 140.0 160.0 149.0 206.0 138.0 19:47:23 INFO - PROCESS | 1680 | 19:47:23 INFO - PROCESS | 1680 | [#2] audio-dft Cycles:10 Average:229.00 Median:251.50 stddev:39.61 (15.7%) stddev-sans-first:40.28 19:47:23 INFO - PROCESS | 1680 | Values: 197.0 203.0 267.0 267.0 186.0 201.0 181.0 268.0 284.0 236.0 19:47:23 INFO - PROCESS | 1680 | 19:47:23 INFO - PROCESS | 1680 | [#3] audio-fft Cycles:10 Average:80.40 Median:61.00 stddev:32.34 (53.0%) stddev-sans-first:34.19 19:47:23 INFO - PROCESS | 1680 | Values: 73.0 81.0 75.0 146.0 58.0 58.0 58.0 61.0 133.0 61.0 19:47:23 INFO - PROCESS | 1680 | 19:47:23 INFO - PROCESS | 1680 | [#4] audio-oscillator Cycles:10 Average:86.60 Median:72.00 stddev:21.64 (30.1%) stddev-sans-first:22.21 19:47:23 INFO - PROCESS | 1680 | Values: 71.0 73.0 121.0 71.0 74.0 80.0 117.0 71.0 73.0 115.0 19:47:23 INFO - PROCESS | 1680 | 19:47:23 INFO - PROCESS | 1680 | [#5] imaging-gaussian-blur Cycles:10 Average:95.90 Median:88.00 stddev:16.95 (19.3%) stddev-sans-first:17.73 19:47:23 INFO - PROCESS | 1680 | Values: 88.0 86.0 89.0 85.0 91.0 85.0 88.0 123.0 92.0 132.0 19:47:23 INFO - PROCESS | 1680 | 19:47:23 INFO - PROCESS | 1680 | [#6] imaging-darkroom Cycles:10 Average:124.20 Median:152.00 stddev:23.44 (15.4%) stddev-sans-first:23.69 19:47:23 INFO - PROCESS | 1680 | Values: 104.0 97.0 151.0 139.0 95.0 119.0 153.0 117.0 155.0 112.0 19:47:23 INFO - PROCESS | 1680 | 19:47:23 INFO - PROCESS | 1680 | [#7] imaging-desaturate Cycles:10 Average:109.70 Median:89.50 stddev:26.13 (29.2%) stddev-sans-first:27.09 19:47:23 INFO - PROCESS | 1680 | Values: 94.0 85.0 146.0 128.0 95.0 95.0 99.0 145.0 75.0 135.0 19:47:23 INFO - PROCESS | 1680 | 19:47:23 INFO - PROCESS | 1680 | [#8] json-parse-financial Cycles:10 Average:56.60 Median:56.50 stddev:1.07 (1.9%) stddev-sans-first:1.12 19:47:23 INFO - PROCESS | 1680 | Values: 56.0 56.0 57.0 56.0 56.0 58.0 58.0 55.0 58.0 56.0 19:47:23 INFO - PROCESS | 1680 | 19:47:23 INFO - PROCESS | 1680 | [#9] json-stringify-tinderbox Cycles:10 Average:78.30 Median:76.00 stddev:11.94 (15.7%) stddev-sans-first:11.66 19:47:23 INFO - PROCESS | 1680 | Values: 65.0 73.0 76.0 69.0 76.0 78.0 76.0 103.0 96.0 71.0 19:47:23 INFO - PROCESS | 1680 | 19:47:23 INFO - PROCESS | 1680 | [#10] stanford-crypto-aes Cycles:10 Average:102.50 Median:102.50 stddev:10.52 (10.3%) stddev-sans-first:10.89 19:47:23 INFO - PROCESS | 1680 | Values: 96.0 116.0 110.0 106.0 91.0 89.0 106.0 106.0 116.0 89.0 19:47:23 INFO - PROCESS | 1680 | 19:47:23 INFO - PROCESS | 1680 | [#11] stanford-crypto-ccm Cycles:10 Average:154.50 Median:150.00 stddev:20.47 (13.6%) stddev-sans-first:16.92 19:47:23 INFO - PROCESS | 1680 | Values: 191.0 175.0 144.0 138.0 139.0 141.0 138.0 141.0 182.0 156.0 19:47:23 INFO - PROCESS | 1680 | 19:47:23 INFO - PROCESS | 1680 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.30 Median:178.50 stddev:25.29 (14.2%) stddev-sans-first:26.13 19:47:23 INFO - PROCESS | 1680 | Values: 166.0 162.0 176.0 231.0 184.0 171.0 165.0 181.0 162.0 225.0 19:47:23 INFO - PROCESS | 1680 | 19:47:23 INFO - PROCESS | 1680 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.00 Median:68.00 stddev:3.46 (5.1%) stddev-sans-first:3.60 19:47:23 INFO - PROCESS | 1680 | Values: 66.0 69.0 66.0 68.0 67.0 68.0 64.0 68.0 67.0 77.0 19:47:23 INFO - PROCESS | 1680 | -------- Summary: end -------- 19:47:23 INFO - PROCESS | 1680 | 19:47:23 INFO - TEST-INFO | 1680: exit 0 19:47:23 INFO - TEST-OK | kraken | took 61257ms 19:47:23 INFO - SUITE-END | took 1174s 19:47:24 INFO - Completed test suite (00:19:34) 19:47:24 INFO - javascript benchmark 19:47:24 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7446.966745289858, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3188.9202769930757, 3461.948070778939, 3478.5477887874604, 3483.425234836252, 3533.4523148911926, 5722.141678748189, 5820.156077368779, 5836.795712150076, 5864.266966629171, 5887.381824908387, 5702.173184888189, 5844.1818145459665, 5899.734511946962, 5913.083472061827, 5940.465358117768, 5289.074411977917, 5297.783246343454, 5309.442508536606, 5401.4868587484325, 5430.864228394283, 4405.31717583775, 5366.248725178478, 5387.838364849041, 5401.054815407308, 5472.644278121929, 5226.163813789775, 5239.476052394768, 5320.228566857784, 5361.383440904307, 5393.703346315951, 5332.280142180794, 5348.197770334442, 5349.839504814861, 5357.4374690657305, 5358.732063396815, 4774.3077253798, 5339.652922560022, 5351.892962140774, 5359.276497672842, 5378.623496355257, 5437.265969094161, 5537.556995440355, 5708.657480551139, 5733.426657334275, 5738.885222295572, 6256.812295631093, 6284.497240220817, 6291.245050593958, 6294.590851594632, 6348.17473728417, 5229.0, 6271.78048768291, 6330.082138089997, 6341.682915854235, 6343.904841427382, 5923.792667256668, 6012.128241405059, 6017.578769486182, 6032.396760323976, 6051.152838602599, 5391.757370918318, 6343.936560634427, 6373.139626150503, 6384.297727249998, 6387.776427825004, 5223.608229382736, 6113.020255720364, 6149.200603921461, 6275.748970041147, 6288.502683087856, 6232.220972378453, 6253.46845518127, 6293.1504246927, 6303.936960630427, 6323.7786677466065, 4625.699329543569, 5279.838435544174, 6133.0, 6222.191115155091, 6260.311365749764, 6242.001279795211, 6267.373262673696, 6288.591241569284, 6313.43179113882, 6337.556371053982, 4950.158473059517, 5046.419661738873, 5070.442251352349, 5094.363204599425, 5100.362454693163, 4157.230912281238, 4319.57454040166, 4519.186546421675, 4535.092981403733, 4572.0855828834365, 5790.71046447676, 5984.222051133324, 5984.611000284967, 5987.820365389045, 5989.940100598938, 6188.319284878659, 6223.782167624202, 6257.5932564383165, 6307.085472606538, 6348.460380867586, 5868.207791948118, 5909.113632955091, 5916.467517923407, 5930.792422265286, 5965.642061476326, 5377.032134215879, 5449.2371068049715, 5493.752781124859, 5495.890082198333, 5512.448755124536, 6376.458001069961, 6502.479801615954, 6526.869462610626, 6536.084948107174, 6539.346065393423, 6309.558330916975, 6312.179416675803, 6312.368763123651, 6314.905276420949, 6321.146645202839, 6193.287771906288, 6367.522435817425, 6370.745170193326, 6504.934950650433, 6513.120728701565, 6239.282482514569, 6287.811365659038, 6295.496360291258, 6316.621002739666, 6324.936750632619, 6273.466755325849, 6292.087647291026, 6293.118963345044, 6300.180976473029, 6300.936990630218, 6118.923762351488, 6122.947104152957, 6132.5707200495535, 6181.907271391023, 6249.6875656112725, 5872.383399743137, 6137.499563105042, 6218.751249949952, 6226.1905952225925, 6254.562180647493, 5930.11048342752, 6084.300305464928, 6124.38756124384, 6151.0, 6176.567640265138, 6096.573239873166, 6122.15420935445, 6151.8926593213655, 6253.437190652863, 6272.891256227179, 6100.944524685144, 6155.353687862889, 6158.168647232746, 6191.938080619317, 6198.132261483306], "value": 5767.094146925399, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [477.84708893154203, 503.1094961917405, 504.722402678527, 517.1079887194588, 518.7872972081448, 32430.351392972127, 33030.50454243185, 41063.79468102659, 42688.573114268846, 42727.0, 43837.7808110959, 44395.0, 44417.77791111048, 44427.0, 44511.0, 9458.243340532757, 9693.563789629483, 9696.030396960283, 9704.078112579293, 9713.805723885518, 9588.760280992961, 9682.177014953702, 9731.95134024329, 9753.853692194623, 9763.560639771193, 9750.219982401426, 9750.658726944559, 9752.707418777449, 9760.07279308468, 9779.951100244489, 7527.435442341792, 8872.245859101984, 9118.726438206864, 9583.760405989837, 9692.563834627459, 9462.52687365636, 9502.334836561444, 9541.904580954171, 9564.713058608253, 9610.327277090606, 39990.00024999369, 40356.0, 40707.18585628271, 40942.38586421206, 41021.0, 3487.2676737885076, 3522.7181825453904, 3662.688671462929, 3682.558093028854, 3741.4574886641285, 28036.719632803615, 31716.04851854448, 31853.52219716706, 32221.6777832221, 33479.0, 3227.4190645684002, 3416.9145771355666, 3428.4171690812623, 3431.24512607226, 3441.2429265561536, 495526.0447395516, 499093.0, 507820.0, 509417.0, 509787.451062746, 58100.70949645225, 68970.65514672395, 69959.0, 70232.94650580245, 74257.25742742504, 71768.0, 72122.11511539585, 72678.0, 72714.63642681752, 72908.27091729121, 16113.758293625606, 16165.434209802836, 16234.75647865283, 16344.0, 16375.59061023484, 11799.292042477478, 12205.755884882252, 12291.508339666492, 12449.377531123408, 12491.312977786309, 17509.7373539397, 21136.682949755766, 21466.677999829702, 21471.0, 21491.57016859654, 7439.590822504706, 8667.653293868301, 8857.29141668665, 8860.0, 8971.820563588692, 823.81052357958, 940.3887473142375, 1035.3787727363522, 1203.867574566797, 1225.871283515218, 30230.0, 30533.0, 30623.69376306253, 31160.688393116234, 31179.68820311768, 981.3866333541537, 1094.7591529863416, 1109.8501702270253, 1118.0887576625187, 1132.5130194016651, 12551.623451296477, 15598.0, 15852.44516441919, 16035.11806850611, 16189.757153642704, 706911.0, 713430.0, 718248.0, 718824.0, 720335.0, 59064.70467647634, 60187.699061505285, 60786.69606652027, 60790.392096079355, 60836.783264335354, 1416.8441471438134, 1436.547487541421, 1461.7295800276984, 1466.3621324723779, 1466.74331991901, 26766.598501022112, 30058.363330454693, 30994.225144371572, 31009.68990310068, 31029.68970310268, 51450.74274628603, 52017.69955751136, 52130.739346303024, 52215.0, 52384.0, 642487.0, 648638.0, 651529.4847051658, 652839.0, 654583.0, 42914.0, 43763.343549847414, 44857.775711121234, 44863.775681121384, 44886.960173584535, 48686.75656621694, 48785.268220976, 49414.50585494099, 49696.751516242184, 49970.000599988525, 39246.4113038299, 40416.79791601023, 40615.78152655425, 40690.59309406868, 41237.96905077277, 35143.297134057684, 37495.0, 39203.41194882022, 39660.60339396569, 39952.40071398873, 32415.837920810245, 32666.836665816518, 35113.824430877685, 38291.0, 38692.0, 33011.504827427125, 34023.48964765481, 35055.82472087623, 35619.0, 35644.643553564136, 25317.62023569611, 25569.74430255748, 25631.0, 25768.0, 26350.736492634827], "value": 20897.80082506874, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.6192269653043, 388.3689005366279, 390.7030656700907, 391.48128729433506, 414.12619373122703, 14081.155130692166, 15986.680266394666, 17045.914770426145, 20539.691904621443, 20598.86706231157, 16492.587685307844, 19666.508337291576, 20191.2933047343, 21476.677849832264, 21538.0, 5804.767809287623, 5877.265341832271, 6110.694465276741, 6119.969400152993, 6155.969220153893, 6039.063945088518, 6086.665233412161, 6104.542159338067, 6107.847303817418, 6117.724702388381, 5679.602427830054, 6001.309849367335, 6003.819885403423, 6003.849903752397, 6074.301455332627, 5871.765129394819, 5961.433663801932, 5984.132300816401, 5988.131720900488, 5990.221271234734, 5048.091343558158, 5198.844034678966, 5231.136862417697, 5232.607554433394, 5250.212468129773, 20722.48193795152, 22569.435764105863, 24775.00899963999, 25572.0, 25957.480850382886, 2236.452069243046, 2254.7060260058697, 2269.3645779181857, 2280.8289378296527, 2334.3580515358412, 15459.072455652584, 15732.842671573253, 15904.045757254602, 16061.518154455385, 16283.511494655178, 1852.97220541692, 1894.886306821595, 1900.0, 1920.9519762005923, 1965.8722183058055, 115458.0, 118075.0, 119187.0, 119668.0, 119764.0, 10554.736131596617, 10772.838407423895, 10944.23390362667, 11014.118870490502, 11038.227324087236, 11346.149038822121, 13704.65738356549, 13734.725305494034, 13790.379432925542, 13814.723705525834, 8179.4274400792565, 8352.624131914079, 8357.331413486907, 8435.620397082022, 8451.281641060456, 8197.221263979913, 8246.752597422086, 8248.381371397169, 8318.625661845232, 8356.373272004625, 5073.061483625541, 5597.552195824324, 6239.377761781879, 6607.471402287805, 6779.694913728796, 1847.1133855749047, 1852.777666680007, 1884.4535084825247, 1908.7327774111636, 1909.634611252961, 942.1661829281136, 991.1723710701501, 1012.5747186181674, 1055.3034996902009, 1134.8881466136681, 18480.907595462206, 19337.0, 21653.0, 21941.68349899011, 22128.557428851655, 869.6390997735995, 979.6571200079915, 1000.1598657127902, 1013.3008224325192, 1027.6660085472251, 11341.206115571997, 11799.764004719858, 11808.763824723457, 11828.52685892572, 11860.0, 580001.0, 594035.0, 600875.0, 601384.0, 602637.0, 65349.38602455943, 80203.0, 80721.0, 113832.43083784694, 125911.0, 932.4172392254841, 984.4634674102774, 1004.4826914139078, 1005.1205195453978, 1006.7030226083323, 18111.547211319823, 19168.0, 19677.704834427208, 19775.70336444926, 19888.0, 38773.0, 48765.75617121892, 50062.0, 50324.24513632225, 51750.741246293524, 70425.0, 71076.0, 71435.64282178556, 71476.0, 72518.63740681263, 7060.8940865886025, 7411.332980031823, 7533.472656913963, 7771.417143714131, 8098.595070246346, 11701.531938722355, 12976.545820896223, 13054.673633159247, 13247.602571922858, 13358.864496517905, 11326.037286830711, 11366.602168924048, 11528.423578821194, 11584.710382240175, 11678.182527223353, 11249.437528123724, 11373.0, 11441.370724610226, 11515.19393642437, 11705.356205408785, 11432.428378581204, 11617.419129043683, 11791.17236828293, 11818.82271765907, 11949.0, 7543.886841697488, 7598.278163574451, 7609.315161635479, 7622.0, 7739.5743234122665, 11438.656840294805, 11479.655610331703, 11653.88346116528, 11720.589779357682, 11767.470463828977], "value": 9616.820465553863, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1516.5601975427126, 1548.78317035615, 1747.4146161036053, 1867.0011543824048, 1925.1048262557924, 7995.320397766182, 8517.701880434186, 8579.0, 8965.372423930336, 8984.775380615489, 7551.395888328934, 8667.046624871258, 8767.868481972775, 8841.778955526115, 8849.115088491164, 2342.2387723989686, 2363.846349987254, 2375.061850569024, 2376.5128148729555, 2382.892769825362, 2051.569170474202, 2347.1784875293683, 2360.114956891166, 2395.185636883459, 2403.759624037591, 1968.6850103983365, 2343.6953196084487, 2362.83460157789, 2367.550165468564, 2382.9761702383016, 2326.6510023496444, 2350.177437896731, 2361.0673783855455, 2361.8110551155864, 2367.7868991790733, 2326.639370897514, 2353.9058437662475, 2360.586897292984, 2366.7041619797524, 2380.488195038075, 8765.561721913942, 8834.337424693109, 8842.955785221096, 8851.60167792451, 8868.02451730309, 5215.191645294947, 5220.0, 5386.865328366822, 5387.084195686703, 5392.676439413648, 3494.109002204422, 3599.0822340303316, 3672.8714494992546, 3673.338799016176, 3677.3196958562753, 1035.2390992620417, 1052.0584077250894, 1075.9031687148115, 1083.4474418046775, 1086.342762628614, 11606.709832254177, 11652.475638596283, 11685.065194784482, 11756.470958806874, 11780.587679431264, 3987.940180897289, 4359.673024523174, 4579.610733087725, 4690.319903613957, 4724.385829842167, 9857.112859842506, 9883.604655813688, 9895.109440150422, 9913.900860991442, 9918.702438926844, 5260.079486089995, 5277.102892508283, 5322.2548843161985, 5357.946420535823, 5369.624126311199, 4413.183561041217, 4450.265706158512, 4879.731614761151, 4893.21708526634, 4914.410270767531, 3957.0700885291935, 3979.0, 4039.2931237033404, 4074.246264441088, 4097.426360309559, 1123.0454114003032, 1306.0409385921118, 1315.1188703568632, 1315.7171208190093, 1317.308413083139, 731.3454458259828, 895.6417433026842, 900.9549522523979, 961.3676293503371, 969.9224062075017, 5866.2960444746905, 5894.440028197314, 5922.111683247461, 5968.761249550056, 6168.629882207082, 441.26969864873683, 444.72871548355477, 445.1542070066899, 454.313985881315, 463.53646353646354, 3978.6419222270133, 4038.172174704179, 4052.0275133968225, 4127.9174416511505, 4166.8749937501925, 10252.282340236112, 10689.679309620724, 10760.3543787373, 10761.408122553177, 10808.0, 10713.732156696145, 10720.195985301134, 10725.678229653124, 10732.517036733365, 10750.892491074988, 7753.883691744629, 8091.716789912325, 8094.392920530866, 8136.982877140357, 8291.917080829235, 4854.980454104678, 5349.919751203657, 5467.261919640798, 5871.354151043467, 6043.0, 8210.712625058095, 9611.0, 10052.54763535628, 10068.949655251678, 10071.848922266025, 8862.20240178387, 9602.471864047266, 10136.949315253376, 10161.898381016095, 10203.7449063774, 7563.205863384485, 7604.771856844303, 7949.8807517886125, 8215.34277257806, 8395.076541580544, 8314.501129932223, 8374.748757537283, 8416.747497575083, 8424.789380265542, 8491.36314776382, 6595.1096601958125, 7343.302386273295, 7491.625418729151, 7545.056867891513, 7583.355414789805, 6791.74558472085, 7387.963060184879, 7430.219826918095, 7438.479306448497, 7489.101307843093, 6894.0, 6895.689693963684, 6902.0, 6964.129483814523, 7026.683799229149, 4856.127638821192, 4933.827316043921, 5036.269740887477, 5087.186050231945, 5098.643094983316, 5047.217681259411, 5080.796768129233, 5118.334616499831, 5131.897362052663, 5209.4530074343165], "value": 4494.9478044663465, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.27718584141434, 314.84257871064466, 320.0207365461687, 322.69505317474966, 326.0154333911587, 4670.781378845047, 5312.548433383167, 5447.132535899511, 5661.216716734231, 5703.572232082595, 5067.315912351832, 5580.525655319292, 5598.664080155194, 5637.3235211774645, 5645.717714114298, 3507.9298414031705, 3539.7168226541817, 4082.8571000015004, 4221.176870510244, 4256.957430425702, 3392.6607339266125, 4244.026527664635, 4250.447441832557, 4261.318189089746, 4276.593723596254, 4107.219628270634, 4150.875473735778, 4161.460259703898, 4167.562405947378, 4193.6645068394455, 3513.9648603514147, 3587.0852932502175, 3625.456181572759, 4027.2549578328103, 4178.874633760992, 3600.027992442052, 3601.1897323102094, 3625.2024554597947, 3626.1116026573395, 3667.0, 4961.826336078256, 5334.839954801362, 5336.51971322579, 5449.291592093042, 5458.471627944182, 3983.721139520235, 4339.717918335298, 4343.326784348431, 4649.86050418488, 4815.951840481621, 4872.4640289568115, 5461.508464238238, 5800.390958949334, 5909.349971503131, 5938.198343223653, 3198.408294465508, 3634.437192007436, 3815.2560250751117, 3932.033983008496, 3992.8802135935966, 4741.90936084699, 5044.798208071709, 5066.670666406671, 6212.534059945522, 6231.626102433868, 3576.445650924111, 3806.9428958565645, 3823.655870971626, 4024.396340548941, 4041.2927737645796, 3862.1117143057254, 3959.544652365015, 4009.8396064157105, 4094.1811637672586, 4102.958970410317, 3961.9896926283614, 4202.453681021448, 4753.405824271966, 4774.092922344744, 4777.327935222714, 4987.426445958761, 5314.751033507123, 5939.554533410012, 5946.762129514771, 5982.414660115073, 1262.810578413227, 1352.7767918293569, 1401.6846209602759, 1402.5161319344809, 1404.803327534146, 758.3213024343293, 795.777182388932, 821.8191997760483, 835.306695442781, 846.8433339832151, 4174.874753757392, 4265.445492086071, 4352.042550638855, 4644.5616051572515, 4698.95301046992, 4425.402570652985, 4496.7077139985795, 5162.6128040397125, 5208.427073021964, 5231.424543300234, 4087.6321131097748, 4373.278409062469, 4480.401367207012, 4623.0966417595555, 4757.881052973703, 2180.9345719628436, 2539.2889990802605, 2639.5776675732177, 2722.3466368071918, 2747.845904720022, 4509.909801803946, 4659.483878705461, 5012.122878496249, 5039.571636410873, 5337.759800808973, 4832.565069143794, 5341.866453338697, 5353.973230133903, 5361.463853614685, 5374.813305160539, 6130.394582302592, 6375.330590287953, 6382.266039405527, 6413.0, 6424.196975378078, 4986.825461108844, 5650.434956504317, 5742.0, 6134.6625935573975, 6184.938150618616, 5083.771230294572, 5331.013762453958, 5446.972765136307, 6096.359882212304, 6098.176746139215, 5405.972970135124, 5964.733401307475, 6285.654289014148, 6376.298607153302, 6427.2967663570125, 3922.2906734875055, 4001.639852413297, 4063.167050754529, 4067.3695577185586, 4074.2258970795456, 5249.265102885675, 5321.137601839325, 5663.262246917015, 5717.199592057199, 5911.541748077251, 5122.616893438825, 5310.628256022042, 5639.548836093185, 5918.141869428995, 5943.732532035982, 5832.358440571449, 5865.706714664335, 5882.441168089024, 5887.499562537232, 5912.61567998096, 5155.917257375993, 5471.343438787371, 5537.916931246115, 5545.694986775766, 5865.882682346244, 5389.865253368697, 5742.827715168552, 5835.299764028174, 5958.34458209588, 5990.513936716388, 5551.139573366135, 5559.972200138974, 5875.94124058771, 5878.823635290948, 5891.381404952418], "value": 4092.170220943144, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [743.3012967810258, 761.2919984414492, 775.7944144801628, 820.1019883227871, 875.2953872132932, 14877.107373557594, 15507.534773956786, 15597.766033509506, 15787.28957196926, 15823.841761582395, 14520.0, 14688.339024743886, 14690.779638305434, 14705.411783528672, 14724.263786810643, 704.1972151747012, 869.3088994249555, 885.2386947225381, 893.008760276093, 893.4773157702751, 904.4663648447415, 905.1355955062899, 911.3483859040784, 911.9726408207764, 915.555955361649, 948.2650945517211, 979.4564016970578, 980.0640388429068, 983.0415345038601, 985.8718366612367, 916.8028873792167, 964.1563631822156, 967.1682353176262, 967.4872317671575, 969.0890562870914, 686.5262968551683, 712.8574285142992, 723.4357201382877, 726.5894769455293, 727.6761840980765, 14944.775828362583, 15098.773518397233, 15234.923825380913, 15250.694986100216, 15275.38898444061, 14709.485168019175, 14729.337179826827, 14762.26898023869, 14777.261136943218, 14951.177685227309, 11856.22934509254, 12067.939660301643, 12349.19730217533, 12385.75228495434, 12412.379381030913, 3810.90472738181, 3849.268638958589, 4030.6372426481757, 4037.7173597848164, 4042.43405923171, 97948.51025744826, 98334.0, 102038.48980755049, 102672.0, 102710.48644756728, 11427.257228280136, 11483.483243254073, 11485.425728713531, 11589.130815188895, 11663.125265605113, 21226.620269682422, 21350.0, 22525.662115068288, 23267.18564850222, 23295.7670423297, 8097.959510202411, 8130.430869839172, 8150.673973041012, 8196.221358970888, 8261.17388261181, 4485.372047913294, 4540.02389486262, 4540.886477838015, 4580.129775342741, 4620.768961551909, 3531.540899683076, 3575.4636804479014, 3649.9817500912327, 3651.6713495785507, 3658.835352409148, 689.5311188391942, 693.2547511425238, 729.8795698709654, 730.6273800361747, 735.1215297719174, 478.80608353616145, 541.3855274263739, 627.8273474794468, 644.6615526848442, 716.233630015878, 9542.809143817085, 9730.64826270372, 9845.655402060893, 9862.408255504693, 9969.30214884951, 606.0, 650.72344253692, 664.3954001858285, 687.3058211206617, 709.5139829217003, 4503.932441013388, 4667.13657973276, 4693.413323334583, 4773.76131193439, 4799.6400269979895, 144762.0, 147228.26385868216, 147637.0, 151998.0, 156595.0, 122345.38827305807, 130328.0, 135021.0, 135324.0, 136538.0, 77151.22848771553, 113381.0, 116823.0, 117987.8201217994, 119044.40477797555, 9258.907410925805, 9280.72157835266, 9293.210077143518, 9329.440233586007, 9335.766605834908, 52222.21666674927, 61271.77456450935, 63353.68323158355, 64416.0, 65804.34195657982, 73995.0, 83245.16754832616, 86622.0, 86849.0, 88578.11421885957, 13229.536966206137, 13316.600501984956, 13327.533536326182, 13596.320183990958, 13666.385012674255, 14281.14313141215, 14612.634684132981, 14770.33533490974, 14983.175925324213, 15076.0, 11389.601363952223, 13352.399142038827, 14036.508722194674, 14394.424223031381, 14418.711625767635, 13467.932660337028, 13959.581212563638, 14219.50231741884, 14384.424623014962, 14470.710585788014, 13651.38568764388, 13681.863181368059, 13801.723965520432, 13809.378577963815, 14103.224322661941, 11163.776724465628, 11269.211155219384, 11460.255083419552, 11580.305181689126, 11606.184665527073, 10502.842457362993, 11324.32054076758, 11568.479418426023, 11637.650870473899, 11640.592579259684], "value": 7999.459546064352, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1643.7, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 109.0, 117.0, 115.0, 113.0, 115.0, 98.0, 172.0, 168.0, 132.0], "value": 125.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 143.0, 149.0, 149.0, 108.0, 140.0, 160.0, 149.0, 206.0, 138.0], "value": 150.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 203.0, 267.0, 267.0, 186.0, 201.0, 181.0, 268.0, 284.0, 236.0], "value": 229.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 81.0, 75.0, 146.0, 58.0, 58.0, 58.0, 61.0, 133.0, 61.0], "value": 80.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 73.0, 121.0, 71.0, 74.0, 80.0, 117.0, 71.0, 73.0, 115.0], "value": 86.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 89.0, 85.0, 91.0, 85.0, 88.0, 123.0, 92.0, 132.0], "value": 95.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 97.0, 151.0, 139.0, 95.0, 119.0, 153.0, 117.0, 155.0, 112.0], "value": 124.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 85.0, 146.0, 128.0, 95.0, 95.0, 99.0, 145.0, 75.0, 135.0], "value": 109.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 57.0, 56.0, 56.0, 58.0, 58.0, 55.0, 58.0, 56.0], "value": 56.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 73.0, 76.0, 69.0, 76.0, 78.0, 76.0, 103.0, 96.0, 71.0], "value": 78.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 116.0, 110.0, 106.0, 91.0, 89.0, 106.0, 106.0, 116.0, 89.0], "value": 102.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [191.0, 175.0, 144.0, 138.0, 139.0, 141.0, 138.0, 141.0, 182.0, 156.0], "value": 154.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 162.0, 176.0, 231.0, 184.0, 171.0, 165.0, 181.0, 162.0, 225.0], "value": 182.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 66.0, 68.0, 67.0, 68.0, 64.0, 68.0, 67.0, 77.0], "value": 68.0, "unit": "score"}]}]} 19:47:24 INFO - Return code: 0 19:47:24 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 19:47:24 ERROR - # TBPL SUCCESS # 19:47:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:47:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:47:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:47:24 INFO - 2016-06-01 19:47:24.405 screenresolution[1685:10436] starting screenresolution argv=screenresolution get 19:47:24 INFO - 2016-06-01 19:47:24.408 screenresolution[1685:10436] Display 0: 1600x1200x32@60 19:47:24 INFO - 2016-06-01 19:47:24.414 screenresolution[1686:10439] starting screenresolution argv=screenresolution list 19:47:24 INFO - Available Modes on Display 0 19:47:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:47:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:47:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:47:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:47:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:47:24 INFO - Intel Iris: 19:47:24 INFO - Chipset Model: Intel Iris 19:47:24 INFO - Type: GPU 19:47:24 INFO - Bus: Built-In 19:47:24 INFO - VRAM (Dynamic, Max): 1536 MB 19:47:24 INFO - Vendor: Intel (0x8086) 19:47:24 INFO - Device ID: 0x0a2e 19:47:24 INFO - Revision ID: 0x0009 19:47:24 INFO - Displays: 19:47:24 INFO - Display: 19:47:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:47:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:47:24 INFO - Display Serial Number: 1600x1200 60 19:47:24 INFO - Main Display: Yes 19:47:24 INFO - Mirror: Off 19:47:24 INFO - Online: Yes 19:47:24 INFO - Rotation: Supported 19:47:24 INFO - Return code: 0 19:47:24 INFO - Running post-action listener: _resource_record_post_action 19:47:24 INFO - ##### 19:47:24 INFO - ##### Finished run-tests step (success) 19:47:24 INFO - ##### 19:47:24 INFO - Running post-run listener: _resource_record_post_run 19:47:24 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 31448576; Write bytes: 698891264; Read time: 202; Write time: 1946 19:47:24 INFO - TinderboxPrint: CPU usage
54.5% 19:47:24 INFO - TinderboxPrint: I/O read bytes / time
31,448,576 / 202 19:47:24 INFO - TinderboxPrint: I/O write bytes / time
698,891,264 / 1,946 19:47:24 INFO - TinderboxPrint: CPU idle
2,204.9 (45.5%) 19:47:24 INFO - TinderboxPrint: CPU system
970.6 (20.0%) 19:47:24 INFO - TinderboxPrint: CPU user
1,670.2 (34.5%) 19:47:24 INFO - TinderboxPrint: Swap in / out
427,446,272 / 0 19:47:24 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 394111488; Write bytes: 388187648; Read time: 29296; Write time: 796 19:47:24 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 29666816; Write bytes: 295237120; Read time: 187; Write time: 1111 19:47:25 INFO - Running post-run listener: _upload_blobber_files 19:47:25 INFO - Blob upload gear active. 19:47:25 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:47:25 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 19:47:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:47:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:47:25 INFO - (blobuploader) - INFO - Open directory for files ... 19:47:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:47:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:47:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:47:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:47:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:47:26 INFO - (blobuploader) - INFO - Done attempting. 19:47:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:47:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:47:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:47:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:47:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:47:27 INFO - (blobuploader) - INFO - Done attempting. 19:47:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:47:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:47:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:47:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:47:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:47:29 INFO - (blobuploader) - INFO - Done attempting. 19:47:29 INFO - (blobuploader) - INFO - Iteration through files over. 19:47:29 INFO - Return code: 0 19:47:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:47:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:47:29 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/d31d20a31da526a82fd3786a3f90f85d71852bcaafbd9aceb8dcc78c89c0bfb128f7e2091e7451de21a6ad9084176c7edc793a7233b5602b8a570a6b9de6b62a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/d2f6f30058da2b639f3bcae64a90a9f152dd7d6a99f2addb028792939b4494001de46d6011d26a722dd229d264f63d59d169eacdccad800e9096eb772c0185ac", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/c3b6df8baae426b057dcfeef7d28b8b14f5e1e0f672e642c5c05e749e8ac2b41f082ce58843837c29d98f8fceae435ee8557d9e45757f0e1337e9ce7ec549ff3"} 19:47:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:47:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:47:29 INFO - Contents: 19:47:29 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/d31d20a31da526a82fd3786a3f90f85d71852bcaafbd9aceb8dcc78c89c0bfb128f7e2091e7451de21a6ad9084176c7edc793a7233b5602b8a570a6b9de6b62a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/d2f6f30058da2b639f3bcae64a90a9f152dd7d6a99f2addb028792939b4494001de46d6011d26a722dd229d264f63d59d169eacdccad800e9096eb772c0185ac", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/c3b6df8baae426b057dcfeef7d28b8b14f5e1e0f672e642c5c05e749e8ac2b41f082ce58843837c29d98f8fceae435ee8557d9e45757f0e1337e9ce7ec549ff3"} 19:47:29 INFO - Running post-run listener: copy_logs_to_upload_dir 19:47:29 INFO - Copying logs to upload dir... 19:47:29 INFO - mkdir: /builds/slave/test/build/upload/logs 19:47:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1279.770752 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 19 secs) (at 2016-06-01 19:47:29.993574) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-01 19:47:29.997173) ========= 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.T6eEChsRCL/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.gD2XAiyyWv/Listeners TMPDIR=/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/d31d20a31da526a82fd3786a3f90f85d71852bcaafbd9aceb8dcc78c89c0bfb128f7e2091e7451de21a6ad9084176c7edc793a7233b5602b8a570a6b9de6b62a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/d2f6f30058da2b639f3bcae64a90a9f152dd7d6a99f2addb028792939b4494001de46d6011d26a722dd229d264f63d59d169eacdccad800e9096eb772c0185ac", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/c3b6df8baae426b057dcfeef7d28b8b14f5e1e0f672e642c5c05e749e8ac2b41f082ce58843837c29d98f8fceae435ee8557d9e45757f0e1337e9ce7ec549ff3"} build_url:https://queue.taskcluster.net/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011038 build_url: 'https://queue.taskcluster.net/v1/task/aJVo-QCsRp6aSBwgElzrmg/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/d31d20a31da526a82fd3786a3f90f85d71852bcaafbd9aceb8dcc78c89c0bfb128f7e2091e7451de21a6ad9084176c7edc793a7233b5602b8a570a6b9de6b62a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/d2f6f30058da2b639f3bcae64a90a9f152dd7d6a99f2addb028792939b4494001de46d6011d26a722dd229d264f63d59d169eacdccad800e9096eb772c0185ac", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/c3b6df8baae426b057dcfeef7d28b8b14f5e1e0f672e642c5c05e749e8ac2b41f082ce58843837c29d98f8fceae435ee8557d9e45757f0e1337e9ce7ec549ff3"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-01 19:47:30.029721) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 19:47:30.030439) ========= 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.T6eEChsRCL/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.gD2XAiyyWv/Listeners TMPDIR=/var/folders/7d/hhvqsjp50qbbmc1b90zffcn000000w/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.005267 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 19:47:30.084973) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-01 19:47:30.085618) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-01 19:47:30.086293) ========= ========= Total master_lag: 0.18 =========