builder: larch_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0059 starttime: 1464815477.68 results: success (0) buildid: 20160601115742 builduid: 71f90a3874f84c86a34a3adaf2fccb01 revision: 248d35aedf8866d3b5bf747324a1384aa1228d8e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-01 14:11:17.683813) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-01 14:11:17.684236) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-01 14:11:17.684525) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-01 14:11:17.706756) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 14:11:17.707074) ========= 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.3IMx3hi8N8/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.xzvODllfJj/Listeners TMPDIR=/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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 14:11:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 64.7M=0s 2016-06-01 14:11:17 (64.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.117327 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 14:11:17.846523) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 14:11:17.846827) ========= 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.3IMx3hi8N8/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.xzvODllfJj/Listeners TMPDIR=/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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.026726 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 14:11:17.895974) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-01 14:11:17.896330) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 248d35aedf8866d3b5bf747324a1384aa1228d8e --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 248d35aedf8866d3b5bf747324a1384aa1228d8e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.3IMx3hi8N8/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.xzvODllfJj/Listeners TMPDIR=/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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 14:11:17,971 truncating revision to first 12 chars 2016-06-01 14:11:17,971 Setting DEBUG logging. 2016-06-01 14:11:17,971 attempt 1/10 2016-06-01 14:11:17,971 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/248d35aedf88?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-01 14:11:18,940 unpacking tar archive at: larch-248d35aedf88/testing/mozharness/ program finished with exit code 0 elapsedTime=1.236832 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-01 14:11:19.152846) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-01 14:11:19.153177) ========= script_repo_revision: 248d35aedf8866d3b5bf747324a1384aa1228d8e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-01 14:11:19.153577) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-01 14:11:19.153838) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-01 14:11:19.170338) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-06-01 14:11:19.170626) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --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', '--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.3IMx3hi8N8/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.xzvODllfJj/Listeners TMPDIR=/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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 14:11:19 INFO - MultiFileLogger online at 20160601 14:11:19 in /builds/slave/test 14:11:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 14:11:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:11:19 INFO - {'append_to_log': False, 14:11:19 INFO - 'base_work_dir': '/builds/slave/test', 14:11:19 INFO - 'blob_upload_branch': 'Larch', 14:11:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:11:19 INFO - 'branch': 'Larch', 14:11:19 INFO - 'buildbot_json_path': 'buildprops.json', 14:11:19 INFO - 'config_files': ('talos/mac_config.py',), 14:11:19 INFO - 'default_actions': ('clobber', 14:11:19 INFO - 'read-buildbot-config', 14:11:19 INFO - 'download-and-extract', 14:11:19 INFO - 'populate-webroot', 14:11:19 INFO - 'create-virtualenv', 14:11:19 INFO - 'install', 14:11:19 INFO - 'run-tests'), 14:11:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:11:19 INFO - 'download_minidump_stackwalk': True, 14:11:19 INFO - 'download_symbols': 'ondemand', 14:11:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:11:19 INFO - 'tooltool.py': '/tools/tooltool.py', 14:11:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:11:19 INFO - '/tools/misc-python/virtualenv.py')}, 14:11:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:11:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:11:19 INFO - 'installer_path': 'installer.exe', 14:11:19 INFO - 'log_level': 'info', 14:11:19 INFO - 'log_name': 'talos', 14:11:19 INFO - 'log_to_console': True, 14:11:19 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:11:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:11:19 INFO - 'opt_config_files': (), 14:11:19 INFO - 'pip_index': False, 14:11:19 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:11:19 INFO - 'cmd': ('bash', 14:11:19 INFO - '-c', 14:11:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:11:19 INFO - 'enabled': True, 14:11:19 INFO - 'halt_on_failure': False, 14:11:19 INFO - 'name': 'check_screen_resolution'},), 14:11:19 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:11:19 INFO - 'cmd': ('bash', 14:11:19 INFO - '-c', 14:11:19 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:11:19 INFO - 'enabled': True, 14:11:19 INFO - 'halt_on_failure': False, 14:11:19 INFO - 'name': 'check_screen_resolution'},), 14:11:19 INFO - 'run_cmd_checks_enabled': True, 14:11:19 INFO - 'sps_profile': False, 14:11:19 INFO - 'sps_profile_interval': 0, 14:11:19 INFO - 'suite': 'dromaeojs', 14:11:19 INFO - 'system_bits': '32', 14:11:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:11:19 INFO - 'title': 't-yosemite-r7-0059', 14:11:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:11:19 INFO - 'use_talos_json': True, 14:11:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:11:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:11:19 INFO - 'work_dir': 'build'} 14:11:19 INFO - ##### 14:11:19 INFO - ##### Running clobber step. 14:11:19 INFO - ##### 14:11:19 INFO - Running pre-action listener: _resource_record_pre_action 14:11:19 INFO - Running main action method: clobber 14:11:19 INFO - rmtree: /builds/slave/test/build 14:11:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:11:21 INFO - Running post-action listener: _resource_record_post_action 14:11:21 INFO - ##### 14:11:21 INFO - ##### Finished clobber step (success) 14:11:21 INFO - ##### 14:11:21 INFO - ##### 14:11:21 INFO - ##### Running read-buildbot-config step. 14:11:21 INFO - ##### 14:11:21 INFO - Running pre-action listener: _resource_record_pre_action 14:11:21 INFO - Running main action method: read_buildbot_config 14:11:21 INFO - Using buildbot properties: 14:11:21 INFO - { 14:11:21 INFO - "project": "", 14:11:21 INFO - "product": "firefox", 14:11:21 INFO - "script_repo_revision": "production", 14:11:21 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs", 14:11:21 INFO - "repository": "", 14:11:21 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs", 14:11:21 INFO - "buildid": "20160601115742", 14:11:21 INFO - "pgo_build": "False", 14:11:21 INFO - "basedir": "/builds/slave/test", 14:11:21 INFO - "buildnumber": 2, 14:11:21 INFO - "slavename": "t-yosemite-r7-0059", 14:11:21 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 14:11:21 INFO - "platform": "yosemite_r7", 14:11:21 INFO - "scheduler": "tests-larch-macosx64-talos", 14:11:21 INFO - "branch": "larch", 14:11:21 INFO - "repo_path": "projects/larch", 14:11:21 INFO - "revision": "248d35aedf8866d3b5bf747324a1384aa1228d8e", 14:11:21 INFO - "stage_platform": "macosx64", 14:11:21 INFO - "builduid": "71f90a3874f84c86a34a3adaf2fccb01", 14:11:21 INFO - "slavebuilddir": "test" 14:11:21 INFO - } 14:11:21 INFO - Found installer url https://queue.taskcluster.net/v1/task/U_5KaG-5RjK-G_w6NrjVtw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 14:11:21 INFO - Running post-action listener: _resource_record_post_action 14:11:21 INFO - ##### 14:11:21 INFO - ##### Finished read-buildbot-config step (success) 14:11:21 INFO - ##### 14:11:21 INFO - ##### 14:11:21 INFO - ##### Running download-and-extract step. 14:11:21 INFO - ##### 14:11:21 INFO - Running pre-action listener: _resource_record_pre_action 14:11:21 INFO - Running main action method: download_and_extract 14:11:21 INFO - mkdir: /builds/slave/test/build/tests 14:11:21 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')]} 14:11:21 INFO - https://queue.taskcluster.net/v1/task/U_5KaG-5RjK-G_w6NrjVtw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 14:11:21 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U_5KaG-5RjK-G_w6NrjVtw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 14:11:21 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U_5KaG-5RjK-G_w6NrjVtw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 14:11:21 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U_5KaG-5RjK-G_w6NrjVtw/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 14:11:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U_5KaG-5RjK-G_w6NrjVtw/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 14:11:23 INFO - Downloaded 1199 bytes. 14:11:23 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 14:11:23 INFO - Using the following test package requirements: 14:11:23 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 14:11:23 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:11:23 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 14:11:23 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:11:23 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 14:11:23 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:11:23 INFO - u'jsshell-mac.zip'], 14:11:23 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:11:23 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 14:11:23 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 14:11:23 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:11:23 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 14:11:23 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:11:23 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 14:11:23 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:11:23 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 14:11:23 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:11:23 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 14:11:23 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:11:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:11:23 INFO - https://queue.taskcluster.net/v1/task/U_5KaG-5RjK-G_w6NrjVtw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 14:11:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U_5KaG-5RjK-G_w6NrjVtw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:11:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U_5KaG-5RjK-G_w6NrjVtw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:11:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U_5KaG-5RjK-G_w6NrjVtw/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 14:11:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U_5KaG-5RjK-G_w6NrjVtw/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 14:11:33 INFO - Downloaded 18696424 bytes. 14:11:33 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'] 14:11:33 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 14:11:36 INFO - Return code: 0 14:11:36 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 14:11:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:11:36 INFO - https://queue.taskcluster.net/v1/task/U_5KaG-5RjK-G_w6NrjVtw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 14:11:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U_5KaG-5RjK-G_w6NrjVtw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:11:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U_5KaG-5RjK-G_w6NrjVtw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:11:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U_5KaG-5RjK-G_w6NrjVtw/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 14:11:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U_5KaG-5RjK-G_w6NrjVtw/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 14:11:37 INFO - Downloaded 18696424 bytes. 14:11:37 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'] 14:11:37 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 14:11:41 INFO - Return code: 0 14:11:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:11:41 INFO - https://queue.taskcluster.net/v1/task/U_5KaG-5RjK-G_w6NrjVtw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 14:11:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U_5KaG-5RjK-G_w6NrjVtw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 14:11:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U_5KaG-5RjK-G_w6NrjVtw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 14:11:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U_5KaG-5RjK-G_w6NrjVtw/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 14:11:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U_5KaG-5RjK-G_w6NrjVtw/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 14:11:42 INFO - Downloaded 10834063 bytes. 14:11:42 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'] 14:11:42 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 14:11:42 INFO - Return code: 0 14:11: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')]} 14:11:42 INFO - https://queue.taskcluster.net/v1/task/U_5KaG-5RjK-G_w6NrjVtw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 14:11:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U_5KaG-5RjK-G_w6NrjVtw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:11:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U_5KaG-5RjK-G_w6NrjVtw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:11:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U_5KaG-5RjK-G_w6NrjVtw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 14:11:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/U_5KaG-5RjK-G_w6NrjVtw/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 14:11:45 INFO - Downloaded 111192562 bytes. 14:11:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/U_5KaG-5RjK-G_w6NrjVtw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:11:45 INFO - mkdir: /builds/slave/test/properties 14:11:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:11:45 INFO - Writing to file /builds/slave/test/properties/build_url 14:11:45 INFO - Contents: 14:11:45 INFO - build_url:https://queue.taskcluster.net/v1/task/U_5KaG-5RjK-G_w6NrjVtw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:11:47 INFO - Running post-action listener: _resource_record_post_action 14:11:47 INFO - Running post-action listener: set_extra_try_arguments 14:11:47 INFO - ##### 14:11:47 INFO - ##### Finished download-and-extract step (success) 14:11:47 INFO - ##### 14:11:47 INFO - ##### 14:11:47 INFO - ##### Running populate-webroot step. 14:11:47 INFO - ##### 14:11:47 INFO - Running pre-action listener: _resource_record_pre_action 14:11:47 INFO - Running main action method: populate_webroot 14:11:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:11:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:11:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:11:47 INFO - '--tppagecycles', 14:11:47 INFO - '7'], 14:11:47 INFO - 'tests': ['tsvgm']}}, 14:11:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:11:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:11:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:11:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:11:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:11:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:11:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:11:47 INFO - 'talos_options': ['--disable-e10s'], 14:11:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:11:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:11:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:11:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:11:47 INFO - 'talos_options': ['--disable-e10s'], 14:11:47 INFO - 'tests': ['damp', 'tps']}, 14:11:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:11:47 INFO - 'tests': ['damp', 'tps']}, 14:11:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:11:47 INFO - 'tests': ['dromaeo_dom']}, 14:11:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:11:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:11:47 INFO - 'tests': ['a11yr', 14:11:47 INFO - 'ts_paint', 14:11:47 INFO - 'tpaint', 14:11:47 INFO - 'sessionrestore', 14:11:47 INFO - 'sessionrestore_no_auto_restore', 14:11:47 INFO - 'tabpaint']}, 14:11:47 INFO - 'other-e10s': {'tests': ['a11yr', 14:11:47 INFO - 'ts_paint', 14:11:47 INFO - 'tpaint', 14:11:47 INFO - 'sessionrestore', 14:11:47 INFO - 'sessionrestore_no_auto_restore', 14:11:47 INFO - 'tabpaint']}, 14:11:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:11:47 INFO - 'ts_paint', 14:11:47 INFO - 'tpaint', 14:11:47 INFO - 'sessionrestore', 14:11:47 INFO - 'sessionrestore_no_auto_restore', 14:11:47 INFO - 'tabpaint']}, 14:11:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:11:47 INFO - 'ts_paint', 14:11:47 INFO - 'tpaint', 14:11:47 INFO - 'sessionrestore', 14:11:47 INFO - 'sessionrestore_no_auto_restore', 14:11:47 INFO - 'tabpaint']}, 14:11:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:11:47 INFO - 'tests': ['a11yr', 14:11:47 INFO - 'ts_paint', 14:11:47 INFO - 'tpaint', 14:11:47 INFO - 'sessionrestore', 14:11:47 INFO - 'sessionrestore_no_auto_restore', 14:11:47 INFO - 'tabpaint']}, 14:11:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:11:47 INFO - 'tests': ['a11yr', 14:11:47 INFO - 'ts_paint', 14:11:47 INFO - 'tpaint', 14:11:47 INFO - 'sessionrestore', 14:11:47 INFO - 'sessionrestore_no_auto_restore', 14:11:47 INFO - 'tabpaint']}, 14:11:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:11:47 INFO - 'tests': ['tsvgx', 14:11:47 INFO - 'tsvgr_opacity', 14:11:47 INFO - 'tart', 14:11:47 INFO - 'tscrollx', 14:11:47 INFO - 'cart']}, 14:11:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:11:47 INFO - 'tsvgr_opacity', 14:11:47 INFO - 'tart', 14:11:47 INFO - 'tscrollx', 14:11:47 INFO - 'cart']}, 14:11:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:11:47 INFO - 'talos_options': ['--disable-e10s'], 14:11:47 INFO - 'tests': ['tp5o']}, 14:11:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:11:47 INFO - 'tests': ['tp5o']}, 14:11:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:11:47 INFO - 'talos_options': ['--disable-e10s', 14:11:47 INFO - '--xperf_path', 14:11:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:11:47 INFO - 'tests': ['tp5n']}, 14:11:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:11:47 INFO - 'talos_options': ['--xperf_path', 14:11:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:11:47 INFO - 'tests': ['tp5n']}}, 14:11:47 INFO - 'talos.zip': {'path': '', 14:11:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:11:47 INFO - Running post-action listener: _resource_record_post_action 14:11:47 INFO - ##### 14:11:47 INFO - ##### Finished populate-webroot step (success) 14:11:47 INFO - ##### 14:11:47 INFO - ##### 14:11:47 INFO - ##### Running create-virtualenv step. 14:11:47 INFO - ##### 14:11:47 INFO - Running pre-action listener: _resource_record_pre_action 14:11:47 INFO - Running main action method: create_virtualenv 14:11:47 INFO - Creating virtualenv /builds/slave/test/build/venv 14:11:47 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 14:11:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:11:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:11:47 INFO - Using real prefix '/tools/python27' 14:11:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:11:48 INFO - Installing distribute.............................................................................................................................................................................................done. 14:11:50 INFO - Installing pip.................done. 14:11:50 INFO - Return code: 0 14:11:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:11: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')]} 14:11:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:11:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:11: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')]} 14:11:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:11:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:11: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10705aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107060570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6c8cac620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106efed30>, '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 0x106d22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ef5b20>, '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.3IMx3hi8N8/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.xzvODllfJj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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 14:11: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', 'pip>=1.5'] in /builds/slave/test/build 14:11: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 pip>=1.5 14:11:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3IMx3hi8N8/Render', 14:11:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:11:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:11:50 INFO - 'HOME': '/Users/cltbld', 14:11:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:11:50 INFO - 'LOGNAME': 'cltbld', 14:11:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:11:50 INFO - 'MOZ_NO_REMOTE': '1', 14:11:50 INFO - 'NO_EM_RESTART': '1', 14:11:50 INFO - 'PAGER': '/bin/cat', 14:11:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:11:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:11:50 INFO - 'PWD': '/builds/slave/test', 14:11:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:11:50 INFO - 'SHELL': '/bin/bash', 14:11:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xzvODllfJj/Listeners', 14:11:50 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 14:11:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:11:50 INFO - 'USER': 'cltbld', 14:11:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:11:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:11:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:11:50 INFO - 'XPC_FLAGS': '0x0', 14:11:50 INFO - 'XPC_SERVICE_NAME': '0', 14:11:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:11:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:11:51 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 14:11:51 INFO - Cleaning up... 14:11:51 INFO - Return code: 0 14:11:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:11:51 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')]} 14:11:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:11:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:11:51 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')]} 14:11:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:11:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:11:51 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 0x10705aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107060570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6c8cac620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106efed30>, '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 0x106d22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ef5b20>, '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.3IMx3hi8N8/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.xzvODllfJj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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 14:11:51 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 14:11:51 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 14:11:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3IMx3hi8N8/Render', 14:11:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:11:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:11:51 INFO - 'HOME': '/Users/cltbld', 14:11:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:11:51 INFO - 'LOGNAME': 'cltbld', 14:11:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:11:51 INFO - 'MOZ_NO_REMOTE': '1', 14:11:51 INFO - 'NO_EM_RESTART': '1', 14:11:51 INFO - 'PAGER': '/bin/cat', 14:11:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:11:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:11:51 INFO - 'PWD': '/builds/slave/test', 14:11:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:11:51 INFO - 'SHELL': '/bin/bash', 14:11:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xzvODllfJj/Listeners', 14:11:51 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 14:11:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:11:51 INFO - 'USER': 'cltbld', 14:11:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:11:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:11:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:11:51 INFO - 'XPC_FLAGS': '0x0', 14:11:51 INFO - 'XPC_SERVICE_NAME': '0', 14:11:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:11:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:11:51 INFO - Downloading/unpacking psutil>=3.1.1 14:11:51 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 14:11:51 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 14:11:51 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 14:11:51 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 14:11:51 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 14:11:51 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 14:11:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:11:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:11:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:11:54 INFO - Installing collected packages: psutil 14:11:54 INFO - Running setup.py install for psutil 14:11:54 INFO - building 'psutil._psutil_osx' extension 14:11:54 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 14:11:54 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 14:11:54 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 14:11:54 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 14:11:55 INFO - building 'psutil._psutil_posix' extension 14:11:55 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 14:11:55 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:11:55 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:11:55 INFO - ^ 14:11:55 INFO - 1 warning generated. 14:11:55 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 14:11:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:11:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:11:55 INFO - Successfully installed psutil 14:11:55 INFO - Cleaning up... 14:11:55 INFO - Return code: 0 14:11:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:11: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')]} 14:11:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:11:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:11: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')]} 14:11:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:11:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:11:55 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 0x10705aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107060570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6c8cac620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106efed30>, '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 0x106d22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ef5b20>, '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.3IMx3hi8N8/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.xzvODllfJj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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 14:11:55 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 14:11:55 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 14:11:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3IMx3hi8N8/Render', 14:11:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:11:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:11:55 INFO - 'HOME': '/Users/cltbld', 14:11:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:11:55 INFO - 'LOGNAME': 'cltbld', 14:11:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:11:55 INFO - 'MOZ_NO_REMOTE': '1', 14:11:55 INFO - 'NO_EM_RESTART': '1', 14:11:55 INFO - 'PAGER': '/bin/cat', 14:11:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:11:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:11:55 INFO - 'PWD': '/builds/slave/test', 14:11:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:11:55 INFO - 'SHELL': '/bin/bash', 14:11:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xzvODllfJj/Listeners', 14:11:55 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 14:11:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:11:55 INFO - 'USER': 'cltbld', 14:11:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:11:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:11:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:11:55 INFO - 'XPC_FLAGS': '0x0', 14:11:55 INFO - 'XPC_SERVICE_NAME': '0', 14:11:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:11:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:11:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:11:55 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 14:11:55 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 14:11:55 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 14:11:55 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 14:11:55 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 14:11:55 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 14:11:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:11:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:11:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:11:58 INFO - Installing collected packages: mozsystemmonitor 14:11:58 INFO - Running setup.py install for mozsystemmonitor 14:11:58 INFO - Successfully installed mozsystemmonitor 14:11:58 INFO - Cleaning up... 14:11:58 INFO - Return code: 0 14:11:58 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:11:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:11:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:11:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:11:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:11:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:11:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:11:58 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 0x10705aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107060570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6c8cac620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106efed30>, '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 0x106d22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ef5b20>, '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.3IMx3hi8N8/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.xzvODllfJj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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 14:11:58 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 14:11:58 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 14:11:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3IMx3hi8N8/Render', 14:11:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:11:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:11:58 INFO - 'HOME': '/Users/cltbld', 14:11:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:11:58 INFO - 'LOGNAME': 'cltbld', 14:11:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:11:58 INFO - 'MOZ_NO_REMOTE': '1', 14:11:58 INFO - 'NO_EM_RESTART': '1', 14:11:58 INFO - 'PAGER': '/bin/cat', 14:11:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:11:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:11:58 INFO - 'PWD': '/builds/slave/test', 14:11:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:11:58 INFO - 'SHELL': '/bin/bash', 14:11:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xzvODllfJj/Listeners', 14:11:58 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 14:11:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:11:58 INFO - 'USER': 'cltbld', 14:11:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:11:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:11:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:11:58 INFO - 'XPC_FLAGS': '0x0', 14:11:58 INFO - 'XPC_SERVICE_NAME': '0', 14:11:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:11:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:11:58 INFO - Downloading/unpacking blobuploader==1.2.4 14:11:58 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 14:11:58 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 14:11:58 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 14:11:58 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 14:11:58 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 14:11:58 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 14:12:01 INFO - Downloading blobuploader-1.2.4.tar.gz 14:12:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:12:01 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:12: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 14:12: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 14:12: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 14:12: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 14:12: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 14:12: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 14:12:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:12:01 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:12: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 14:12: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 14:12: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 14:12: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 14:12: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 14:12: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 14:12:02 INFO - Downloading docopt-0.6.1.tar.gz 14:12:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:12:02 INFO - Installing collected packages: blobuploader, requests, docopt 14:12:02 INFO - Running setup.py install for blobuploader 14:12:02 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:12:02 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:12:02 INFO - Running setup.py install for requests 14:12:03 INFO - Running setup.py install for docopt 14:12:03 INFO - Successfully installed blobuploader requests docopt 14:12:03 INFO - Cleaning up... 14:12:03 INFO - Return code: 0 14:12:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:12:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:12:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:12:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:12:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:12:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:03 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 0x10705aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107060570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6c8cac620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106efed30>, '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 0x106d22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ef5b20>, '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.3IMx3hi8N8/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.xzvODllfJj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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 14:12:03 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 14:12:03 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 14:12:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3IMx3hi8N8/Render', 14:12:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:12:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:12:03 INFO - 'HOME': '/Users/cltbld', 14:12:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:12:03 INFO - 'LOGNAME': 'cltbld', 14:12:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:12:03 INFO - 'MOZ_NO_REMOTE': '1', 14:12:03 INFO - 'NO_EM_RESTART': '1', 14:12:03 INFO - 'PAGER': '/bin/cat', 14:12:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:12:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:12:03 INFO - 'PWD': '/builds/slave/test', 14:12:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:12:03 INFO - 'SHELL': '/bin/bash', 14:12:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xzvODllfJj/Listeners', 14:12:03 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 14:12:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:12:03 INFO - 'USER': 'cltbld', 14:12:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:12:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:12:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:12:03 INFO - 'XPC_FLAGS': '0x0', 14:12:03 INFO - 'XPC_SERVICE_NAME': '0', 14:12:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:12:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:12:03 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-hNLHa2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:12:03 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-jhDCk8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:12:03 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-S_geE6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:12:03 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-V7vHo0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:12:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:12:03 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-qBBybS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:12:04 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-ckiJUY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:12:04 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-NsE3oC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:12:04 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-ep4x5t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:12:04 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-GdK7ON-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:12:04 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-2TjAQC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:12:04 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-22y16F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:12:04 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-Is63Fe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:12:04 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-QceQlR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:12:04 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-6tbpZO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:12:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:12:05 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-k3KqPy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:12:05 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-lHYC9X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:12:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:12:05 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-MDxKpp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:12:05 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:12:05 INFO - Running setup.py install for manifestparser 14:12:05 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:12:05 INFO - Running setup.py install for mozcrash 14:12:05 INFO - Running setup.py install for mozdebug 14:12:05 INFO - Running setup.py install for mozdevice 14:12:05 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:12:05 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:12:05 INFO - Running setup.py install for mozfile 14:12:06 INFO - Running setup.py install for mozhttpd 14:12:06 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:12:06 INFO - Running setup.py install for mozinfo 14:12:06 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:12:06 INFO - Running setup.py install for mozInstall 14:12:06 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:12:06 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:12:06 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:12:06 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:12:06 INFO - Running setup.py install for mozleak 14:12:06 INFO - Running setup.py install for mozlog 14:12:06 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:12:06 INFO - Running setup.py install for moznetwork 14:12:06 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:12:06 INFO - Running setup.py install for mozprocess 14:12:07 INFO - Running setup.py install for mozprofile 14:12:07 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:12:07 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:12:07 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:12:07 INFO - Running setup.py install for mozrunner 14:12:07 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:12:07 INFO - Running setup.py install for mozscreenshot 14:12:07 INFO - Running setup.py install for moztest 14:12:07 INFO - Running setup.py install for mozversion 14:12:07 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:12:07 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:12:07 INFO - Cleaning up... 14:12:07 INFO - Return code: 0 14:12:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:12:07 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')]} 14:12:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:12:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:07 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')]} 14:12:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:12:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:07 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 0x10705aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107060570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6c8cac620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106efed30>, '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 0x106d22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ef5b20>, '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.3IMx3hi8N8/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.xzvODllfJj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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 14:12:07 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 14:12:07 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 14:12:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3IMx3hi8N8/Render', 14:12:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:12:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:12:07 INFO - 'HOME': '/Users/cltbld', 14:12:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:12:07 INFO - 'LOGNAME': 'cltbld', 14:12:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:12:07 INFO - 'MOZ_NO_REMOTE': '1', 14:12:07 INFO - 'NO_EM_RESTART': '1', 14:12:07 INFO - 'PAGER': '/bin/cat', 14:12:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:12:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:12:07 INFO - 'PWD': '/builds/slave/test', 14:12:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:12:07 INFO - 'SHELL': '/bin/bash', 14:12:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xzvODllfJj/Listeners', 14:12:07 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 14:12:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:12:07 INFO - 'USER': 'cltbld', 14:12:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:12:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:12:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:12:07 INFO - 'XPC_FLAGS': '0x0', 14:12:07 INFO - 'XPC_SERVICE_NAME': '0', 14:12:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:12:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:12:08 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-7kEJ9n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:12:08 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)) 14:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:12:08 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-ON7728-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:12:08 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)) 14:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:12:08 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-gq6rPk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:12:08 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)) 14:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:12:08 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-CqSqpG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:12:08 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)) 14:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:12:08 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-r7GJOv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:12:08 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)) 14:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:12:08 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-9k4ySz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:12:08 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)) 14:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:12:08 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-MLp2PG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:12:08 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)) 14:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:12:08 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-0nOX_a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:12:08 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)) 14:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:12:08 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-PVAGBO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:12:09 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)) 14:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:12:09 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-0NQDuS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:12:09 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)) 14:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:12:09 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-zaLG90-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:12:09 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)) 14:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:12:09 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-UcPvED-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:12:09 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)) 14:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:12:09 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-kzDK5o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:12:09 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)) 14:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:12:09 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-rCv1sU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:12:09 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)) 14:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:12:09 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-RsjEps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:12:09 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)) 14:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:12:09 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-TpbRud-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:12:09 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)) 14:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:12:09 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-IznZUU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:12:09 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)) 14:12:09 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)) 14:12:09 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)) 14:12:09 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)) 14:12:09 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)) 14:12:09 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)) 14:12:09 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:12: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 14:12: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 14:12: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 14:12: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 14:12: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 14:12: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 14:12:12 INFO - Downloading blessings-1.6.tar.gz 14:12:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:12:12 INFO - Installing collected packages: blessings 14:12:12 INFO - Running setup.py install for blessings 14:12:12 INFO - Successfully installed blessings 14:12:12 INFO - Cleaning up... 14:12:12 INFO - Return code: 0 14:12:12 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:12:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:12:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:12:12 INFO - Reading from file tmpfile_stdout 14:12:12 INFO - Current package versions: 14:12:12 INFO - blessings == 1.6 14:12:12 INFO - blobuploader == 1.2.4 14:12:12 INFO - docopt == 0.6.1 14:12:12 INFO - manifestparser == 1.1 14:12:12 INFO - mozInstall == 1.12 14:12:12 INFO - mozcrash == 0.17 14:12:12 INFO - mozdebug == 0.1 14:12:12 INFO - mozdevice == 0.48 14:12:12 INFO - mozfile == 1.2 14:12:12 INFO - mozhttpd == 0.7 14:12:12 INFO - mozinfo == 0.9 14:12:12 INFO - mozleak == 0.1 14:12:12 INFO - mozlog == 3.2 14:12:12 INFO - moznetwork == 0.27 14:12:12 INFO - mozprocess == 0.22 14:12:12 INFO - mozprofile == 0.28 14:12:12 INFO - mozrunner == 6.11 14:12:12 INFO - mozscreenshot == 0.1 14:12:12 INFO - mozsystemmonitor == 0.3 14:12:12 INFO - moztest == 0.7 14:12:12 INFO - mozversion == 1.4 14:12:12 INFO - psutil == 3.1.1 14:12:12 INFO - requests == 1.2.3 14:12:12 INFO - wsgiref == 0.1.2 14:12:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:12: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')]} 14:12:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:12:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:12:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:12:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:13 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 0x10705aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107060570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6c8cac620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106efed30>, '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 0x106d22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ef5b20>, '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.3IMx3hi8N8/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.xzvODllfJj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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 14:12:13 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 14:12:13 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 14:12:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3IMx3hi8N8/Render', 14:12:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:12:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:12:13 INFO - 'HOME': '/Users/cltbld', 14:12:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:12:13 INFO - 'LOGNAME': 'cltbld', 14:12:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:12:13 INFO - 'MOZ_NO_REMOTE': '1', 14:12:13 INFO - 'NO_EM_RESTART': '1', 14:12:13 INFO - 'PAGER': '/bin/cat', 14:12:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:12:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:12:13 INFO - 'PWD': '/builds/slave/test', 14:12:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:12:13 INFO - 'SHELL': '/bin/bash', 14:12:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xzvODllfJj/Listeners', 14:12:13 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 14:12:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:12:13 INFO - 'USER': 'cltbld', 14:12:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:12:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:12:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:12:13 INFO - 'XPC_FLAGS': '0x0', 14:12:13 INFO - 'XPC_SERVICE_NAME': '0', 14:12:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:12:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:12:13 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)) 14:12:13 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)) 14:12:13 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)) 14:12:13 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)) 14:12:13 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)) 14:12:13 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)) 14:12:13 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)) 14:12:13 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)) 14:12:13 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)) 14:12:13 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)) 14:12:13 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)) 14:12:13 INFO - Cleaning up... 14:12:13 INFO - Return code: 0 14:12:13 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:12:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:12:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:12:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:12:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:12:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10705aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107060570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6c8cac620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106efed30>, '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 0x106d22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ef5b20>, '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.3IMx3hi8N8/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.xzvODllfJj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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 14:12:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:12:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:12:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3IMx3hi8N8/Render', 14:12:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:12:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:12:13 INFO - 'HOME': '/Users/cltbld', 14:12:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:12:13 INFO - 'LOGNAME': 'cltbld', 14:12:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:12:13 INFO - 'MOZ_NO_REMOTE': '1', 14:12:13 INFO - 'NO_EM_RESTART': '1', 14:12:13 INFO - 'PAGER': '/bin/cat', 14:12:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:12:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:12:13 INFO - 'PWD': '/builds/slave/test', 14:12:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:12:13 INFO - 'SHELL': '/bin/bash', 14:12:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xzvODllfJj/Listeners', 14:12:13 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 14:12:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:12:13 INFO - 'USER': 'cltbld', 14:12:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:12:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:12:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:12:13 INFO - 'XPC_FLAGS': '0x0', 14:12:13 INFO - 'XPC_SERVICE_NAME': '0', 14:12:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:12:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:12:13 INFO - Downloading/unpacking jsonschema 14:12:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:12:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:12:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:12:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:12:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:12:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:12:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:12:16 INFO - Downloading/unpacking functools32 (from jsonschema) 14:12:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:12:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:12:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:12:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:12:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:12:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:12:17 INFO - Downloading functools32-3.2.3-2.tar.gz 14:12:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:12:17 INFO - warning: no files found matching '*.txt' 14:12:17 INFO - no previously-included directories found matching 'build' 14:12:17 INFO - no previously-included directories found matching 'dist' 14:12:17 INFO - no previously-included directories found matching '.git*' 14:12:17 INFO - Installing collected packages: jsonschema, functools32 14:12:17 INFO - Running setup.py install for functools32 14:12:17 INFO - warning: no files found matching '*.txt' 14:12:17 INFO - no previously-included directories found matching 'build' 14:12:17 INFO - no previously-included directories found matching 'dist' 14:12:17 INFO - no previously-included directories found matching '.git*' 14:12:17 INFO - Successfully installed jsonschema functools32 14:12:17 INFO - Cleaning up... 14:12:17 INFO - Return code: 0 14:12:17 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 14:12:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:12:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:12:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:12:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:12:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:12:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10705aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107060570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa6c8cac620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106efed30>, '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 0x106d22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ef5b20>, '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.3IMx3hi8N8/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.xzvODllfJj/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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 14:12:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 14:12:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 14:12:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3IMx3hi8N8/Render', 14:12:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:12:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:12:17 INFO - 'HOME': '/Users/cltbld', 14:12:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:12:17 INFO - 'LOGNAME': 'cltbld', 14:12:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:12:17 INFO - 'MOZ_NO_REMOTE': '1', 14:12:17 INFO - 'NO_EM_RESTART': '1', 14:12:17 INFO - 'PAGER': '/bin/cat', 14:12:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:12:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:12:17 INFO - 'PWD': '/builds/slave/test', 14:12:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:12:17 INFO - 'SHELL': '/bin/bash', 14:12:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xzvODllfJj/Listeners', 14:12:17 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 14:12:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:12:17 INFO - 'USER': 'cltbld', 14:12:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:12:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:12:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:12:17 INFO - 'XPC_FLAGS': '0x0', 14:12:17 INFO - 'XPC_SERVICE_NAME': '0', 14:12:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:12:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:12:17 INFO - Downloading/unpacking flake8 14:12:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:12:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:12:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:12:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:12:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:12:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:12:20 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:12:20 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:12:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:12:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:12:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:12:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:12:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:12:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:12:20 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:12:20 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:12:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:12:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:12:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:12:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:12:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:12:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:12:21 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:12:21 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 14:12:21 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 14:12:21 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 14:12:21 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 14:12:21 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 14:12:21 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 14:12:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 14:12:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:12:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:12:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:12:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:12:21 INFO - no previously-included directories found matching 'docs/_build' 14:12:21 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:12:21 INFO - Running setup.py install for pep8 14:12:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:12:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:12:21 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:12:21 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:12:21 INFO - no previously-included directories found matching 'docs/_build' 14:12:21 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 14:12:21 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:12:21 INFO - Cleaning up... 14:12:21 INFO - Return code: 0 14:12:21 INFO - Running post-action listener: _resource_record_post_action 14:12:21 INFO - Running post-action listener: _start_resource_monitoring 14:12:21 INFO - Starting resource monitoring. 14:12:21 INFO - ##### 14:12:21 INFO - ##### Finished create-virtualenv step (success) 14:12:21 INFO - ##### 14:12:21 INFO - ##### 14:12:21 INFO - ##### Running install step. 14:12:21 INFO - ##### 14:12:21 INFO - Running pre-action listener: _resource_record_pre_action 14:12:21 INFO - Running main action method: install 14:12:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:12:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:12:22 INFO - Reading from file tmpfile_stdout 14:12:22 INFO - Detecting whether we're running mozinstall >=1.0... 14:12:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:12:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:12:22 INFO - Reading from file tmpfile_stdout 14:12:22 INFO - Output received: 14:12:22 INFO - Usage: mozinstall [options] installer 14:12:22 INFO - Options: 14:12:22 INFO - -h, --help show this help message and exit 14:12:22 INFO - -d DEST, --destination=DEST 14:12:22 INFO - Directory to install application into. [default: 14:12:22 INFO - "/builds/slave/test"] 14:12:22 INFO - --app=APP Application being installed. [default: firefox] 14:12:22 INFO - mkdir: /builds/slave/test/build/application 14:12:22 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'] 14:12:22 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 14:12:56 INFO - Reading from file tmpfile_stdout 14:12:56 INFO - Output received: 14:12:56 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:12:56 INFO - Running post-action listener: _resource_record_post_action 14:12:56 INFO - ##### 14:12:56 INFO - ##### Finished install step (success) 14:12:56 INFO - ##### 14:12:56 INFO - ##### 14:12:56 INFO - ##### Running run-tests step. 14:12:56 INFO - ##### 14:12:56 INFO - Running pre-action listener: _resource_record_pre_action 14:12:56 INFO - Running main action method: run_tests 14:12:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:12:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:12:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:12:56 INFO - 2016-06-01 14:12:56.359 screenresolution[1644:7636] starting screenresolution argv=screenresolution get 14:12:56 INFO - 2016-06-01 14:12:56.362 screenresolution[1644:7636] Display 0: 1600x1200x32@60 14:12:56 INFO - 2016-06-01 14:12:56.367 screenresolution[1645:7638] starting screenresolution argv=screenresolution list 14:12:56 INFO - Available Modes on Display 0 14:12:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:12:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:12:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:12:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:12:56 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:12:56 INFO - Intel Iris: 14:12:56 INFO - Chipset Model: Intel Iris 14:12:56 INFO - Type: GPU 14:12:56 INFO - Bus: Built-In 14:12:56 INFO - VRAM (Dynamic, Max): 1536 MB 14:12:56 INFO - Vendor: Intel (0x8086) 14:12:56 INFO - Device ID: 0x0a2e 14:12:56 INFO - Revision ID: 0x0009 14:12:56 INFO - Displays: 14:12:56 INFO - Display: 14:12:56 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:12:56 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:12:56 INFO - Display Serial Number: 1600x1200 60 14:12:56 INFO - Main Display: Yes 14:12:56 INFO - Mirror: Off 14:12:56 INFO - Online: Yes 14:12:56 INFO - Rotation: Supported 14:12:56 INFO - Return code: 0 14:12:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:12:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:12:56 INFO - Python 2.7.3 14:12:56 INFO - Return code: 0 14:12:56 INFO - grabbing minidump binary from tooltool 14:12:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:12:56 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 0x106efed30>, '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 0x106d22db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106ef5b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:12:56 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 14:12:56 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 14:12:56 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 14:12:56 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:12:56 INFO - Return code: 0 14:12:56 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:12:56 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:12:56 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:12:56 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:12:56 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:12:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 14:12:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 14:12:57 INFO - Return code: 0 14:12:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/U_5KaG-5RjK-G_w6NrjVtw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0059', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 14:12:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/U_5KaG-5RjK-G_w6NrjVtw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0059 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 14:12:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3IMx3hi8N8/Render', 14:12:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:12:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:12:57 INFO - 'HOME': '/Users/cltbld', 14:12:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:12:57 INFO - 'LOGNAME': 'cltbld', 14:12:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:12:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:12:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:12:57 INFO - 'MOZ_NO_REMOTE': '1', 14:12:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:12:57 INFO - 'NO_EM_RESTART': '1', 14:12:57 INFO - 'PAGER': '/bin/cat', 14:12:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:12:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:12:57 INFO - 'PWD': '/builds/slave/test', 14:12:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:12:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:12:57 INFO - 'SHELL': '/bin/bash', 14:12:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.xzvODllfJj/Listeners', 14:12:57 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 14:12:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:12:57 INFO - 'USER': 'cltbld', 14:12:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:12:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:12:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:12:57 INFO - 'XPC_FLAGS': '0x0', 14:12:57 INFO - 'XPC_SERVICE_NAME': '0', 14:12:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:12:57 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/U_5KaG-5RjK-G_w6NrjVtw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0059', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 14:12:58 INFO - mozversion application_buildid: 20160601115742 14:12:58 INFO - mozversion application_changeset: 248d35aedf8866d3b5bf747324a1384aa1228d8e 14:12:58 INFO - mozversion application_display_name: Nightly 14:12:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:12:58 INFO - mozversion application_name: Firefox 14:12:58 INFO - mozversion application_remotingname: firefox 14:12:58 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 14:12:58 INFO - mozversion application_vendor: Mozilla 14:12:58 INFO - mozversion application_version: 49.0a1 14:12:58 INFO - mozversion platform_buildid: 20160601115742 14:12:58 INFO - mozversion platform_changeset: 248d35aedf8866d3b5bf747324a1384aa1228d8e 14:12:58 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 14:12:58 INFO - mozversion platform_version: 49.0a1 14:12:58 INFO - using testdate: 1464815578 14:12:58 INFO - actual date: 1464815578 14:12:58 INFO - starting webserver on 'localhost:49276' 14:12:58 INFO - SUITE-START | Running 2 tests 14:12:58 INFO - TEST-START | dromaeo_css 14:12:58 INFO - operating with platform_type : mac_ 14:12:58 INFO - Initialising browser for dromaeo_css test... 14:12:58 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/tmpwkjSzT/profile http://localhost:49276/getInfo.html) 14:13:00 INFO - PROCESS | 1663 | __metrics Screen width/height:1600/1200 14:13:00 INFO - PROCESS | 1663 | colorDepth:24 14:13:00 INFO - PROCESS | 1663 | Browser inner width/height: 1024/689 14:13:00 INFO - PROCESS | 1663 | __metrics 14:13:00 INFO - PROCESS | 1663 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:13:00 ERROR - PROCESS | 1663 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 14:13:01 INFO - TEST-INFO | 1663: exit 0 14:13:01 INFO - Browser initialized. 14:13:01 INFO - Running cycle 1/1 for dromaeo_css test... 14:13:01 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/tmpwkjSzT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:13:12 INFO - PROCESS | 1665 | RSS: Main: 241971200 14:13:12 INFO - PROCESS | 1665 | 14:14:04 INFO - PROCESS | 1665 | 1464815644539 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] 14:16:15 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49276/tests/dromaeo/cssquery-ext.html) 14:16:16 INFO - PROCESS | 1665 | RSS: Main: 354357248 14:16:16 INFO - PROCESS | 1665 | 14:19:19 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-ext.html (next: http://localhost:49276/tests/dromaeo/cssquery-jquery.html) 14:19:20 INFO - PROCESS | 1665 | RSS: Main: 375431168 14:19:20 INFO - PROCESS | 1665 | 14:22:22 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49276/tests/dromaeo/cssquery-mootools.html) 14:22:23 INFO - PROCESS | 1665 | RSS: Main: 373760000 14:22:23 INFO - PROCESS | 1665 | 14:25:25 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49276/tests/dromaeo/cssquery-prototype.html) 14:25:26 INFO - PROCESS | 1665 | RSS: Main: 375930880 14:25:26 INFO - PROCESS | 1665 | 14:28:29 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49276/tests/dromaeo/cssquery-yui.html) 14:28:30 INFO - PROCESS | 1665 | RSS: Main: 346230784 14:28:30 INFO - PROCESS | 1665 | 14:31:33 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49276/tests/dromaeo/cssquery-yui.html (next: http://localhost:49276/tests/dromaeo/cssquery-dojo.html) 14:31:33 INFO - PROCESS | 1665 | RSS: Main: 382935040 14:31:33 INFO - PROCESS | 1665 | 14:31:33 INFO - PROCESS | 1665 | __start_tp_report 14:31:33 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 14:31:33 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 14:31:33 INFO - PROCESS | 1665 | |i|pagename|runs| 14:31:33 INFO - PROCESS | 1665 | |0;dojo.html;3205.43904816657;3395;3414.5902491700986;3449.655034496549;3472.496488009239;5563.193336966143;5569.526590239824;5628.971855140729;5632.521235694966;5669.574781891364;5653.406392328798;5690.146478028298;5694.601377903538;5698.316202055748;5712.857178570538;5139.871503212412;5176.145935920568;5210.921836172461;5216.061109000379;5257.658252213594;5264.894702105956;5271.604629652791;5286.524212820846;5292.92060619091;5296.576273898079;5181.119209734355;5187.221916712486;5192.688438693691;5246.344206974129;5264.8683782905355;5240.899411123688;5278;5284;5308.283381743492;5347.25138480613;4205.684573656988;5088.542031217208;5107.897842043176;5192.818251361185;5222.451642577551;5558.583106267006;5559.805406810783;5563.777448902039;5565.582581306418;5620.91568626471;6229.190205273286;6256;6256.6871656417;6256.937430625727;6257.217847769029;5028.22062580297;6158.291796443376;6210.975189093794;6304.085907543381;6326.778562750282;5752.913706294409;5826.329972053183;5827.417258274223;5829.533637309005;5832.24180856486;6264.843378915563;6290.905636415458;6305.905411418832;6307.716152773136;6315.305316415191;5988.760449581968;5998.940010599926;6062.636241825505;6164;6188.597741146811;6193.473554747807;6251.9687401562705;6266.185395898504;6280.05799130125;6280.089387038854;6148.9692551536955;6176.135341052256;6193.721282542297;6198.0702894566175;6200.193974783249;5973.432523910222;6003;6219.533534984804;6229;6229.127922090911;4803.687760295569;4816.879578010507;4828.806847726051;4835.758212089381;4842.806287748451;4373.387725718402;4390.429244198233;4425.247707889602;4425.889352766207;4431.069475410135;4849.927251091236;5708.257926469532;5755.1367294906095;5767.913481297784;5799.594028417971;5475.561955043587;6227.6263424193785;6247.969085100907;6254.874902501924;6296.905546416808;4750.61995040396;5621.4659607337235;5682.374938756733;5743.339515955635;5745.885082298414;5324.227986941872;5340.038793017216;5350.170723537858;5364.22218778275;5404.54061404785;6309.842253943596;6317.526185536103;6353.491720662336;6364.872702546016;6373.139626150503;5489.737360407208;6002.009668404664;6143.665407145256;6223;6273.247210334789;5369.865753356197;6250.406211409909;6350.650714210763;6362.268339140873;6369.172007638977;6132.55978547995;6200.534959877937;6201.658908760061;6213.9067913982235;6214.40963108504;6107.4922612924165;6198.690065496798;6207.16203312547;6276.058591211355;6301.779437719658;6115.331611966083;6118.663473508822;6126.487138463829;6128.258691089182;6134.4978165938865;5775.075987841925;6097.786577469945;6098.237720284965;6118.510519158368;6124;5779.075347944308;5790.449907258803;5843.2695913010875;5847.64914105138;5980.970095149671;6062.42406971337;6109.009944784373;6112.229929638781;6120.588748038681;6130.647240459146;5910.379410162043;5914.970425147847;5916.083007133901;5927.555433342603;5937.732802023833 14:31:33 INFO - PROCESS | 1665 | |1;ext.html;512.6846989101701;514.7477735909404;516.9638125331227;546.7211722021767;562.7001626243378;30817.22956926075;31251.531227031584;32366.67633323666;32414.351712965727;32561.67438325616;43628.90927726808;44676.77661611688;44928;47290.76354618231;47619;9402.62389504419;9588.137067663909;9616.663416780415;9618.519074046304;9661;8578.571071446433;8830.426022308562;9509.001554836712;9648.877247005648;9649.228061755079;11336.093112551018;11541.769164616702;11550;11826.349550774705;11826.881731182664;11593.24643898144;11676.708082297926;11691.883081169164;11694.239874408222;11708.941455292668;8332.541710205936;8518.148185181495;8538.957305213435;8670.219680228809;11513.769724605461;41142.38286425706;41765.1646967059;41777.16445671069;43286.35070473945;43334.56665433337;3488.2674638325984;3817.48463957365;3967.5238971323624;4002.3596224604216;4341.41390912226;31901.361972760416;31982.6801731982;32116.03651890447;32254.516182257285;33173.50239746406;3217.8551965161623;3239.578854748891;3240.562524059269;3249.000537024879;3349.866005359783;538576;543971;545427;545466;546702;56610;61610.0758488623;62949;62983.68508157522;66690;55918.720406397704;66767.66616166985;67168.32831671719;67296.32703672901;67358.66320668464;16725.247363868413;16896.746548801777;16925.153742312836;16933.15334233308;16978.745318820227;13016.934915325552;13136.671583210496;13136.737265254642;13254.873335766375;13256.337183140997;14825.036372635745;15165.545033649008;15195.088294702133;15478.767818482731;15489;5716.171155182476;5745.741441635137;6082.239720034996;6325.588836725598;6367.904481432783;884.7964968057382;906.5376657904516;915.4370062411568;998.8701468809154;1002.884668263159;30622.08133755991;30847.383052338828;31006.22484437862;31209.8439507801;31343.990349867126;1050.448514529878;1124.4658787076073;1153.532819208222;1183.8579370475427;1221.3343727668264;15998.280077396545;16085;16091.597710057107;16156.67686646284;16557.337706491606;655084;664402;670012.1995120238;670342;670893;52588;60386.39613603896;61334.38665613287;61448.692756536824;61848.381516184265;1429.535400994681;1446.0167086381152;1454.4327712192041;1455.678193281714;1459.9781003284959;30954.53568196434;31263.53104703386;31674.68325316717;31931.36137277288;32712.018639440856;53157.46842531525;53207;53835.730821347206;53855.73072134614;53857.11500097478;662426;663975;665763;668814;670412;40071.19857602773;41179.58820411757;41531.79234103931;41648.95877603084;41723.582764171966;48796.75601621969;49050.77373065702;49244.2613360807;49567.00865982732;51438.74280628573;39983.60016399915;41286.38070429006;41859;42433.72698819041;42696;39844.40233396559;40001.39997900092;40176;41367.793161034;41388;36627.718029868825;40162;40240.993975149686;41198;41994;35008;35273;35465;36068;36102;25784.355391115372;26198.690065496266;26234.21297361082;26404.471910561297;26437.248568201154 14:31:33 INFO - PROCESS | 1665 | |2;jquery.html;380.43885269227883;385.1680370399935;388.5803332401005;399.0442889280176;401.67865707434044;19881.304154354584;21338.786612133874;21601.45996350092;21746.238881639147;22256.8887155564;11646.650600481998;12159.57441489548;12165.635030949064;20647.38057858263;21610;5286.894262114746;5346.973265133669;5449.128139497681;5493.533049690761;5493.807716729915;5227.18978558324;5328.094223981913;5356.196570514415;5387.1111266640955;5398.244245805571;5285.656432331905;5351.812686555972;5372.167314066326;5381.354237491507;5383.892322153554;5202.167653175474;5333.57331413486;5351.25082488452;5353.518183363516;5365.704886231256;4463.464384273875;4990.101781679296;5192.247124166975;5217.139172036647;5224.268602395668;13504.32478376087;13509.05436619437;13511.797323040064;13566.45734170632;16391.67216655672;2021.181421524285;2232.0848452134546;2280.247518318951;2405;2942.8528573571234;9946.254030947635;9948.10467057961;10063.748406289827;10471.057604815604;10471.214658900613;1759.7976232733145;1801.2704854533936;1808.4574627611664;1859.9721004184948;1898.4779185723899;79650;105257;119136;119427.2085918712;120219.99999999913;11322.716932076764;11384.430778461045;11569.76860462786;11646.068314534818;11684.88315116855;14599.78100328496;14736;14737.48418805358;14740.926295368454;14756.778648320284;6850.725970961105;6875.278095799969;8518.318534517224;8539.786505337292;8647.481151130953;8392.314459372941;8393.202645748644;8502.319814414832;8513.489190648581;8575.699850505202;4495.57292057254;4545.136424079415;4568.246239370533;4569.131864945716;4634.050019745944;1648.83756951349;1894.488488108203;1943.8250557449614;1946.7566554180728;1986.9205231790565;904.4889637354873;906.419891269588;962.5860879821604;992.503748125937;1071.8338657508098;18691.71962420565;20516.897415512827;21339.67990480144;21459.356219313446;21491.8179500129;928.5960607135918;939.0750111140521;993.9503024848729;1018.9643362482277;1021.3412349034979;11058.447077646086;11147.108231341474;11210.159238057015;11311.377874216998;11312.717182070513;532223;603190;614277;616466;621516;88441;121968;129137;139453;144706.276468617;976.077606661712;976.497103991445;1006.4816619440994;1035.4563853976572;1055.3140458701905;13973.860261397662;14667.699767552216;17346.65306693884;19003.049847507846;19401.708974365112;52040.479595203564;54693.453065468835;56386;57110.16394425655;57936.13095803482;85301;85768.57115714382;86244.56877715822;86766.69849952382;86930.56534717286;7720.420968427278;7766.844663106819;7906.960465197637;7942.126366099609;8188.222118898695;10163.54264058134;12039.75920481603;12804.61586152417;12917.677058073623;12975.870241297465;11146.108311334912;11221.270617409842;11340.262882912584;11352.829707554229;11373.715657108638;11023.338599684044;11036.503357348778;11192.776144476902;11218.439078046229;11560.42197890119;11159.944200278947;11532.654020379403;11681;11688.240264382788;11773.293602383885;8165.14266002085;8247.95876020616;8309.04445988719;8325.000999880052;8436.704715334934;11192.272502287326;11270.549178032787;11544.538218471504;11575;11617.070634349062 14:31:33 INFO - PROCESS | 1665 | |3;mootools.html;1413.8586141385856;1585.0489706176293;1602.230929154006;1867.5704587944772;2008.307134038757;8167.91832081679;8942.91057089429;9144.359894807367;9154.86267705985;9264;8939.865902011476;9055.185033346997;9100.40847344922;9110.316726245524;9116.04281550436;2118.3221369161874;2197.604431202383;2399.5440866235404;2429.5991161458337;2440.3777036855668;2195.6267434536167;2222.7110475638146;2365.8225633077586;2455.8035357171475;2456.8894399752053;2421.2857207123852;2424.260600516842;2429.8906549205326;2430.283066495388;2431.3192306154306;2378.940526486843;2424.6363045543135;2425.369403954967;2429.514097180553;2431.4286142756614;1811.4565630310854;2123.819475344598;2313.745487996319;2376.9762302377103;2390.8685022323766;8950.597223124974;9033.54832258391;9046.276297896218;9051.637934482613;9061.18449339556;5385.461453854583;5566.860828479321;5566.914451681923;5570.637908535932;5588.888222235573;3137.51368537877;3551.4140166872435;3677.7793332400142;3757.9436308455397;3763.7929913854728;1051.416463862556;1086.1202426034858;1097.226455348985;1109.450821843193;1123.376526028054;12217.328046957413;12292.201006934522;12298.631041068782;12301.384930753427;12308.199967002205;4740.45484769249;4859.684120532154;4932.901341973212;4937.950620493749;4942.085714142895;10339.896601033894;10396.792064158675;10400.687979360631;10402.843957340647;10425.426601536836;5551.250581171571;5555.250041244381;5559.444055594408;5565.220869078333;5565.9443405566235;4529.184746745552;4927.317820482768;4929.433115191727;5047.596192304607;5084.059449001947;3672.6143754905884;3980.104476492766;4030.9596904031173;4100.938485922714;4107.8973025674;1074.414444127952;1106.4301884529473;1298.9025823063118;1300.291341219019;1311.6392991927296;776.3827756933251;788.578110710767;807.6083099696642;941.4774799985937;976.6386437018349;4905.705657660552;6109.480694140959;6137.539684523678;6202.875942481125;6215.533834962396;814.7840822182127;953.7472569769017;964.5081008685621;997.0976266478704;1000.7798284377425;3998.4602078719;4065;4084.162746636992;4133.689973252018;4156.106437116035;10752.462376881125;11177.608783692533;11187.32876027441;11189;11209.383483908463;10970.012698857141;11168.050715689258;11297.983181513704;11302.321860688386;11306.321620702784;7001.159860816734;8223.54770487617;8561.657533698706;8580.35647326453;8587.785305367292;5991.520678345809;5996.310424301261;6021.42796434338;6029;6043.456088952015;10041.146502547364;10434.321769084985;10436.165106791286;10469.528871200966;10470.476476176313;10214.233932454947;10477.31897426665;10539.894601053891;10616.84074738864;10734.141268698642;6750.35871592198;6968.930310696828;7240.094988126484;7399.963000184965;7420.183779784327;8274.544900030556;8362.121977192308;8366.24703776663;8441.662333506836;8717.956410217908;7263.854722905618;7684.3084122429245;7749.457537972513;7907;7923.920760792549;6680.432163265982;7439.962800186181;7565.016547848744;7690.1925297845155;7878.645460954385;7211.747588834365;7222.566646001257;7291.270872912666;7291.598962057138;7300.379467745089;4922.310876477224;5092;5111.952049829851;5140.871478213075;5200.193969934666;4698.483166851575;4779.696722777607;4869.70781753096;5043.9747801260755;5151.562117220078 14:31:33 INFO - PROCESS | 1665 | |4;prototype.html;301.4558721507678;305.6210299228956;319.1781163503977;321.11372611592;323.91578189670696;5403.945960540394;5413.240696773545;5424.159255315422;5457.808976685817;5470.617056806025;5072.467390923946;5089.872753181172;5239.502247286511;5382.085045542258;5406.405295417501;3870.7098965878918;3912.3740201567753;4138.1103062841485;4153.210889930911;4177.624013838754;4074.286999775042;4136.420901073852;4152.97923510382;4159.6464300534335;4170.18596910345;2895.5367937153947;3462.844172012253;3660.798656073928;3906.765594064351;4155.314373128445;3531.5055892175114;3811.9237615247816;4065.6137666921595;4071.491063617033;4086.2031903778775;2983.701629837021;3400.3199360128074;3551.254236610315;3560.643935606419;3598.118460977051;5059.822906198302;5164.586833053347;5176.637635365526;5201.5838732901275;5280.841574752763;3856.479375284329;4577.404937358122;4659.464161621423;4660.533946605312;4661.88345291367;5612.943870561324;5631.9718401408145;5643.266375371217;5662.348829884575;5679.829605111853;3821.76426790328;3912.7652340859354;3942.172143849795;3956.951407876915;3995.5615978247806;5278.630495865253;5479.753411096471;5889.79385721502;5928.97035514824;5954.225950626391;3693.7414380993073;3989.58109398509;4010.578889216649;4020.276350256982;4030.979845100756;3179.4753865611915;3507.2985402919517;3747.0445036515516;3932.646061854447;4104.671626269951;4067.5525692173837;4599.6090332321455;4639.582437580634;4655.9291362986705;4692.741899195577;4780.139574876486;5456.371512943927;5635.943640563624;5641.717914104278;5686.9715651421475;1281.7052078022107;1290.7031382782013;1381.1505923856755;1387.4658256571292;1394.9721005579831;731.685375288631;876.6449587916904;949.7103383468109;952.3095755577151;953.7997020625608;4239.618434340924;5231.895362092737;5232.2413250078525;5269.602330939413;5310.4424035476495;4790.353302304214;4798.5441383068555;5020.7740651670765;5549.361823390281;5869.20765696634;4682.929756053662;5030.496950304941;5291.444398338196;5309.33633295838;5312.256284120226;2570.4859028194437;2636.4727054589157;2770.503927878963;2814.9014784482856;2960.5263157894633;5346.331708536433;5584.21820945068;5848.5321174305955;5889.823305300848;6119.265688117365;5700.8859822803315;5848.64908105515;5872.706364681749;5882.617629854046;5933.970330148321;5211.687298762162;5269.920951185735;5448.346198456211;5949.970250148722;5987.790427335022;4413.5807098326895;4671.972166123447;4825.396825396825;5758.222639943721;5806.419358064329;5092.6435149539175;5631.49316561495;5636.449976256562;5666.421778091041;5674.744636491285;5465.754041068082;5732.882087992802;5981.7308221131025;5983.683589610278;6013.548983826143;3493.371193185251;3844.788536630512;3931.3709806430834;3935.2129574084724;3964.980175099106;4744.651397558231;5315.9468405317;5330.62685612019;5654.85862853432;5664.801731939362;4770.405144187186;4982.601391888567;5632.774689012557;5636.549076073986;5657.652074389082;4422.955770442383;4799.952000480091;5492.505674489315;5502.5883951950045;5596.944030559643;4224.261528547197;4491.326301054868;4893.877653058702;5124.615653825904;5403.6217464778665;4659.580637742484;4722.811087556459;5335.813246536352;5483.369412517612;5635.211070450058;5526.55787536988;5530;5538.972305138448;5558.333000040021;5615.747291371817 14:31:33 INFO - PROCESS | 1665 | |5;yui.html;717.2003216413699;792.7621713485953;812.7033632724056;816.9387295952802;844.3287586368837;14784.852151478482;14993.47522836701;15256.23718814059;15265;15266;14028.649283767885;14100.788488172686;14100.929495352535;14110.929445352758;14111.647208819784;699.5383047188857;724.7970568240901;726.397090414957;883.2315885179886;904.5567671840784;719.2879049740745;721.1130309719049;881.3566096749377;915.1809130827905;915.276931224332;969.1229437359201;969.1955676788284;975.902409759022;977.6675930183736;978.7455261631991;950.1970834644749;961.2310151878504;969.7914948286082;971.4656938683696;972.6644307713827;542.785599688121;696.5437638346891;716.6094478509225;717.9641017949082;721.779857143571;14706.55880323592;14774.704505909822;14777;14783.260836958108;14785.630359240997;13296.135751176143;13519.594412167651;13583.35581736968;13645.590632280948;14014.509492167826;11758.235714678518;12229.93885030578;12259.448324825315;12275;12441.626751197478;4484.8878778030485;4488.932666010012;4514.638828893713;4685.835995740133;4695.88260293492;51067.489325106646;69398.65300673514;69706.65146674284;81587.3682526352;81635.36729265441;10683.83974240387;10706.054372878058;10717.83923241152;10813.243072984815;11151.888481115086;20564.794352056586;20568.794312056987;20572.897135514526;20578;20616.48458788542;7624.580648064299;7780.922190778133;7795.688172473151;7878.72424465141;7890;4449.466064072268;4473.530279320689;4483.08096840147;4484.170428470743;4579.450465944108;3351.3967485852295;3405.0976491229844;3447.24160684649;3449.6032956209856;3449.741269404805;665.7503436211421;672.7309076369492;677.0825531404973;677.2719326723792;679.3376457953555;489.2324299699381;543.4158279849178;591.074967675595;605.3946053946054;610.6671863834221;6715.832104197337;7943.364530837519;8054.23484768946;8093.392995525359;8211.05572859117;565.451512033327;596.9701514924236;606.448132199705;618.8607563298222;626.5269721360344;3411.6498276239677;4045.1707399982934;4100.507939047334;4253.234417804825;4302.612764851178;70841.93737093947;82607;83228;85425.57287213524;85511;68745;77566;79964;80774.59612702017;80900;30216.24459388489;30388.936387226342;45117;72340.55318893697;72961.63519182477;9504.382215155993;9516.524173791422;9533.094356036165;9554.04459554039;9659.855102173613;45167.09665806731;46103.53896460992;46417.76791116023;46470.30294545652;47172.7641361791;60931.78136437335;63149.36850631435;63314.41713957188;63537.364626354996;64160.67919660372;11913.868182522647;12198.51205951752;12739.936300318439;12886.935565322114;12965.870341296466;14349.784753228918;14486.492972745904;14614.415423382945;14657.853421466076;15010.774838377214;13162.40769165409;13190.73618527605;13235.536756213487;13289.667758305734;13751.587452376445;8162.938817953628;13069;13218.339083046001;13314.443352506216;14144.292785360485;12841.93579032099;12867.420966056363;12872.22766634005;12978.545750898673;13387.263700496565;10940.726481838017;11081.778364432828;11083.94458027737;11165.497552610315;11621.302721836717;8672.306215502618;9538.28462865274;10856.565737370416;11324.754277029355;11452.770944581229 14:31:33 INFO - PROCESS | 1665 | __end_tp_report 14:31:33 INFO - PROCESS | 1665 | __start_cc_report 14:31:33 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,1180 14:31:33 INFO - PROCESS | 1665 | __end_cc_report 14:31:33 INFO - PROCESS | 1665 | __startTimestamp1464816693452__endTimestamp 14:31:33 INFO - PROCESS | 1665 | 14:31:33 INFO - PROCESS | 1665 | ------- Summary: start ------- 14:31:33 INFO - PROCESS | 1665 | Number of tests: 6 14:31:33 INFO - PROCESS | 1665 | 14:31:33 INFO - PROCESS | 1665 | [#0] dojo.html Cycles:165 Average:5696.21 Median:5843.27 stddev:652.54 (11.2%) stddev-sans-first:624.60 14:31:33 INFO - PROCESS | 1665 | Values: 3205.4 3395.0 3414.6 3449.7 3472.5 5563.2 5569.5 5629.0 5632.5 5669.6 5653.4 5690.1 5694.6 5698.3 5712.9 5139.9 5176.1 5210.9 5216.1 5257.7 5264.9 5271.6 5286.5 5292.9 5296.6 5181.1 5187.2 5192.7 5246.3 5264.9 5240.9 5278.0 5284.0 5308.3 5347.3 4205.7 5088.5 5107.9 5192.8 5222.5 5558.6 5559.8 5563.8 5565.6 5620.9 6229.2 6256.0 6256.7 6256.9 6257.2 5028.2 6158.3 6211.0 6304.1 6326.8 5752.9 5826.3 5827.4 5829.5 5832.2 6264.8 6290.9 6305.9 6307.7 6315.3 5988.8 5998.9 6062.6 6164.0 6188.6 6193.5 6252.0 6266.2 6280.1 6280.1 6149.0 6176.1 6193.7 6198.1 6200.2 5973.4 6003.0 6219.5 6229.0 6229.1 4803.7 4816.9 4828.8 4835.8 4842.8 4373.4 4390.4 4425.2 4425.9 4431.1 4849.9 5708.3 5755.1 5767.9 5799.6 5475.6 6227.6 6248.0 6254.9 6296.9 4750.6 5621.5 5682.4 5743.3 5745.9 5324.2 5340.0 5350.2 5364.2 5404.5 6309.8 6317.5 6353.5 6364.9 6373.1 5489.7 6002.0 6143.7 6223.0 6273.2 5369.9 6250.4 6350.7 6362.3 6369.2 6132.6 6200.5 6201.7 6213.9 6214.4 6107.5 6198.7 6207.2 6276.1 6301.8 6115.3 6118.7 6126.5 6128.3 6134.5 5775.1 6097.8 6098.2 6118.5 6124.0 5779.1 5790.4 5843.3 5847.6 5981.0 6062.4 6109.0 6112.2 6120.6 6130.6 5910.4 5915.0 5916.1 5927.6 5937.7 14:31:33 INFO - PROCESS | 1665 | 14:31:33 INFO - PROCESS | 1665 | [#1] ext.html Cycles:180 Average:76007.76 Median:41188.79 stddev:168032.39 (408.0%) stddev-sans-first:168408.17 14:31:33 INFO - PROCESS | 1665 | Values: 512.7 514.7 517.0 546.7 562.7 30817.2 31251.5 32366.7 32414.4 32561.7 43628.9 44676.8 44928.0 47290.8 47619.0 9402.6 9588.1 9616.7 9618.5 9661.0 8578.6 8830.4 9509.0 9648.9 9649.2 11336.1 11541.8 11550.0 11826.3 11826.9 11593.2 11676.7 11691.9 11694.2 11708.9 8332.5 8518.1 8539.0 8670.2 11513.8 41142.4 41765.2 41777.2 43286.4 43334.6 3488.3 3817.5 3967.5 4002.4 4341.4 31901.4 31982.7 32116.0 32254.5 33173.5 3217.9 3239.6 3240.6 3249.0 3349.9 538576.0 543971.0 545427.0 545466.0 546702.0 56610.0 61610.1 62949.0 62983.7 66690.0 55918.7 66767.7 67168.3 67296.3 67358.7 16725.2 16896.7 16925.2 16933.2 16978.7 13016.9 13136.7 13136.7 13254.9 13256.3 14825.0 15165.5 15195.1 15478.8 15489.0 5716.2 5745.7 6082.2 6325.6 6367.9 884.8 906.5 915.4 998.9 1002.9 30622.1 30847.4 31006.2 31209.8 31344.0 1050.4 1124.5 1153.5 1183.9 1221.3 15998.3 16085.0 16091.6 16156.7 16557.3 655084.0 664402.0 670012.2 670342.0 670893.0 52588.0 60386.4 61334.4 61448.7 61848.4 1429.5 1446.0 1454.4 1455.7 1460.0 30954.5 31263.5 31674.7 31931.4 32712.0 53157.5 53207.0 53835.7 53855.7 53857.1 662426.0 663975.0 665763.0 668814.0 670412.0 40071.2 41179.6 41531.8 41649.0 41723.6 48796.8 49050.8 49244.3 49567.0 51438.7 39983.6 41286.4 41859.0 42433.7 42696.0 39844.4 40001.4 40176.0 41367.8 41388.0 36627.7 40162.0 40241.0 41198.0 41994.0 35008.0 35273.0 35465.0 36068.0 36102.0 25784.4 26198.7 26234.2 26404.5 26437.2 14:31:33 INFO - PROCESS | 1665 | 14:31:33 INFO - PROCESS | 1665 | [#2] jquery.html Cycles:180 Average:34440.19 Median:21678.12 stddev:99906.17 (460.9%) stddev-sans-first:100153.70 14:31:33 INFO - PROCESS | 1665 | Values: 380.4 385.2 388.6 399.0 401.7 19881.3 21338.8 21601.5 21746.2 22256.9 11646.7 12159.6 12165.6 20647.4 21610.0 5286.9 5347.0 5449.1 5493.5 5493.8 5227.2 5328.1 5356.2 5387.1 5398.2 5285.7 5351.8 5372.2 5381.4 5383.9 5202.2 5333.6 5351.3 5353.5 5365.7 4463.5 4990.1 5192.2 5217.1 5224.3 13504.3 13509.1 13511.8 13566.5 16391.7 2021.2 2232.1 2280.2 2405.0 2942.9 9946.3 9948.1 10063.7 10471.1 10471.2 1759.8 1801.3 1808.5 1860.0 1898.5 79650.0 105257.0 119136.0 119427.2 120220.0 11322.7 11384.4 11569.8 11646.1 11684.9 14599.8 14736.0 14737.5 14740.9 14756.8 6850.7 6875.3 8518.3 8539.8 8647.5 8392.3 8393.2 8502.3 8513.5 8575.7 4495.6 4545.1 4568.2 4569.1 4634.1 1648.8 1894.5 1943.8 1946.8 1986.9 904.5 906.4 962.6 992.5 1071.8 18691.7 20516.9 21339.7 21459.4 21491.8 928.6 939.1 994.0 1019.0 1021.3 11058.4 11147.1 11210.2 11311.4 11312.7 532223.0 603190.0 614277.0 616466.0 621516.0 88441.0 121968.0 129137.0 139453.0 144706.3 976.1 976.5 1006.5 1035.5 1055.3 13973.9 14667.7 17346.7 19003.0 19401.7 52040.5 54693.5 56386.0 57110.2 57936.1 85301.0 85768.6 86244.6 86766.7 86930.6 7720.4 7766.8 7907.0 7942.1 8188.2 10163.5 12039.8 12804.6 12917.7 12975.9 11146.1 11221.3 11340.3 11352.8 11373.7 11023.3 11036.5 11192.8 11218.4 11560.4 11159.9 11532.7 11681.0 11688.2 11773.3 8165.1 8248.0 8309.0 8325.0 8436.7 11192.3 11270.5 11544.5 11575.0 11617.1 14:31:33 INFO - PROCESS | 1665 | 14:31:33 INFO - PROCESS | 1665 | [#3] mootools.html Cycles:180 Average:5863.26 Median:4864.70 stddev:3347.10 (68.8%) stddev-sans-first:3339.79 14:31:33 INFO - PROCESS | 1665 | Values: 1413.9 1585.0 1602.2 1867.6 2008.3 8167.9 8942.9 9144.4 9154.9 9264.0 8939.9 9055.2 9100.4 9110.3 9116.0 2118.3 2197.6 2399.5 2429.6 2440.4 2195.6 2222.7 2365.8 2455.8 2456.9 2421.3 2424.3 2429.9 2430.3 2431.3 2378.9 2424.6 2425.4 2429.5 2431.4 1811.5 2123.8 2313.7 2377.0 2390.9 8950.6 9033.5 9046.3 9051.6 9061.2 5385.5 5566.9 5566.9 5570.6 5588.9 3137.5 3551.4 3677.8 3757.9 3763.8 1051.4 1086.1 1097.2 1109.5 1123.4 12217.3 12292.2 12298.6 12301.4 12308.2 4740.5 4859.7 4932.9 4938.0 4942.1 10339.9 10396.8 10400.7 10402.8 10425.4 5551.3 5555.3 5559.4 5565.2 5565.9 4529.2 4927.3 4929.4 5047.6 5084.1 3672.6 3980.1 4031.0 4100.9 4107.9 1074.4 1106.4 1298.9 1300.3 1311.6 776.4 788.6 807.6 941.5 976.6 4905.7 6109.5 6137.5 6202.9 6215.5 814.8 953.7 964.5 997.1 1000.8 3998.5 4065.0 4084.2 4133.7 4156.1 10752.5 11177.6 11187.3 11189.0 11209.4 10970.0 11168.1 11298.0 11302.3 11306.3 7001.2 8223.5 8561.7 8580.4 8587.8 5991.5 5996.3 6021.4 6029.0 6043.5 10041.1 10434.3 10436.2 10469.5 10470.5 10214.2 10477.3 10539.9 10616.8 10734.1 6750.4 6968.9 7240.1 7400.0 7420.2 8274.5 8362.1 8366.2 8441.7 8718.0 7263.9 7684.3 7749.5 7907.0 7923.9 6680.4 7440.0 7565.0 7690.2 7878.6 7211.7 7222.6 7291.3 7291.6 7300.4 4922.3 5092.0 5112.0 5140.9 5200.2 4698.5 4779.7 4869.7 5044.0 5151.6 14:31:33 INFO - PROCESS | 1665 | 14:31:33 INFO - PROCESS | 1665 | [#4] prototype.html Cycles:180 Average:4498.02 Median:5066.15 stddev:1384.25 (27.3%) stddev-sans-first:1351.82 14:31:33 INFO - PROCESS | 1665 | Values: 301.5 305.6 319.2 321.1 323.9 5403.9 5413.2 5424.2 5457.8 5470.6 5072.5 5089.9 5239.5 5382.1 5406.4 3870.7 3912.4 4138.1 4153.2 4177.6 4074.3 4136.4 4153.0 4159.6 4170.2 2895.5 3462.8 3660.8 3906.8 4155.3 3531.5 3811.9 4065.6 4071.5 4086.2 2983.7 3400.3 3551.3 3560.6 3598.1 5059.8 5164.6 5176.6 5201.6 5280.8 3856.5 4577.4 4659.5 4660.5 4661.9 5612.9 5632.0 5643.3 5662.3 5679.8 3821.8 3912.8 3942.2 3957.0 3995.6 5278.6 5479.8 5889.8 5929.0 5954.2 3693.7 3989.6 4010.6 4020.3 4031.0 3179.5 3507.3 3747.0 3932.6 4104.7 4067.6 4599.6 4639.6 4655.9 4692.7 4780.1 5456.4 5635.9 5641.7 5687.0 1281.7 1290.7 1381.2 1387.5 1395.0 731.7 876.6 949.7 952.3 953.8 4239.6 5231.9 5232.2 5269.6 5310.4 4790.4 4798.5 5020.8 5549.4 5869.2 4682.9 5030.5 5291.4 5309.3 5312.3 2570.5 2636.5 2770.5 2814.9 2960.5 5346.3 5584.2 5848.5 5889.8 6119.3 5700.9 5848.6 5872.7 5882.6 5934.0 5211.7 5269.9 5448.3 5950.0 5987.8 4413.6 4672.0 4825.4 5758.2 5806.4 5092.6 5631.5 5636.4 5666.4 5674.7 5465.8 5732.9 5981.7 5983.7 6013.5 3493.4 3844.8 3931.4 3935.2 3965.0 4744.7 5315.9 5330.6 5654.9 5664.8 4770.4 4982.6 5632.8 5636.5 5657.7 4423.0 4800.0 5492.5 5502.6 5596.9 4224.3 4491.3 4893.9 5124.6 5403.6 4659.6 4722.8 5335.8 5483.4 5635.2 5526.6 5530.0 5539.0 5558.3 5615.7 14:31:33 INFO - PROCESS | 1665 | 14:31:33 INFO - PROCESS | 1665 | [#5] yui.html Cycles:180 Average:17400.02 Median:46444.04 stddev:22949.07 (49.4%) stddev-sans-first:22979.26 14:31:33 INFO - PROCESS | 1665 | Values: 717.2 792.8 812.7 816.9 844.3 14784.9 14993.5 15256.2 15265.0 15266.0 14028.6 14100.8 14100.9 14110.9 14111.6 699.5 724.8 726.4 883.2 904.6 719.3 721.1 881.4 915.2 915.3 969.1 969.2 975.9 977.7 978.7 950.2 961.2 969.8 971.5 972.7 542.8 696.5 716.6 718.0 721.8 14706.6 14774.7 14777.0 14783.3 14785.6 13296.1 13519.6 13583.4 13645.6 14014.5 11758.2 12229.9 12259.4 12275.0 12441.6 4484.9 4488.9 4514.6 4685.8 4695.9 51067.5 69398.7 69706.7 81587.4 81635.4 10683.8 10706.1 10717.8 10813.2 11151.9 20564.8 20568.8 20572.9 20578.0 20616.5 7624.6 7780.9 7795.7 7878.7 7890.0 4449.5 4473.5 4483.1 4484.2 4579.5 3351.4 3405.1 3447.2 3449.6 3449.7 665.8 672.7 677.1 677.3 679.3 489.2 543.4 591.1 605.4 610.7 6715.8 7943.4 8054.2 8093.4 8211.1 565.5 597.0 606.4 618.9 626.5 3411.6 4045.2 4100.5 4253.2 4302.6 70841.9 82607.0 83228.0 85425.6 85511.0 68745.0 77566.0 79964.0 80774.6 80900.0 30216.2 30388.9 45117.0 72340.6 72961.6 9504.4 9516.5 9533.1 9554.0 9659.9 45167.1 46103.5 46417.8 46470.3 47172.8 60931.8 63149.4 63314.4 63537.4 64160.7 11913.9 12198.5 12739.9 12886.9 12965.9 14349.8 14486.5 14614.4 14657.9 15010.8 13162.4 13190.7 13235.5 13289.7 13751.6 8162.9 13069.0 13218.3 13314.4 14144.3 12841.9 12867.4 12872.2 12978.5 13387.3 10940.7 11081.8 11083.9 11165.5 11621.3 8672.3 9538.3 10856.6 11324.8 11452.8 14:31:33 INFO - PROCESS | 1665 | -------- Summary: end -------- 14:31:33 INFO - PROCESS | 1665 | 14:31:34 INFO - TEST-INFO | 1665: exit 0 14:31:34 INFO - TEST-OK | dromaeo_css | took 1116081ms 14:31:34 INFO - TEST-START | kraken 14:31:34 INFO - operating with platform_type : mac_ 14:31:34 INFO - Initialising browser for kraken test... 14:31:34 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/tmpKCxMTL/profile http://localhost:49276/getInfo.html) 14:31:36 INFO - PROCESS | 1672 | __metrics Screen width/height:1600/1200 14:31:36 INFO - PROCESS | 1672 | colorDepth:24 14:31:36 INFO - PROCESS | 1672 | Browser inner width/height: 1024/689 14:31:36 INFO - PROCESS | 1672 | __metrics 14:31:36 INFO - PROCESS | 1672 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:31:36 ERROR - PROCESS | 1672 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 14:31:37 INFO - TEST-INFO | 1672: exit 0 14:31:37 INFO - Browser initialized. 14:31:37 INFO - Running cycle 1/1 for kraken test... 14:31:37 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/tmpKCxMTL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:31:49 INFO - PROCESS | 1673 | RSS: Main: 241819648 14:31:49 INFO - PROCESS | 1673 | 14:31:51 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection) 14:31:52 INFO - PROCESS | 1673 | RSS: Main: 292429824 14:31:52 INFO - PROCESS | 1673 | 14:31:55 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-dft) 14:31:55 INFO - PROCESS | 1673 | RSS: Main: 301895680 14:31:55 INFO - PROCESS | 1673 | 14:31:59 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-fft) 14:32:00 INFO - PROCESS | 1673 | RSS: Main: 389165056 14:32:00 INFO - PROCESS | 1673 | 14:32:02 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator) 14:32:02 INFO - PROCESS | 1673 | RSS: Main: 289583104 14:32:02 INFO - PROCESS | 1673 | 14:32:04 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:32:05 INFO - PROCESS | 1673 | RSS: Main: 293388288 14:32:05 INFO - PROCESS | 1673 | 14:32:08 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom) 14:32:08 INFO - PROCESS | 1673 | RSS: Main: 329871360 14:32:08 INFO - PROCESS | 1673 | 14:32:11 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate) 14:32:12 INFO - PROCESS | 1673 | RSS: Main: 332492800 14:32:12 INFO - PROCESS | 1673 | 14:32:14 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial) 14:32:15 INFO - PROCESS | 1673 | RSS: Main: 331374592 14:32:15 INFO - PROCESS | 1673 | 14:32:17 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:32:17 INFO - PROCESS | 1673 | RSS: Main: 310054912 14:32:17 INFO - PROCESS | 1673 | 14:32:19 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:32:20 INFO - PROCESS | 1673 | RSS: Main: 317714432 14:32:20 INFO - PROCESS | 1673 | 14:32:22 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:32:23 INFO - PROCESS | 1673 | RSS: Main: 320282624 14:32:23 INFO - PROCESS | 1673 | 14:32:25 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:32:26 INFO - PROCESS | 1673 | RSS: Main: 322928640 14:32:26 INFO - PROCESS | 1673 | 14:32:29 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:32:30 INFO - PROCESS | 1673 | RSS: Main: 324706304 14:32:30 INFO - PROCESS | 1673 | 14:32:31 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49276/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49276/tests/kraken/driver.html?testName=ai-astar) 14:32:32 INFO - PROCESS | 1673 | RSS: Main: 368402432 14:32:32 INFO - PROCESS | 1673 | 14:32:32 INFO - PROCESS | 1673 | __start_tp_report 14:32:32 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 14:32:32 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 14:32:32 INFO - PROCESS | 1673 | |i|pagename|runs| 14:32:32 INFO - PROCESS | 1673 | |0;ai-astar;113;108;112;112;112;112;112;112;111;113 14:32:32 INFO - PROCESS | 1673 | |1;audio-beat-detection;157;126;118;167;119;119;141;117;150;118 14:32:32 INFO - PROCESS | 1673 | |2;audio-dft;183;186;183;183;183;181;180;182;185;182 14:32:32 INFO - PROCESS | 1673 | |3;audio-fft;77;73;57;107;58;57;64;63;97;56 14:32:32 INFO - PROCESS | 1673 | |4;audio-oscillator;85;84;92;70;71;68;94;69;67;100 14:32:32 INFO - PROCESS | 1673 | |5;imaging-gaussian-blur;86;83;87;84;87;83;91;128;86;82 14:32:32 INFO - PROCESS | 1673 | |6;imaging-darkroom;93;96;93;94;93;92;94;94;93;92 14:32:32 INFO - PROCESS | 1673 | |7;imaging-desaturate;84;85;84;84;85;84;86;82;86;86 14:32:32 INFO - PROCESS | 1673 | |8;json-parse-financial;53;53;57;57;55;55;57;57;57;55 14:32:32 INFO - PROCESS | 1673 | |9;json-stringify-tinderbox;65;69;75;80;70;71;69;70;70;69 14:32:32 INFO - PROCESS | 1673 | |10;stanford-crypto-aes;86;86;87;86;84;80;85;85;120;87 14:32:32 INFO - PROCESS | 1673 | |11;stanford-crypto-ccm;141;139;138;142;142;134;141;143;153;129 14:32:32 INFO - PROCESS | 1673 | |12;stanford-crypto-pbkdf2;163;164;164;163;168;219;202;166;161;163 14:32:32 INFO - PROCESS | 1673 | |13;stanford-crypto-sha256-iterative;66;66;68;66;64;69;67;66;65;65 14:32:32 INFO - PROCESS | 1673 | __end_tp_report 14:32:32 INFO - PROCESS | 1673 | __start_cc_report 14:32:32 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,1937 14:32:32 INFO - PROCESS | 1673 | __end_cc_report 14:32:32 INFO - PROCESS | 1673 | __startTimestamp1464816752005__endTimestamp 14:32:32 INFO - PROCESS | 1673 | 14:32:32 INFO - PROCESS | 1673 | ------- Summary: start ------- 14:32:32 INFO - PROCESS | 1673 | Number of tests: 14 14:32:32 INFO - PROCESS | 1673 | 14:32:32 INFO - PROCESS | 1673 | [#0] ai-astar Cycles:10 Average:111.70 Median:112.00 stddev:1.42 (1.3%) stddev-sans-first:1.42 14:32:32 INFO - PROCESS | 1673 | Values: 113.0 108.0 112.0 112.0 112.0 112.0 112.0 112.0 111.0 113.0 14:32:32 INFO - PROCESS | 1673 | 14:32:32 INFO - PROCESS | 1673 | [#1] audio-beat-detection Cycles:10 Average:133.20 Median:133.50 stddev:18.95 (14.2%) stddev-sans-first:18.04 14:32:32 INFO - PROCESS | 1673 | Values: 157.0 126.0 118.0 167.0 119.0 119.0 141.0 117.0 150.0 118.0 14:32:32 INFO - PROCESS | 1673 | 14:32:32 INFO - PROCESS | 1673 | [#2] audio-dft Cycles:10 Average:182.80 Median:183.00 stddev:1.75 (1.0%) stddev-sans-first:1.86 14:32:32 INFO - PROCESS | 1673 | Values: 183.0 186.0 183.0 183.0 183.0 181.0 180.0 182.0 185.0 182.0 14:32:32 INFO - PROCESS | 1673 | 14:32:32 INFO - PROCESS | 1673 | [#3] audio-fft Cycles:10 Average:70.90 Median:63.50 stddev:17.98 (28.3%) stddev-sans-first:18.94 14:32:32 INFO - PROCESS | 1673 | Values: 77.0 73.0 57.0 107.0 58.0 57.0 64.0 63.0 97.0 56.0 14:32:32 INFO - PROCESS | 1673 | 14:32:32 INFO - PROCESS | 1673 | [#4] audio-oscillator Cycles:10 Average:80.00 Median:77.50 stddev:12.45 (16.1%) stddev-sans-first:13.08 14:32:32 INFO - PROCESS | 1673 | Values: 85.0 84.0 92.0 70.0 71.0 68.0 94.0 69.0 67.0 100.0 14:32:32 INFO - PROCESS | 1673 | 14:32:32 INFO - PROCESS | 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:89.70 Median:86.00 stddev:13.71 (15.9%) stddev-sans-first:14.48 14:32:32 INFO - PROCESS | 1673 | Values: 86.0 83.0 87.0 84.0 87.0 83.0 91.0 128.0 86.0 82.0 14:32:32 INFO - PROCESS | 1673 | 14:32:32 INFO - PROCESS | 1673 | [#6] imaging-darkroom Cycles:10 Average:93.40 Median:93.50 stddev:1.17 (1.3%) stddev-sans-first:1.24 14:32:32 INFO - PROCESS | 1673 | Values: 93.0 96.0 93.0 94.0 93.0 92.0 94.0 94.0 93.0 92.0 14:32:32 INFO - PROCESS | 1673 | 14:32:32 INFO - PROCESS | 1673 | [#7] imaging-desaturate Cycles:10 Average:84.60 Median:85.00 stddev:1.26 (1.5%) stddev-sans-first:1.32 14:32:32 INFO - PROCESS | 1673 | Values: 84.0 85.0 84.0 84.0 85.0 84.0 86.0 82.0 86.0 86.0 14:32:32 INFO - PROCESS | 1673 | 14:32:32 INFO - PROCESS | 1673 | [#8] json-parse-financial Cycles:10 Average:55.60 Median:57.00 stddev:1.65 (2.9%) stddev-sans-first:1.45 14:32:32 INFO - PROCESS | 1673 | Values: 53.0 53.0 57.0 57.0 55.0 55.0 57.0 57.0 57.0 55.0 14:32:32 INFO - PROCESS | 1673 | 14:32:32 INFO - PROCESS | 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:70.80 Median:70.00 stddev:4.05 (5.8%) stddev-sans-first:3.71 14:32:32 INFO - PROCESS | 1673 | Values: 65.0 69.0 75.0 80.0 70.0 71.0 69.0 70.0 70.0 69.0 14:32:32 INFO - PROCESS | 1673 | 14:32:32 INFO - PROCESS | 1673 | [#10] stanford-crypto-aes Cycles:10 Average:88.60 Median:86.00 stddev:11.22 (13.0%) stddev-sans-first:11.86 14:32:32 INFO - PROCESS | 1673 | Values: 86.0 86.0 87.0 86.0 84.0 80.0 85.0 85.0 120.0 87.0 14:32:32 INFO - PROCESS | 1673 | 14:32:32 INFO - PROCESS | 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:140.20 Median:141.50 stddev:6.23 (4.4%) stddev-sans-first:6.60 14:32:32 INFO - PROCESS | 1673 | Values: 141.0 139.0 138.0 142.0 142.0 134.0 141.0 143.0 153.0 129.0 14:32:32 INFO - PROCESS | 1673 | 14:32:32 INFO - PROCESS | 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.30 Median:165.00 stddev:20.10 (12.2%) stddev-sans-first:20.97 14:32:32 INFO - PROCESS | 1673 | Values: 163.0 164.0 164.0 163.0 168.0 219.0 202.0 166.0 161.0 163.0 14:32:32 INFO - PROCESS | 1673 | 14:32:32 INFO - PROCESS | 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:66.00 stddev:1.48 (2.2%) stddev-sans-first:1.56 14:32:32 INFO - PROCESS | 1673 | Values: 66.0 66.0 68.0 66.0 64.0 69.0 67.0 66.0 65.0 65.0 14:32:32 INFO - PROCESS | 1673 | -------- Summary: end -------- 14:32:32 INFO - PROCESS | 1673 | 14:32:32 INFO - TEST-INFO | 1673: exit 0 14:32:32 INFO - TEST-OK | kraken | took 58676ms 14:32:32 INFO - SUITE-END | took 1174s 14:32:33 INFO - Completed test suite (00:19:35) 14:32:33 INFO - javascript benchmark 14:32:33 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3205.43904816657, 3395.0, 3414.5902491700986, 3449.655034496549, 3472.496488009239, 5563.193336966143, 5569.526590239824, 5628.971855140729, 5632.521235694966, 5669.574781891364, 5653.406392328798, 5690.146478028298, 5694.601377903538, 5698.316202055748, 5712.857178570538, 5139.871503212412, 5176.145935920568, 5210.921836172461, 5216.061109000379, 5257.658252213594, 5264.894702105956, 5271.604629652791, 5286.524212820846, 5292.92060619091, 5296.576273898079, 5181.119209734355, 5187.221916712486, 5192.688438693691, 5246.344206974129, 5264.8683782905355, 5240.899411123688, 5278.0, 5284.0, 5308.283381743492, 5347.25138480613, 4205.684573656988, 5088.542031217208, 5107.897842043176, 5192.818251361185, 5222.451642577551, 5558.583106267006, 5559.805406810783, 5563.777448902039, 5565.582581306418, 5620.91568626471, 6229.190205273286, 6256.0, 6256.6871656417, 6256.937430625727, 6257.217847769029, 5028.22062580297, 6158.291796443376, 6210.975189093794, 6304.085907543381, 6326.778562750282, 5752.913706294409, 5826.329972053183, 5827.417258274223, 5829.533637309005, 5832.24180856486, 6264.843378915563, 6290.905636415458, 6305.905411418832, 6307.716152773136, 6315.305316415191, 5988.760449581968, 5998.940010599926, 6062.636241825505, 6164.0, 6188.597741146811, 6193.473554747807, 6251.9687401562705, 6266.185395898504, 6280.05799130125, 6280.089387038854, 6148.9692551536955, 6176.135341052256, 6193.721282542297, 6198.0702894566175, 6200.193974783249, 5973.432523910222, 6003.0, 6219.533534984804, 6229.0, 6229.127922090911, 4803.687760295569, 4816.879578010507, 4828.806847726051, 4835.758212089381, 4842.806287748451, 4373.387725718402, 4390.429244198233, 4425.247707889602, 4425.889352766207, 4431.069475410135, 4849.927251091236, 5708.257926469532, 5755.1367294906095, 5767.913481297784, 5799.594028417971, 5475.561955043587, 6227.6263424193785, 6247.969085100907, 6254.874902501924, 6296.905546416808, 4750.61995040396, 5621.4659607337235, 5682.374938756733, 5743.339515955635, 5745.885082298414, 5324.227986941872, 5340.038793017216, 5350.170723537858, 5364.22218778275, 5404.54061404785, 6309.842253943596, 6317.526185536103, 6353.491720662336, 6364.872702546016, 6373.139626150503, 5489.737360407208, 6002.009668404664, 6143.665407145256, 6223.0, 6273.247210334789, 5369.865753356197, 6250.406211409909, 6350.650714210763, 6362.268339140873, 6369.172007638977, 6132.55978547995, 6200.534959877937, 6201.658908760061, 6213.9067913982235, 6214.40963108504, 6107.4922612924165, 6198.690065496798, 6207.16203312547, 6276.058591211355, 6301.779437719658, 6115.331611966083, 6118.663473508822, 6126.487138463829, 6128.258691089182, 6134.4978165938865, 5775.075987841925, 6097.786577469945, 6098.237720284965, 6118.510519158368, 6124.0, 5779.075347944308, 5790.449907258803, 5843.2695913010875, 5847.64914105138, 5980.970095149671, 6062.42406971337, 6109.009944784373, 6112.229929638781, 6120.588748038681, 6130.647240459146, 5910.379410162043, 5914.970425147847, 5916.083007133901, 5927.555433342603, 5937.732802023833], "value": 5655.694789148538, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [512.6846989101701, 514.7477735909404, 516.9638125331227, 546.7211722021767, 562.7001626243378, 30817.22956926075, 31251.531227031584, 32366.67633323666, 32414.351712965727, 32561.67438325616, 43628.90927726808, 44676.77661611688, 44928.0, 47290.76354618231, 47619.0, 9402.62389504419, 9588.137067663909, 9616.663416780415, 9618.519074046304, 9661.0, 8578.571071446433, 8830.426022308562, 9509.001554836712, 9648.877247005648, 9649.228061755079, 11336.093112551018, 11541.769164616702, 11550.0, 11826.349550774705, 11826.881731182664, 11593.24643898144, 11676.708082297926, 11691.883081169164, 11694.239874408222, 11708.941455292668, 8332.541710205936, 8518.148185181495, 8538.957305213435, 8670.219680228809, 11513.769724605461, 41142.38286425706, 41765.1646967059, 41777.16445671069, 43286.35070473945, 43334.56665433337, 3488.2674638325984, 3817.48463957365, 3967.5238971323624, 4002.3596224604216, 4341.41390912226, 31901.361972760416, 31982.6801731982, 32116.03651890447, 32254.516182257285, 33173.50239746406, 3217.8551965161623, 3239.578854748891, 3240.562524059269, 3249.000537024879, 3349.866005359783, 538576.0, 543971.0, 545427.0, 545466.0, 546702.0, 56610.0, 61610.0758488623, 62949.0, 62983.68508157522, 66690.0, 55918.720406397704, 66767.66616166985, 67168.32831671719, 67296.32703672901, 67358.66320668464, 16725.247363868413, 16896.746548801777, 16925.153742312836, 16933.15334233308, 16978.745318820227, 13016.934915325552, 13136.671583210496, 13136.737265254642, 13254.873335766375, 13256.337183140997, 14825.036372635745, 15165.545033649008, 15195.088294702133, 15478.767818482731, 15489.0, 5716.171155182476, 5745.741441635137, 6082.239720034996, 6325.588836725598, 6367.904481432783, 884.7964968057382, 906.5376657904516, 915.4370062411568, 998.8701468809154, 1002.884668263159, 30622.08133755991, 30847.383052338828, 31006.22484437862, 31209.8439507801, 31343.990349867126, 1050.448514529878, 1124.4658787076073, 1153.532819208222, 1183.8579370475427, 1221.3343727668264, 15998.280077396545, 16085.0, 16091.597710057107, 16156.67686646284, 16557.337706491606, 655084.0, 664402.0, 670012.1995120238, 670342.0, 670893.0, 52588.0, 60386.39613603896, 61334.38665613287, 61448.692756536824, 61848.381516184265, 1429.535400994681, 1446.0167086381152, 1454.4327712192041, 1455.678193281714, 1459.9781003284959, 30954.53568196434, 31263.53104703386, 31674.68325316717, 31931.36137277288, 32712.018639440856, 53157.46842531525, 53207.0, 53835.730821347206, 53855.73072134614, 53857.11500097478, 662426.0, 663975.0, 665763.0, 668814.0, 670412.0, 40071.19857602773, 41179.58820411757, 41531.79234103931, 41648.95877603084, 41723.582764171966, 48796.75601621969, 49050.77373065702, 49244.2613360807, 49567.00865982732, 51438.74280628573, 39983.60016399915, 41286.38070429006, 41859.0, 42433.72698819041, 42696.0, 39844.40233396559, 40001.39997900092, 40176.0, 41367.793161034, 41388.0, 36627.718029868825, 40162.0, 40240.993975149686, 41198.0, 41994.0, 35008.0, 35273.0, 35465.0, 36068.0, 36102.0, 25784.355391115372, 26198.690065496266, 26234.21297361082, 26404.471910561297, 26437.248568201154], "value": 20840.900804478446, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [380.43885269227883, 385.1680370399935, 388.5803332401005, 399.0442889280176, 401.67865707434044, 19881.304154354584, 21338.786612133874, 21601.45996350092, 21746.238881639147, 22256.8887155564, 11646.650600481998, 12159.57441489548, 12165.635030949064, 20647.38057858263, 21610.0, 5286.894262114746, 5346.973265133669, 5449.128139497681, 5493.533049690761, 5493.807716729915, 5227.18978558324, 5328.094223981913, 5356.196570514415, 5387.1111266640955, 5398.244245805571, 5285.656432331905, 5351.812686555972, 5372.167314066326, 5381.354237491507, 5383.892322153554, 5202.167653175474, 5333.57331413486, 5351.25082488452, 5353.518183363516, 5365.704886231256, 4463.464384273875, 4990.101781679296, 5192.247124166975, 5217.139172036647, 5224.268602395668, 13504.32478376087, 13509.05436619437, 13511.797323040064, 13566.45734170632, 16391.67216655672, 2021.181421524285, 2232.0848452134546, 2280.247518318951, 2405.0, 2942.8528573571234, 9946.254030947635, 9948.10467057961, 10063.748406289827, 10471.057604815604, 10471.214658900613, 1759.7976232733145, 1801.2704854533936, 1808.4574627611664, 1859.9721004184948, 1898.4779185723899, 79650.0, 105257.0, 119136.0, 119427.2085918712, 120219.99999999913, 11322.716932076764, 11384.430778461045, 11569.76860462786, 11646.068314534818, 11684.88315116855, 14599.78100328496, 14736.0, 14737.48418805358, 14740.926295368454, 14756.778648320284, 6850.725970961105, 6875.278095799969, 8518.318534517224, 8539.786505337292, 8647.481151130953, 8392.314459372941, 8393.202645748644, 8502.319814414832, 8513.489190648581, 8575.699850505202, 4495.57292057254, 4545.136424079415, 4568.246239370533, 4569.131864945716, 4634.050019745944, 1648.83756951349, 1894.488488108203, 1943.8250557449614, 1946.7566554180728, 1986.9205231790565, 904.4889637354873, 906.419891269588, 962.5860879821604, 992.503748125937, 1071.8338657508098, 18691.71962420565, 20516.897415512827, 21339.67990480144, 21459.356219313446, 21491.8179500129, 928.5960607135918, 939.0750111140521, 993.9503024848729, 1018.9643362482277, 1021.3412349034979, 11058.447077646086, 11147.108231341474, 11210.159238057015, 11311.377874216998, 11312.717182070513, 532223.0, 603190.0, 614277.0, 616466.0, 621516.0, 88441.0, 121968.0, 129137.0, 139453.0, 144706.276468617, 976.077606661712, 976.497103991445, 1006.4816619440994, 1035.4563853976572, 1055.3140458701905, 13973.860261397662, 14667.699767552216, 17346.65306693884, 19003.049847507846, 19401.708974365112, 52040.479595203564, 54693.453065468835, 56386.0, 57110.16394425655, 57936.13095803482, 85301.0, 85768.57115714382, 86244.56877715822, 86766.69849952382, 86930.56534717286, 7720.420968427278, 7766.844663106819, 7906.960465197637, 7942.126366099609, 8188.222118898695, 10163.54264058134, 12039.75920481603, 12804.61586152417, 12917.677058073623, 12975.870241297465, 11146.108311334912, 11221.270617409842, 11340.262882912584, 11352.829707554229, 11373.715657108638, 11023.338599684044, 11036.503357348778, 11192.776144476902, 11218.439078046229, 11560.42197890119, 11159.944200278947, 11532.654020379403, 11681.0, 11688.240264382788, 11773.293602383885, 8165.14266002085, 8247.95876020616, 8309.04445988719, 8325.000999880052, 8436.704715334934, 11192.272502287326, 11270.549178032787, 11544.538218471504, 11575.0, 11617.070634349062], "value": 9265.839490617795, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1413.8586141385856, 1585.0489706176293, 1602.230929154006, 1867.5704587944772, 2008.307134038757, 8167.91832081679, 8942.91057089429, 9144.359894807367, 9154.86267705985, 9264.0, 8939.865902011476, 9055.185033346997, 9100.40847344922, 9110.316726245524, 9116.04281550436, 2118.3221369161874, 2197.604431202383, 2399.5440866235404, 2429.5991161458337, 2440.3777036855668, 2195.6267434536167, 2222.7110475638146, 2365.8225633077586, 2455.8035357171475, 2456.8894399752053, 2421.2857207123852, 2424.260600516842, 2429.8906549205326, 2430.283066495388, 2431.3192306154306, 2378.940526486843, 2424.6363045543135, 2425.369403954967, 2429.514097180553, 2431.4286142756614, 1811.4565630310854, 2123.819475344598, 2313.745487996319, 2376.9762302377103, 2390.8685022323766, 8950.597223124974, 9033.54832258391, 9046.276297896218, 9051.637934482613, 9061.18449339556, 5385.461453854583, 5566.860828479321, 5566.914451681923, 5570.637908535932, 5588.888222235573, 3137.51368537877, 3551.4140166872435, 3677.7793332400142, 3757.9436308455397, 3763.7929913854728, 1051.416463862556, 1086.1202426034858, 1097.226455348985, 1109.450821843193, 1123.376526028054, 12217.328046957413, 12292.201006934522, 12298.631041068782, 12301.384930753427, 12308.199967002205, 4740.45484769249, 4859.684120532154, 4932.901341973212, 4937.950620493749, 4942.085714142895, 10339.896601033894, 10396.792064158675, 10400.687979360631, 10402.843957340647, 10425.426601536836, 5551.250581171571, 5555.250041244381, 5559.444055594408, 5565.220869078333, 5565.9443405566235, 4529.184746745552, 4927.317820482768, 4929.433115191727, 5047.596192304607, 5084.059449001947, 3672.6143754905884, 3980.104476492766, 4030.9596904031173, 4100.938485922714, 4107.8973025674, 1074.414444127952, 1106.4301884529473, 1298.9025823063118, 1300.291341219019, 1311.6392991927296, 776.3827756933251, 788.578110710767, 807.6083099696642, 941.4774799985937, 976.6386437018349, 4905.705657660552, 6109.480694140959, 6137.539684523678, 6202.875942481125, 6215.533834962396, 814.7840822182127, 953.7472569769017, 964.5081008685621, 997.0976266478704, 1000.7798284377425, 3998.4602078719, 4065.0, 4084.162746636992, 4133.689973252018, 4156.106437116035, 10752.462376881125, 11177.608783692533, 11187.32876027441, 11189.0, 11209.383483908463, 10970.012698857141, 11168.050715689258, 11297.983181513704, 11302.321860688386, 11306.321620702784, 7001.159860816734, 8223.54770487617, 8561.657533698706, 8580.35647326453, 8587.785305367292, 5991.520678345809, 5996.310424301261, 6021.42796434338, 6029.0, 6043.456088952015, 10041.146502547364, 10434.321769084985, 10436.165106791286, 10469.528871200966, 10470.476476176313, 10214.233932454947, 10477.31897426665, 10539.894601053891, 10616.84074738864, 10734.141268698642, 6750.35871592198, 6968.930310696828, 7240.094988126484, 7399.963000184965, 7420.183779784327, 8274.544900030556, 8362.121977192308, 8366.24703776663, 8441.662333506836, 8717.956410217908, 7263.854722905618, 7684.3084122429245, 7749.457537972513, 7907.0, 7923.920760792549, 6680.432163265982, 7439.962800186181, 7565.016547848744, 7690.1925297845155, 7878.645460954385, 7211.747588834365, 7222.566646001257, 7291.270872912666, 7291.598962057138, 7300.379467745089, 4922.310876477224, 5092.0, 5111.952049829851, 5140.871478213075, 5200.193969934666, 4698.483166851575, 4779.696722777607, 4869.70781753096, 5043.9747801260755, 5151.562117220078], "value": 4673.958589172776, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.4558721507678, 305.6210299228956, 319.1781163503977, 321.11372611592, 323.91578189670696, 5403.945960540394, 5413.240696773545, 5424.159255315422, 5457.808976685817, 5470.617056806025, 5072.467390923946, 5089.872753181172, 5239.502247286511, 5382.085045542258, 5406.405295417501, 3870.7098965878918, 3912.3740201567753, 4138.1103062841485, 4153.210889930911, 4177.624013838754, 4074.286999775042, 4136.420901073852, 4152.97923510382, 4159.6464300534335, 4170.18596910345, 2895.5367937153947, 3462.844172012253, 3660.798656073928, 3906.765594064351, 4155.314373128445, 3531.5055892175114, 3811.9237615247816, 4065.6137666921595, 4071.491063617033, 4086.2031903778775, 2983.701629837021, 3400.3199360128074, 3551.254236610315, 3560.643935606419, 3598.118460977051, 5059.822906198302, 5164.586833053347, 5176.637635365526, 5201.5838732901275, 5280.841574752763, 3856.479375284329, 4577.404937358122, 4659.464161621423, 4660.533946605312, 4661.88345291367, 5612.943870561324, 5631.9718401408145, 5643.266375371217, 5662.348829884575, 5679.829605111853, 3821.76426790328, 3912.7652340859354, 3942.172143849795, 3956.951407876915, 3995.5615978247806, 5278.630495865253, 5479.753411096471, 5889.79385721502, 5928.97035514824, 5954.225950626391, 3693.7414380993073, 3989.58109398509, 4010.578889216649, 4020.276350256982, 4030.979845100756, 3179.4753865611915, 3507.2985402919517, 3747.0445036515516, 3932.646061854447, 4104.671626269951, 4067.5525692173837, 4599.6090332321455, 4639.582437580634, 4655.9291362986705, 4692.741899195577, 4780.139574876486, 5456.371512943927, 5635.943640563624, 5641.717914104278, 5686.9715651421475, 1281.7052078022107, 1290.7031382782013, 1381.1505923856755, 1387.4658256571292, 1394.9721005579831, 731.685375288631, 876.6449587916904, 949.7103383468109, 952.3095755577151, 953.7997020625608, 4239.618434340924, 5231.895362092737, 5232.2413250078525, 5269.602330939413, 5310.4424035476495, 4790.353302304214, 4798.5441383068555, 5020.7740651670765, 5549.361823390281, 5869.20765696634, 4682.929756053662, 5030.496950304941, 5291.444398338196, 5309.33633295838, 5312.256284120226, 2570.4859028194437, 2636.4727054589157, 2770.503927878963, 2814.9014784482856, 2960.5263157894633, 5346.331708536433, 5584.21820945068, 5848.5321174305955, 5889.823305300848, 6119.265688117365, 5700.8859822803315, 5848.64908105515, 5872.706364681749, 5882.617629854046, 5933.970330148321, 5211.687298762162, 5269.920951185735, 5448.346198456211, 5949.970250148722, 5987.790427335022, 4413.5807098326895, 4671.972166123447, 4825.396825396825, 5758.222639943721, 5806.419358064329, 5092.6435149539175, 5631.49316561495, 5636.449976256562, 5666.421778091041, 5674.744636491285, 5465.754041068082, 5732.882087992802, 5981.7308221131025, 5983.683589610278, 6013.548983826143, 3493.371193185251, 3844.788536630512, 3931.3709806430834, 3935.2129574084724, 3964.980175099106, 4744.651397558231, 5315.9468405317, 5330.62685612019, 5654.85862853432, 5664.801731939362, 4770.405144187186, 4982.601391888567, 5632.774689012557, 5636.549076073986, 5657.652074389082, 4422.955770442383, 4799.952000480091, 5492.505674489315, 5502.5883951950045, 5596.944030559643, 4224.261528547197, 4491.326301054868, 4893.877653058702, 5124.615653825904, 5403.6217464778665, 4659.580637742484, 4722.811087556459, 5335.813246536352, 5483.369412517612, 5635.211070450058, 5526.55787536988, 5530.0, 5538.972305138448, 5558.333000040021, 5615.747291371817], "value": 4062.9857801574317, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [717.2003216413699, 792.7621713485953, 812.7033632724056, 816.9387295952802, 844.3287586368837, 14784.852151478482, 14993.47522836701, 15256.23718814059, 15265.0, 15266.0, 14028.649283767885, 14100.788488172686, 14100.929495352535, 14110.929445352758, 14111.647208819784, 699.5383047188857, 724.7970568240901, 726.397090414957, 883.2315885179886, 904.5567671840784, 719.2879049740745, 721.1130309719049, 881.3566096749377, 915.1809130827905, 915.276931224332, 969.1229437359201, 969.1955676788284, 975.902409759022, 977.6675930183736, 978.7455261631991, 950.1970834644749, 961.2310151878504, 969.7914948286082, 971.4656938683696, 972.6644307713827, 542.785599688121, 696.5437638346891, 716.6094478509225, 717.9641017949082, 721.779857143571, 14706.55880323592, 14774.704505909822, 14777.0, 14783.260836958108, 14785.630359240997, 13296.135751176143, 13519.594412167651, 13583.35581736968, 13645.590632280948, 14014.509492167826, 11758.235714678518, 12229.93885030578, 12259.448324825315, 12275.0, 12441.626751197478, 4484.8878778030485, 4488.932666010012, 4514.638828893713, 4685.835995740133, 4695.88260293492, 51067.489325106646, 69398.65300673514, 69706.65146674284, 81587.3682526352, 81635.36729265441, 10683.83974240387, 10706.054372878058, 10717.83923241152, 10813.243072984815, 11151.888481115086, 20564.794352056586, 20568.794312056987, 20572.897135514526, 20578.0, 20616.48458788542, 7624.580648064299, 7780.922190778133, 7795.688172473151, 7878.72424465141, 7890.0, 4449.466064072268, 4473.530279320689, 4483.08096840147, 4484.170428470743, 4579.450465944108, 3351.3967485852295, 3405.0976491229844, 3447.24160684649, 3449.6032956209856, 3449.741269404805, 665.7503436211421, 672.7309076369492, 677.0825531404973, 677.2719326723792, 679.3376457953555, 489.2324299699381, 543.4158279849178, 591.074967675595, 605.3946053946054, 610.6671863834221, 6715.832104197337, 7943.364530837519, 8054.23484768946, 8093.392995525359, 8211.05572859117, 565.451512033327, 596.9701514924236, 606.448132199705, 618.8607563298222, 626.5269721360344, 3411.6498276239677, 4045.1707399982934, 4100.507939047334, 4253.234417804825, 4302.612764851178, 70841.93737093947, 82607.0, 83228.0, 85425.57287213524, 85511.0, 68745.0, 77566.0, 79964.0, 80774.59612702017, 80900.0, 30216.24459388489, 30388.936387226342, 45117.0, 72340.55318893697, 72961.63519182477, 9504.382215155993, 9516.524173791422, 9533.094356036165, 9554.04459554039, 9659.855102173613, 45167.09665806731, 46103.53896460992, 46417.76791116023, 46470.30294545652, 47172.7641361791, 60931.78136437335, 63149.36850631435, 63314.41713957188, 63537.364626354996, 64160.67919660372, 11913.868182522647, 12198.51205951752, 12739.936300318439, 12886.935565322114, 12965.870341296466, 14349.784753228918, 14486.492972745904, 14614.415423382945, 14657.853421466076, 15010.774838377214, 13162.40769165409, 13190.73618527605, 13235.536756213487, 13289.667758305734, 13751.587452376445, 8162.938817953628, 13069.0, 13218.339083046001, 13314.443352506216, 14144.292785360485, 12841.93579032099, 12867.420966056363, 12872.22766634005, 12978.545750898673, 13387.263700496565, 10940.726481838017, 11081.778364432828, 11083.94458027737, 11165.497552610315, 11621.302721836717, 8672.306215502618, 9538.28462865274, 10856.565737370416, 11324.754277029355, 11452.770944581229], "value": 7099.863182519625, "unit": "score"}], "name": "dromaeo_css", "value": 7266.953401034654, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 108.0, 112.0, 112.0, 112.0, 112.0, 112.0, 112.0, 111.0, 113.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 126.0, 118.0, 167.0, 119.0, 119.0, 141.0, 117.0, 150.0, 118.0], "value": 133.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 186.0, 183.0, 183.0, 183.0, 181.0, 180.0, 182.0, 185.0, 182.0], "value": 182.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 73.0, 57.0, 107.0, 58.0, 57.0, 64.0, 63.0, 97.0, 56.0], "value": 70.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 92.0, 70.0, 71.0, 68.0, 94.0, 69.0, 67.0, 100.0], "value": 80.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 87.0, 84.0, 87.0, 83.0, 91.0, 128.0, 86.0, 82.0], "value": 89.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 96.0, 93.0, 94.0, 93.0, 92.0, 94.0, 94.0, 93.0, 92.0], "value": 93.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 84.0, 84.0, 85.0, 84.0, 86.0, 82.0, 86.0, 86.0], "value": 84.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 57.0, 57.0, 55.0, 55.0, 57.0, 57.0, 57.0, 55.0], "value": 55.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 69.0, 75.0, 80.0, 70.0, 71.0, 69.0, 70.0, 70.0, 69.0], "value": 70.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 87.0, 86.0, 84.0, 80.0, 85.0, 85.0, 120.0, 87.0], "value": 88.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 139.0, 138.0, 142.0, 142.0, 134.0, 141.0, 143.0, 153.0, 129.0], "value": 140.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 164.0, 163.0, 168.0, 219.0, 202.0, 166.0, 161.0, 163.0], "value": 173.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 68.0, 66.0, 64.0, 69.0, 67.0, 66.0, 65.0, 65.0], "value": 66.2, "unit": "score"}], "name": "kraken", "value": 1441.0, "alertThreshold": 2.0}]} 14:32:33 INFO - Return code: 0 14:32:33 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 14:32:33 ERROR - # TBPL SUCCESS # 14:32:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:32:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:32:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:32:33 INFO - 2016-06-01 14:32:33.331 screenresolution[1677:9751] starting screenresolution argv=screenresolution get 14:32:33 INFO - 2016-06-01 14:32:33.333 screenresolution[1677:9751] Display 0: 1600x1200x32@60 14:32:33 INFO - 2016-06-01 14:32:33.339 screenresolution[1678:9754] starting screenresolution argv=screenresolution list 14:32:33 INFO - Available Modes on Display 0 14:32:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:32:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:32:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:32:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:32:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:32:33 INFO - Intel Iris: 14:32:33 INFO - Chipset Model: Intel Iris 14:32:33 INFO - Type: GPU 14:32:33 INFO - Bus: Built-In 14:32:33 INFO - VRAM (Dynamic, Max): 1536 MB 14:32:33 INFO - Vendor: Intel (0x8086) 14:32:33 INFO - Device ID: 0x0a2e 14:32:33 INFO - Revision ID: 0x0009 14:32:33 INFO - Displays: 14:32:33 INFO - Display: 14:32:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:32:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:32:33 INFO - Display Serial Number: 1600x1200 60 14:32:33 INFO - Main Display: Yes 14:32:33 INFO - Mirror: Off 14:32:33 INFO - Online: Yes 14:32:33 INFO - Rotation: Supported 14:32:33 INFO - Return code: 0 14:32:33 INFO - Running post-action listener: _resource_record_post_action 14:32:33 INFO - ##### 14:32:33 INFO - ##### Finished run-tests step (success) 14:32:33 INFO - ##### 14:32:33 INFO - Running post-run listener: _resource_record_post_run 14:32:33 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 38012416; Write bytes: 695905280; Read time: 384; Write time: 2032 14:32:33 INFO - TinderboxPrint: CPU usage
53.7% 14:32:33 INFO - TinderboxPrint: I/O read bytes / time
38,012,416 / 384 14:32:33 INFO - TinderboxPrint: I/O write bytes / time
695,905,280 / 2,032 14:32:33 INFO - TinderboxPrint: CPU idle
2,242.1 (46.3%) 14:32:33 INFO - TinderboxPrint: CPU system
967.0 (20.0%) 14:32:33 INFO - TinderboxPrint: CPU user
1,636.5 (33.8%) 14:32:33 INFO - TinderboxPrint: Swap in / out
437,760,000 / 0 14:32:33 INFO - install - Wall time: 34s; CPU: 55.0%; Read bytes: 399307264; Write bytes: 388294656; Read time: 29391; Write time: 702 14:32:33 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 30875136; Write bytes: 292103168; Read time: 302; Write time: 1292 14:32:34 INFO - Running post-run listener: _upload_blobber_files 14:32:34 INFO - Blob upload gear active. 14:32:34 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:32:34 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 14:32:34 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'] 14:32:34 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 14:32:34 INFO - (blobuploader) - INFO - Open directory for files ... 14:32:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:32:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:32:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:32:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:32:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:32:35 INFO - (blobuploader) - INFO - Done attempting. 14:32:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:32:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:32:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:32:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:32:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:32:36 INFO - (blobuploader) - INFO - Done attempting. 14:32:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:32:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:32:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:32:39 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:32:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:32:39 INFO - (blobuploader) - INFO - Done attempting. 14:32:39 INFO - (blobuploader) - INFO - Iteration through files over. 14:32:39 INFO - Return code: 0 14:32:39 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:32:39 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:32:39 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/263a3d462843ce9a069217037ffe52ee7790b91c62b1a4548818da63f0e29f21514e0bdf84dba253f65047d73496cb2cfa4a674406fa9f524ea4333616c3cc9b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/521d64c0d343f6912abbe71a0123fb158c76d6e64194c18e5bc8f87c540dc16892c5bb1cda8e33c68edd59d7a7b27d69b353962da988d234d4d95706bdf8ed67", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/f65ee6e162f778af918083dea6ad7c419aae9a5898e892c67813555e7010936137e5554665eb6032f8b2b9f49dc763acf1e9c6c51708e621b1c52fd8f74c2df8"} 14:32:39 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:32:39 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:32:39 INFO - Contents: 14:32:39 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/263a3d462843ce9a069217037ffe52ee7790b91c62b1a4548818da63f0e29f21514e0bdf84dba253f65047d73496cb2cfa4a674406fa9f524ea4333616c3cc9b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/521d64c0d343f6912abbe71a0123fb158c76d6e64194c18e5bc8f87c540dc16892c5bb1cda8e33c68edd59d7a7b27d69b353962da988d234d4d95706bdf8ed67", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/f65ee6e162f778af918083dea6ad7c419aae9a5898e892c67813555e7010936137e5554665eb6032f8b2b9f49dc763acf1e9c6c51708e621b1c52fd8f74c2df8"} 14:32:39 INFO - Running post-run listener: copy_logs_to_upload_dir 14:32:39 INFO - Copying logs to upload dir... 14:32:39 INFO - mkdir: /builds/slave/test/build/upload/logs 14:32:39 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1280.340593 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-06-01 14:32:39.549551) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-01 14:32:39.553571) ========= 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.3IMx3hi8N8/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.xzvODllfJj/Listeners TMPDIR=/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/263a3d462843ce9a069217037ffe52ee7790b91c62b1a4548818da63f0e29f21514e0bdf84dba253f65047d73496cb2cfa4a674406fa9f524ea4333616c3cc9b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/521d64c0d343f6912abbe71a0123fb158c76d6e64194c18e5bc8f87c540dc16892c5bb1cda8e33c68edd59d7a7b27d69b353962da988d234d4d95706bdf8ed67", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/f65ee6e162f778af918083dea6ad7c419aae9a5898e892c67813555e7010936137e5554665eb6032f8b2b9f49dc763acf1e9c6c51708e621b1c52fd8f74c2df8"} build_url:https://queue.taskcluster.net/v1/task/U_5KaG-5RjK-G_w6NrjVtw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011148 build_url: 'https://queue.taskcluster.net/v1/task/U_5KaG-5RjK-G_w6NrjVtw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/263a3d462843ce9a069217037ffe52ee7790b91c62b1a4548818da63f0e29f21514e0bdf84dba253f65047d73496cb2cfa4a674406fa9f524ea4333616c3cc9b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/521d64c0d343f6912abbe71a0123fb158c76d6e64194c18e5bc8f87c540dc16892c5bb1cda8e33c68edd59d7a7b27d69b353962da988d234d4d95706bdf8ed67", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/f65ee6e162f778af918083dea6ad7c419aae9a5898e892c67813555e7010936137e5554665eb6032f8b2b9f49dc763acf1e9c6c51708e621b1c52fd8f74c2df8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-01 14:32:39.585204) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 14:32:39.585573) ========= 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.3IMx3hi8N8/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.xzvODllfJj/Listeners TMPDIR=/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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.005254 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 14:32:39.634473) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-01 14:32:39.634904) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-01 14:32:39.635331) ========= ========= Total master_lag: 0.17 =========