builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0070 starttime: 1464804252.62 results: success (0) buildid: 20160601090255 builduid: 7ffcc385040e4328bb1420909edbf665 revision: 369f9791b11e00f73f9c984101dd8e3026a38ae3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-01 11:04:12.623224) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-01 11:04:12.623619) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-01 11:04:12.623898) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-01 11:04:12.687937) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 11:04:12.688298) ========= 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.bKZEbFwB4P/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.winfgECt4I/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 11:04:12-- 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% 38.9M=0s 2016-06-01 11:04:13 (38.9 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.614597 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 11:04:13.339748) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 11:04:13.340206) ========= 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.bKZEbFwB4P/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.winfgECt4I/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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.027436 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 11:04:13.414978) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-01 11:04:13.415317) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 369f9791b11e00f73f9c984101dd8e3026a38ae3 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 369f9791b11e00f73f9c984101dd8e3026a38ae3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bKZEbFwB4P/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.winfgECt4I/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 11:04:13,496 truncating revision to first 12 chars 2016-06-01 11:04:13,496 Setting DEBUG logging. 2016-06-01 11:04:13,496 attempt 1/10 2016-06-01 11:04:13,496 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/369f9791b11e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-01 11:04:14,827 unpacking tar archive at: fx-team-369f9791b11e/testing/mozharness/ program finished with exit code 0 elapsedTime=1.588432 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-01 11:04:15.030193) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-01 11:04:15.030899) ========= script_repo_revision: 369f9791b11e00f73f9c984101dd8e3026a38ae3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-01 11:04:15.031809) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-01 11:04:15.032438) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-01 11:04:15.057585) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 35 secs) (at 2016-06-01 11:04:15.057917) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bKZEbFwB4P/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.winfgECt4I/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 11:04:15 INFO - MultiFileLogger online at 20160601 11:04:15 in /builds/slave/test 11:04:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 11:04:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:04:15 INFO - {'append_to_log': False, 11:04:15 INFO - 'base_work_dir': '/builds/slave/test', 11:04:15 INFO - 'blob_upload_branch': 'Fx-Team', 11:04:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:04:15 INFO - 'branch': 'Fx-Team', 11:04:15 INFO - 'buildbot_json_path': 'buildprops.json', 11:04:15 INFO - 'config_files': ('talos/mac_config.py',), 11:04:15 INFO - 'default_actions': ('clobber', 11:04:15 INFO - 'read-buildbot-config', 11:04:15 INFO - 'download-and-extract', 11:04:15 INFO - 'populate-webroot', 11:04:15 INFO - 'create-virtualenv', 11:04:15 INFO - 'install', 11:04:15 INFO - 'run-tests'), 11:04:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:04:15 INFO - 'download_minidump_stackwalk': True, 11:04:15 INFO - 'download_symbols': 'ondemand', 11:04:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:04:15 INFO - 'tooltool.py': '/tools/tooltool.py', 11:04:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:04:15 INFO - '/tools/misc-python/virtualenv.py')}, 11:04:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:04:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:04:15 INFO - 'installer_path': 'installer.exe', 11:04:15 INFO - 'log_level': 'info', 11:04:15 INFO - 'log_name': 'talos', 11:04:15 INFO - 'log_to_console': True, 11:04:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:04:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:04:15 INFO - 'opt_config_files': (), 11:04:15 INFO - 'pip_index': False, 11:04:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:04:15 INFO - 'cmd': ('bash', 11:04:15 INFO - '-c', 11:04:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:04:15 INFO - 'enabled': True, 11:04:15 INFO - 'halt_on_failure': False, 11:04:15 INFO - 'name': 'check_screen_resolution'},), 11:04:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:04:15 INFO - 'cmd': ('bash', 11:04:15 INFO - '-c', 11:04:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:04:15 INFO - 'enabled': True, 11:04:15 INFO - 'halt_on_failure': False, 11:04:15 INFO - 'name': 'check_screen_resolution'},), 11:04:15 INFO - 'run_cmd_checks_enabled': True, 11:04:15 INFO - 'sps_profile': False, 11:04:15 INFO - 'sps_profile_interval': 0, 11:04:15 INFO - 'suite': 'dromaeojs', 11:04:15 INFO - 'system_bits': '32', 11:04:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:04:15 INFO - 'title': 't-yosemite-r7-0070', 11:04:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:04:15 INFO - 'use_talos_json': True, 11:04:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:04:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:04:15 INFO - 'work_dir': 'build'} 11:04:15 INFO - ##### 11:04:15 INFO - ##### Running clobber step. 11:04:15 INFO - ##### 11:04:15 INFO - Running pre-action listener: _resource_record_pre_action 11:04:15 INFO - Running main action method: clobber 11:04:15 INFO - rmtree: /builds/slave/test/build 11:04:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:04:17 INFO - Running post-action listener: _resource_record_post_action 11:04:17 INFO - ##### 11:04:17 INFO - ##### Finished clobber step (success) 11:04:17 INFO - ##### 11:04:17 INFO - ##### 11:04:17 INFO - ##### Running read-buildbot-config step. 11:04:17 INFO - ##### 11:04:17 INFO - Running pre-action listener: _resource_record_pre_action 11:04:17 INFO - Running main action method: read_buildbot_config 11:04:17 INFO - Using buildbot properties: 11:04:17 INFO - { 11:04:17 INFO - "project": "", 11:04:17 INFO - "product": "firefox", 11:04:17 INFO - "script_repo_revision": "production", 11:04:17 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 11:04:17 INFO - "repository": "", 11:04:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 11:04:17 INFO - "buildid": "20160601090255", 11:04:17 INFO - "pgo_build": "False", 11:04:17 INFO - "basedir": "/builds/slave/test", 11:04:17 INFO - "buildnumber": 43, 11:04:17 INFO - "slavename": "t-yosemite-r7-0070", 11:04:17 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 11:04:17 INFO - "platform": "yosemite_r7", 11:04:17 INFO - "scheduler": "tests-fx-team-macosx64-talos", 11:04:17 INFO - "branch": "fx-team", 11:04:17 INFO - "repo_path": "integration/fx-team", 11:04:17 INFO - "revision": "369f9791b11e00f73f9c984101dd8e3026a38ae3", 11:04:17 INFO - "stage_platform": "macosx64", 11:04:17 INFO - "builduid": "7ffcc385040e4328bb1420909edbf665", 11:04:17 INFO - "slavebuilddir": "test" 11:04:17 INFO - } 11:04:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/G0jJlLLlTFSl82_uKmQgbw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 11:04:17 INFO - Running post-action listener: _resource_record_post_action 11:04:17 INFO - ##### 11:04:17 INFO - ##### Finished read-buildbot-config step (success) 11:04:17 INFO - ##### 11:04:17 INFO - ##### 11:04:17 INFO - ##### Running download-and-extract step. 11:04:17 INFO - ##### 11:04:17 INFO - Running pre-action listener: _resource_record_pre_action 11:04:17 INFO - Running main action method: download_and_extract 11:04:17 INFO - mkdir: /builds/slave/test/build/tests 11:04: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')]} 11:04:17 INFO - https://queue.taskcluster.net/v1/task/G0jJlLLlTFSl82_uKmQgbw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 11:04:17 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G0jJlLLlTFSl82_uKmQgbw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 11:04:17 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G0jJlLLlTFSl82_uKmQgbw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 11:04:17 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G0jJlLLlTFSl82_uKmQgbw/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 11:04:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G0jJlLLlTFSl82_uKmQgbw/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 11:04:23 INFO - Downloaded 1199 bytes. 11:04:23 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 11:04:23 INFO - Using the following test package requirements: 11:04:23 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 11:04:23 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:04:23 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 11:04:23 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:04:23 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 11:04:23 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:04:23 INFO - u'jsshell-mac.zip'], 11:04:23 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:04:23 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 11:04:23 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 11:04:23 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:04:23 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 11:04:23 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:04:23 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 11:04:23 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:04:23 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 11:04:23 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 11:04:23 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 11:04:23 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:04: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')]} 11:04:23 INFO - https://queue.taskcluster.net/v1/task/G0jJlLLlTFSl82_uKmQgbw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:04:23 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G0jJlLLlTFSl82_uKmQgbw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 11:04:23 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G0jJlLLlTFSl82_uKmQgbw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 11:04:23 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G0jJlLLlTFSl82_uKmQgbw/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 11:04:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G0jJlLLlTFSl82_uKmQgbw/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 11:04:41 INFO - Downloaded 18696540 bytes. 11:04:41 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'] 11:04:41 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 11:04:44 INFO - Return code: 0 11:04:44 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 11:04:44 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')]} 11:04:44 INFO - https://queue.taskcluster.net/v1/task/G0jJlLLlTFSl82_uKmQgbw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:04:44 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G0jJlLLlTFSl82_uKmQgbw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 11:04:44 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G0jJlLLlTFSl82_uKmQgbw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 11:04:44 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G0jJlLLlTFSl82_uKmQgbw/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 11:04:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G0jJlLLlTFSl82_uKmQgbw/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 11:04:46 INFO - Downloaded 18696540 bytes. 11:04:46 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'] 11:04:46 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 11:04:49 INFO - Return code: 0 11:04:49 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')]} 11:04:49 INFO - https://queue.taskcluster.net/v1/task/G0jJlLLlTFSl82_uKmQgbw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 11:04:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G0jJlLLlTFSl82_uKmQgbw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 11:04:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G0jJlLLlTFSl82_uKmQgbw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 11:04:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G0jJlLLlTFSl82_uKmQgbw/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 11:04:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G0jJlLLlTFSl82_uKmQgbw/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 11:04:51 INFO - Downloaded 10834055 bytes. 11:04:51 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'] 11:04:51 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 11:04:51 INFO - Return code: 0 11:04: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')]} 11:04:51 INFO - https://queue.taskcluster.net/v1/task/G0jJlLLlTFSl82_uKmQgbw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 11:04:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G0jJlLLlTFSl82_uKmQgbw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 11:04:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G0jJlLLlTFSl82_uKmQgbw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 11:04:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G0jJlLLlTFSl82_uKmQgbw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 11:04:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/G0jJlLLlTFSl82_uKmQgbw/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 11:04:58 INFO - Downloaded 111050368 bytes. 11:04:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/G0jJlLLlTFSl82_uKmQgbw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 11:04:58 INFO - mkdir: /builds/slave/test/properties 11:04:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:04:58 INFO - Writing to file /builds/slave/test/properties/build_url 11:04:58 INFO - Contents: 11:04:58 INFO - build_url:https://queue.taskcluster.net/v1/task/G0jJlLLlTFSl82_uKmQgbw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 11:04:59 INFO - Running post-action listener: _resource_record_post_action 11:04:59 INFO - Running post-action listener: set_extra_try_arguments 11:04:59 INFO - ##### 11:04:59 INFO - ##### Finished download-and-extract step (success) 11:04:59 INFO - ##### 11:04:59 INFO - ##### 11:04:59 INFO - ##### Running populate-webroot step. 11:04:59 INFO - ##### 11:04:59 INFO - Running pre-action listener: _resource_record_pre_action 11:04:59 INFO - Running main action method: populate_webroot 11:04:59 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:04:59 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:04:59 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:04:59 INFO - '--tppagecycles', 11:04:59 INFO - '7'], 11:04:59 INFO - 'tests': ['tsvgm']}}, 11:04:59 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:04:59 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:04:59 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:04:59 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:04:59 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:04:59 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:04:59 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:04:59 INFO - 'talos_options': ['--disable-e10s'], 11:04:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:04:59 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:04:59 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:04:59 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:04:59 INFO - 'talos_options': ['--disable-e10s'], 11:04:59 INFO - 'tests': ['damp', 'tps']}, 11:04:59 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:04:59 INFO - 'tests': ['damp', 'tps']}, 11:04:59 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:04:59 INFO - 'tests': ['dromaeo_dom']}, 11:04:59 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:04:59 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:04:59 INFO - 'tests': ['a11yr', 11:04:59 INFO - 'ts_paint', 11:04:59 INFO - 'tpaint', 11:04:59 INFO - 'sessionrestore', 11:04:59 INFO - 'sessionrestore_no_auto_restore', 11:04:59 INFO - 'tabpaint']}, 11:04:59 INFO - 'other-e10s': {'tests': ['a11yr', 11:04:59 INFO - 'ts_paint', 11:04:59 INFO - 'tpaint', 11:04:59 INFO - 'sessionrestore', 11:04:59 INFO - 'sessionrestore_no_auto_restore', 11:04:59 INFO - 'tabpaint']}, 11:04:59 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:04:59 INFO - 'ts_paint', 11:04:59 INFO - 'tpaint', 11:04:59 INFO - 'sessionrestore', 11:04:59 INFO - 'sessionrestore_no_auto_restore', 11:04:59 INFO - 'tabpaint']}, 11:04:59 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:04:59 INFO - 'ts_paint', 11:04:59 INFO - 'tpaint', 11:04:59 INFO - 'sessionrestore', 11:04:59 INFO - 'sessionrestore_no_auto_restore', 11:04:59 INFO - 'tabpaint']}, 11:04:59 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:04:59 INFO - 'tests': ['a11yr', 11:04:59 INFO - 'ts_paint', 11:04:59 INFO - 'tpaint', 11:04:59 INFO - 'sessionrestore', 11:04:59 INFO - 'sessionrestore_no_auto_restore', 11:04:59 INFO - 'tabpaint']}, 11:04:59 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:04:59 INFO - 'tests': ['a11yr', 11:04:59 INFO - 'ts_paint', 11:04:59 INFO - 'tpaint', 11:04:59 INFO - 'sessionrestore', 11:04:59 INFO - 'sessionrestore_no_auto_restore', 11:04:59 INFO - 'tabpaint']}, 11:04:59 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:04:59 INFO - 'tests': ['tsvgx', 11:04:59 INFO - 'tsvgr_opacity', 11:04:59 INFO - 'tart', 11:04:59 INFO - 'tscrollx', 11:04:59 INFO - 'cart']}, 11:04:59 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:04:59 INFO - 'tsvgr_opacity', 11:04:59 INFO - 'tart', 11:04:59 INFO - 'tscrollx', 11:04:59 INFO - 'cart']}, 11:04:59 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:04:59 INFO - 'talos_options': ['--disable-e10s'], 11:04:59 INFO - 'tests': ['tp5o']}, 11:04:59 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:04:59 INFO - 'tests': ['tp5o']}, 11:04:59 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:04:59 INFO - 'talos_options': ['--disable-e10s', 11:04:59 INFO - '--xperf_path', 11:04:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:04:59 INFO - 'tests': ['tp5n']}, 11:04:59 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:04:59 INFO - 'talos_options': ['--xperf_path', 11:04:59 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:04:59 INFO - 'tests': ['tp5n']}}, 11:04:59 INFO - 'talos.zip': {'path': '', 11:04:59 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:04:59 INFO - Running post-action listener: _resource_record_post_action 11:04:59 INFO - ##### 11:04:59 INFO - ##### Finished populate-webroot step (success) 11:04:59 INFO - ##### 11:04:59 INFO - ##### 11:04:59 INFO - ##### Running create-virtualenv step. 11:04:59 INFO - ##### 11:04:59 INFO - Running pre-action listener: _resource_record_pre_action 11:04:59 INFO - Running main action method: create_virtualenv 11:04:59 INFO - Creating virtualenv /builds/slave/test/build/venv 11:04:59 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 11:04:59 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:05:00 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:05:00 INFO - Using real prefix '/tools/python27' 11:05:00 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:05:00 INFO - Installing distribute.............................................................................................................................................................................................done. 11:05:03 INFO - Installing pip.................done. 11:05:03 INFO - Return code: 0 11:05:03 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:05: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')]} 11:05:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05: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')]} 11:05:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:03 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 0x10ad5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad64570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff448d911c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac02d30>, '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 0x10aa26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abf9b20>, '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.bKZEbFwB4P/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.winfgECt4I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 11:05:03 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 11:05:03 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 11:05:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bKZEbFwB4P/Render', 11:05:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:05:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:05:03 INFO - 'HOME': '/Users/cltbld', 11:05:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:05:03 INFO - 'LOGNAME': 'cltbld', 11:05:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:03 INFO - 'MOZ_NO_REMOTE': '1', 11:05:03 INFO - 'NO_EM_RESTART': '1', 11:05:03 INFO - 'PAGER': '/bin/cat', 11:05:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:05:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:03 INFO - 'PWD': '/builds/slave/test', 11:05:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:05:03 INFO - 'SHELL': '/bin/bash', 11:05:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.winfgECt4I/Listeners', 11:05:03 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 11:05:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:05:03 INFO - 'USER': 'cltbld', 11:05:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:05:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:05:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:03 INFO - 'XPC_FLAGS': '0x0', 11:05:03 INFO - 'XPC_SERVICE_NAME': '0', 11:05:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:05:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:03 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 11:05:03 INFO - Cleaning up... 11:05:03 INFO - Return code: 0 11:05:03 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:05: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')]} 11:05:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05: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')]} 11:05:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:03 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 0x10ad5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad64570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff448d911c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac02d30>, '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 0x10aa26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abf9b20>, '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.bKZEbFwB4P/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.winfgECt4I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 11:05:03 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 11:05:03 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 11:05:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bKZEbFwB4P/Render', 11:05:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:05:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:05:03 INFO - 'HOME': '/Users/cltbld', 11:05:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:05:03 INFO - 'LOGNAME': 'cltbld', 11:05:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:03 INFO - 'MOZ_NO_REMOTE': '1', 11:05:03 INFO - 'NO_EM_RESTART': '1', 11:05:03 INFO - 'PAGER': '/bin/cat', 11:05:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:05:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:03 INFO - 'PWD': '/builds/slave/test', 11:05:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:05:03 INFO - 'SHELL': '/bin/bash', 11:05:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.winfgECt4I/Listeners', 11:05:03 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 11:05:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:05:03 INFO - 'USER': 'cltbld', 11:05:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:05:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:05:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:03 INFO - 'XPC_FLAGS': '0x0', 11:05:03 INFO - 'XPC_SERVICE_NAME': '0', 11:05:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:05:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:04 INFO - Downloading/unpacking psutil>=3.1.1 11:05:04 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 11:05:04 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 11:05:04 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 11:05:04 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 11:05:04 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 11:05:04 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 11:05:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:05:07 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:05:07 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:05:07 INFO - Installing collected packages: psutil 11:05:07 INFO - Running setup.py install for psutil 11:05:07 INFO - building 'psutil._psutil_osx' extension 11:05:07 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 11:05:07 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 11:05:07 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 11:05:07 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 11:05:08 INFO - building 'psutil._psutil_posix' extension 11:05:08 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 11:05:08 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:05:08 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:05:08 INFO - ^ 11:05:08 INFO - 1 warning generated. 11:05:08 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 11:05:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:05:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:05:08 INFO - Successfully installed psutil 11:05:08 INFO - Cleaning up... 11:05:08 INFO - Return code: 0 11:05:08 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:05:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:05:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:05:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ad5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad64570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff448d911c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac02d30>, '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 0x10aa26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abf9b20>, '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.bKZEbFwB4P/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.winfgECt4I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 11:05:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:05:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:05:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bKZEbFwB4P/Render', 11:05:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:05:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:05:08 INFO - 'HOME': '/Users/cltbld', 11:05:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:05:08 INFO - 'LOGNAME': 'cltbld', 11:05:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:08 INFO - 'MOZ_NO_REMOTE': '1', 11:05:08 INFO - 'NO_EM_RESTART': '1', 11:05:08 INFO - 'PAGER': '/bin/cat', 11:05:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:05:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:08 INFO - 'PWD': '/builds/slave/test', 11:05:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:05:08 INFO - 'SHELL': '/bin/bash', 11:05:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.winfgECt4I/Listeners', 11:05:08 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 11:05:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:05:08 INFO - 'USER': 'cltbld', 11:05:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:05:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:05:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:08 INFO - 'XPC_FLAGS': '0x0', 11:05:08 INFO - 'XPC_SERVICE_NAME': '0', 11:05:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:05:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:08 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:05:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:11 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:05:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:05:11 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:05:11 INFO - Installing collected packages: mozsystemmonitor 11:05:11 INFO - Running setup.py install for mozsystemmonitor 11:05:11 INFO - Successfully installed mozsystemmonitor 11:05:11 INFO - Cleaning up... 11:05:11 INFO - Return code: 0 11:05:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:05:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:05:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:05:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:11 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 0x10ad5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad64570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff448d911c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac02d30>, '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 0x10aa26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abf9b20>, '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.bKZEbFwB4P/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.winfgECt4I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 11:05:11 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 11:05:11 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 11:05:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bKZEbFwB4P/Render', 11:05:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:05:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:05:11 INFO - 'HOME': '/Users/cltbld', 11:05:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:05:11 INFO - 'LOGNAME': 'cltbld', 11:05:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:11 INFO - 'MOZ_NO_REMOTE': '1', 11:05:11 INFO - 'NO_EM_RESTART': '1', 11:05:11 INFO - 'PAGER': '/bin/cat', 11:05:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:05:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:11 INFO - 'PWD': '/builds/slave/test', 11:05:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:05:11 INFO - 'SHELL': '/bin/bash', 11:05:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.winfgECt4I/Listeners', 11:05:11 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 11:05:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:05:11 INFO - 'USER': 'cltbld', 11:05:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:05:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:05:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:11 INFO - 'XPC_FLAGS': '0x0', 11:05:11 INFO - 'XPC_SERVICE_NAME': '0', 11:05:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:05:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:11 INFO - Downloading/unpacking blobuploader==1.2.4 11:05:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:14 INFO - Downloading blobuploader-1.2.4.tar.gz 11:05:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:05:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:05:14 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 11:05:14 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 11:05:14 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 11:05:14 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 11:05:14 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 11:05:14 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 11:05:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:05:14 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:05:14 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 11:05:14 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 11:05:14 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 11:05:14 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 11:05:14 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 11:05:14 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 11:05:15 INFO - Downloading docopt-0.6.1.tar.gz 11:05:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:05:15 INFO - Installing collected packages: blobuploader, requests, docopt 11:05:15 INFO - Running setup.py install for blobuploader 11:05:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:05:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:05:15 INFO - Running setup.py install for requests 11:05:15 INFO - Running setup.py install for docopt 11:05:16 INFO - Successfully installed blobuploader requests docopt 11:05:16 INFO - Cleaning up... 11:05:16 INFO - Return code: 0 11:05:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:05:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:05:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:05:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:16 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 0x10ad5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad64570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff448d911c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac02d30>, '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 0x10aa26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abf9b20>, '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.bKZEbFwB4P/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.winfgECt4I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 11:05:16 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 11:05:16 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 11:05:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bKZEbFwB4P/Render', 11:05:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:05:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:05:16 INFO - 'HOME': '/Users/cltbld', 11:05:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:05:16 INFO - 'LOGNAME': 'cltbld', 11:05:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:16 INFO - 'MOZ_NO_REMOTE': '1', 11:05:16 INFO - 'NO_EM_RESTART': '1', 11:05:16 INFO - 'PAGER': '/bin/cat', 11:05:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:05:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:16 INFO - 'PWD': '/builds/slave/test', 11:05:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:05:16 INFO - 'SHELL': '/bin/bash', 11:05:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.winfgECt4I/Listeners', 11:05:16 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 11:05:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:05:16 INFO - 'USER': 'cltbld', 11:05:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:05:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:05:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:16 INFO - 'XPC_FLAGS': '0x0', 11:05:16 INFO - 'XPC_SERVICE_NAME': '0', 11:05:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:05:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:05:16 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-0AgpMF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:05:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:05:16 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-AusBlc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:05:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:05:16 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-EoWNGc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:05:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:05:16 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-_appQH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:05:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:05:16 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-4VF_gd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:05:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:05:16 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-ejf0ki-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:05:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:05:17 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-zu6LVb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:05:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:05:17 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-sNf7Gm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:05:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:05:17 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-8f1teD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:05:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:05:17 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-gP2YEN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:05:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:05:17 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-Lz5pSl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:05:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:05:17 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-dRrRyp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:05:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:05:17 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-TSlLP0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:05:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:05:17 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-1f9rof-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:05:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:05:17 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-wDx6yU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:05:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:05:18 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-xa1jB5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:05:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:05:18 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-04YpTS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:05:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:05:18 INFO - Running setup.py install for manifestparser 11:05:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:05:18 INFO - Running setup.py install for mozcrash 11:05:18 INFO - Running setup.py install for mozdebug 11:05:18 INFO - Running setup.py install for mozdevice 11:05:18 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:05:18 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:05:18 INFO - Running setup.py install for mozfile 11:05:18 INFO - Running setup.py install for mozhttpd 11:05:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:05:19 INFO - Running setup.py install for mozinfo 11:05:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:05:19 INFO - Running setup.py install for mozInstall 11:05:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:05:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:05:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:05:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:05:19 INFO - Running setup.py install for mozleak 11:05:19 INFO - Running setup.py install for mozlog 11:05:19 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:05:19 INFO - Running setup.py install for moznetwork 11:05:19 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:05:19 INFO - Running setup.py install for mozprocess 11:05:19 INFO - Running setup.py install for mozprofile 11:05:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:05:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:05:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:05:20 INFO - Running setup.py install for mozrunner 11:05:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:05:20 INFO - Running setup.py install for mozscreenshot 11:05:20 INFO - Running setup.py install for moztest 11:05:20 INFO - Running setup.py install for mozversion 11:05:20 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:05:20 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:05:20 INFO - Cleaning up... 11:05:20 INFO - Return code: 0 11:05:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:05:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:05:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05: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')]} 11:05:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:21 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 0x10ad5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad64570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff448d911c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac02d30>, '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 0x10aa26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abf9b20>, '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.bKZEbFwB4P/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.winfgECt4I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 11:05:21 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 11:05:21 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 11:05:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bKZEbFwB4P/Render', 11:05:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:05:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:05:21 INFO - 'HOME': '/Users/cltbld', 11:05:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:05:21 INFO - 'LOGNAME': 'cltbld', 11:05:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:21 INFO - 'MOZ_NO_REMOTE': '1', 11:05:21 INFO - 'NO_EM_RESTART': '1', 11:05:21 INFO - 'PAGER': '/bin/cat', 11:05:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:05:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:21 INFO - 'PWD': '/builds/slave/test', 11:05:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:05:21 INFO - 'SHELL': '/bin/bash', 11:05:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.winfgECt4I/Listeners', 11:05:21 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 11:05:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:05:21 INFO - 'USER': 'cltbld', 11:05:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:05:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:05:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:21 INFO - 'XPC_FLAGS': '0x0', 11:05:21 INFO - 'XPC_SERVICE_NAME': '0', 11:05:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:05:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:05:21 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-gqKNRw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:05:21 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)) 11:05:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:05:21 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-94cvW7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:05:21 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)) 11:05:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:05:21 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-v5LxWl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:05:21 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)) 11:05:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:05:21 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-6V6ut4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:05:21 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)) 11:05:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:05:21 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-Z8xBrZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:05:21 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)) 11:05:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:05:21 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-eKIk2X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:05:22 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)) 11:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:05:22 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-x9wIuq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:05:22 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)) 11:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:05:22 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-RXXV0o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:05:22 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)) 11:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:05:22 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-OeWOUS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:05:22 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)) 11:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:05:22 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-bIboLl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:05:22 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)) 11:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:05:22 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-NBydfG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:05:22 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)) 11:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:05:22 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-1yqFGo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:05:22 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)) 11:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:05:22 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-g3iP0t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:05:22 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)) 11:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:05:22 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-UQSeKG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:05:22 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)) 11:05:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:05:22 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-dwWtzh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:05:23 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)) 11:05:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:05:23 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-oh3GWF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:05:23 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)) 11:05:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:05:23 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-RsiVQI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:05:23 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)) 11:05:23 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)) 11:05:23 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)) 11:05:23 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)) 11:05:23 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)) 11:05:23 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)) 11:05:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:05:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:25 INFO - Downloading blessings-1.6.tar.gz 11:05:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:05:25 INFO - Installing collected packages: blessings 11:05:25 INFO - Running setup.py install for blessings 11:05:25 INFO - Successfully installed blessings 11:05:25 INFO - Cleaning up... 11:05:26 INFO - Return code: 0 11:05:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:05:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:05:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:05:26 INFO - Reading from file tmpfile_stdout 11:05:26 INFO - Current package versions: 11:05:26 INFO - blessings == 1.6 11:05:26 INFO - blobuploader == 1.2.4 11:05:26 INFO - docopt == 0.6.1 11:05:26 INFO - manifestparser == 1.1 11:05:26 INFO - mozInstall == 1.12 11:05:26 INFO - mozcrash == 0.17 11:05:26 INFO - mozdebug == 0.1 11:05:26 INFO - mozdevice == 0.48 11:05:26 INFO - mozfile == 1.2 11:05:26 INFO - mozhttpd == 0.7 11:05:26 INFO - mozinfo == 0.9 11:05:26 INFO - mozleak == 0.1 11:05:26 INFO - mozlog == 3.2 11:05:26 INFO - moznetwork == 0.27 11:05:26 INFO - mozprocess == 0.22 11:05:26 INFO - mozprofile == 0.28 11:05:26 INFO - mozrunner == 6.11 11:05:26 INFO - mozscreenshot == 0.1 11:05:26 INFO - mozsystemmonitor == 0.3 11:05:26 INFO - moztest == 0.7 11:05:26 INFO - mozversion == 1.4 11:05:26 INFO - psutil == 3.1.1 11:05:26 INFO - requests == 1.2.3 11:05:26 INFO - wsgiref == 0.1.2 11:05:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:05:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:05:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:05:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:26 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 0x10ad5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad64570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff448d911c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac02d30>, '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 0x10aa26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abf9b20>, '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.bKZEbFwB4P/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.winfgECt4I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 11:05:26 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 11:05:26 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 11:05:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bKZEbFwB4P/Render', 11:05:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:05:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:05:26 INFO - 'HOME': '/Users/cltbld', 11:05:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:05:26 INFO - 'LOGNAME': 'cltbld', 11:05:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:26 INFO - 'MOZ_NO_REMOTE': '1', 11:05:26 INFO - 'NO_EM_RESTART': '1', 11:05:26 INFO - 'PAGER': '/bin/cat', 11:05:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:05:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:26 INFO - 'PWD': '/builds/slave/test', 11:05:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:05:26 INFO - 'SHELL': '/bin/bash', 11:05:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.winfgECt4I/Listeners', 11:05:26 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 11:05:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:05:26 INFO - 'USER': 'cltbld', 11:05:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:05:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:05:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:26 INFO - 'XPC_FLAGS': '0x0', 11:05:26 INFO - 'XPC_SERVICE_NAME': '0', 11:05:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:05:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:26 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)) 11:05:26 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)) 11:05:26 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)) 11:05:26 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)) 11:05:26 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)) 11:05:26 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)) 11:05:26 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)) 11:05:26 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)) 11:05:26 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)) 11:05:26 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)) 11:05:26 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)) 11:05:26 INFO - Cleaning up... 11:05:26 INFO - Return code: 0 11:05:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:05:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:05:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:05:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ad5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad64570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff448d911c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac02d30>, '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 0x10aa26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abf9b20>, '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.bKZEbFwB4P/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.winfgECt4I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 11:05:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:05:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:05:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bKZEbFwB4P/Render', 11:05:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:05:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:05:26 INFO - 'HOME': '/Users/cltbld', 11:05:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:05:26 INFO - 'LOGNAME': 'cltbld', 11:05:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:26 INFO - 'MOZ_NO_REMOTE': '1', 11:05:26 INFO - 'NO_EM_RESTART': '1', 11:05:26 INFO - 'PAGER': '/bin/cat', 11:05:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:05:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:26 INFO - 'PWD': '/builds/slave/test', 11:05:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:05:26 INFO - 'SHELL': '/bin/bash', 11:05:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.winfgECt4I/Listeners', 11:05:26 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 11:05:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:05:26 INFO - 'USER': 'cltbld', 11:05:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:05:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:05:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:26 INFO - 'XPC_FLAGS': '0x0', 11:05:26 INFO - 'XPC_SERVICE_NAME': '0', 11:05:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:05:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:26 INFO - Downloading/unpacking jsonschema 11:05:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:05:29 INFO - Downloading/unpacking functools32 (from jsonschema) 11:05:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:29 INFO - Downloading functools32-3.2.3-2.tar.gz 11:05:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:05:29 INFO - warning: no files found matching '*.txt' 11:05:29 INFO - no previously-included directories found matching 'build' 11:05:29 INFO - no previously-included directories found matching 'dist' 11:05:29 INFO - no previously-included directories found matching '.git*' 11:05:29 INFO - Installing collected packages: jsonschema, functools32 11:05:29 INFO - Running setup.py install for functools32 11:05:30 INFO - warning: no files found matching '*.txt' 11:05:30 INFO - no previously-included directories found matching 'build' 11:05:30 INFO - no previously-included directories found matching 'dist' 11:05:30 INFO - no previously-included directories found matching '.git*' 11:05:30 INFO - Successfully installed jsonschema functools32 11:05:30 INFO - Cleaning up... 11:05:30 INFO - Return code: 0 11:05:30 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 11:05:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:05:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:05:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:05:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:05:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:05:30 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 0x10ad5ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ad64570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff448d911c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ac02d30>, '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 0x10aa26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abf9b20>, '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.bKZEbFwB4P/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.winfgECt4I/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 11:05:30 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 11:05:30 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 11:05:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bKZEbFwB4P/Render', 11:05:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:05:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:05:30 INFO - 'HOME': '/Users/cltbld', 11:05:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:05:30 INFO - 'LOGNAME': 'cltbld', 11:05:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:05:30 INFO - 'MOZ_NO_REMOTE': '1', 11:05:30 INFO - 'NO_EM_RESTART': '1', 11:05:30 INFO - 'PAGER': '/bin/cat', 11:05:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:05:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:05:30 INFO - 'PWD': '/builds/slave/test', 11:05:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:05:30 INFO - 'SHELL': '/bin/bash', 11:05:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.winfgECt4I/Listeners', 11:05:30 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 11:05:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:05:30 INFO - 'USER': 'cltbld', 11:05:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:05:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:05:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:05:30 INFO - 'XPC_FLAGS': '0x0', 11:05:30 INFO - 'XPC_SERVICE_NAME': '0', 11:05:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:05:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:05:30 INFO - Downloading/unpacking flake8 11:05:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:32 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 11:05:32 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 11:05:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:05:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:05:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:05:33 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 11:05:33 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 11:05:33 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 11:05:33 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 11:05:33 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 11:05:33 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 11:05:33 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 11:05:33 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 11:05:33 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 11:05:33 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 11:05:33 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 11:05:33 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 11:05:33 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 11:05:33 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 11:05:33 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 11:05:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 11:05:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:05:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:05:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:05:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:05:34 INFO - no previously-included directories found matching 'docs/_build' 11:05:34 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 11:05:34 INFO - Running setup.py install for pep8 11:05:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 11:05:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 11:05:34 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 11:05:34 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 11:05:34 INFO - no previously-included directories found matching 'docs/_build' 11:05:34 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 11:05:34 INFO - Successfully installed flake8 mccabe pyflakes pep8 11:05:34 INFO - Cleaning up... 11:05:34 INFO - Return code: 0 11:05:34 INFO - Running post-action listener: _resource_record_post_action 11:05:34 INFO - Running post-action listener: _start_resource_monitoring 11:05:34 INFO - Starting resource monitoring. 11:05:34 INFO - ##### 11:05:34 INFO - ##### Finished create-virtualenv step (success) 11:05:34 INFO - ##### 11:05:34 INFO - ##### 11:05:34 INFO - ##### Running install step. 11:05:34 INFO - ##### 11:05:34 INFO - Running pre-action listener: _resource_record_pre_action 11:05:34 INFO - Running main action method: install 11:05:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:05:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:05:34 INFO - Reading from file tmpfile_stdout 11:05:34 INFO - Detecting whether we're running mozinstall >=1.0... 11:05:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:05:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:05:34 INFO - Reading from file tmpfile_stdout 11:05:34 INFO - Output received: 11:05:34 INFO - Usage: mozinstall [options] installer 11:05:34 INFO - Options: 11:05:34 INFO - -h, --help show this help message and exit 11:05:34 INFO - -d DEST, --destination=DEST 11:05:34 INFO - Directory to install application into. [default: 11:05:34 INFO - "/builds/slave/test"] 11:05:34 INFO - --app=APP Application being installed. [default: firefox] 11:05:34 INFO - mkdir: /builds/slave/test/build/application 11:05:34 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'] 11:05:34 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 11:06:08 INFO - Reading from file tmpfile_stdout 11:06:08 INFO - Output received: 11:06:08 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:06:08 INFO - Running post-action listener: _resource_record_post_action 11:06:08 INFO - ##### 11:06:08 INFO - ##### Finished install step (success) 11:06:08 INFO - ##### 11:06:08 INFO - ##### 11:06:08 INFO - ##### Running run-tests step. 11:06:08 INFO - ##### 11:06:08 INFO - Running pre-action listener: _resource_record_pre_action 11:06:08 INFO - Running main action method: run_tests 11:06:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:06:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:06:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:06:08 INFO - 2016-06-01 11:06:08.817 screenresolution[1647:7969] starting screenresolution argv=screenresolution get 11:06:08 INFO - 2016-06-01 11:06:08.820 screenresolution[1647:7969] Display 0: 1600x1200x32@60 11:06:08 INFO - 2016-06-01 11:06:08.825 screenresolution[1648:7971] starting screenresolution argv=screenresolution list 11:06:08 INFO - Available Modes on Display 0 11:06:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:06:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:06:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:06:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:06:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:06:09 INFO - Intel Iris: 11:06:09 INFO - Chipset Model: Intel Iris 11:06:09 INFO - Type: GPU 11:06:09 INFO - Bus: Built-In 11:06:09 INFO - VRAM (Dynamic, Max): 1536 MB 11:06:09 INFO - Vendor: Intel (0x8086) 11:06:09 INFO - Device ID: 0x0a2e 11:06:09 INFO - Revision ID: 0x0009 11:06:09 INFO - Displays: 11:06:09 INFO - Display: 11:06:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:06:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:06:09 INFO - Display Serial Number: 1600x1200 60 11:06:09 INFO - Main Display: Yes 11:06:09 INFO - Mirror: Off 11:06:09 INFO - Online: Yes 11:06:09 INFO - Rotation: Supported 11:06:09 INFO - Return code: 0 11:06:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:06:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:06:09 INFO - Python 2.7.3 11:06:09 INFO - Return code: 0 11:06:09 INFO - grabbing minidump binary from tooltool 11:06:09 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')]} 11:06:09 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 0x10ac02d30>, '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 0x10aa26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10abf9b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:06:09 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 11:06:09 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 11:06:09 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 11:06:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:06:09 INFO - Return code: 0 11:06:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:06:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:06:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:06:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:06:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:06:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 11:06:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 11:06:10 INFO - Return code: 0 11:06:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/G0jJlLLlTFSl82_uKmQgbw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0070', '--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 11:06:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/G0jJlLLlTFSl82_uKmQgbw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0070 --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 11:06:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bKZEbFwB4P/Render', 11:06:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:06:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:06:10 INFO - 'HOME': '/Users/cltbld', 11:06:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:06:10 INFO - 'LOGNAME': 'cltbld', 11:06:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:06:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:06:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:06:10 INFO - 'MOZ_NO_REMOTE': '1', 11:06:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:06:10 INFO - 'NO_EM_RESTART': '1', 11:06:10 INFO - 'PAGER': '/bin/cat', 11:06:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:06:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:06:10 INFO - 'PWD': '/builds/slave/test', 11:06:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:06:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:06:10 INFO - 'SHELL': '/bin/bash', 11:06:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.winfgECt4I/Listeners', 11:06:10 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 11:06:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:06:10 INFO - 'USER': 'cltbld', 11:06:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:06:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:06:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:06:10 INFO - 'XPC_FLAGS': '0x0', 11:06:10 INFO - 'XPC_SERVICE_NAME': '0', 11:06:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:06:10 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/G0jJlLLlTFSl82_uKmQgbw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0070', '--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 11:06:10 INFO - mozversion application_buildid: 20160601090255 11:06:10 INFO - mozversion application_changeset: 369f9791b11e00f73f9c984101dd8e3026a38ae3 11:06:10 INFO - mozversion application_display_name: Nightly 11:06:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:06:10 INFO - mozversion application_name: Firefox 11:06:10 INFO - mozversion application_remotingname: firefox 11:06:10 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 11:06:10 INFO - mozversion application_vendor: Mozilla 11:06:10 INFO - mozversion application_version: 49.0a1 11:06:10 INFO - mozversion platform_buildid: 20160601090255 11:06:10 INFO - mozversion platform_changeset: 369f9791b11e00f73f9c984101dd8e3026a38ae3 11:06:10 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 11:06:10 INFO - mozversion platform_version: 49.0a1 11:06:10 INFO - using testdate: 1464804370 11:06:10 INFO - actual date: 1464804370 11:06:10 INFO - starting webserver on 'localhost:49275' 11:06:10 INFO - SUITE-START | Running 2 tests 11:06:10 INFO - TEST-START | dromaeo_css 11:06:10 INFO - operating with platform_type : mac_ 11:06:10 INFO - Initialising browser for dromaeo_css test... 11:06:10 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/tmpvTUpNM/profile http://localhost:49275/getInfo.html) 11:06:12 INFO - PROCESS | 1666 | __metrics Screen width/height:1600/1200 11:06:12 INFO - PROCESS | 1666 | colorDepth:24 11:06:12 INFO - PROCESS | 1666 | Browser inner width/height: 1024/689 11:06:12 INFO - PROCESS | 1666 | __metrics 11:06:12 INFO - PROCESS | 1666 | 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" 11:06:12 ERROR - PROCESS | 1666 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 11:06:13 INFO - TEST-INFO | 1666: exit 0 11:06:13 INFO - Browser initialized. 11:06:13 INFO - Running cycle 1/1 for dromaeo_css test... 11:06:13 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/tmpvTUpNM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:06:25 INFO - PROCESS | 1669 | RSS: Main: 241815552 11:06:25 INFO - PROCESS | 1669 | 11:07:18 INFO - PROCESS | 1669 | 1464804438000 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] 11:09:26 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 11:09:27 INFO - PROCESS | 1669 | RSS: Main: 375947264 11:09:27 INFO - PROCESS | 1669 | 11:12:30 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 11:12:31 INFO - PROCESS | 1669 | RSS: Main: 372858880 11:12:31 INFO - PROCESS | 1669 | 11:15:33 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 11:15:34 INFO - PROCESS | 1669 | RSS: Main: 368521216 11:15:34 INFO - PROCESS | 1669 | 11:18:36 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 11:18:37 INFO - PROCESS | 1669 | RSS: Main: 371400704 11:18:37 INFO - PROCESS | 1669 | 11:21:40 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 11:21:41 INFO - PROCESS | 1669 | RSS: Main: 336330752 11:21:41 INFO - PROCESS | 1669 | 11:24:44 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 11:24:44 INFO - PROCESS | 1669 | RSS: Main: 378179584 11:24:44 INFO - PROCESS | 1669 | 11:24:44 INFO - PROCESS | 1669 | __start_tp_report 11:24:44 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 11:24:44 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 11:24:44 INFO - PROCESS | 1669 | |i|pagename|runs| 11:24:44 INFO - PROCESS | 1669 | |0;dojo.html;3423.1271025888404;3443.8966830995064;3467.7572569920108;3495.493153492745;3511.315293517765;5604.3835178130375;5649.293838270216;5664.801731939383;5723.255976723025;5756.856078598037;5673.6879471629045;5731.054376027961;5741.885162296762;5744.511716504092;5745.5690823188215;5110.335656364668;5137.203733421316;5158.329417175762;5187.818426355078;5225.399079105913;5096.413912400066;5157.6131790115705;5168;5173.120569503175;5272.103742363807;5166.664166829163;5245.396779370364;5256.842294731164;5260.473952604747;5261.263423120766;267.371943545704;5176.767045482962;5181.792728290901;5249.895002099936;5281.603879709037;5123.359580052494;5127.9743601282125;5139.0749665060275;5155.66488178271;5187.610929180327;5530.391656917735;5616.691081990489;5649.745761440745;5664.461876121762;5671.092625179993;6099.176611157525;6129.080637904305;6129.23653806838;6256.0615907613765;6263.217097862767;6218.28489723683;6236.282827474808;6285.9057114143325;6296.496280297565;6302.905456418157;5824.388439213906;5857.560682948796;5863.882722345572;5867.061270196791;5869.530437564985;6192.938070619326;6205.441510264008;6286;6288.119663247149;6317.1156038154695;6097.420745029215;6126.693665316717;6142.723577438936;6145.96927015371;6146.5390095743;6194.163787888668;6244.250689917239;6264;6264.436200741864;6273.435390814849;6077.361877002851;6101.277634935909;6120.051392034242;6124.473467760062;6181.598196117238;6198.411150940653;6261.812145635638;6265.937340626627;6279.058141278753;6284.371562843679;4970.428400733889;4974.37820272466;4978.925316120261;4986.925196122061;4994.800207991712;4338.674599405057;4374.825006999685;4428.601425871687;4442.333649952533;4454.888627784331;4685.742284174403;5608.691521966331;5638.689872057076;5654.095344744821;5666.546676265889;6169.5064394848305;6192.102145188923;6224.626522408579;6233.189685340877;6244.344343843922;5658.264425624642;5675.5459563234845;5691.544676425876;5697.772089116389;5736.340320863071;5353.357597088374;5353.946460535422;5362.249285100089;5370.167624018283;5372.446598278087;6210.596311239755;6245.96877015612;6285.120083188266;6292.1505596743855;6355.205599300088;6281.905771413524;6283.214598175228;6290.2766181889665;6292.4966002720585;6292.968535157295;6309.148264984352;6355.3644635536075;6357.396047375492;6366.808995730135;6370.713317900798;5041.319421878;6039.335673075871;6099.763790468328;6175.938240617536;6241.843953901189;6256.311805701278;6282.371762823681;6288.151099601679;6300.243970723543;6313.2424109107205;5931.525477961666;5984.311804142405;6143;6152.077188421773;6155.446009859134;6040.456358927718;6102.359252278447;6110.602810817282;6135.969320153371;6151.292601350723;5945.672987985702;5988.311344195473;5990.281166260077;5993.820185394271;6015.338312785502;5923.466887980103;6029.366916473882;6141.3551577085545;6146.385361463997;6147.66187859672;5867;5930.377310382521;5940.405959404025;5957.493613042767;6002.339742628395 11:24:44 INFO - PROCESS | 1669 | |1;ext.html;483.54546726077484;505.4238168487922;521.7991073436729;523.2700382965766;526.944670809565;30200.546991795112;30955.535666964985;32054.358912821728;32085.51871721926;32142.714291428343;44780;45244.77377613107;45321.77339113308;47631.52368476314;47698.28452573214;9555.18780903624;9720.951395243013;9746.707598772047;9748.561314740853;9780.168685661727;8599.871001934976;8851.513166775825;9592.328537002413;9723.367981081243;9735.075167859042;8994.640214391417;9070.501122438263;9107;11361.431928403588;11654.766904661901;11743.41282935854;11863.584774532894;11864.940675296568;11879.643610691694;11887;11681.88318116825;11821;11831.645050648494;11832.171747977645;11836.230645008132;31488.370232595218;41134.79432602848;41581;41597;43884.12231755347;3542.6280240574624;3554.680078792921;3555.9111022224392;3582.659647333499;3725.5715592706642;26487;30314.84842575795;31849.203769905707;31865.522017169762;32175.83912080448;2621.8332841885367;3125.5155450905145;3250.6749325067303;3271.4765637497885;3376.3922493951077;513097.869021305;515442.4227878874;516655;516705.4988350393;517901;67114.32885671179;67464;67498.98751518731;67515.3248467509;67667;69689.65155174192;69959.65020174968;69982;70007.29992700007;70185.64907175432;15932.68134637324;16271;16402.507924762278;16411;16411.343546258257;12658.303793291458;12695;12695.428705708266;12706.174098683556;12710.809337859939;12444.81332780009;15584.298706558006;16432.424865129662;16864.964306424718;16867.060288066783;5366.490183432567;5429.886873190986;5783.421657834183;6410.1987251593555;6471.546991710629;866.7009881590841;962.5177227070374;1051.9737006574744;1053.947302634865;1071.844582535528;30241.395172096876;30558.69441305603;30804.075877723702;31444.842775786434;31467.685323146932;1099.4228030284164;1110.6001839337832;1118.7091356247272;1123.9325640461598;1171.701216189866;13693.383797729126;15893.046417215004;15909.96585221957;16297.266623001993;16303;650561;938505;954406;955248;958858;54432.72783636136;56081;64319;64582.67708661521;65606.0159097614;1415.2852809331223;1449.7028109237792;1457.460739526382;1459.1536908593202;1462.4150339864138;30946.0716178506;31718.365632686757;31983.629875476883;32304.35391292114;32470;53573.73213133909;54099.72950135224;54135.45864541304;54296.45703543072;54362;395798;618483.9075804593;625387;628246;827043;26322.341941451617;26619.60070598904;26627.201183964513;26730.465390691686;26758;46471.74697771259;47055;47096;48012.03975920532;48026.759866200446;31767.841160794047;39448.802755986035;39498.012549686486;39759.80120099381;40169.79915100406;37342.2531549362;37458.812705936296;38253.61746382612;38347.84956451311;39154.82535523939;37489.31298091647;38180.80909595434;38519.84440466791;38651.61348386593;38708.41937370998;32238.871639492507;32311.35377292488;33856.65119856988;34401.65598343984;34447.483287750205;25083;25126;25188.874055629603;25467.490650187265;25586.61620075663 11:24:44 INFO - PROCESS | 1669 | |2;jquery.html;372.4878292348022;377.486618199249;383.24691980258785;395.045963996947;405.8153540139237;19176.232950681973;20277;21091.894540527293;21093.894530527326;21181;11916.404179790998;12089.153759236857;12225.572104976327;12239.388030598479;12258.264504129758;4112.547619761824;5496.725163741817;5535;5538.529225015878;5541.27963364762;3908.609139086097;4151.356539736345;4652.860414187563;6008.849778755544;6154.692265386735;6114.785982490614;6148.8462788430425;6153.323134455206;6167.969160154193;6174.475169610566;6646.833829154286;6671.132752742161;6683.164604424447;6683.632400217986;6691.93308066918;3781.7920014399197;3982.203559288125;4022.5776293489057;4300.290452075404;5907.4683278505145;10591.523381447776;13032.957363410998;13033.934830325883;13190;13206.471741130343;1799.4061959553317;2257.3566533537864;2292.7592602776635;2488.726240113586;2737.2235597805893;9873.160781333596;9993.200543956538;10367.274290799647;13489.460421583124;14412.855871441256;1876.5402476393372;1879.736836842843;1893.640208360407;1962.8331591814713;1996.1217064491577;117451;123852;123863.76136238613;124533.37733311456;126352.36823815914;11428.371439570903;11438.254818843418;11456.026237769716;11458.312501249786;11607.129465290138;14402.495912643008;14458.783118253024;14465.421383144556;14470.059446135967;14480.85519144795;8259.752207433787;8445.831083378298;8472.237498625153;8570.48577085377;8585.31317494599;8311.792205194797;8313.6674533018;8345.332373410112;8445.451045682012;8470;3764.962350376516;4400.625946794495;4491.865244042684;4506.301523263899;4557.863264102082;1458.5624312706145;1693.542743459259;1719.406804652393;1860.2930886263405;1910.2550005497867;927.5130556457914;970.9563069661741;1039;1043.9425831579183;1070.9946450267698;20471.38585842427;21128.78871211299;21195.364139075853;21197.89401052985;21341;939.1594522901964;956.8469044952774;961.5432669481941;1044.7074819050679;1082.577794660083;10594.682159535227;11150.609728659458;11183.273087249303;11194.328340299608;11208.15938804593;575621;580928;594737;597178;597296;125774.74225257813;126428.36785816013;129595;129790;137808;1012.8480727890728;1026.235454586338;1031.0926384781194;1048.4705223861902;1050.3750268590177;13859.722805544034;17965;18111.184996675445;18223;18921;48942.75528622334;49517.00965980588;50738.49261507337;51151.209707660055;52250.73874630602;51046.74476627593;70417;72612;73040.17399565053;73514.63242683752;5697.316322041382;6683.565568238049;7129.536580122275;7822.256885595859;8081.676732930617;10168.796624067625;10469.267151299337;12408.875911240772;12414.503419863466;12436.37818109109;10509.421981790769;10722.892771072502;10795.838062429228;10802.973717496825;10816.188785840935;10347.896521034694;10509.632162874263;10677.466126693478;10758.73103172427;10765.677029689123;10448.634297799541;10690;10940.713029259345;11017.779644407226;11167.609133680608;7039.507234493536;7677.848322751632;7935.5635440049045;7954.880676789737;7966.322862556516;8935.419197752126;10823.891761082288;10903.182261330274;11008.834867476835;11025.393603351893 11:24:44 INFO - PROCESS | 1669 | |3;mootools.html;1395.2884029145146;1574.1027614259872;1597.448880136353;1823.9088045597716;1975.3678822776708;7905.4861434006825;8601.698940537082;8840.38117331787;8854.026057133726;8965.237954773836;8576.65693372266;8795.692150774725;8799.736007919757;8802.779930501742;8854.158854908774;2093.769685334612;2181.2692747929464;2345.870977096259;2382.5711371953084;2415.3961509622595;2410.144398738449;2451.509698060386;2457.717362503317;2458.164224163793;2464.6549483072386;2375.9168429104984;2412.686350774397;2413.3242692046256;2419.8427102238297;2420.419099416145;1762.8365278915983;1955.7261983322346;2046.8771873687626;2134.5090629155225;2146.61360955028;2163.5456554123652;2332.685087513181;2405.5549723301247;2413.276017194852;2420.5279970405777;8781.473111613259;8889.244414224733;8901.020887702347;8901.332400070021;8907.777305567413;5158.9484105158845;5329.22726204702;5342.652727572696;5345.278387417688;5364.463553644643;3225.774205801804;3300.96699033009;3625.1843335249623;3654.9817250913575;3702.5927148013966;1042.3433237060678;1054.5939813171983;1107.1198397274186;1110.8833572474855;1115.9621551956677;11936.820947685705;11937.224080434744;11961.162718609701;11965.521379144824;11970.521179152824;4613.100445413179;4762.714237145782;4823.855284341475;4830.637702172351;4833.516648335139;8168.509889406655;9996.250281228791;10037.749056273651;10052.748681283027;10075.546600402999;5292.259083728281;5292.761825717852;5300.787968481218;5301.0723123453245;5303.178007408857;4311.6335111515245;4471.5081341052455;4689.507601701772;4799.6400269979895;4842.539958703917;3342.8495717692763;3943.684505239574;3986.1828325193264;4050.1292222172374;4095.795210239476;1150.8031647087103;1257.654145110102;1286.761949039412;1295.203449878318;1309.7445998030294;741.4290995933102;919.7562645898843;923.6628630549801;931.6646007437317;970.4565443351604;4591;5167.405748338914;5798.797042103506;6015.639061656315;6149.169862068564;923.3905622289257;940.4498368454394;975.7560609847538;978.9314747967574;1020.5356562763925;3843.7309388342546;3984.3425834737523;4002.799860006988;4055.1281474483126;4108.630223279919;10634.840477392845;10992.17558683102;11052.392118433563;11058.391788451712;11064.28082174656;10808.51361688726;10923.289986150874;10957.397343146044;10978.286411383235;11022.448877556251;6865.691043902936;8249.340052795882;8252.298554622805;8333.374996875258;8337.583120843934;4632.698874573142;5822.2140011099655;5961.940380596312;6084.452399284086;6216.125761155665;9850.507474626096;10271.229657775846;10275.332103413255;10283.94858025705;10284.434356110485;9710.271729620448;9861.556229969525;10291.485425728833;10302.639407620698;10389.948050260004;6237.625742455467;6528.281888992302;6970.477214208853;7224.422046236184;7497.062867141607;8470.449420787629;8585.18440748128;8642.56787160652;8659.13408659129;8673.045964943978;7487.064116985377;7566.886496702664;7643.159252482334;7692.653830577526;7716.92283077162;7310.122785265802;7380.630968451448;7406.814829629302;7544.132424771002;7564.773056808304;7068.787936361918;7132.251113632994;7163.8567228656175;7166.104236970379;7170.928290717026;4863.781129849236;5022.020705962448;5072.442031376472;5072.771725272298;5076.18780995039;5032.169692000779;5060.620453465931;5101.79592816298;5139;5208.8437346879655 11:24:44 INFO - PROCESS | 1669 | |4;prototype.html;303.3114829337404;310.83525731362374;312.6935603108954;317.19590837227616;320.3880588076774;4486.371907932892;4901.338319326895;5066.518680725329;5441.8709242185805;5478.863028424292;4473.485549161847;4753.572178503934;5153.4846515348445;5163.767630456629;5462.590305727066;3403.4894765785084;3857.996920800598;3872.419137129439;4022.3363145081025;4081.449004384415;3368.3263347330508;3984.8406063757416;4036.2129384769983;4045.757254564721;4049.3521036634143;3596.4754540550275;3743.28877513273;3825.5791862895057;3921.941170882439;4022.3162062449455;3090.9336278983715;3940.9556467536127;3965.504311961019;4027.1140349123148;4055.776932268724;2652.310399296197;3049.7560195184333;3320.9169770755684;3494.178867966026;3519.63043880394;5145.279660847447;5161.767720452589;5190.351206099238;5211.661242019257;5238.476152384769;4463.352813841975;4655.3947986761505;4669.883252918704;4670.276107203389;4681.695689780154;5561.332640083176;5589.245451863987;5593.804216852432;5596.58025648078;5656.151577263402;3027.8334691591954;3575.982120089409;3774.5659249186147;3889.6888248940018;3903.512060992376;4817.662763606584;5102;5179.766910489037;5215.765290561933;5355.6251062425645;3198.145075855998;3375.746818988586;3904.629060239273;3956.4263181839046;3966.6628336591134;3770.830312635938;3814.8664796732537;4051.858184963512;4070.921205880444;4094.078832262777;4048.8582899598373;4412.977935110304;4602.38712610599;4650.906981860344;4730.739809310452;4158.9584104158575;5669.618760120759;5671.2419150063615;5682.261306030189;5697.4302569742695;1184.644606618011;1209.2986068080493;1278.7889998150201;1284.4027527199896;1346.9259190744604;721.8303698630817;789.3882241262952;815.135955886762;821.6836517940636;822.2271065198694;4253.723507971972;4335.718178318462;4621.491635920046;4837.2260438329695;5221.6083793715625;5375.924815037009;5469.362873688952;5599.384067752544;5754.74103665336;5864.912026319608;5079.568236699922;5258.2638430619745;5284.894302113936;5338.332708411449;5344;2501.624756286535;2928.4875146849217;3085.7377122944354;3091.2890035292007;3099.5195744659613;5657.632253903483;5887.587868849139;6054.334023257438;6100.481459076028;6127.325994140641;5573.303337082864;5611.390703322974;6001.5798894077;6004.609700369462;6024.337322894478;5756.194132821408;5964.866675331673;6052.364501727343;6070.666113363807;6085.786997455068;5627.651919307245;5725.341585717696;5793.536517078539;5818.9127163093435;5846;5080.695158290514;5662.518685911744;5763.567732420001;5763.625364351304;5770.971145144247;5089.389273287081;5121.462246464217;5390.811321603725;5980.970095149496;6083.939160608337;2848.5442329227226;3123.4533956557966;3214.967850321467;3325.019119359746;3366.612839523388;4213.852515161954;4368.453943257093;4555.817767289272;4568.588827005586;4617.769111544342;3927.626875446828;4200.474940632421;4608.101420223024;4627.051454451762;4786.760661966958;3932.050961778666;4168.6039826216465;4251.5748425157235;4532.864014079583;4550.704204226715;3954.466147070224;4345.587169218919;4496.865094047183;4677.976610116928;4722.905541889212;4547.977260113678;4771.618270538418;5325.056354430528;5362.356517217959;5448.891022179613;4490.663200259928;4541.977290113528;4718.245080787057;5461.466982404515;5515.420880807457 11:24:44 INFO - PROCESS | 1669 | |5;yui.html;715.4169351978138;809.4333966223645;814.7515007922583;838.5010918503489;853.7780177153946;15048;15210.16344101074;15499.767503487457;16567.91716041421;17159.05625190614;16150.596235094099;16159.949603275809;16237.756433653505;16298.022118672858;16305.266263018162;705.8411857332085;885.070675790419;890.5591732092623;894.065701342099;896.596531560797;722.8626560953428;875.8467268228067;906.7007887397143;907.7503686486236;909.9681511147111;922.898481167071;929.4794914847673;930.1070971866982;931.6692574136185;933.0389698610409;735.9043324367851;910.3627460777482;927.2767241551601;928.2295694573488;930.5858892792744;690.7616872179141;700.6951975890504;700.8423104801429;701.018573996404;701.2041333086933;15204.315805788767;15305.464308749251;15316.770248446282;15319.080855148726;15332.770008449883;13294.800577991338;13418.53035143775;13432.395542200527;13639.181649101085;13720.862791372158;12082.939585302105;12443.564475243413;12516.99864010877;12541.31022793746;13036.217826930415;4507.391502147234;4513.887152821173;4608.838690645811;4624.260118381077;4665.533446655341;86939.69590456148;87668.68496972551;87789;87856;87906.5604671979;10390.688279351632;10462.63380781669;10621.522031508446;10684.839727404094;10725.624603138853;20322.390328290174;20711.8964405177;21032.263870764757;21061.26285580028;21544.89227553852;8008.27925486709;8104.149064348277;8149.307308878811;8322.50064996102;8322.583870806435;4615.123126606001;4628.652851036186;4652.139354219413;4694.6009589185305;4813.542713442217;3364.4280472319765;3485.5991560970306;3488.686018258369;3496.3706532823826;3500.2474467989505;657.1982181738272;662.3740565165872;673.441043933534;686.3582550315471;687.388224480213;583.3029529712038;597.3130899465563;655.4776012098055;692.6389644348878;708.7661071846278;9131.954340228256;9457.527123643791;9471.715848524555;9563.808723825623;9570.808583828424;654.1202083198091;655.947524198063;694.2571448550104;694.9861002779916;698.6332175607846;3439.466882633146;3537.46937959303;4448.755318457516;4485.955140448619;4556.72659640416;92908;96093;96453;97071.02928970761;97469;67397;80141;80256;84160;84693;68367.31632683708;69401;69573.65213174003;69771.3022869775;70836.29163708503;9486.383385079947;9513.096255855682;9559.713208603753;9716.562754675915;9774.462404567532;42571.57428425676;43518;44130;45112.77443612761;47842;29729.70270297356;46085.07829843451;64360.35639643731;64473;64735.67632161809;12734.299613521345;12846.550370736979;12934.353282336035;12962.287074210632;13373.732525349633;14208.147511149364;14674.046186997812;14701.779473308123;14766.040207386486;14964.700705986037;13346.666333341744;13511.324433778469;13522.79715804244;13529.255890926093;13586.184828910296;12994.285314307805;13351.79972300397;13386.129901556367;13398.330083495981;13828.93085534566;13050.15174013686;13086.345682716017;13102.344882755633;13141.868581314064;13746.793798092836;10712.464376780974;10933.180011499011;10979.28634638746;11055.336679799238;11372.715682108013;10048.246381521561;10405;10488.790224195625;11063.170262230204;11347.262427942158 11:24:44 INFO - PROCESS | 1669 | __end_tp_report 11:24:44 INFO - PROCESS | 1669 | __start_cc_report 11:24:44 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,1366 11:24:44 INFO - PROCESS | 1669 | __end_cc_report 11:24:44 INFO - PROCESS | 1669 | __startTimestamp1464805484806__endTimestamp 11:24:44 INFO - PROCESS | 1669 | 11:24:44 INFO - PROCESS | 1669 | ------- Summary: start ------- 11:24:44 INFO - PROCESS | 1669 | Number of tests: 6 11:24:44 INFO - PROCESS | 1669 | 11:24:44 INFO - PROCESS | 1669 | [#0] dojo.html Cycles:165 Average:5702.72 Median:5957.49 stddev:763.65 (12.8%) stddev-sans-first:744.76 11:24:44 INFO - PROCESS | 1669 | Values: 3423.1 3443.9 3467.8 3495.5 3511.3 5604.4 5649.3 5664.8 5723.3 5756.9 5673.7 5731.1 5741.9 5744.5 5745.6 5110.3 5137.2 5158.3 5187.8 5225.4 5096.4 5157.6 5168.0 5173.1 5272.1 5166.7 5245.4 5256.8 5260.5 5261.3 267.4 5176.8 5181.8 5249.9 5281.6 5123.4 5128.0 5139.1 5155.7 5187.6 5530.4 5616.7 5649.7 5664.5 5671.1 6099.2 6129.1 6129.2 6256.1 6263.2 6218.3 6236.3 6285.9 6296.5 6302.9 5824.4 5857.6 5863.9 5867.1 5869.5 6192.9 6205.4 6286.0 6288.1 6317.1 6097.4 6126.7 6142.7 6146.0 6146.5 6194.2 6244.3 6264.0 6264.4 6273.4 6077.4 6101.3 6120.1 6124.5 6181.6 6198.4 6261.8 6265.9 6279.1 6284.4 4970.4 4974.4 4978.9 4986.9 4994.8 4338.7 4374.8 4428.6 4442.3 4454.9 4685.7 5608.7 5638.7 5654.1 5666.5 6169.5 6192.1 6224.6 6233.2 6244.3 5658.3 5675.5 5691.5 5697.8 5736.3 5353.4 5353.9 5362.2 5370.2 5372.4 6210.6 6246.0 6285.1 6292.2 6355.2 6281.9 6283.2 6290.3 6292.5 6293.0 6309.1 6355.4 6357.4 6366.8 6370.7 5041.3 6039.3 6099.8 6175.9 6241.8 6256.3 6282.4 6288.2 6300.2 6313.2 5931.5 5984.3 6143.0 6152.1 6155.4 6040.5 6102.4 6110.6 6136.0 6151.3 5945.7 5988.3 5990.3 5993.8 6015.3 5923.5 6029.4 6141.4 6146.4 6147.7 5867.0 5930.4 5940.4 5957.5 6002.3 11:24:44 INFO - PROCESS | 1669 | 11:24:44 INFO - PROCESS | 1669 | [#1] ext.html Cycles:180 Average:79631.63 Median:3654.12 stddev:189345.53 (5181.7%) stddev-sans-first:189783.44 11:24:44 INFO - PROCESS | 1669 | Values: 483.5 505.4 521.8 523.3 526.9 30200.5 30955.5 32054.4 32085.5 32142.7 44780.0 45244.8 45321.8 47631.5 47698.3 9555.2 9721.0 9746.7 9748.6 9780.2 8599.9 8851.5 9592.3 9723.4 9735.1 8994.6 9070.5 9107.0 11361.4 11654.8 11743.4 11863.6 11864.9 11879.6 11887.0 11681.9 11821.0 11831.6 11832.2 11836.2 31488.4 41134.8 41581.0 41597.0 43884.1 3542.6 3554.7 3555.9 3582.7 3725.6 26487.0 30314.8 31849.2 31865.5 32175.8 2621.8 3125.5 3250.7 3271.5 3376.4 513097.9 515442.4 516655.0 516705.5 517901.0 67114.3 67464.0 67499.0 67515.3 67667.0 69689.7 69959.7 69982.0 70007.3 70185.6 15932.7 16271.0 16402.5 16411.0 16411.3 12658.3 12695.0 12695.4 12706.2 12710.8 12444.8 15584.3 16432.4 16865.0 16867.1 5366.5 5429.9 5783.4 6410.2 6471.5 866.7 962.5 1052.0 1053.9 1071.8 30241.4 30558.7 30804.1 31444.8 31467.7 1099.4 1110.6 1118.7 1123.9 1171.7 13693.4 15893.0 15910.0 16297.3 16303.0 650561.0 938505.0 954406.0 955248.0 958858.0 54432.7 56081.0 64319.0 64582.7 65606.0 1415.3 1449.7 1457.5 1459.2 1462.4 30946.1 31718.4 31983.6 32304.4 32470.0 53573.7 54099.7 54135.5 54296.5 54362.0 395798.0 618483.9 625387.0 628246.0 827043.0 26322.3 26619.6 26627.2 26730.5 26758.0 46471.7 47055.0 47096.0 48012.0 48026.8 31767.8 39448.8 39498.0 39759.8 40169.8 37342.3 37458.8 38253.6 38347.8 39154.8 37489.3 38180.8 38519.8 38651.6 38708.4 32238.9 32311.4 33856.7 34401.7 34447.5 25083.0 25126.0 25188.9 25467.5 25586.6 11:24:44 INFO - PROCESS | 1669 | 11:24:44 INFO - PROCESS | 1669 | [#2] jquery.html Cycles:180 Average:33901.58 Median:20781.64 stddev:98631.95 (474.6%) stddev-sans-first:98876.51 11:24:44 INFO - PROCESS | 1669 | Values: 372.5 377.5 383.2 395.0 405.8 19176.2 20277.0 21091.9 21093.9 21181.0 11916.4 12089.2 12225.6 12239.4 12258.3 4112.5 5496.7 5535.0 5538.5 5541.3 3908.6 4151.4 4652.9 6008.8 6154.7 6114.8 6148.8 6153.3 6168.0 6174.5 6646.8 6671.1 6683.2 6683.6 6691.9 3781.8 3982.2 4022.6 4300.3 5907.5 10591.5 13033.0 13033.9 13190.0 13206.5 1799.4 2257.4 2292.8 2488.7 2737.2 9873.2 9993.2 10367.3 13489.5 14412.9 1876.5 1879.7 1893.6 1962.8 1996.1 117451.0 123852.0 123863.8 124533.4 126352.4 11428.4 11438.3 11456.0 11458.3 11607.1 14402.5 14458.8 14465.4 14470.1 14480.9 8259.8 8445.8 8472.2 8570.5 8585.3 8311.8 8313.7 8345.3 8445.5 8470.0 3765.0 4400.6 4491.9 4506.3 4557.9 1458.6 1693.5 1719.4 1860.3 1910.3 927.5 971.0 1039.0 1043.9 1071.0 20471.4 21128.8 21195.4 21197.9 21341.0 939.2 956.8 961.5 1044.7 1082.6 10594.7 11150.6 11183.3 11194.3 11208.2 575621.0 580928.0 594737.0 597178.0 597296.0 125774.7 126428.4 129595.0 129790.0 137808.0 1012.8 1026.2 1031.1 1048.5 1050.4 13859.7 17965.0 18111.2 18223.0 18921.0 48942.8 49517.0 50738.5 51151.2 52250.7 51046.7 70417.0 72612.0 73040.2 73514.6 5697.3 6683.6 7129.5 7822.3 8081.7 10168.8 10469.3 12408.9 12414.5 12436.4 10509.4 10722.9 10795.8 10803.0 10816.2 10347.9 10509.6 10677.5 10758.7 10765.7 10448.6 10690.0 10940.7 11017.8 11167.6 7039.5 7677.8 7935.6 7954.9 7966.3 8935.4 10823.9 10903.2 11008.8 11025.4 11:24:44 INFO - PROCESS | 1669 | 11:24:44 INFO - PROCESS | 1669 | [#3] mootools.html Cycles:180 Average:5722.02 Median:4832.08 stddev:3253.02 (67.3%) stddev-sans-first:3245.90 11:24:44 INFO - PROCESS | 1669 | Values: 1395.3 1574.1 1597.4 1823.9 1975.4 7905.5 8601.7 8840.4 8854.0 8965.2 8576.7 8795.7 8799.7 8802.8 8854.2 2093.8 2181.3 2345.9 2382.6 2415.4 2410.1 2451.5 2457.7 2458.2 2464.7 2375.9 2412.7 2413.3 2419.8 2420.4 1762.8 1955.7 2046.9 2134.5 2146.6 2163.5 2332.7 2405.6 2413.3 2420.5 8781.5 8889.2 8901.0 8901.3 8907.8 5158.9 5329.2 5342.7 5345.3 5364.5 3225.8 3301.0 3625.2 3655.0 3702.6 1042.3 1054.6 1107.1 1110.9 1116.0 11936.8 11937.2 11961.2 11965.5 11970.5 4613.1 4762.7 4823.9 4830.6 4833.5 8168.5 9996.3 10037.7 10052.7 10075.5 5292.3 5292.8 5300.8 5301.1 5303.2 4311.6 4471.5 4689.5 4799.6 4842.5 3342.8 3943.7 3986.2 4050.1 4095.8 1150.8 1257.7 1286.8 1295.2 1309.7 741.4 919.8 923.7 931.7 970.5 4591.0 5167.4 5798.8 6015.6 6149.2 923.4 940.4 975.8 978.9 1020.5 3843.7 3984.3 4002.8 4055.1 4108.6 10634.8 10992.2 11052.4 11058.4 11064.3 10808.5 10923.3 10957.4 10978.3 11022.4 6865.7 8249.3 8252.3 8333.4 8337.6 4632.7 5822.2 5961.9 6084.5 6216.1 9850.5 10271.2 10275.3 10283.9 10284.4 9710.3 9861.6 10291.5 10302.6 10389.9 6237.6 6528.3 6970.5 7224.4 7497.1 8470.4 8585.2 8642.6 8659.1 8673.0 7487.1 7566.9 7643.2 7692.7 7716.9 7310.1 7380.6 7406.8 7544.1 7564.8 7068.8 7132.3 7163.9 7166.1 7170.9 4863.8 5022.0 5072.4 5072.8 5076.2 5032.2 5060.6 5101.8 5139.0 5208.8 11:24:44 INFO - PROCESS | 1669 | 11:24:44 INFO - PROCESS | 1669 | [#4] prototype.html Cycles:180 Average:4353.53 Median:4653.15 stddev:1385.74 (29.8%) stddev-sans-first:1355.87 11:24:44 INFO - PROCESS | 1669 | Values: 303.3 310.8 312.7 317.2 320.4 4486.4 4901.3 5066.5 5441.9 5478.9 4473.5 4753.6 5153.5 5163.8 5462.6 3403.5 3858.0 3872.4 4022.3 4081.4 3368.3 3984.8 4036.2 4045.8 4049.4 3596.5 3743.3 3825.6 3921.9 4022.3 3090.9 3941.0 3965.5 4027.1 4055.8 2652.3 3049.8 3320.9 3494.2 3519.6 5145.3 5161.8 5190.4 5211.7 5238.5 4463.4 4655.4 4669.9 4670.3 4681.7 5561.3 5589.2 5593.8 5596.6 5656.2 3027.8 3576.0 3774.6 3889.7 3903.5 4817.7 5102.0 5179.8 5215.8 5355.6 3198.1 3375.7 3904.6 3956.4 3966.7 3770.8 3814.9 4051.9 4070.9 4094.1 4048.9 4413.0 4602.4 4650.9 4730.7 4159.0 5669.6 5671.2 5682.3 5697.4 1184.6 1209.3 1278.8 1284.4 1346.9 721.8 789.4 815.1 821.7 822.2 4253.7 4335.7 4621.5 4837.2 5221.6 5375.9 5469.4 5599.4 5754.7 5864.9 5079.6 5258.3 5284.9 5338.3 5344.0 2501.6 2928.5 3085.7 3091.3 3099.5 5657.6 5887.6 6054.3 6100.5 6127.3 5573.3 5611.4 6001.6 6004.6 6024.3 5756.2 5964.9 6052.4 6070.7 6085.8 5627.7 5725.3 5793.5 5818.9 5846.0 5080.7 5662.5 5763.6 5763.6 5771.0 5089.4 5121.5 5390.8 5981.0 6083.9 2848.5 3123.5 3215.0 3325.0 3366.6 4213.9 4368.5 4555.8 4568.6 4617.8 3927.6 4200.5 4608.1 4627.1 4786.8 3932.1 4168.6 4251.6 4532.9 4550.7 3954.5 4345.6 4496.9 4678.0 4722.9 4548.0 4771.6 5325.1 5362.4 5448.9 4490.7 4542.0 4718.2 5461.5 5515.4 11:24:44 INFO - PROCESS | 1669 | 11:24:44 INFO - PROCESS | 1669 | [#5] yui.html Cycles:180 Average:18793.04 Median:4626.46 stddev:25662.12 (554.7%) stddev-sans-first:25698.21 11:24:44 INFO - PROCESS | 1669 | Values: 715.4 809.4 814.8 838.5 853.8 15048.0 15210.2 15499.8 16567.9 17159.1 16150.6 16159.9 16237.8 16298.0 16305.3 705.8 885.1 890.6 894.1 896.6 722.9 875.8 906.7 907.8 910.0 922.9 929.5 930.1 931.7 933.0 735.9 910.4 927.3 928.2 930.6 690.8 700.7 700.8 701.0 701.2 15204.3 15305.5 15316.8 15319.1 15332.8 13294.8 13418.5 13432.4 13639.2 13720.9 12082.9 12443.6 12517.0 12541.3 13036.2 4507.4 4513.9 4608.8 4624.3 4665.5 86939.7 87668.7 87789.0 87856.0 87906.6 10390.7 10462.6 10621.5 10684.8 10725.6 20322.4 20711.9 21032.3 21061.3 21544.9 8008.3 8104.1 8149.3 8322.5 8322.6 4615.1 4628.7 4652.1 4694.6 4813.5 3364.4 3485.6 3488.7 3496.4 3500.2 657.2 662.4 673.4 686.4 687.4 583.3 597.3 655.5 692.6 708.8 9132.0 9457.5 9471.7 9563.8 9570.8 654.1 655.9 694.3 695.0 698.6 3439.5 3537.5 4448.8 4486.0 4556.7 92908.0 96093.0 96453.0 97071.0 97469.0 67397.0 80141.0 80256.0 84160.0 84693.0 68367.3 69401.0 69573.7 69771.3 70836.3 9486.4 9513.1 9559.7 9716.6 9774.5 42571.6 43518.0 44130.0 45112.8 47842.0 29729.7 46085.1 64360.4 64473.0 64735.7 12734.3 12846.6 12934.4 12962.3 13373.7 14208.1 14674.0 14701.8 14766.0 14964.7 13346.7 13511.3 13522.8 13529.3 13586.2 12994.3 13351.8 13386.1 13398.3 13828.9 13050.2 13086.3 13102.3 13141.9 13746.8 10712.5 10933.2 10979.3 11055.3 11372.7 10048.2 10405.0 10488.8 11063.2 11347.3 11:24:44 INFO - PROCESS | 1669 | -------- Summary: end -------- 11:24:44 INFO - PROCESS | 1669 | 11:24:45 INFO - TEST-INFO | 1669: exit 0 11:24:45 INFO - TEST-OK | dromaeo_css | took 1114948ms 11:24:45 INFO - TEST-START | kraken 11:24:45 INFO - operating with platform_type : mac_ 11:24:45 INFO - Initialising browser for kraken test... 11:24:45 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/tmpQSyfZx/profile http://localhost:49275/getInfo.html) 11:24:47 INFO - PROCESS | 1673 | __metrics Screen width/height:1600/1200 11:24:47 INFO - PROCESS | 1673 | colorDepth:24 11:24:47 INFO - PROCESS | 1673 | Browser inner width/height: 1024/689 11:24:47 INFO - PROCESS | 1673 | __metrics 11:24:47 INFO - PROCESS | 1673 | 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" 11:24:47 ERROR - PROCESS | 1673 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 11:24:48 INFO - TEST-INFO | 1673: exit 0 11:24:48 INFO - Browser initialized. 11:24:48 INFO - Running cycle 1/1 for kraken test... 11:24:48 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/tmpQSyfZx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:25:00 INFO - PROCESS | 1674 | RSS: Main: 241897472 11:25:00 INFO - PROCESS | 1674 | 11:25:03 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 11:25:04 INFO - PROCESS | 1674 | RSS: Main: 291627008 11:25:04 INFO - PROCESS | 1674 | 11:25:06 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 11:25:07 INFO - PROCESS | 1674 | RSS: Main: 302166016 11:25:07 INFO - PROCESS | 1674 | 11:25:10 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 11:25:11 INFO - PROCESS | 1674 | RSS: Main: 385851392 11:25:11 INFO - PROCESS | 1674 | 11:25:13 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 11:25:14 INFO - PROCESS | 1674 | RSS: Main: 288133120 11:25:14 INFO - PROCESS | 1674 | 11:25:16 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:25:16 INFO - PROCESS | 1674 | RSS: Main: 290820096 11:25:16 INFO - PROCESS | 1674 | 11:25:19 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 11:25:20 INFO - PROCESS | 1674 | RSS: Main: 328691712 11:25:20 INFO - PROCESS | 1674 | 11:25:23 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 11:25:23 INFO - PROCESS | 1674 | RSS: Main: 330797056 11:25:23 INFO - PROCESS | 1674 | 11:25:26 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 11:25:27 INFO - PROCESS | 1674 | RSS: Main: 329572352 11:25:27 INFO - PROCESS | 1674 | 11:25:28 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:25:29 INFO - PROCESS | 1674 | RSS: Main: 309665792 11:25:29 INFO - PROCESS | 1674 | 11:25:31 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:25:32 INFO - PROCESS | 1674 | RSS: Main: 316456960 11:25:32 INFO - PROCESS | 1674 | 11:25:34 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:25:35 INFO - PROCESS | 1674 | RSS: Main: 320753664 11:25:35 INFO - PROCESS | 1674 | 11:25:37 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:25:38 INFO - PROCESS | 1674 | RSS: Main: 323575808 11:25:38 INFO - PROCESS | 1674 | 11:25:41 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:25:42 INFO - PROCESS | 1674 | RSS: Main: 323305472 11:25:42 INFO - PROCESS | 1674 | 11:25:43 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 11:25:44 INFO - PROCESS | 1674 | RSS: Main: 367239168 11:25:44 INFO - PROCESS | 1674 | 11:25:44 INFO - PROCESS | 1674 | __start_tp_report 11:25:44 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 11:25:44 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 11:25:44 INFO - PROCESS | 1674 | |i|pagename|runs| 11:25:44 INFO - PROCESS | 1674 | |0;ai-astar;113;106;112;111;114;113;113;113;112;114 11:25:44 INFO - PROCESS | 1674 | |1;audio-beat-detection;151;121;119;176;132;111;143;115;151;121 11:25:44 INFO - PROCESS | 1674 | |2;audio-dft;184;183;187;182;184;180;182;181;183;185 11:25:44 INFO - PROCESS | 1674 | |3;audio-fft;79;73;60;107;58;56;61;64;95;76 11:25:44 INFO - PROCESS | 1674 | |4;audio-oscillator;85;90;92;72;71;67;95;71;69;99 11:25:44 INFO - PROCESS | 1674 | |5;imaging-gaussian-blur;90;86;89;88;90;87;134;119;90;85 11:25:44 INFO - PROCESS | 1674 | |6;imaging-darkroom;96;96;96;96;96;96;96;96;96;95 11:25:44 INFO - PROCESS | 1674 | |7;imaging-desaturate;133;135;86;83;86;94;86;85;86;86 11:25:44 INFO - PROCESS | 1674 | |8;json-parse-financial;55;54;54;56;56;54;54;57;55;54 11:25:44 INFO - PROCESS | 1674 | |9;json-stringify-tinderbox;66;67;72;79;73;72;69;72;69;70 11:25:44 INFO - PROCESS | 1674 | |10;stanford-crypto-aes;86;89;85;85;86;85;100;100;149;108 11:25:44 INFO - PROCESS | 1674 | |11;stanford-crypto-ccm;132;145;128;165;143;146;147;130;156;140 11:25:44 INFO - PROCESS | 1674 | |12;stanford-crypto-pbkdf2;169;225;196;171;167;165;165;165;161;209 11:25:44 INFO - PROCESS | 1674 | |13;stanford-crypto-sha256-iterative;66;66;66;64;66;65;66;66;65;65 11:25:44 INFO - PROCESS | 1674 | __end_tp_report 11:25:44 INFO - PROCESS | 1674 | __start_cc_report 11:25:44 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,1922 11:25:44 INFO - PROCESS | 1674 | __end_cc_report 11:25:44 INFO - PROCESS | 1674 | __startTimestamp1464805544129__endTimestamp 11:25:44 INFO - PROCESS | 1674 | 11:25:44 INFO - PROCESS | 1674 | ------- Summary: start ------- 11:25:44 INFO - PROCESS | 1674 | Number of tests: 14 11:25:44 INFO - PROCESS | 1674 | 11:25:44 INFO - PROCESS | 1674 | [#0] ai-astar Cycles:10 Average:112.10 Median:113.00 stddev:2.33 (2.1%) stddev-sans-first:2.45 11:25:44 INFO - PROCESS | 1674 | Values: 113.0 106.0 112.0 111.0 114.0 113.0 113.0 113.0 112.0 114.0 11:25:44 INFO - PROCESS | 1674 | 11:25:44 INFO - PROCESS | 1674 | [#1] audio-beat-detection Cycles:10 Average:134.00 Median:137.50 stddev:20.76 (15.1%) stddev-sans-first:21.09 11:25:44 INFO - PROCESS | 1674 | Values: 151.0 121.0 119.0 176.0 132.0 111.0 143.0 115.0 151.0 121.0 11:25:44 INFO - PROCESS | 1674 | 11:25:44 INFO - PROCESS | 1674 | [#2] audio-dft Cycles:10 Average:183.10 Median:183.50 stddev:2.02 (1.1%) stddev-sans-first:2.12 11:25:44 INFO - PROCESS | 1674 | Values: 184.0 183.0 187.0 182.0 184.0 180.0 182.0 181.0 183.0 185.0 11:25:44 INFO - PROCESS | 1674 | 11:25:44 INFO - PROCESS | 1674 | [#3] audio-fft Cycles:10 Average:72.90 Median:68.50 stddev:16.97 (24.8%) stddev-sans-first:17.86 11:25:44 INFO - PROCESS | 1674 | Values: 79.0 73.0 60.0 107.0 58.0 56.0 61.0 64.0 95.0 76.0 11:25:44 INFO - PROCESS | 1674 | 11:25:44 INFO - PROCESS | 1674 | [#4] audio-oscillator Cycles:10 Average:81.10 Median:87.50 stddev:12.29 (14.0%) stddev-sans-first:12.95 11:25:44 INFO - PROCESS | 1674 | Values: 85.0 90.0 92.0 72.0 71.0 67.0 95.0 71.0 69.0 99.0 11:25:44 INFO - PROCESS | 1674 | 11:25:44 INFO - PROCESS | 1674 | [#5] imaging-gaussian-blur Cycles:10 Average:95.80 Median:88.50 stddev:16.65 (18.8%) stddev-sans-first:17.53 11:25:44 INFO - PROCESS | 1674 | Values: 90.0 86.0 89.0 88.0 90.0 87.0 134.0 119.0 90.0 85.0 11:25:44 INFO - PROCESS | 1674 | 11:25:44 INFO - PROCESS | 1674 | [#6] imaging-darkroom Cycles:10 Average:95.90 Median:96.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 11:25:44 INFO - PROCESS | 1674 | Values: 96.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 96.0 95.0 11:25:44 INFO - PROCESS | 1674 | 11:25:44 INFO - PROCESS | 1674 | [#7] imaging-desaturate Cycles:10 Average:96.00 Median:86.00 stddev:20.23 (23.5%) stddev-sans-first:16.44 11:25:44 INFO - PROCESS | 1674 | Values: 133.0 135.0 86.0 83.0 86.0 94.0 86.0 85.0 86.0 86.0 11:25:44 INFO - PROCESS | 1674 | 11:25:44 INFO - PROCESS | 1674 | [#8] json-parse-financial Cycles:10 Average:54.90 Median:55.00 stddev:1.10 (2.0%) stddev-sans-first:1.17 11:25:44 INFO - PROCESS | 1674 | Values: 55.0 54.0 54.0 56.0 56.0 54.0 54.0 57.0 55.0 54.0 11:25:44 INFO - PROCESS | 1674 | 11:25:44 INFO - PROCESS | 1674 | [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:72.00 stddev:3.67 (5.1%) stddev-sans-first:3.43 11:25:44 INFO - PROCESS | 1674 | Values: 66.0 67.0 72.0 79.0 73.0 72.0 69.0 72.0 69.0 70.0 11:25:44 INFO - PROCESS | 1674 | 11:25:44 INFO - PROCESS | 1674 | [#10] stanford-crypto-aes Cycles:10 Average:97.30 Median:85.00 stddev:19.94 (23.5%) stddev-sans-first:20.73 11:25:44 INFO - PROCESS | 1674 | Values: 86.0 89.0 85.0 85.0 86.0 85.0 100.0 100.0 149.0 108.0 11:25:44 INFO - PROCESS | 1674 | 11:25:44 INFO - PROCESS | 1674 | [#11] stanford-crypto-ccm Cycles:10 Average:143.20 Median:145.50 stddev:11.57 (8.0%) stddev-sans-first:11.54 11:25:44 INFO - PROCESS | 1674 | Values: 132.0 145.0 128.0 165.0 143.0 146.0 147.0 130.0 156.0 140.0 11:25:44 INFO - PROCESS | 1674 | 11:25:44 INFO - PROCESS | 1674 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.30 Median:170.00 stddev:22.42 (13.2%) stddev-sans-first:23.47 11:25:44 INFO - PROCESS | 1674 | Values: 169.0 225.0 196.0 171.0 167.0 165.0 165.0 165.0 161.0 209.0 11:25:44 INFO - PROCESS | 1674 | 11:25:44 INFO - PROCESS | 1674 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.50 Median:66.00 stddev:0.71 (1.1%) stddev-sans-first:0.73 11:25:44 INFO - PROCESS | 1674 | Values: 66.0 66.0 66.0 64.0 66.0 65.0 66.0 66.0 65.0 65.0 11:25:44 INFO - PROCESS | 1674 | -------- Summary: end -------- 11:25:44 INFO - PROCESS | 1674 | 11:25:44 INFO - TEST-INFO | 1674: exit 0 11:25:44 INFO - TEST-OK | kraken | took 59492ms 11:25:44 INFO - SUITE-END | took 1174s 11:25:45 INFO - Completed test suite (00:19:34) 11:25:45 INFO - javascript benchmark 11:25:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3423.1271025888404, 3443.8966830995064, 3467.7572569920108, 3495.493153492745, 3511.315293517765, 5604.3835178130375, 5649.293838270216, 5664.801731939383, 5723.255976723025, 5756.856078598037, 5673.6879471629045, 5731.054376027961, 5741.885162296762, 5744.511716504092, 5745.5690823188215, 5110.335656364668, 5137.203733421316, 5158.329417175762, 5187.818426355078, 5225.399079105913, 5096.413912400066, 5157.6131790115705, 5168.0, 5173.120569503175, 5272.103742363807, 5166.664166829163, 5245.396779370364, 5256.842294731164, 5260.473952604747, 5261.263423120766, 267.371943545704, 5176.767045482962, 5181.792728290901, 5249.895002099936, 5281.603879709037, 5123.359580052494, 5127.9743601282125, 5139.0749665060275, 5155.66488178271, 5187.610929180327, 5530.391656917735, 5616.691081990489, 5649.745761440745, 5664.461876121762, 5671.092625179993, 6099.176611157525, 6129.080637904305, 6129.23653806838, 6256.0615907613765, 6263.217097862767, 6218.28489723683, 6236.282827474808, 6285.9057114143325, 6296.496280297565, 6302.905456418157, 5824.388439213906, 5857.560682948796, 5863.882722345572, 5867.061270196791, 5869.530437564985, 6192.938070619326, 6205.441510264008, 6286.0, 6288.119663247149, 6317.1156038154695, 6097.420745029215, 6126.693665316717, 6142.723577438936, 6145.96927015371, 6146.5390095743, 6194.163787888668, 6244.250689917239, 6264.0, 6264.436200741864, 6273.435390814849, 6077.361877002851, 6101.277634935909, 6120.051392034242, 6124.473467760062, 6181.598196117238, 6198.411150940653, 6261.812145635638, 6265.937340626627, 6279.058141278753, 6284.371562843679, 4970.428400733889, 4974.37820272466, 4978.925316120261, 4986.925196122061, 4994.800207991712, 4338.674599405057, 4374.825006999685, 4428.601425871687, 4442.333649952533, 4454.888627784331, 4685.742284174403, 5608.691521966331, 5638.689872057076, 5654.095344744821, 5666.546676265889, 6169.5064394848305, 6192.102145188923, 6224.626522408579, 6233.189685340877, 6244.344343843922, 5658.264425624642, 5675.5459563234845, 5691.544676425876, 5697.772089116389, 5736.340320863071, 5353.357597088374, 5353.946460535422, 5362.249285100089, 5370.167624018283, 5372.446598278087, 6210.596311239755, 6245.96877015612, 6285.120083188266, 6292.1505596743855, 6355.205599300088, 6281.905771413524, 6283.214598175228, 6290.2766181889665, 6292.4966002720585, 6292.968535157295, 6309.148264984352, 6355.3644635536075, 6357.396047375492, 6366.808995730135, 6370.713317900798, 5041.319421878, 6039.335673075871, 6099.763790468328, 6175.938240617536, 6241.843953901189, 6256.311805701278, 6282.371762823681, 6288.151099601679, 6300.243970723543, 6313.2424109107205, 5931.525477961666, 5984.311804142405, 6143.0, 6152.077188421773, 6155.446009859134, 6040.456358927718, 6102.359252278447, 6110.602810817282, 6135.969320153371, 6151.292601350723, 5945.672987985702, 5988.311344195473, 5990.281166260077, 5993.820185394271, 6015.338312785502, 5923.466887980103, 6029.366916473882, 6141.3551577085545, 6146.385361463997, 6147.66187859672, 5867.0, 5930.377310382521, 5940.405959404025, 5957.493613042767, 6002.339742628395], "value": 5656.871948020359, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [483.54546726077484, 505.4238168487922, 521.7991073436729, 523.2700382965766, 526.944670809565, 30200.546991795112, 30955.535666964985, 32054.358912821728, 32085.51871721926, 32142.714291428343, 44780.0, 45244.77377613107, 45321.77339113308, 47631.52368476314, 47698.28452573214, 9555.18780903624, 9720.951395243013, 9746.707598772047, 9748.561314740853, 9780.168685661727, 8599.871001934976, 8851.513166775825, 9592.328537002413, 9723.367981081243, 9735.075167859042, 8994.640214391417, 9070.501122438263, 9107.0, 11361.431928403588, 11654.766904661901, 11743.41282935854, 11863.584774532894, 11864.940675296568, 11879.643610691694, 11887.0, 11681.88318116825, 11821.0, 11831.645050648494, 11832.171747977645, 11836.230645008132, 31488.370232595218, 41134.79432602848, 41581.0, 41597.0, 43884.12231755347, 3542.6280240574624, 3554.680078792921, 3555.9111022224392, 3582.659647333499, 3725.5715592706642, 26487.0, 30314.84842575795, 31849.203769905707, 31865.522017169762, 32175.83912080448, 2621.8332841885367, 3125.5155450905145, 3250.6749325067303, 3271.4765637497885, 3376.3922493951077, 513097.869021305, 515442.4227878874, 516655.0, 516705.4988350393, 517901.0, 67114.32885671179, 67464.0, 67498.98751518731, 67515.3248467509, 67667.0, 69689.65155174192, 69959.65020174968, 69982.0, 70007.29992700007, 70185.64907175432, 15932.68134637324, 16271.0, 16402.507924762278, 16411.0, 16411.343546258257, 12658.303793291458, 12695.0, 12695.428705708266, 12706.174098683556, 12710.809337859939, 12444.81332780009, 15584.298706558006, 16432.424865129662, 16864.964306424718, 16867.060288066783, 5366.490183432567, 5429.886873190986, 5783.421657834183, 6410.1987251593555, 6471.546991710629, 866.7009881590841, 962.5177227070374, 1051.9737006574744, 1053.947302634865, 1071.844582535528, 30241.395172096876, 30558.69441305603, 30804.075877723702, 31444.842775786434, 31467.685323146932, 1099.4228030284164, 1110.6001839337832, 1118.7091356247272, 1123.9325640461598, 1171.701216189866, 13693.383797729126, 15893.046417215004, 15909.96585221957, 16297.266623001993, 16303.0, 650561.0, 938505.0, 954406.0, 955248.0, 958858.0, 54432.72783636136, 56081.0, 64319.0, 64582.67708661521, 65606.0159097614, 1415.2852809331223, 1449.7028109237792, 1457.460739526382, 1459.1536908593202, 1462.4150339864138, 30946.0716178506, 31718.365632686757, 31983.629875476883, 32304.35391292114, 32470.0, 53573.73213133909, 54099.72950135224, 54135.45864541304, 54296.45703543072, 54362.0, 395798.0, 618483.9075804593, 625387.0, 628246.0, 827043.0, 26322.341941451617, 26619.60070598904, 26627.201183964513, 26730.465390691686, 26758.0, 46471.74697771259, 47055.0, 47096.0, 48012.03975920532, 48026.759866200446, 31767.841160794047, 39448.802755986035, 39498.012549686486, 39759.80120099381, 40169.79915100406, 37342.2531549362, 37458.812705936296, 38253.61746382612, 38347.84956451311, 39154.82535523939, 37489.31298091647, 38180.80909595434, 38519.84440466791, 38651.61348386593, 38708.41937370998, 32238.871639492507, 32311.35377292488, 33856.65119856988, 34401.65598343984, 34447.483287750205, 25083.0, 25126.0, 25188.874055629603, 25467.490650187265, 25586.61620075663], "value": 20487.306547375356, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.4878292348022, 377.486618199249, 383.24691980258785, 395.045963996947, 405.8153540139237, 19176.232950681973, 20277.0, 21091.894540527293, 21093.894530527326, 21181.0, 11916.404179790998, 12089.153759236857, 12225.572104976327, 12239.388030598479, 12258.264504129758, 4112.547619761824, 5496.725163741817, 5535.0, 5538.529225015878, 5541.27963364762, 3908.609139086097, 4151.356539736345, 4652.860414187563, 6008.849778755544, 6154.692265386735, 6114.785982490614, 6148.8462788430425, 6153.323134455206, 6167.969160154193, 6174.475169610566, 6646.833829154286, 6671.132752742161, 6683.164604424447, 6683.632400217986, 6691.93308066918, 3781.7920014399197, 3982.203559288125, 4022.5776293489057, 4300.290452075404, 5907.4683278505145, 10591.523381447776, 13032.957363410998, 13033.934830325883, 13190.0, 13206.471741130343, 1799.4061959553317, 2257.3566533537864, 2292.7592602776635, 2488.726240113586, 2737.2235597805893, 9873.160781333596, 9993.200543956538, 10367.274290799647, 13489.460421583124, 14412.855871441256, 1876.5402476393372, 1879.736836842843, 1893.640208360407, 1962.8331591814713, 1996.1217064491577, 117451.0, 123852.0, 123863.76136238613, 124533.37733311456, 126352.36823815914, 11428.371439570903, 11438.254818843418, 11456.026237769716, 11458.312501249786, 11607.129465290138, 14402.495912643008, 14458.783118253024, 14465.421383144556, 14470.059446135967, 14480.85519144795, 8259.752207433787, 8445.831083378298, 8472.237498625153, 8570.48577085377, 8585.31317494599, 8311.792205194797, 8313.6674533018, 8345.332373410112, 8445.451045682012, 8470.0, 3764.962350376516, 4400.625946794495, 4491.865244042684, 4506.301523263899, 4557.863264102082, 1458.5624312706145, 1693.542743459259, 1719.406804652393, 1860.2930886263405, 1910.2550005497867, 927.5130556457914, 970.9563069661741, 1039.0, 1043.9425831579183, 1070.9946450267698, 20471.38585842427, 21128.78871211299, 21195.364139075853, 21197.89401052985, 21341.0, 939.1594522901964, 956.8469044952774, 961.5432669481941, 1044.7074819050679, 1082.577794660083, 10594.682159535227, 11150.609728659458, 11183.273087249303, 11194.328340299608, 11208.15938804593, 575621.0, 580928.0, 594737.0, 597178.0, 597296.0, 125774.74225257813, 126428.36785816013, 129595.0, 129790.0, 137808.0, 1012.8480727890728, 1026.235454586338, 1031.0926384781194, 1048.4705223861902, 1050.3750268590177, 13859.722805544034, 17965.0, 18111.184996675445, 18223.0, 18921.0, 48942.75528622334, 49517.00965980588, 50738.49261507337, 51151.209707660055, 52250.73874630602, 51046.74476627593, 70417.0, 72612.0, 73040.17399565053, 73514.63242683752, 5697.316322041382, 6683.565568238049, 7129.536580122275, 7822.256885595859, 8081.676732930617, 10168.796624067625, 10469.267151299337, 12408.875911240772, 12414.503419863466, 12436.37818109109, 10509.421981790769, 10722.892771072502, 10795.838062429228, 10802.973717496825, 10816.188785840935, 10347.896521034694, 10509.632162874263, 10677.466126693478, 10758.73103172427, 10765.677029689123, 10448.634297799541, 10690.0, 10940.713029259345, 11017.779644407226, 11167.609133680608, 7039.507234493536, 7677.848322751632, 7935.5635440049045, 7954.880676789737, 7966.322862556516, 8935.419197752126, 10823.891761082288, 10903.182261330274, 11008.834867476835, 11025.393603351893], "value": 9070.715345777424, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1395.2884029145146, 1574.1027614259872, 1597.448880136353, 1823.9088045597716, 1975.3678822776708, 7905.4861434006825, 8601.698940537082, 8840.38117331787, 8854.026057133726, 8965.237954773836, 8576.65693372266, 8795.692150774725, 8799.736007919757, 8802.779930501742, 8854.158854908774, 2093.769685334612, 2181.2692747929464, 2345.870977096259, 2382.5711371953084, 2415.3961509622595, 2410.144398738449, 2451.509698060386, 2457.717362503317, 2458.164224163793, 2464.6549483072386, 2375.9168429104984, 2412.686350774397, 2413.3242692046256, 2419.8427102238297, 2420.419099416145, 1762.8365278915983, 1955.7261983322346, 2046.8771873687626, 2134.5090629155225, 2146.61360955028, 2163.5456554123652, 2332.685087513181, 2405.5549723301247, 2413.276017194852, 2420.5279970405777, 8781.473111613259, 8889.244414224733, 8901.020887702347, 8901.332400070021, 8907.777305567413, 5158.9484105158845, 5329.22726204702, 5342.652727572696, 5345.278387417688, 5364.463553644643, 3225.774205801804, 3300.96699033009, 3625.1843335249623, 3654.9817250913575, 3702.5927148013966, 1042.3433237060678, 1054.5939813171983, 1107.1198397274186, 1110.8833572474855, 1115.9621551956677, 11936.820947685705, 11937.224080434744, 11961.162718609701, 11965.521379144824, 11970.521179152824, 4613.100445413179, 4762.714237145782, 4823.855284341475, 4830.637702172351, 4833.516648335139, 8168.509889406655, 9996.250281228791, 10037.749056273651, 10052.748681283027, 10075.546600402999, 5292.259083728281, 5292.761825717852, 5300.787968481218, 5301.0723123453245, 5303.178007408857, 4311.6335111515245, 4471.5081341052455, 4689.507601701772, 4799.6400269979895, 4842.539958703917, 3342.8495717692763, 3943.684505239574, 3986.1828325193264, 4050.1292222172374, 4095.795210239476, 1150.8031647087103, 1257.654145110102, 1286.761949039412, 1295.203449878318, 1309.7445998030294, 741.4290995933102, 919.7562645898843, 923.6628630549801, 931.6646007437317, 970.4565443351604, 4591.0, 5167.405748338914, 5798.797042103506, 6015.639061656315, 6149.169862068564, 923.3905622289257, 940.4498368454394, 975.7560609847538, 978.9314747967574, 1020.5356562763925, 3843.7309388342546, 3984.3425834737523, 4002.799860006988, 4055.1281474483126, 4108.630223279919, 10634.840477392845, 10992.17558683102, 11052.392118433563, 11058.391788451712, 11064.28082174656, 10808.51361688726, 10923.289986150874, 10957.397343146044, 10978.286411383235, 11022.448877556251, 6865.691043902936, 8249.340052795882, 8252.298554622805, 8333.374996875258, 8337.583120843934, 4632.698874573142, 5822.2140011099655, 5961.940380596312, 6084.452399284086, 6216.125761155665, 9850.507474626096, 10271.229657775846, 10275.332103413255, 10283.94858025705, 10284.434356110485, 9710.271729620448, 9861.556229969525, 10291.485425728833, 10302.639407620698, 10389.948050260004, 6237.625742455467, 6528.281888992302, 6970.477214208853, 7224.422046236184, 7497.062867141607, 8470.449420787629, 8585.18440748128, 8642.56787160652, 8659.13408659129, 8673.045964943978, 7487.064116985377, 7566.886496702664, 7643.159252482334, 7692.653830577526, 7716.92283077162, 7310.122785265802, 7380.630968451448, 7406.814829629302, 7544.132424771002, 7564.773056808304, 7068.787936361918, 7132.251113632994, 7163.8567228656175, 7166.104236970379, 7170.928290717026, 4863.781129849236, 5022.020705962448, 5072.442031376472, 5072.771725272298, 5076.18780995039, 5032.169692000779, 5060.620453465931, 5101.79592816298, 5139.0, 5208.8437346879655], "value": 4585.221831858277, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.3114829337404, 310.83525731362374, 312.6935603108954, 317.19590837227616, 320.3880588076774, 4486.371907932892, 4901.338319326895, 5066.518680725329, 5441.8709242185805, 5478.863028424292, 4473.485549161847, 4753.572178503934, 5153.4846515348445, 5163.767630456629, 5462.590305727066, 3403.4894765785084, 3857.996920800598, 3872.419137129439, 4022.3363145081025, 4081.449004384415, 3368.3263347330508, 3984.8406063757416, 4036.2129384769983, 4045.757254564721, 4049.3521036634143, 3596.4754540550275, 3743.28877513273, 3825.5791862895057, 3921.941170882439, 4022.3162062449455, 3090.9336278983715, 3940.9556467536127, 3965.504311961019, 4027.1140349123148, 4055.776932268724, 2652.310399296197, 3049.7560195184333, 3320.9169770755684, 3494.178867966026, 3519.63043880394, 5145.279660847447, 5161.767720452589, 5190.351206099238, 5211.661242019257, 5238.476152384769, 4463.352813841975, 4655.3947986761505, 4669.883252918704, 4670.276107203389, 4681.695689780154, 5561.332640083176, 5589.245451863987, 5593.804216852432, 5596.58025648078, 5656.151577263402, 3027.8334691591954, 3575.982120089409, 3774.5659249186147, 3889.6888248940018, 3903.512060992376, 4817.662763606584, 5102.0, 5179.766910489037, 5215.765290561933, 5355.6251062425645, 3198.145075855998, 3375.746818988586, 3904.629060239273, 3956.4263181839046, 3966.6628336591134, 3770.830312635938, 3814.8664796732537, 4051.858184963512, 4070.921205880444, 4094.078832262777, 4048.8582899598373, 4412.977935110304, 4602.38712610599, 4650.906981860344, 4730.739809310452, 4158.9584104158575, 5669.618760120759, 5671.2419150063615, 5682.261306030189, 5697.4302569742695, 1184.644606618011, 1209.2986068080493, 1278.7889998150201, 1284.4027527199896, 1346.9259190744604, 721.8303698630817, 789.3882241262952, 815.135955886762, 821.6836517940636, 822.2271065198694, 4253.723507971972, 4335.718178318462, 4621.491635920046, 4837.2260438329695, 5221.6083793715625, 5375.924815037009, 5469.362873688952, 5599.384067752544, 5754.74103665336, 5864.912026319608, 5079.568236699922, 5258.2638430619745, 5284.894302113936, 5338.332708411449, 5344.0, 2501.624756286535, 2928.4875146849217, 3085.7377122944354, 3091.2890035292007, 3099.5195744659613, 5657.632253903483, 5887.587868849139, 6054.334023257438, 6100.481459076028, 6127.325994140641, 5573.303337082864, 5611.390703322974, 6001.5798894077, 6004.609700369462, 6024.337322894478, 5756.194132821408, 5964.866675331673, 6052.364501727343, 6070.666113363807, 6085.786997455068, 5627.651919307245, 5725.341585717696, 5793.536517078539, 5818.9127163093435, 5846.0, 5080.695158290514, 5662.518685911744, 5763.567732420001, 5763.625364351304, 5770.971145144247, 5089.389273287081, 5121.462246464217, 5390.811321603725, 5980.970095149496, 6083.939160608337, 2848.5442329227226, 3123.4533956557966, 3214.967850321467, 3325.019119359746, 3366.612839523388, 4213.852515161954, 4368.453943257093, 4555.817767289272, 4568.588827005586, 4617.769111544342, 3927.626875446828, 4200.474940632421, 4608.101420223024, 4627.051454451762, 4786.760661966958, 3932.050961778666, 4168.6039826216465, 4251.5748425157235, 4532.864014079583, 4550.704204226715, 3954.466147070224, 4345.587169218919, 4496.865094047183, 4677.976610116928, 4722.905541889212, 4547.977260113678, 4771.618270538418, 5325.056354430528, 5362.356517217959, 5448.891022179613, 4490.663200259928, 4541.977290113528, 4718.245080787057, 5461.466982404515, 5515.420880807457], "value": 3918.0935406036347, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [715.4169351978138, 809.4333966223645, 814.7515007922583, 838.5010918503489, 853.7780177153946, 15048.0, 15210.16344101074, 15499.767503487457, 16567.91716041421, 17159.05625190614, 16150.596235094099, 16159.949603275809, 16237.756433653505, 16298.022118672858, 16305.266263018162, 705.8411857332085, 885.070675790419, 890.5591732092623, 894.065701342099, 896.596531560797, 722.8626560953428, 875.8467268228067, 906.7007887397143, 907.7503686486236, 909.9681511147111, 922.898481167071, 929.4794914847673, 930.1070971866982, 931.6692574136185, 933.0389698610409, 735.9043324367851, 910.3627460777482, 927.2767241551601, 928.2295694573488, 930.5858892792744, 690.7616872179141, 700.6951975890504, 700.8423104801429, 701.018573996404, 701.2041333086933, 15204.315805788767, 15305.464308749251, 15316.770248446282, 15319.080855148726, 15332.770008449883, 13294.800577991338, 13418.53035143775, 13432.395542200527, 13639.181649101085, 13720.862791372158, 12082.939585302105, 12443.564475243413, 12516.99864010877, 12541.31022793746, 13036.217826930415, 4507.391502147234, 4513.887152821173, 4608.838690645811, 4624.260118381077, 4665.533446655341, 86939.69590456148, 87668.68496972551, 87789.0, 87856.0, 87906.5604671979, 10390.688279351632, 10462.63380781669, 10621.522031508446, 10684.839727404094, 10725.624603138853, 20322.390328290174, 20711.8964405177, 21032.263870764757, 21061.26285580028, 21544.89227553852, 8008.27925486709, 8104.149064348277, 8149.307308878811, 8322.50064996102, 8322.583870806435, 4615.123126606001, 4628.652851036186, 4652.139354219413, 4694.6009589185305, 4813.542713442217, 3364.4280472319765, 3485.5991560970306, 3488.686018258369, 3496.3706532823826, 3500.2474467989505, 657.1982181738272, 662.3740565165872, 673.441043933534, 686.3582550315471, 687.388224480213, 583.3029529712038, 597.3130899465563, 655.4776012098055, 692.6389644348878, 708.7661071846278, 9131.954340228256, 9457.527123643791, 9471.715848524555, 9563.808723825623, 9570.808583828424, 654.1202083198091, 655.947524198063, 694.2571448550104, 694.9861002779916, 698.6332175607846, 3439.466882633146, 3537.46937959303, 4448.755318457516, 4485.955140448619, 4556.72659640416, 92908.0, 96093.0, 96453.0, 97071.02928970761, 97469.0, 67397.0, 80141.0, 80256.0, 84160.0, 84693.0, 68367.31632683708, 69401.0, 69573.65213174003, 69771.3022869775, 70836.29163708503, 9486.383385079947, 9513.096255855682, 9559.713208603753, 9716.562754675915, 9774.462404567532, 42571.57428425676, 43518.0, 44130.0, 45112.77443612761, 47842.0, 29729.70270297356, 46085.07829843451, 64360.35639643731, 64473.0, 64735.67632161809, 12734.299613521345, 12846.550370736979, 12934.353282336035, 12962.287074210632, 13373.732525349633, 14208.147511149364, 14674.046186997812, 14701.779473308123, 14766.040207386486, 14964.700705986037, 13346.666333341744, 13511.324433778469, 13522.79715804244, 13529.255890926093, 13586.184828910296, 12994.285314307805, 13351.79972300397, 13386.129901556367, 13398.330083495981, 13828.93085534566, 13050.15174013686, 13086.345682716017, 13102.344882755633, 13141.868581314064, 13746.793798092836, 10712.464376780974, 10933.180011499011, 10979.28634638746, 11055.336679799238, 11372.715682108013, 10048.246381521561, 10405.0, 10488.790224195625, 11063.170262230204, 11347.262427942158], "value": 7408.595653522731, "unit": "score"}], "name": "dromaeo_css", "value": 7205.326990896491, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 106.0, 112.0, 111.0, 114.0, 113.0, 113.0, 113.0, 112.0, 114.0], "value": 112.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 121.0, 119.0, 176.0, 132.0, 111.0, 143.0, 115.0, 151.0, 121.0], "value": 134.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 187.0, 182.0, 184.0, 180.0, 182.0, 181.0, 183.0, 185.0], "value": 183.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 73.0, 60.0, 107.0, 58.0, 56.0, 61.0, 64.0, 95.0, 76.0], "value": 72.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 90.0, 92.0, 72.0, 71.0, 67.0, 95.0, 71.0, 69.0, 99.0], "value": 81.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 86.0, 89.0, 88.0, 90.0, 87.0, 134.0, 119.0, 90.0, 85.0], "value": 95.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 96.0, 95.0], "value": 95.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [133.0, 135.0, 86.0, 83.0, 86.0, 94.0, 86.0, 85.0, 86.0, 86.0], "value": 96.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 54.0, 56.0, 56.0, 54.0, 54.0, 57.0, 55.0, 54.0], "value": 54.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 72.0, 79.0, 73.0, 72.0, 69.0, 72.0, 69.0, 70.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 89.0, 85.0, 85.0, 86.0, 85.0, 100.0, 100.0, 149.0, 108.0], "value": 97.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 145.0, 128.0, 165.0, 143.0, 146.0, 147.0, 130.0, 156.0, 140.0], "value": 143.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 225.0, 196.0, 171.0, 167.0, 165.0, 165.0, 165.0, 161.0, 209.0], "value": 179.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 64.0, 66.0, 65.0, 66.0, 66.0, 65.0, 65.0], "value": 65.5, "unit": "score"}], "name": "kraken", "value": 1482.0, "alertThreshold": 2.0}]} 11:25:45 INFO - Return code: 0 11:25:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 11:25:45 ERROR - # TBPL SUCCESS # 11:25:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:25:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:25:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:25:45 INFO - 2016-06-01 11:25:45.430 screenresolution[1678:9925] starting screenresolution argv=screenresolution get 11:25:45 INFO - 2016-06-01 11:25:45.432 screenresolution[1678:9925] Display 0: 1600x1200x32@60 11:25:45 INFO - 2016-06-01 11:25:45.438 screenresolution[1679:9928] starting screenresolution argv=screenresolution list 11:25:45 INFO - Available Modes on Display 0 11:25:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:25:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:25:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:25:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:25:45 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:25:45 INFO - Intel Iris: 11:25:45 INFO - Chipset Model: Intel Iris 11:25:45 INFO - Type: GPU 11:25:45 INFO - Bus: Built-In 11:25:45 INFO - VRAM (Dynamic, Max): 1536 MB 11:25:45 INFO - Vendor: Intel (0x8086) 11:25:45 INFO - Device ID: 0x0a2e 11:25:45 INFO - Revision ID: 0x0009 11:25:45 INFO - Displays: 11:25:45 INFO - Display: 11:25:45 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:25:45 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:25:45 INFO - Display Serial Number: 1600x1200 60 11:25:45 INFO - Main Display: Yes 11:25:45 INFO - Mirror: Off 11:25:45 INFO - Online: Yes 11:25:45 INFO - Rotation: Supported 11:25:45 INFO - Return code: 0 11:25:45 INFO - Running post-action listener: _resource_record_post_action 11:25:45 INFO - ##### 11:25:45 INFO - ##### Finished run-tests step (success) 11:25:45 INFO - ##### 11:25:45 INFO - Running post-run listener: _resource_record_post_run 11:25:45 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 32378368; Write bytes: 697821184; Read time: 326; Write time: 2092 11:25:45 INFO - TinderboxPrint: CPU usage
53.5% 11:25:45 INFO - TinderboxPrint: I/O read bytes / time
32,378,368 / 326 11:25:45 INFO - TinderboxPrint: I/O write bytes / time
697,821,184 / 2,092 11:25:45 INFO - TinderboxPrint: CPU idle
2,248.9 (46.4%) 11:25:45 INFO - TinderboxPrint: CPU system
963.3 (19.9%) 11:25:45 INFO - TinderboxPrint: CPU user
1,629.3 (33.7%) 11:25:45 INFO - TinderboxPrint: Swap in / out
427,741,184 / 0 11:25:45 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 395510272; Write bytes: 389117440; Read time: 29495; Write time: 711 11:25:45 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 30588416; Write bytes: 295297536; Read time: 304; Write time: 1347 11:25:46 INFO - Running post-run listener: _upload_blobber_files 11:25:46 INFO - Blob upload gear active. 11:25:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:25:46 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 11:25:46 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:25:46 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:25:46 INFO - (blobuploader) - INFO - Open directory for files ... 11:25:46 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:25:46 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:25:46 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:25:47 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:25:47 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:25:47 INFO - (blobuploader) - INFO - Done attempting. 11:25:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:25:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:25:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:25:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:25:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:25:48 INFO - (blobuploader) - INFO - Done attempting. 11:25:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:25:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:25:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:25:50 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:25:50 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:25:50 INFO - (blobuploader) - INFO - Done attempting. 11:25:50 INFO - (blobuploader) - INFO - Iteration through files over. 11:25:50 INFO - Return code: 0 11:25:50 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:25:50 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:25:50 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c01643c9b0a713dc6884592320a67f5cae61b1ce71c164fda0c557564339c34100c0b561377600fc7426eaa81f92020db4e05ce8fb27f4107e9580b84edcdee5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/76163e84f21e6d071f50d06f1fe8268182baafa550fcc09f44de53d96906e72fc57b2b97fb17c69857a1aa8553cdc2c68612bf1741d8d9255d11cf9778b63f6b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a56a107e179c98f737702938ff4d29c873969383695ff7c4afac102f2a74ca5630c6570b86b24e51679ea01aeec703fa0011fa67de3d42c5f6d91fe62ef24ca8"} 11:25:50 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:25:50 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:25:50 INFO - Contents: 11:25:50 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c01643c9b0a713dc6884592320a67f5cae61b1ce71c164fda0c557564339c34100c0b561377600fc7426eaa81f92020db4e05ce8fb27f4107e9580b84edcdee5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/76163e84f21e6d071f50d06f1fe8268182baafa550fcc09f44de53d96906e72fc57b2b97fb17c69857a1aa8553cdc2c68612bf1741d8d9255d11cf9778b63f6b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a56a107e179c98f737702938ff4d29c873969383695ff7c4afac102f2a74ca5630c6570b86b24e51679ea01aeec703fa0011fa67de3d42c5f6d91fe62ef24ca8"} 11:25:50 INFO - Running post-run listener: copy_logs_to_upload_dir 11:25:50 INFO - Copying logs to upload dir... 11:25:50 INFO - mkdir: /builds/slave/test/build/upload/logs 11:25:50 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1295.562225 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 35 secs) (at 2016-06-01 11:25:50.672914) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-01 11:25:50.679991) ========= 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.bKZEbFwB4P/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.winfgECt4I/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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/Fx-Team/sha512/c01643c9b0a713dc6884592320a67f5cae61b1ce71c164fda0c557564339c34100c0b561377600fc7426eaa81f92020db4e05ce8fb27f4107e9580b84edcdee5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/76163e84f21e6d071f50d06f1fe8268182baafa550fcc09f44de53d96906e72fc57b2b97fb17c69857a1aa8553cdc2c68612bf1741d8d9255d11cf9778b63f6b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a56a107e179c98f737702938ff4d29c873969383695ff7c4afac102f2a74ca5630c6570b86b24e51679ea01aeec703fa0011fa67de3d42c5f6d91fe62ef24ca8"} build_url:https://queue.taskcluster.net/v1/task/G0jJlLLlTFSl82_uKmQgbw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011184 build_url: 'https://queue.taskcluster.net/v1/task/G0jJlLLlTFSl82_uKmQgbw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c01643c9b0a713dc6884592320a67f5cae61b1ce71c164fda0c557564339c34100c0b561377600fc7426eaa81f92020db4e05ce8fb27f4107e9580b84edcdee5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/76163e84f21e6d071f50d06f1fe8268182baafa550fcc09f44de53d96906e72fc57b2b97fb17c69857a1aa8553cdc2c68612bf1741d8d9255d11cf9778b63f6b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a56a107e179c98f737702938ff4d29c873969383695ff7c4afac102f2a74ca5630c6570b86b24e51679ea01aeec703fa0011fa67de3d42c5f6d91fe62ef24ca8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-01 11:25:50.710349) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 11:25:50.710737) ========= 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.bKZEbFwB4P/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.winfgECt4I/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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.005210 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-01 11:25:50.759324) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-01 11:25:50.759665) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-01 11:25:50.760008) ========= ========= Total master_lag: 0.23 =========