builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0240 starttime: 1464220521.42 results: success (0) buildid: 20160525145246 builduid: 3d683f58b56d45c7bfe2c5cacb47cc42 revision: 94bc94b961768538296b30ef704f99fcfe9d5874 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-25 16:55:21.423694) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-25 16:55:21.424156) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-25 16:55:21.424490) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-25 16:55:21.445186) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 16:55:21.446038) ========= 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.VI4smXMnM3/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.5NcLNPCbX6/Listeners TMPDIR=/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-05-25 16:55:21-- 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% 8.21M=0.001s 2016-05-25 16:55:21 (8.21 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.105240 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 16:55:21.573176) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 16:55:21.573689) ========= 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.VI4smXMnM3/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.5NcLNPCbX6/Listeners TMPDIR=/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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.020682 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-25 16:55:21.625239) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-25 16:55:21.625917) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 94bc94b961768538296b30ef704f99fcfe9d5874 --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 94bc94b961768538296b30ef704f99fcfe9d5874 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.VI4smXMnM3/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.5NcLNPCbX6/Listeners TMPDIR=/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-05-25 16:55:21,714 truncating revision to first 12 chars 2016-05-25 16:55:21,715 Setting DEBUG logging. 2016-05-25 16:55:21,715 attempt 1/10 2016-05-25 16:55:21,715 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/94bc94b96176?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-25 16:55:22,844 unpacking tar archive at: fx-team-94bc94b96176/testing/mozharness/ program finished with exit code 0 elapsedTime=1.396507 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-25 16:55:23.043394) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-25 16:55:23.043787) ========= script_repo_revision: 94bc94b961768538296b30ef704f99fcfe9d5874 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-25 16:55:23.044272) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-25 16:55:23.044651) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-25 16:55:23.061123) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-05-25 16:55:23.061512) ========= /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.VI4smXMnM3/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.5NcLNPCbX6/Listeners TMPDIR=/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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 16:55:23 INFO - MultiFileLogger online at 20160525 16:55:23 in /builds/slave/test 16:55:23 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 16:55:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:55:23 INFO - {'append_to_log': False, 16:55:23 INFO - 'base_work_dir': '/builds/slave/test', 16:55:23 INFO - 'blob_upload_branch': 'Fx-Team', 16:55:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:55:23 INFO - 'branch': 'Fx-Team', 16:55:23 INFO - 'buildbot_json_path': 'buildprops.json', 16:55:23 INFO - 'config_files': ('talos/mac_config.py',), 16:55:23 INFO - 'default_actions': ('clobber', 16:55:23 INFO - 'read-buildbot-config', 16:55:23 INFO - 'download-and-extract', 16:55:23 INFO - 'populate-webroot', 16:55:23 INFO - 'create-virtualenv', 16:55:23 INFO - 'install', 16:55:23 INFO - 'run-tests'), 16:55:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:55:23 INFO - 'download_minidump_stackwalk': True, 16:55:23 INFO - 'download_symbols': 'ondemand', 16:55:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:55:23 INFO - 'tooltool.py': '/tools/tooltool.py', 16:55:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:55:23 INFO - '/tools/misc-python/virtualenv.py')}, 16:55:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:55:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:55:23 INFO - 'installer_path': 'installer.exe', 16:55:23 INFO - 'log_level': 'info', 16:55:23 INFO - 'log_name': 'talos', 16:55:23 INFO - 'log_to_console': True, 16:55:23 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:55:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:55:23 INFO - 'opt_config_files': (), 16:55:23 INFO - 'pip_index': False, 16:55:23 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:55:23 INFO - 'cmd': ('bash', 16:55:23 INFO - '-c', 16:55:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:55:23 INFO - 'enabled': True, 16:55:23 INFO - 'halt_on_failure': False, 16:55:23 INFO - 'name': 'check_screen_resolution'},), 16:55:23 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:55:23 INFO - 'cmd': ('bash', 16:55:23 INFO - '-c', 16:55:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:55:23 INFO - 'enabled': True, 16:55:23 INFO - 'halt_on_failure': False, 16:55:23 INFO - 'name': 'check_screen_resolution'},), 16:55:23 INFO - 'run_cmd_checks_enabled': True, 16:55:23 INFO - 'sps_profile': False, 16:55:23 INFO - 'sps_profile_interval': 0, 16:55:23 INFO - 'suite': 'dromaeojs', 16:55:23 INFO - 'system_bits': '32', 16:55:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:55:23 INFO - 'title': 't-yosemite-r7-0240', 16:55:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:55:23 INFO - 'use_talos_json': True, 16:55:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:55:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:55:23 INFO - 'work_dir': 'build'} 16:55:23 INFO - ##### 16:55:23 INFO - ##### Running clobber step. 16:55:23 INFO - ##### 16:55:23 INFO - Running pre-action listener: _resource_record_pre_action 16:55:23 INFO - Running main action method: clobber 16:55:23 INFO - rmtree: /builds/slave/test/build 16:55:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:55:25 INFO - Running post-action listener: _resource_record_post_action 16:55:25 INFO - ##### 16:55:25 INFO - ##### Finished clobber step (success) 16:55:25 INFO - ##### 16:55:25 INFO - ##### 16:55:25 INFO - ##### Running read-buildbot-config step. 16:55:25 INFO - ##### 16:55:25 INFO - Running pre-action listener: _resource_record_pre_action 16:55:25 INFO - Running main action method: read_buildbot_config 16:55:25 INFO - Using buildbot properties: 16:55:25 INFO - { 16:55:25 INFO - "project": "", 16:55:25 INFO - "product": "firefox", 16:55:25 INFO - "script_repo_revision": "production", 16:55:25 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 16:55:25 INFO - "repository": "", 16:55:25 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 16:55:25 INFO - "buildid": "20160525145246", 16:55:25 INFO - "pgo_build": "False", 16:55:25 INFO - "basedir": "/builds/slave/test", 16:55:25 INFO - "buildnumber": 22, 16:55:25 INFO - "slavename": "t-yosemite-r7-0240", 16:55:25 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 16:55:25 INFO - "platform": "yosemite_r7", 16:55:25 INFO - "scheduler": "tests-fx-team-macosx64-talos", 16:55:25 INFO - "branch": "fx-team", 16:55:25 INFO - "repo_path": "integration/fx-team", 16:55:25 INFO - "revision": "94bc94b961768538296b30ef704f99fcfe9d5874", 16:55:25 INFO - "stage_platform": "macosx64", 16:55:25 INFO - "builduid": "3d683f58b56d45c7bfe2c5cacb47cc42", 16:55:25 INFO - "slavebuilddir": "test" 16:55:25 INFO - } 16:55:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/P2ZJxbWwS2mfRkUzMacv4A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 16:55:25 INFO - Running post-action listener: _resource_record_post_action 16:55:25 INFO - ##### 16:55:25 INFO - ##### Finished read-buildbot-config step (success) 16:55:25 INFO - ##### 16:55:25 INFO - ##### 16:55:25 INFO - ##### Running download-and-extract step. 16:55:25 INFO - ##### 16:55:25 INFO - Running pre-action listener: _resource_record_pre_action 16:55:25 INFO - Running main action method: download_and_extract 16:55:25 INFO - mkdir: /builds/slave/test/build/tests 16:55:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:55:25 INFO - https://queue.taskcluster.net/v1/task/P2ZJxbWwS2mfRkUzMacv4A/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 16:55:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P2ZJxbWwS2mfRkUzMacv4A/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 16:55:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P2ZJxbWwS2mfRkUzMacv4A/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 16:55:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P2ZJxbWwS2mfRkUzMacv4A/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 16:55:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P2ZJxbWwS2mfRkUzMacv4A/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 16:55:27 INFO - Downloaded 1199 bytes. 16:55:27 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 16:55:27 INFO - Using the following test package requirements: 16:55:27 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 16:55:27 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:55:27 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 16:55:27 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:55:27 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 16:55:27 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:55:27 INFO - u'jsshell-mac.zip'], 16:55:27 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:55:27 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 16:55:27 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 16:55:27 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:55:27 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 16:55:27 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:55:27 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 16:55:27 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:55:27 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 16:55:27 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 16:55:27 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 16:55:27 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:55:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:55:27 INFO - https://queue.taskcluster.net/v1/task/P2ZJxbWwS2mfRkUzMacv4A/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:55:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P2ZJxbWwS2mfRkUzMacv4A/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:55:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P2ZJxbWwS2mfRkUzMacv4A/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:55:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P2ZJxbWwS2mfRkUzMacv4A/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 16:55:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P2ZJxbWwS2mfRkUzMacv4A/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 16:55:28 INFO - Downloaded 18634815 bytes. 16:55:28 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'] 16:55:28 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 16:55:31 INFO - Return code: 0 16:55:31 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 16:55:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:55:31 INFO - https://queue.taskcluster.net/v1/task/P2ZJxbWwS2mfRkUzMacv4A/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 16:55:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P2ZJxbWwS2mfRkUzMacv4A/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:55:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P2ZJxbWwS2mfRkUzMacv4A/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 16:55:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P2ZJxbWwS2mfRkUzMacv4A/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 16:55:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P2ZJxbWwS2mfRkUzMacv4A/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 16:55:32 INFO - Downloaded 18634815 bytes. 16:55:32 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'] 16:55:32 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 16:55:35 INFO - Return code: 0 16:55:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:55:35 INFO - https://queue.taskcluster.net/v1/task/P2ZJxbWwS2mfRkUzMacv4A/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 16:55:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P2ZJxbWwS2mfRkUzMacv4A/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 16:55:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P2ZJxbWwS2mfRkUzMacv4A/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 16:55:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P2ZJxbWwS2mfRkUzMacv4A/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 16:55:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P2ZJxbWwS2mfRkUzMacv4A/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 16:55:36 INFO - Downloaded 10834034 bytes. 16:55:36 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'] 16:55:36 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 16:55:37 INFO - Return code: 0 16:55:37 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')]} 16:55:37 INFO - https://queue.taskcluster.net/v1/task/P2ZJxbWwS2mfRkUzMacv4A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 16:55:37 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P2ZJxbWwS2mfRkUzMacv4A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:55:37 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P2ZJxbWwS2mfRkUzMacv4A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:55:37 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P2ZJxbWwS2mfRkUzMacv4A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 16:55:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/P2ZJxbWwS2mfRkUzMacv4A/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 16:55:40 INFO - Downloaded 110517931 bytes. 16:55:40 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/P2ZJxbWwS2mfRkUzMacv4A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:55:40 INFO - mkdir: /builds/slave/test/properties 16:55:40 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:55:40 INFO - Writing to file /builds/slave/test/properties/build_url 16:55:40 INFO - Contents: 16:55:40 INFO - build_url:https://queue.taskcluster.net/v1/task/P2ZJxbWwS2mfRkUzMacv4A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 16:55:41 INFO - Running post-action listener: _resource_record_post_action 16:55:41 INFO - Running post-action listener: set_extra_try_arguments 16:55:41 INFO - ##### 16:55:41 INFO - ##### Finished download-and-extract step (success) 16:55:41 INFO - ##### 16:55:41 INFO - ##### 16:55:41 INFO - ##### Running populate-webroot step. 16:55:41 INFO - ##### 16:55:41 INFO - Running pre-action listener: _resource_record_pre_action 16:55:41 INFO - Running main action method: populate_webroot 16:55:41 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:55:41 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:55:41 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:55:41 INFO - '--tppagecycles', 16:55:41 INFO - '7'], 16:55:41 INFO - 'tests': ['tsvgm']}}, 16:55:41 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:55:41 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:55:41 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:55:41 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:55:41 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:55:41 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:55:41 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:55:41 INFO - 'talos_options': ['--disable-e10s'], 16:55:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:55:41 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:55:41 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:55:41 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:55:41 INFO - 'talos_options': ['--disable-e10s'], 16:55:41 INFO - 'tests': ['damp', 'tps']}, 16:55:41 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:55:41 INFO - 'tests': ['damp', 'tps']}, 16:55:41 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:55:41 INFO - 'tests': ['dromaeo_dom']}, 16:55:41 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:55:41 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:55:41 INFO - 'tests': ['a11yr', 16:55:41 INFO - 'ts_paint', 16:55:41 INFO - 'tpaint', 16:55:41 INFO - 'sessionrestore', 16:55:41 INFO - 'sessionrestore_no_auto_restore', 16:55:41 INFO - 'tabpaint']}, 16:55:41 INFO - 'other-e10s': {'tests': ['a11yr', 16:55:41 INFO - 'ts_paint', 16:55:41 INFO - 'tpaint', 16:55:41 INFO - 'sessionrestore', 16:55:41 INFO - 'sessionrestore_no_auto_restore', 16:55:41 INFO - 'tabpaint']}, 16:55:41 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:55:41 INFO - 'ts_paint', 16:55:41 INFO - 'tpaint', 16:55:41 INFO - 'sessionrestore', 16:55:41 INFO - 'sessionrestore_no_auto_restore', 16:55:41 INFO - 'tabpaint']}, 16:55:41 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:55:41 INFO - 'ts_paint', 16:55:41 INFO - 'tpaint', 16:55:41 INFO - 'sessionrestore', 16:55:41 INFO - 'sessionrestore_no_auto_restore', 16:55:41 INFO - 'tabpaint']}, 16:55:41 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:55:41 INFO - 'tests': ['a11yr', 16:55:41 INFO - 'ts_paint', 16:55:41 INFO - 'tpaint', 16:55:41 INFO - 'sessionrestore', 16:55:41 INFO - 'sessionrestore_no_auto_restore', 16:55:41 INFO - 'tabpaint']}, 16:55:41 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:55:41 INFO - 'tests': ['a11yr', 16:55:41 INFO - 'ts_paint', 16:55:41 INFO - 'tpaint', 16:55:41 INFO - 'sessionrestore', 16:55:41 INFO - 'sessionrestore_no_auto_restore', 16:55:41 INFO - 'tabpaint']}, 16:55:41 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:55:41 INFO - 'tests': ['tsvgx', 16:55:41 INFO - 'tsvgr_opacity', 16:55:41 INFO - 'tart', 16:55:41 INFO - 'tscrollx', 16:55:41 INFO - 'cart']}, 16:55:41 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:55:41 INFO - 'tsvgr_opacity', 16:55:41 INFO - 'tart', 16:55:41 INFO - 'tscrollx', 16:55:41 INFO - 'cart']}, 16:55:41 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:55:41 INFO - 'talos_options': ['--disable-e10s'], 16:55:41 INFO - 'tests': ['tp5o']}, 16:55:41 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:55:41 INFO - 'tests': ['tp5o']}, 16:55:41 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:55:41 INFO - 'talos_options': ['--disable-e10s', 16:55:41 INFO - '--xperf_path', 16:55:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:55:41 INFO - 'tests': ['tp5n']}, 16:55:41 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:55:41 INFO - 'talos_options': ['--xperf_path', 16:55:41 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:55:41 INFO - 'tests': ['tp5n']}}, 16:55:41 INFO - 'talos.zip': {'path': '', 16:55:41 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:55:41 INFO - Running post-action listener: _resource_record_post_action 16:55:41 INFO - ##### 16:55:41 INFO - ##### Finished populate-webroot step (success) 16:55:41 INFO - ##### 16:55:41 INFO - ##### 16:55:41 INFO - ##### Running create-virtualenv step. 16:55:41 INFO - ##### 16:55:41 INFO - Running pre-action listener: _resource_record_pre_action 16:55:41 INFO - Running main action method: create_virtualenv 16:55:41 INFO - Creating virtualenv /builds/slave/test/build/venv 16:55:41 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 16:55:41 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:55:41 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:55:41 INFO - Using real prefix '/tools/python27' 16:55:41 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:55:42 INFO - Installing distribute.............................................................................................................................................................................................done. 16:55:45 INFO - Installing pip.................done. 16:55:45 INFO - Return code: 0 16:55:45 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:55:45 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')]} 16:55:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:55:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55:45 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')]} 16:55:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:55:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55:45 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 0x104cfda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d03570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7cb494f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ba1d30>, '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 0x1049c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104b98b20>, '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.VI4smXMnM3/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.5NcLNPCbX6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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 16:55:45 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 16:55:45 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 16:55:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VI4smXMnM3/Render', 16:55:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:55:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:55:45 INFO - 'HOME': '/Users/cltbld', 16:55:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:55:45 INFO - 'LOGNAME': 'cltbld', 16:55:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:55:45 INFO - 'MOZ_NO_REMOTE': '1', 16:55:45 INFO - 'NO_EM_RESTART': '1', 16:55:45 INFO - 'PAGER': '/bin/cat', 16:55:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:55:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:55:45 INFO - 'PWD': '/builds/slave/test', 16:55:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:55:45 INFO - 'SHELL': '/bin/bash', 16:55:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5NcLNPCbX6/Listeners', 16:55:45 INFO - 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/', 16:55:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:55:45 INFO - 'USER': 'cltbld', 16:55:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:55:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:55:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:55:45 INFO - 'XPC_FLAGS': '0x0', 16:55:45 INFO - 'XPC_SERVICE_NAME': '0', 16:55:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:55:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:55:45 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 16:55:45 INFO - Cleaning up... 16:55:45 INFO - Return code: 0 16:55:45 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:55:45 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')]} 16:55:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:55:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55:45 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')]} 16:55:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:55:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55:45 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 0x104cfda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d03570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7cb494f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ba1d30>, '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 0x1049c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104b98b20>, '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.VI4smXMnM3/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.5NcLNPCbX6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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 16:55:45 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 16:55:45 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 16:55:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VI4smXMnM3/Render', 16:55:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:55:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:55:45 INFO - 'HOME': '/Users/cltbld', 16:55:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:55:45 INFO - 'LOGNAME': 'cltbld', 16:55:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:55:45 INFO - 'MOZ_NO_REMOTE': '1', 16:55:45 INFO - 'NO_EM_RESTART': '1', 16:55:45 INFO - 'PAGER': '/bin/cat', 16:55:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:55:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:55:45 INFO - 'PWD': '/builds/slave/test', 16:55:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:55:45 INFO - 'SHELL': '/bin/bash', 16:55:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5NcLNPCbX6/Listeners', 16:55:45 INFO - 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/', 16:55:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:55:45 INFO - 'USER': 'cltbld', 16:55:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:55:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:55:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:55:45 INFO - 'XPC_FLAGS': '0x0', 16:55:45 INFO - 'XPC_SERVICE_NAME': '0', 16:55:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:55:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:55:45 INFO - Downloading/unpacking psutil>=3.1.1 16:55:45 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 16:55:45 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 16:55:45 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 16:55:45 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 16:55:45 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 16:55:45 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 16:55:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:55:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:55:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:55:48 INFO - Installing collected packages: psutil 16:55:48 INFO - Running setup.py install for psutil 16:55:48 INFO - building 'psutil._psutil_osx' extension 16:55:48 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 16:55:48 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 16:55:49 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 16:55:49 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 16:55:49 INFO - building 'psutil._psutil_posix' extension 16:55:49 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 16:55:49 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:55:49 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:55:49 INFO - ^ 16:55:49 INFO - 1 warning generated. 16:55:49 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 16:55:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:55:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:55:49 INFO - Successfully installed psutil 16:55:49 INFO - Cleaning up... 16:55:49 INFO - Return code: 0 16:55:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:55: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')]} 16:55:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:55:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55: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')]} 16:55:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:55:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55:49 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 0x104cfda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d03570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7cb494f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ba1d30>, '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 0x1049c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104b98b20>, '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.VI4smXMnM3/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.5NcLNPCbX6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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 16:55:49 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 16:55:49 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 16:55:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VI4smXMnM3/Render', 16:55:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:55:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:55:49 INFO - 'HOME': '/Users/cltbld', 16:55:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:55:49 INFO - 'LOGNAME': 'cltbld', 16:55:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:55:49 INFO - 'MOZ_NO_REMOTE': '1', 16:55:49 INFO - 'NO_EM_RESTART': '1', 16:55:49 INFO - 'PAGER': '/bin/cat', 16:55:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:55:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:55:49 INFO - 'PWD': '/builds/slave/test', 16:55:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:55:49 INFO - 'SHELL': '/bin/bash', 16:55:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5NcLNPCbX6/Listeners', 16:55:49 INFO - 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/', 16:55:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:55:49 INFO - 'USER': 'cltbld', 16:55:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:55:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:55:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:55:49 INFO - 'XPC_FLAGS': '0x0', 16:55:49 INFO - 'XPC_SERVICE_NAME': '0', 16:55:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:55:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:55:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:55:49 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 16:55:49 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 16:55:49 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 16:55:49 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 16:55:49 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 16:55:49 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 16:55:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:55:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:55:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:55:52 INFO - Installing collected packages: mozsystemmonitor 16:55:52 INFO - Running setup.py install for mozsystemmonitor 16:55:52 INFO - Successfully installed mozsystemmonitor 16:55:52 INFO - Cleaning up... 16:55:52 INFO - Return code: 0 16:55:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:55:52 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')]} 16:55:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:55:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55:52 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')]} 16:55:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:55:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55:52 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 0x104cfda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d03570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7cb494f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ba1d30>, '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 0x1049c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104b98b20>, '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.VI4smXMnM3/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.5NcLNPCbX6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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 16:55:52 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 16:55:52 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 16:55:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VI4smXMnM3/Render', 16:55:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:55:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:55:52 INFO - 'HOME': '/Users/cltbld', 16:55:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:55:52 INFO - 'LOGNAME': 'cltbld', 16:55:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:55:52 INFO - 'MOZ_NO_REMOTE': '1', 16:55:52 INFO - 'NO_EM_RESTART': '1', 16:55:52 INFO - 'PAGER': '/bin/cat', 16:55:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:55:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:55:52 INFO - 'PWD': '/builds/slave/test', 16:55:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:55:52 INFO - 'SHELL': '/bin/bash', 16:55:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5NcLNPCbX6/Listeners', 16:55:52 INFO - 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/', 16:55:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:55:52 INFO - 'USER': 'cltbld', 16:55:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:55:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:55:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:55:52 INFO - 'XPC_FLAGS': '0x0', 16:55:52 INFO - 'XPC_SERVICE_NAME': '0', 16:55:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:55:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:55:52 INFO - Downloading/unpacking blobuploader==1.2.4 16:55:52 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 16:55:52 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 16:55:52 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 16:55:52 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 16:55:52 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 16:55:52 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 16:55:55 INFO - Downloading blobuploader-1.2.4.tar.gz 16:55:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:55:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:55:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:55:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:55:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:55:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:55:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:55:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:55:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:55:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:55:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:55:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:55:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:55:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:55:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:55:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:55:56 INFO - Downloading docopt-0.6.1.tar.gz 16:55:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:55:56 INFO - Installing collected packages: blobuploader, requests, docopt 16:55:56 INFO - Running setup.py install for blobuploader 16:55:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:55:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:55:56 INFO - Running setup.py install for requests 16:55:56 INFO - Running setup.py install for docopt 16:55:57 INFO - Successfully installed blobuploader requests docopt 16:55:57 INFO - Cleaning up... 16:55:57 INFO - Return code: 0 16:55:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:55:57 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')]} 16:55:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:55:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55:57 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')]} 16:55:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:55:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:55:57 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 0x104cfda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d03570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7cb494f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ba1d30>, '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 0x1049c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104b98b20>, '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.VI4smXMnM3/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.5NcLNPCbX6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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 16:55:57 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 16:55:57 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 16:55:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VI4smXMnM3/Render', 16:55:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:55:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:55:57 INFO - 'HOME': '/Users/cltbld', 16:55:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:55:57 INFO - 'LOGNAME': 'cltbld', 16:55:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:55:57 INFO - 'MOZ_NO_REMOTE': '1', 16:55:57 INFO - 'NO_EM_RESTART': '1', 16:55:57 INFO - 'PAGER': '/bin/cat', 16:55:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:55:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:55:57 INFO - 'PWD': '/builds/slave/test', 16:55:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:55:57 INFO - 'SHELL': '/bin/bash', 16:55:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5NcLNPCbX6/Listeners', 16:55:57 INFO - 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/', 16:55:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:55:57 INFO - 'USER': 'cltbld', 16:55:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:55:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:55:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:55:57 INFO - 'XPC_FLAGS': '0x0', 16:55:57 INFO - 'XPC_SERVICE_NAME': '0', 16:55:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:55:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:55:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:55:57 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-N0qIFb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:55:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:55:57 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-jpx7fZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:55:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:55:57 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-4KuGkA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:55:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:55:57 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-28L0rM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:55:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:55:57 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-mmCkqQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:55:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:55:57 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-LzQHeG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:55:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:55:58 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-TlQ9kg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:55:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:55:58 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-KuVG4H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:55:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:55:58 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-mwurB5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:55:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:55:58 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-KQIZiE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:55:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:55:58 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-e3sUBZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:55:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:55:58 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-qeOmcA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:55:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:55:58 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-cTaOPA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:55:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:55:58 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-nEsv2s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:55:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:55:58 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-xz0Snn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:55:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:55:59 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-bqJSWV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:55:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:55:59 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-XPF99E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:55:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:55:59 INFO - Running setup.py install for manifestparser 16:55:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:55:59 INFO - Running setup.py install for mozcrash 16:55:59 INFO - Running setup.py install for mozdebug 16:55:59 INFO - Running setup.py install for mozdevice 16:55:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:55:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:55:59 INFO - Running setup.py install for mozfile 16:55:59 INFO - Running setup.py install for mozhttpd 16:56:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:56:00 INFO - Running setup.py install for mozinfo 16:56:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:56:00 INFO - Running setup.py install for mozInstall 16:56:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:56:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:56:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:56:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:56:00 INFO - Running setup.py install for mozleak 16:56:00 INFO - Running setup.py install for mozlog 16:56:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:56:00 INFO - Running setup.py install for moznetwork 16:56:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:56:00 INFO - Running setup.py install for mozprocess 16:56:00 INFO - Running setup.py install for mozprofile 16:56:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:56:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:56:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:56:01 INFO - Running setup.py install for mozrunner 16:56:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:56:01 INFO - Running setup.py install for mozscreenshot 16:56:01 INFO - Running setup.py install for moztest 16:56:01 INFO - Running setup.py install for mozversion 16:56:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:56:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:56:01 INFO - Cleaning up... 16:56:01 INFO - Return code: 0 16:56:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:56:01 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')]} 16:56:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:56:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:56:01 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')]} 16:56:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:56:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:56:01 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 0x104cfda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d03570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7cb494f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ba1d30>, '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 0x1049c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104b98b20>, '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.VI4smXMnM3/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.5NcLNPCbX6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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 16:56:01 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 16:56:01 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 16:56:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VI4smXMnM3/Render', 16:56:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:56:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:56:01 INFO - 'HOME': '/Users/cltbld', 16:56:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:56:01 INFO - 'LOGNAME': 'cltbld', 16:56:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:56:01 INFO - 'MOZ_NO_REMOTE': '1', 16:56:01 INFO - 'NO_EM_RESTART': '1', 16:56:01 INFO - 'PAGER': '/bin/cat', 16:56:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:56:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:56:01 INFO - 'PWD': '/builds/slave/test', 16:56:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:56:01 INFO - 'SHELL': '/bin/bash', 16:56:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5NcLNPCbX6/Listeners', 16:56:01 INFO - 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/', 16:56:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:56:01 INFO - 'USER': 'cltbld', 16:56:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:56:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:56:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:56:01 INFO - 'XPC_FLAGS': '0x0', 16:56:01 INFO - 'XPC_SERVICE_NAME': '0', 16:56:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:56:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:56:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:56:02 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-AHfsuX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:56:02 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)) 16:56:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:56:02 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-w9MGff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:56:02 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)) 16:56:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:56:02 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-FaRlh_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:56:02 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)) 16:56:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:56:02 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-gziyyg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:56:02 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)) 16:56:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:56:02 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-CF5CBu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:56:02 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)) 16:56:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:56:02 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-DLcCID-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:56:02 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)) 16:56:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:56:02 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-N2xLeP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:56:02 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)) 16:56:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:56:02 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-eHxu69-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:56:02 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)) 16:56:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:56:02 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-q0f9DV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:56:02 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)) 16:56:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:56:03 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-XykauL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:56:03 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)) 16:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:56:03 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-v2HnAx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:56:03 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)) 16:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:56:03 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-f7kkew-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:56:03 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)) 16:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:56:03 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-_DvKD5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:56:03 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)) 16:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:56:03 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-YF2lGZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:56:03 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)) 16:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:56:03 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-B4b3C_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:56:03 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)) 16:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:56:03 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-g4Fb7r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:56:03 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)) 16:56:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:56:03 INFO - Running setup.py (path:/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/pip-HWvxWl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:56:03 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)) 16:56:03 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)) 16:56:03 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)) 16:56:03 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)) 16:56:03 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)) 16:56:03 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)) 16:56:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:56:03 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 16:56:03 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 16:56:03 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 16:56:03 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 16:56:03 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 16:56:03 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 16:56:06 INFO - Downloading blessings-1.6.tar.gz 16:56:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:56:06 INFO - Installing collected packages: blessings 16:56:06 INFO - Running setup.py install for blessings 16:56:06 INFO - Successfully installed blessings 16:56:06 INFO - Cleaning up... 16:56:06 INFO - Return code: 0 16:56:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:56:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:56:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:56:06 INFO - Reading from file tmpfile_stdout 16:56:06 INFO - Current package versions: 16:56:06 INFO - blessings == 1.6 16:56:06 INFO - blobuploader == 1.2.4 16:56:06 INFO - docopt == 0.6.1 16:56:06 INFO - manifestparser == 1.1 16:56:06 INFO - mozInstall == 1.12 16:56:06 INFO - mozcrash == 0.17 16:56:06 INFO - mozdebug == 0.1 16:56:06 INFO - mozdevice == 0.48 16:56:06 INFO - mozfile == 1.2 16:56:06 INFO - mozhttpd == 0.7 16:56:06 INFO - mozinfo == 0.9 16:56:06 INFO - mozleak == 0.1 16:56:06 INFO - mozlog == 3.2 16:56:06 INFO - moznetwork == 0.27 16:56:06 INFO - mozprocess == 0.22 16:56:06 INFO - mozprofile == 0.28 16:56:06 INFO - mozrunner == 6.11 16:56:06 INFO - mozscreenshot == 0.1 16:56:06 INFO - mozsystemmonitor == 0.3 16:56:06 INFO - moztest == 0.7 16:56:06 INFO - mozversion == 1.4 16:56:06 INFO - psutil == 3.1.1 16:56:06 INFO - requests == 1.2.3 16:56:06 INFO - wsgiref == 0.1.2 16:56:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:56:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:56:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:56:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:56:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:56:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:56:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:56:06 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 0x104cfda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d03570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7cb494f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ba1d30>, '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 0x1049c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104b98b20>, '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.VI4smXMnM3/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.5NcLNPCbX6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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 16:56:06 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 16:56:06 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 16:56:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VI4smXMnM3/Render', 16:56:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:56:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:56:06 INFO - 'HOME': '/Users/cltbld', 16:56:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:56:06 INFO - 'LOGNAME': 'cltbld', 16:56:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:56:06 INFO - 'MOZ_NO_REMOTE': '1', 16:56:06 INFO - 'NO_EM_RESTART': '1', 16:56:06 INFO - 'PAGER': '/bin/cat', 16:56:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:56:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:56:06 INFO - 'PWD': '/builds/slave/test', 16:56:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:56:06 INFO - 'SHELL': '/bin/bash', 16:56:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5NcLNPCbX6/Listeners', 16:56:06 INFO - 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/', 16:56:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:56:06 INFO - 'USER': 'cltbld', 16:56:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:56:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:56:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:56:06 INFO - 'XPC_FLAGS': '0x0', 16:56:06 INFO - 'XPC_SERVICE_NAME': '0', 16:56:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:56:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:56:07 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)) 16:56:07 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)) 16:56:07 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)) 16:56:07 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)) 16:56:07 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)) 16:56:07 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)) 16:56:07 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)) 16:56:07 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)) 16:56:07 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)) 16:56:07 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)) 16:56:07 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)) 16:56:07 INFO - Cleaning up... 16:56:07 INFO - Return code: 0 16:56:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:56:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:56:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:56:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:56:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:56:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:56:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:56:07 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 0x104cfda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d03570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7cb494f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ba1d30>, '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 0x1049c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104b98b20>, '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.VI4smXMnM3/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.5NcLNPCbX6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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 16:56:07 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 16:56:07 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 16:56:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VI4smXMnM3/Render', 16:56:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:56:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:56:07 INFO - 'HOME': '/Users/cltbld', 16:56:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:56:07 INFO - 'LOGNAME': 'cltbld', 16:56:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:56:07 INFO - 'MOZ_NO_REMOTE': '1', 16:56:07 INFO - 'NO_EM_RESTART': '1', 16:56:07 INFO - 'PAGER': '/bin/cat', 16:56:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:56:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:56:07 INFO - 'PWD': '/builds/slave/test', 16:56:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:56:07 INFO - 'SHELL': '/bin/bash', 16:56:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5NcLNPCbX6/Listeners', 16:56:07 INFO - 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/', 16:56:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:56:07 INFO - 'USER': 'cltbld', 16:56:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:56:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:56:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:56:07 INFO - 'XPC_FLAGS': '0x0', 16:56:07 INFO - 'XPC_SERVICE_NAME': '0', 16:56:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:56:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:56:07 INFO - Downloading/unpacking jsonschema 16:56:07 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 16:56:07 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 16:56:07 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 16:56:07 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 16:56:07 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 16:56:07 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 16:56:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:56:09 INFO - Downloading/unpacking functools32 (from jsonschema) 16:56:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:56:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:56:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:10 INFO - Downloading functools32-3.2.3-2.tar.gz 16:56:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:56:10 INFO - warning: no files found matching '*.txt' 16:56:10 INFO - no previously-included directories found matching 'build' 16:56:10 INFO - no previously-included directories found matching 'dist' 16:56:10 INFO - no previously-included directories found matching '.git*' 16:56:10 INFO - Installing collected packages: jsonschema, functools32 16:56:10 INFO - Running setup.py install for functools32 16:56:10 INFO - warning: no files found matching '*.txt' 16:56:10 INFO - no previously-included directories found matching 'build' 16:56:10 INFO - no previously-included directories found matching 'dist' 16:56:10 INFO - no previously-included directories found matching '.git*' 16:56:10 INFO - Successfully installed jsonschema functools32 16:56:10 INFO - Cleaning up... 16:56:10 INFO - Return code: 0 16:56:10 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:56:10 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')]} 16:56:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:56:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:56:10 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')]} 16:56:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:56:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:56:10 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 0x104cfda30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104d03570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff7cb494f00>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104ba1d30>, '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 0x1049c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104b98b20>, '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.VI4smXMnM3/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.5NcLNPCbX6/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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 16:56:10 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 16:56:10 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 16:56:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VI4smXMnM3/Render', 16:56:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:56:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:56:10 INFO - 'HOME': '/Users/cltbld', 16:56:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:56:10 INFO - 'LOGNAME': 'cltbld', 16:56:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:56:10 INFO - 'MOZ_NO_REMOTE': '1', 16:56:10 INFO - 'NO_EM_RESTART': '1', 16:56:10 INFO - 'PAGER': '/bin/cat', 16:56:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:56:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:56:10 INFO - 'PWD': '/builds/slave/test', 16:56:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:56:10 INFO - 'SHELL': '/bin/bash', 16:56:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5NcLNPCbX6/Listeners', 16:56:10 INFO - 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/', 16:56:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:56:10 INFO - 'USER': 'cltbld', 16:56:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:56:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:56:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:56:10 INFO - 'XPC_FLAGS': '0x0', 16:56:10 INFO - 'XPC_SERVICE_NAME': '0', 16:56:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:56:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:56:10 INFO - Downloading/unpacking flake8 16:56:10 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 16:56:10 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 16:56:10 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 16:56:10 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 16:56:10 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 16:56:10 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 16:56:13 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:56:13 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:56:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:56:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:56:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:13 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:56:13 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:56:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:56:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:56:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:56:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:56:14 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:56: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 16:56: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 16:56: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 16:56: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 16:56: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 16:56: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 16:56:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:56:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:56:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:56:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:56:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:56:14 INFO - no previously-included directories found matching 'docs/_build' 16:56:14 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:56:14 INFO - Running setup.py install for pep8 16:56:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:56:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:56:14 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:56:14 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:56:14 INFO - no previously-included directories found matching 'docs/_build' 16:56:14 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:56:14 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:56:14 INFO - Cleaning up... 16:56:14 INFO - Return code: 0 16:56:14 INFO - Running post-action listener: _resource_record_post_action 16:56:14 INFO - Running post-action listener: _start_resource_monitoring 16:56:14 INFO - Starting resource monitoring. 16:56:14 INFO - ##### 16:56:14 INFO - ##### Finished create-virtualenv step (success) 16:56:14 INFO - ##### 16:56:14 INFO - ##### 16:56:14 INFO - ##### Running install step. 16:56:14 INFO - ##### 16:56:14 INFO - Running pre-action listener: _resource_record_pre_action 16:56:14 INFO - Running main action method: install 16:56:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:56:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:56:15 INFO - Reading from file tmpfile_stdout 16:56:15 INFO - Detecting whether we're running mozinstall >=1.0... 16:56:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:56:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:56:15 INFO - Reading from file tmpfile_stdout 16:56:15 INFO - Output received: 16:56:15 INFO - Usage: mozinstall [options] installer 16:56:15 INFO - Options: 16:56:15 INFO - -h, --help show this help message and exit 16:56:15 INFO - -d DEST, --destination=DEST 16:56:15 INFO - Directory to install application into. [default: 16:56:15 INFO - "/builds/slave/test"] 16:56:15 INFO - --app=APP Application being installed. [default: firefox] 16:56:15 INFO - mkdir: /builds/slave/test/build/application 16:56:15 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'] 16:56:15 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 16:56:49 INFO - Reading from file tmpfile_stdout 16:56:49 INFO - Output received: 16:56:49 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:56:49 INFO - Running post-action listener: _resource_record_post_action 16:56:49 INFO - ##### 16:56:49 INFO - ##### Finished install step (success) 16:56:49 INFO - ##### 16:56:49 INFO - ##### 16:56:49 INFO - ##### Running run-tests step. 16:56:49 INFO - ##### 16:56:49 INFO - Running pre-action listener: _resource_record_pre_action 16:56:49 INFO - Running main action method: run_tests 16:56:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:56:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:56:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:56:49 INFO - 2016-05-25 16:56:49.064 screenresolution[1940:61672] starting screenresolution argv=screenresolution get 16:56:49 INFO - 2016-05-25 16:56:49.067 screenresolution[1940:61672] Display 0: 1600x1200x32@60 16:56:49 INFO - 2016-05-25 16:56:49.072 screenresolution[1941:61675] starting screenresolution argv=screenresolution list 16:56:49 INFO - Available Modes on Display 0 16:56:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:56:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:56:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:56:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:56:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:56:49 INFO - Intel Iris: 16:56:49 INFO - Chipset Model: Intel Iris 16:56:49 INFO - Type: GPU 16:56:49 INFO - Bus: Built-In 16:56:49 INFO - VRAM (Dynamic, Max): 1536 MB 16:56:49 INFO - Vendor: Intel (0x8086) 16:56:49 INFO - Device ID: 0x0a2e 16:56:49 INFO - Revision ID: 0x0009 16:56:49 INFO - Displays: 16:56:49 INFO - Display: 16:56:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:56:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:56:49 INFO - Display Serial Number: 1600x1200 60 16:56:49 INFO - Main Display: Yes 16:56:49 INFO - Mirror: Off 16:56:49 INFO - Online: Yes 16:56:49 INFO - Rotation: Supported 16:56:49 INFO - Return code: 0 16:56:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:56:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:56:49 INFO - Python 2.7.3 16:56:49 INFO - Return code: 0 16:56:49 INFO - grabbing minidump binary from tooltool 16:56: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')]} 16:56:49 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 0x104ba1d30>, '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 0x1049c5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x104b98b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:56:49 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 16:56:49 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 16:56:49 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 16:56:49 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:56:49 INFO - Return code: 0 16:56:49 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:56:49 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:56:49 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:56:49 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:56:49 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:56:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:56:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:56:50 INFO - Return code: 0 16:56:50 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/P2ZJxbWwS2mfRkUzMacv4A/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0240', '--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 16:56:50 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/P2ZJxbWwS2mfRkUzMacv4A/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0240 --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 16:56:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.VI4smXMnM3/Render', 16:56:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:56:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:56:50 INFO - 'HOME': '/Users/cltbld', 16:56:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:56:50 INFO - 'LOGNAME': 'cltbld', 16:56:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:56:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:56:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:56:50 INFO - 'MOZ_NO_REMOTE': '1', 16:56:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:56:50 INFO - 'NO_EM_RESTART': '1', 16:56:50 INFO - 'PAGER': '/bin/cat', 16:56:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:56:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:56:50 INFO - 'PWD': '/builds/slave/test', 16:56:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:56:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:56:50 INFO - 'SHELL': '/bin/bash', 16:56:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.5NcLNPCbX6/Listeners', 16:56:50 INFO - 'TMPDIR': '/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/', 16:56:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:56:50 INFO - 'USER': 'cltbld', 16:56:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:56:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:56:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:56:50 INFO - 'XPC_FLAGS': '0x0', 16:56:50 INFO - 'XPC_SERVICE_NAME': '0', 16:56:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:56:50 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/P2ZJxbWwS2mfRkUzMacv4A/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0240', '--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 16:56:50 INFO - mozversion application_buildid: 20160525145246 16:56:50 INFO - mozversion application_changeset: 94bc94b961768538296b30ef704f99fcfe9d5874 16:56:50 INFO - mozversion application_display_name: Nightly 16:56:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:56:50 INFO - mozversion application_name: Firefox 16:56:50 INFO - mozversion application_remotingname: firefox 16:56:50 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 16:56:50 INFO - mozversion application_vendor: Mozilla 16:56:50 INFO - mozversion application_version: 49.0a1 16:56:50 INFO - mozversion platform_buildid: 20160525145246 16:56:50 INFO - mozversion platform_changeset: 94bc94b961768538296b30ef704f99fcfe9d5874 16:56:50 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 16:56:50 INFO - mozversion platform_version: 49.0a1 16:56:50 INFO - using testdate: 1464220610 16:56:50 INFO - actual date: 1464220610 16:56:50 INFO - starting webserver on 'localhost:50569' 16:56:50 INFO - SUITE-START | Running 2 tests 16:56:50 INFO - TEST-START | dromaeo_css 16:56:50 INFO - operating with platform_type : mac_ 16:56:50 INFO - Initialising browser for dromaeo_css test... 16:56:50 INFO - TEST-INFO | started process 1959 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/tmpU7czmU/profile http://localhost:50569/getInfo.html) 16:56:53 INFO - PROCESS | 1959 | __metrics Screen width/height:1600/1200 16:56:53 INFO - PROCESS | 1959 | colorDepth:24 16:56:53 INFO - PROCESS | 1959 | Browser inner width/height: 1024/689 16:56:53 INFO - PROCESS | 1959 | __metrics 16:56:53 INFO - PROCESS | 1959 | 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" 16:56:53 ERROR - PROCESS | 1959 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 16:56:53 INFO - TEST-INFO | 1959: exit 0 16:56:53 INFO - Browser initialized. 16:56:53 INFO - Running cycle 1/1 for dromaeo_css test... 16:56:53 INFO - TEST-INFO | started process 1961 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/tmpU7czmU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:57:05 INFO - PROCESS | 1961 | RSS: Main: 241221632 16:57:05 INFO - PROCESS | 1961 | 16:57:57 INFO - PROCESS | 1961 | 1464220677259 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] 17:00:07 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:50569/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50569/tests/dromaeo/cssquery-ext.html) 17:00:09 INFO - PROCESS | 1961 | RSS: Main: 369827840 17:00:09 INFO - PROCESS | 1961 | 17:03:12 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:50569/tests/dromaeo/cssquery-ext.html (next: http://localhost:50569/tests/dromaeo/cssquery-jquery.html) 17:03:12 INFO - PROCESS | 1961 | RSS: Main: 378343424 17:03:12 INFO - PROCESS | 1961 | 17:06:14 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:50569/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50569/tests/dromaeo/cssquery-mootools.html) 17:06:15 INFO - PROCESS | 1961 | RSS: Main: 374173696 17:06:15 INFO - PROCESS | 1961 | 17:09:17 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:50569/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50569/tests/dromaeo/cssquery-prototype.html) 17:09:18 INFO - PROCESS | 1961 | RSS: Main: 366419968 17:09:18 INFO - PROCESS | 1961 | 17:12:21 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:50569/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50569/tests/dromaeo/cssquery-yui.html) 17:12:22 INFO - PROCESS | 1961 | RSS: Main: 338014208 17:12:22 INFO - PROCESS | 1961 | 17:15:25 INFO - PROCESS | 1961 | Cycle 1(1): loaded http://localhost:50569/tests/dromaeo/cssquery-yui.html (next: http://localhost:50569/tests/dromaeo/cssquery-dojo.html) 17:15:25 INFO - PROCESS | 1961 | RSS: Main: 377630720 17:15:25 INFO - PROCESS | 1961 | 17:15:25 INFO - PROCESS | 1961 | __start_tp_report 17:15:25 INFO - PROCESS | 1961 | _x_x_mozilla_page_load 17:15:25 INFO - PROCESS | 1961 | _x_x_mozilla_page_load_details 17:15:25 INFO - PROCESS | 1961 | |i|pagename|runs| 17:15:25 INFO - PROCESS | 1961 | |0;dojo.html;3391.8304084795755;3398.4052790761625;3414.487826825974;3419.5554577904877;3462.2902305027474;5581.888362232753;5592.776288948442;5620.494155526002;5676.262085928829;5701.657900525966;5631.831045068644;5667.546596272288;5740.540756739461;5753.252077229965;5764.942350576493;5091.185410334348;5136.332276804011;5176.637635365507;5183.403908550508;5303.496167864047;5240.764165612557;5285.445028272034;5301.496357845998;5308.814191503298;5337.439568845273;4974.975125124387;5126.077306084884;5176.0683077046115;5178.016176926372;5204.557612602934;5013.273075404082;5119.87200319995;5137.357830271216;5141.794328226866;5142.485751424828;4985.977874535712;5155.175171972466;5160.303359046518;5186.988537235241;5262.210668399733;5300.522952934254;5468.48049435303;5564.86087847803;5626.071698169797;5693.829185124411;6115.48018418435;6120.204373431426;6129.450483694044;6175.66033868137;6188.164597779332;5519.668819870821;6232.906506402408;6252.874942501125;6278.591891527036;6280.87438251237;5640.689762063126;5827.446392592697;5831.329397119301;5835.183074369592;5840.094785308284;5182.714950677673;5295.788168473295;6229.563930524911;6269.435750782452;6269.749210031548;6147.84630384244;6156.661383623944;6167.105769663373;6169.136320915076;6176.320604733476;6137.294211165684;6197;6256.843578910563;6281.811545653638;6294.779682711083;6101.389861013863;6109.480694140959;6123.801220575414;6137.254412462224;6152.600080994721;4783.923617186106;5771.595988280864;6191.752329906753;6195.132681424604;6221.2845522764555;4812.494688057774;4886.120498310338;4893.070316639827;4904.411470623548;4939.086269040167;4348.739075655408;4351.06452112791;4351.543087975717;4365.672574556921;4414.646828253732;5713.571482138773;5726.348071551925;5741.397153298927;5812.59311848175;5825.970870145622;6067.332593414721;6093.025115981422;6120.908186377208;6156.168917196211;6171.9691401542705;5717.113847353667;5719.113537401709;5738.626989245686;5743.368229494752;5748.137779333133;5375.489328513785;5378.139497680352;5381.7578208980685;5387.595930305243;5400.135978243462;6233.688315584202;6260.561760676709;6265.966115590876;6278.277998030194;6317.873642527124;6134.877302454015;6174.783882564088;6186.226721659793;6203.131561581473;6206.193194884636;6359.268684101203;6395.072714456471;6403.871922561616;6406.487481001602;6407.519436042222;6048.576599637983;6093.542984276109;6106;6115.510759139347;6140.539459540463;6144.556029333193;6168.753249869955;6169.444749972524;6191.19514463117;6207.131001659862;5435.776950186335;5890.676012819336;6097.512199023979;6118.051701986199;6119.785807496716;5866.765329386947;5946.910796337972;6027.457528822251;6040.214772079602;6078.665673388006;5925.496332811759;5945.078512830518;5955.076963070731;5986.102084687158;5990.7004649768205;6099.11562823397;6109.511239100772;6114.844865410725;6129.912172896396;6139.640854993005;5754.05058165398;5771.047777116729;5823.272090988627;5882.764689412375;5992.340842507408 17:15:25 INFO - PROCESS | 1961 | |1;ext.html;478.67928487913093;510.12768166435393;515.6905856486109;530.2258702294649;540.3893600231737;31145;42977.35533966989;43962.90092747675;44024.55975440245;44035.33946990798;44572.3314150288;44869.77565112178;45082.54917450825;45155.548444515545;45167.09665806682;8738.694145704902;9653.951730241339;9895.010498950083;9903.752406189868;9903.752406189868;8742.650293988232;11642.476088575992;11910.464029118668;11946.283223006603;12237;10677.466126693673;11412.029977451926;12050.457729402155;12051.638450846445;12057.819132713017;8703.738887833375;11833.881661183406;11916.702082448008;11979.101567382437;12306.200096993667;11764.294142351486;11891.227070240408;11898.405079745979;11906.761864762657;11917.22538035025;42368.78815605933;42801.35797963033;42851.35722964158;42872.78563607193;43060.56939430597;3066.0955018269665;3096.5819614352;3205.5351973963643;3262.3964566555264;3733.3653278942643;23758.64362034571;29373.412531749247;29651;30064.398712025635;30314.84842575795;2676.9866150669122;2678.8794504247358;2707.7156898525764;2785.7632101271415;2803.0889960762834;504848;512436;513353;513672;513993.9999999963;65753;66172.66913665498;66285.00572491417;66374.33625663682;66378.66810666013;35980;36500.45249321209;36514.26971460556;37523.4371484428;37533.436998445046;17219.82780172182;17336.65326693459;17414.738778918327;17432.82567174312;17452.738208926876;11891.464884080237;12142.635720928387;12925.741485170243;13030.413631386611;13163;18133.45599632013;19074.661934161468;20138.093785779674;20170.822275864448;20390.79609203889;6665.833354166185;7829.608519573999;7913.248241417171;7945.854619252885;7976.800579985431;854.9016863060704;963.802420503795;966.3863446711248;996.8953259907751;1087.6138970665459;24707.505849883262;28530.85734571314;30404;30446.93435729739;30553;1055.3957983864636;1067.108964015056;1072.769354588767;1166.4984056855633;1169.6432588060723;14526.927365363106;15524.844751552566;15665.68668626621;15858.841411585967;16091.356345746273;574505;855546;872410.6379468061;875603;876010;59861.40138598646;59906;60455.395446045855;60592.15262778967;60604;1305.4712841299288;1347.4946894914408;1437.058726534122;1455.2536955653259;1472.4036765110147;29918.401631967674;30618.234544136576;30683;31522.68477315289;32337.51493727549;61235;61854.38145618666;62188.689056556235;62234;62383;625352;634511;634995;635308.8234558798;635760;26767;27694.584581231702;27736.167914962585;27751.889924402796;28554.85722571374;47178.52821471879;47970.520294798;48123.79690507765;49154.508454916424;49622;38357.424638631004;39281.82154535368;39745.80127099346;40430.19139617132;40742.79628601838;38993.02517436973;39284.80357598194;39476;40206;40517.59482405256;38146.618533814304;38609.42085868771;39024.148115056756;39393.80303098466;39440;32296;33858.3228335427;34524.65475345315;34628.30743385068;34747.478787817694;22310.442238944157;24243.63634545448;24785.38894971821;25095.623565646896;25658 17:15:25 INFO - PROCESS | 1961 | |2;jquery.html;383.8925100971728;384.961503849615;386.9400242962342;403.6427761431133;412.59359530862116;18538.814611853893;18875.62248755024;21423.785762142375;21471.57056858862;21638.350849474522;20214.898925505353;20290;21233.893830530866;21237.362879113614;21248.8937555312;5196.610254230929;5780.277465316846;6041.72812223451;6063.605865618742;6074.453299203071;5904.143899134644;5920.585559010871;5972.492338151263;6040.184575082373;6045.3047899491685;5732.398098199692;5918.40815918409;5928.822135335947;5932.317783454915;5933.584649074566;5714.657120572738;5893.823185304448;5910.97044514779;5916.733746981375;5921.73352199152;4995.400551933792;5159.71621560814;5180.922286165711;5188.481151884819;5189.714565698885;20387.592248155102;25078.24765257045;25440.81989751281;25728.871355643103;25840.74159258402;1806.7922188948307;2180.618391781432;2347.5774360615083;2390.8206884483734;2583.289595361272;6803.455723542104;13292.069555131146;14177.78733319001;14640.560783176521;14822.40710371584;1587.3333200056004;1590.7454807230788;1732.5841797968396;1789.5168304557697;1846.2522678315304;86658;104916;115342.42328788302;118714.40642796818;119121;10539.525721342558;10737.832339459877;10964.451777411097;10970.506327215295;11181.83227251592;13989.650258743613;14002.929985350213;14009.299535023209;14034.36845341962;14079.22564258955;7154.35610795031;8535.615897284637;8680.8697869532;8695.260902823204;8854.911450885538;8748.387612867165;8778;8780;8868.068852770495;8919.331050171133;6564.67176641166;6699.196096468358;6723.260441351447;6726.461883049345;6779.457643388517;1847.5381154711322;1872.2230274436229;1877.11775465531;1883.4067268810281;1913;833.966641334352;902.7968707040864;947.6114792934864;1049.1344640671477;1093.1145771924607;19279.903600481906;20405.591888162155;20543.8972805135;20909.37271881846;20949.476263093544;912.8680436258991;937.2689302344182;949.4635530925005;976.3458482816388;990.321629683669;9998;11166.944165279121;11213.9907408332;11223.214374993835;11263.493142808593;493035;517543;523524;524873;598246;128394;132941;134940;135982;137460;930.5998420679038;961.2838435365697;975.6682727872416;977.2182254196614;1003.1423133221136;18022.459326220236;18193.363232286807;18330.541736456697;18339.541511461786;18346.082695864887;55065;55604.88790224254;55867.72066139643;56716.432835672764;57038.57403564778;73915.63042184754;74816.62591687006;77724;81910;84461;7591.033210148682;7943.92056079455;7994.1606131355375;8133.8373232536205;8306.335493160654;11626.593069242535;12615.49538018469;12620.116591838483;12654.746905062031;12809.935950320189;8538.914610853812;10809.405482698527;10811.945940270249;11094.389808560607;11366.976972072553;9155.450672959643;9231.353805233837;11153.442327883735;11215.271007384494;11536;11148;11308.547658093585;11487.54049837997;11657.941710291394;11671.708207294885;8069.919300807152;8162.551059691774;8210.014798224012;8211.753647390588;8265.793355166168;8324.70863519774;10616;11040.668779936615;11388.974992250738;11433.542658293907 17:15:25 INFO - PROCESS | 1961 | |3;mootools.html;1281.589891234804;1527.6486408126132;1560.134125560314;1788.2131861980727;1955.9022048897552;7786.338161256293;8603;8829.823403531926;8875.334349923758;8990.550472476367;8606.61270242839;8800.735977920656;8804.691835785748;8807.515586642732;8836.0722124177;2046;2369.0523790483817;2406.4224586099385;2409.4458274596855;2413.6379543068506;2194.714687090676;2237.049254067023;2354.458474550854;2373.3354660694945;2405.3625789165885;2350.753170917055;2377.9048838046456;2386.820988425875;2389.5340408620327;2390.605550084234;2338.918137865184;2364.9645255321097;2371.6323969784626;2375.3705268103968;2385.6183010718373;2274.341866669317;2358.9410264743346;2366.6331718583647;2367.337145599235;2368.194813763329;7880.605969701549;8157.102718700998;8798.780030499225;8876.490996530605;8901.198892099743;4804.447488538828;5048.7728052237735;5178.17149256121;5413.052715774724;5418.620696551243;3437.79373237604;3533.0990597397754;3582.2119133790784;3597.2985267872778;3602.081469225357;980.5048450532507;1024.5440778853413;1027.9897201027968;1029.608748675506;1034.4517405774875;11629.651110466699;11732.765344693144;11733.53065877364;11737.94131029348;11738.882611173864;4742.312364707098;4779.656175979588;4876.049170411807;4878.487758785347;4886.682365646222;10054.64808731691;10059.748506287255;10060.295779295378;10068.798624027624;10093.343932644355;5414.810481633124;5420.349558053059;5423.538999185034;5429.809956651498;5449.782008719607;4786.712797232177;4879.634027447955;4949.059678661043;4966.776495057731;4977.9751101244265;3321.2527181383994;3775.5846856845724;3807.200487897517;3901.902452438655;3908.5700572936953;1064.6220591689994;1072.7961687279392;1079.1636481726725;1081.734974931147;1200.5257923120319;810.5379933438002;872.646578135856;879.8900137482815;898.595631965618;920.7882187097006;5635.633683810539;5642.71786410686;5739.540836733051;5769.653820770767;5902.409759024149;431.98704038878884;438.39939283182247;441.54520843531213;453.33133627899247;455.22384334709244;3792.488014118114;3849.8117693679083;3859.7684138951754;3946.8223929922647;3951.7233793634746;9066.501342426098;10119.7470063249;10326.432046237378;10805.891941080488;10819.729506762395;10637.202209834293;10746.731331716614;10761.354318740901;10769;10791.352518848737;7943.404244681672;8118.75643730689;8243.464174828618;8249.8350032999;8278.751637450887;5574.91637625444;5655.406182350794;5711.771529138954;5785.161151632906;5939.792107276225;9417.764555885884;9829.901700982899;9867.654632087842;10012.899871001488;10015;9804.607815687292;10082.899171008195;10124.69625911224;10180.796384072426;10227.335223210468;6608.405243527914;6863.965680171567;6930;7248.96375518119;7352.522086064602;8037.156098609562;8202.794930126556;8211.630476628447;8216.301614362606;8233.17668233172;6623.238327592196;7017.087778588751;7106.076210092655;7355.448341374526;7430.628468576658;7183.245759195418;7258.782236532912;7290.088738907637;7427.2201418850245;7430.517016393917;5874.530037597067;6974.860502790018;6999.580025198505;7009.738247115364;7025.402840758593;4047.332190188586;4674.252119660838;4713.764311784328;4747.813046738315;5089.338386009943;4815.133276010211;4981.053599816023;4998.700077995332;5013.548780609763;5177.8187763428095 17:15:25 INFO - PROCESS | 1961 | |4;prototype.html;308.7483700783861;312.8498320806013;314.8252719740543;319.9648038715741;325.19514202349205;4399.604035636792;4924.818043669516;5280.877548777685;5300.655457395276;5374.7043912584795;4351.651867850573;4973.104841128605;5207.7135757533415;5252.264682944381;5254.0332049302815;3053.3435311408043;3747.2318174774105;3954.861579844706;4045.615666511677;4072.9796351018203;3471.75697701161;3928.057266256092;3982.641562259396;3994.720369574131;4025.376066709665;3329.6836800503916;3782.9621703782886;3932.7640341579454;3974.1122966590783;3997.3804060370544;3638.6543278388776;3767.9638099522576;3837.443570682249;3986.940195897064;4010.9398359024344;3462.272922686239;3491.2493813830147;3492.4761285807076;3497.9825100874586;3499.4575840744724;4948.851534453972;4950.8267210647455;4961.305417241589;4966.726830024347;4970.6520543561965;4323.654107671378;4389.758563279019;4444.622207112399;4529.4791099023705;4577.33628623851;4754.286856971447;5150;5372.7313634318125;5383.43473935239;5386.78452861885;3687.3547129252274;3813.0848596336677;3817.2747178036084;3821.700621788605;3855.248226595815;4982.352294201767;5128.564072053842;5259.579233661298;5653.717314134276;5719.857003574903;3430.8970730878113;3837.846486140523;3878.9612103879163;3883.126296583246;3894.552126505488;3070.2017475456096;3929.410588411704;3931.331673615435;3954.347532657137;4033.4353190553347;4490.146872094291;4531.456225252925;4558.475775285885;4563.726176429424;4580.404547408815;5174.8965020700125;5304.628675992643;5529.921665275313;5557.3886872444;5563.749631266603;1155.7168493719093;1317.631063302277;1323.4639970811836;1325.6818363592668;1326.8257592031123;683.1255992329826;748.8916403722521;810.8121601853313;830.1032141556515;851.0638297872291;4437.689361744647;4518.615917646971;4713;5082.246624914399;5139.203423469368;4755.881102972384;4939.777710002986;5623;5628.943710562842;5635.774569017275;4488.349189367524;4769.046190761792;4895.510448955147;5094.643374963717;5101.464346243665;2734.9726502735116;2769.7507224349906;2933.603963464948;2938.5298352263962;2939.3392733105725;5806.983777838861;5836.643929740416;5843.5909486336395;5862.882742345129;5867.647941123546;5125.513076257749;5396.3524377075;5419.34967803866;5754.5971781975695;5782.624129431658;5775.740091695884;5791.797287094932;5814.244148260784;5872.647641141545;5909.6158749681135;4388;4715.03341814934;5014.323066385991;5607.915881261702;5648.265725455664;5061.861081256688;5476.561875050066;5586.486081263529;5616.775328986795;5620.85947851307;4597.954020459886;5203.791848326025;5832.708364581838;5836.970815145897;5839.912401313899;3256.674332566819;3554.7118915004035;3781.8865434037025;3802.319364847831;3816.4656948026745;5173.7671804769625;5203.323567936144;5240.318758561362;5608.579356548194;5611.326640802967;4868.683535570177;5015.824446144367;5446.509814116748;5449.700266485382;5463.093887870192;4750.762461876823;5405.270288511001;5437.4562543745305;5457.871430142327;5472.671639701631;5294.258803767399;5332.173513105475;5337;5350.732463376893;5351.143816989263;4303.569643035671;5012.47369026247;5121.207577347898;5299.655522391032;5304.628675992643;4468.173387923114;4574.862754117382;4933.358663373739;5128.999845030333;5350.8127215547265 17:15:25 INFO - PROCESS | 1961 | |5;yui.html;609.8444896551379;629.1223742878688;638.1162090504652;670.4200866250694;678.5080816408105;14984.325705343259;15565;15576.610584735374;15583.688326233498;15597.610059748513;14143.858561414409;14198.077124986869;14617.342219600117;14644.341004654789;14684.779728304084;901.2699713232287;908.3641450984304;918.5085979001236;918.9081091890824;919.204887772079;721.0950257426922;741.314284287035;873.9126087391242;904.1500989070286;906.4380084347714;962.3600305796637;963.2631037256505;968.5738275158908;970.4032020307495;973.095021629884;754.8754455514834;960.3133759362055;962.6726912849595;963.3930623707055;964.7588102974256;692.7332976803916;713.2154629907112;713.4185638704439;713.9750108746169;714.0503130835975;14710.779338309934;14737.926310368486;14909.925450372788;14975.026623269561;15017.399304027826;11945.820812687816;12156.756864862742;12189.451474683661;12599.05507086963;12734.363281835871;10296.124829389437;10804.297720648132;11178.27341222818;11320.830187547193;11337.206395552315;3969.0077480629843;4044.211378781139;4059.6955228357697;4060.350343944984;4379.5182529921685;73833.26166738318;76012.61993690052;76101.61949190273;84370.57814710948;84427;9242.86135707965;9349.39228950116;9365.906340936503;9418.811623767486;9753.317267791328;20916.37250882476;21039.368818935458;21082;21083.578328433345;21089;7404.444666650023;7473.51422157558;7480.139783924918;7565.546067235983;7657.234276572298;3397.592288925295;3593.5328407306883;3960.2871483133313;4065.8576949806616;4073.877783666495;3536.1159710072484;3633.8364773585254;3643.3624115780162;3643.927121457556;3643.9635603644156;635.139386131794;643.8358218654307;651.4202359899693;665.0921492163162;672.8452455935064;462.6345187302061;579.823153938053;591.6952769323801;597.5488506177808;605.4974371271834;7685.500442471333;7968.641411136513;7995.560244186509;8098.635561399751;8121.309688676411;541.6565580547945;566.2164182824137;587.6415386614162;593.456986857021;604.9546284028715;3689.575698794673;3704.203596226824;3816.255830113101;3818.694504439638;3822.464854920258;92215.61676574856;92815;93593.53203234076;93653;94334.58498122534;59899;72886;76284.61857690677;81303.5934820334;84478.15521844826;50795.492045079074;59000.9349672768;66369;67657.66171169112;70170;7776.377889768692;7992.600369981361;8072.4349295548745;8143.5928203590765;8226.0540037894;34189.29053547362;45054.54945450504;45400.77299613481;45531.54468455273;45719;61930.380696192464;62069.37930620636;63091.684541577;63493.682531587045;63772.7245455079;10629.377552283133;10656.840147397641;10682.946585267024;11044.447777611247;11130.610428634958;11303.604373846876;11712.180147389601;11810.822837657613;11858.288502689866;12325.938370308091;11099;11145.721356965816;11212;11258.66224013281;11522.82715759281;10490.213234007328;10606.09848162884;10921.071708904832;11115.333080015225;11715.824262635897;10823.567057317934;10891.346519208873;10903.945480272549;11095.056920161876;12130.818037729618;9117.635294588408;9436.339456237998;9571.569279382305;9609.9039009609;9810.548078643444;9172.908270917473;9477.857832132662;9503.144716975505;9524.283241479467;9530.571124299562 17:15:25 INFO - PROCESS | 1961 | __end_tp_report 17:15:25 INFO - PROCESS | 1961 | __start_cc_report 17:15:25 INFO - PROCESS | 1961 | _x_x_mozilla_cycle_collect,1337 17:15:25 INFO - PROCESS | 1961 | __end_cc_report 17:15:25 INFO - PROCESS | 1961 | __startTimestamp1464221725489__endTimestamp 17:15:25 INFO - PROCESS | 1961 | 17:15:25 INFO - PROCESS | 1961 | ------- Summary: start ------- 17:15:25 INFO - PROCESS | 1961 | Number of tests: 6 17:15:25 INFO - PROCESS | 1961 | 17:15:25 INFO - PROCESS | 1961 | [#0] dojo.html Cycles:165 Average:5686.28 Median:5835.18 stddev:631.71 (10.8%) stddev-sans-first:607.46 17:15:25 INFO - PROCESS | 1961 | Values: 3391.8 3398.4 3414.5 3419.6 3462.3 5581.9 5592.8 5620.5 5676.3 5701.7 5631.8 5667.5 5740.5 5753.3 5764.9 5091.2 5136.3 5176.6 5183.4 5303.5 5240.8 5285.4 5301.5 5308.8 5337.4 4975.0 5126.1 5176.1 5178.0 5204.6 5013.3 5119.9 5137.4 5141.8 5142.5 4986.0 5155.2 5160.3 5187.0 5262.2 5300.5 5468.5 5564.9 5626.1 5693.8 6115.5 6120.2 6129.5 6175.7 6188.2 5519.7 6232.9 6252.9 6278.6 6280.9 5640.7 5827.4 5831.3 5835.2 5840.1 5182.7 5295.8 6229.6 6269.4 6269.7 6147.8 6156.7 6167.1 6169.1 6176.3 6137.3 6197.0 6256.8 6281.8 6294.8 6101.4 6109.5 6123.8 6137.3 6152.6 4783.9 5771.6 6191.8 6195.1 6221.3 4812.5 4886.1 4893.1 4904.4 4939.1 4348.7 4351.1 4351.5 4365.7 4414.6 5713.6 5726.3 5741.4 5812.6 5826.0 6067.3 6093.0 6120.9 6156.2 6172.0 5717.1 5719.1 5738.6 5743.4 5748.1 5375.5 5378.1 5381.8 5387.6 5400.1 6233.7 6260.6 6266.0 6278.3 6317.9 6134.9 6174.8 6186.2 6203.1 6206.2 6359.3 6395.1 6403.9 6406.5 6407.5 6048.6 6093.5 6106.0 6115.5 6140.5 6144.6 6168.8 6169.4 6191.2 6207.1 5435.8 5890.7 6097.5 6118.1 6119.8 5866.8 5946.9 6027.5 6040.2 6078.7 5925.5 5945.1 5955.1 5986.1 5990.7 6099.1 6109.5 6114.8 6129.9 6139.6 5754.1 5771.0 5823.3 5882.8 5992.3 17:15:25 INFO - PROCESS | 1961 | 17:15:25 INFO - PROCESS | 1961 | [#1] ext.html Cycles:180 Average:77498.25 Median:36507.36 stddev:179285.28 (491.1%) stddev-sans-first:179694.96 17:15:25 INFO - PROCESS | 1961 | Values: 478.7 510.1 515.7 530.2 540.4 31145.0 42977.4 43962.9 44024.6 44035.3 44572.3 44869.8 45082.5 45155.5 45167.1 8738.7 9654.0 9895.0 9903.8 9903.8 8742.7 11642.5 11910.5 11946.3 12237.0 10677.5 11412.0 12050.5 12051.6 12057.8 8703.7 11833.9 11916.7 11979.1 12306.2 11764.3 11891.2 11898.4 11906.8 11917.2 42368.8 42801.4 42851.4 42872.8 43060.6 3066.1 3096.6 3205.5 3262.4 3733.4 23758.6 29373.4 29651.0 30064.4 30314.8 2677.0 2678.9 2707.7 2785.8 2803.1 504848.0 512436.0 513353.0 513672.0 513994.0 65753.0 66172.7 66285.0 66374.3 66378.7 35980.0 36500.5 36514.3 37523.4 37533.4 17219.8 17336.7 17414.7 17432.8 17452.7 11891.5 12142.6 12925.7 13030.4 13163.0 18133.5 19074.7 20138.1 20170.8 20390.8 6665.8 7829.6 7913.2 7945.9 7976.8 854.9 963.8 966.4 996.9 1087.6 24707.5 28530.9 30404.0 30446.9 30553.0 1055.4 1067.1 1072.8 1166.5 1169.6 14526.9 15524.8 15665.7 15858.8 16091.4 574505.0 855546.0 872410.6 875603.0 876010.0 59861.4 59906.0 60455.4 60592.2 60604.0 1305.5 1347.5 1437.1 1455.3 1472.4 29918.4 30618.2 30683.0 31522.7 32337.5 61235.0 61854.4 62188.7 62234.0 62383.0 625352.0 634511.0 634995.0 635308.8 635760.0 26767.0 27694.6 27736.2 27751.9 28554.9 47178.5 47970.5 48123.8 49154.5 49622.0 38357.4 39281.8 39745.8 40430.2 40742.8 38993.0 39284.8 39476.0 40206.0 40517.6 38146.6 38609.4 39024.1 39393.8 39440.0 32296.0 33858.3 34524.7 34628.3 34747.5 22310.4 24243.6 24785.4 25095.6 25658.0 17:15:25 INFO - PROCESS | 1961 | 17:15:25 INFO - PROCESS | 1961 | [#2] jquery.html Cycles:180 Average:33204.58 Median:13112.32 stddev:89572.65 (683.1%) stddev-sans-first:89790.03 17:15:25 INFO - PROCESS | 1961 | Values: 383.9 385.0 386.9 403.6 412.6 18538.8 18875.6 21423.8 21471.6 21638.4 20214.9 20290.0 21233.9 21237.4 21248.9 5196.6 5780.3 6041.7 6063.6 6074.5 5904.1 5920.6 5972.5 6040.2 6045.3 5732.4 5918.4 5928.8 5932.3 5933.6 5714.7 5893.8 5911.0 5916.7 5921.7 4995.4 5159.7 5180.9 5188.5 5189.7 20387.6 25078.2 25440.8 25728.9 25840.7 1806.8 2180.6 2347.6 2390.8 2583.3 6803.5 13292.1 14177.8 14640.6 14822.4 1587.3 1590.7 1732.6 1789.5 1846.3 86658.0 104916.0 115342.4 118714.4 119121.0 10539.5 10737.8 10964.5 10970.5 11181.8 13989.7 14002.9 14009.3 14034.4 14079.2 7154.4 8535.6 8680.9 8695.3 8854.9 8748.4 8778.0 8780.0 8868.1 8919.3 6564.7 6699.2 6723.3 6726.5 6779.5 1847.5 1872.2 1877.1 1883.4 1913.0 834.0 902.8 947.6 1049.1 1093.1 19279.9 20405.6 20543.9 20909.4 20949.5 912.9 937.3 949.5 976.3 990.3 9998.0 11166.9 11214.0 11223.2 11263.5 493035.0 517543.0 523524.0 524873.0 598246.0 128394.0 132941.0 134940.0 135982.0 137460.0 930.6 961.3 975.7 977.2 1003.1 18022.5 18193.4 18330.5 18339.5 18346.1 55065.0 55604.9 55867.7 56716.4 57038.6 73915.6 74816.6 77724.0 81910.0 84461.0 7591.0 7943.9 7994.2 8133.8 8306.3 11626.6 12615.5 12620.1 12654.7 12809.9 8538.9 10809.4 10811.9 11094.4 11367.0 9155.5 9231.4 11153.4 11215.3 11536.0 11148.0 11308.5 11487.5 11657.9 11671.7 8069.9 8162.6 8210.0 8211.8 8265.8 8324.7 10616.0 11040.7 11389.0 11433.5 17:15:25 INFO - PROCESS | 1961 | 17:15:25 INFO - PROCESS | 1961 | [#3] mootools.html Cycles:180 Average:5626.75 Median:4795.58 stddev:3198.76 (66.7%) stddev-sans-first:3191.06 17:15:25 INFO - PROCESS | 1961 | Values: 1281.6 1527.6 1560.1 1788.2 1955.9 7786.3 8603.0 8829.8 8875.3 8990.6 8606.6 8800.7 8804.7 8807.5 8836.1 2046.0 2369.1 2406.4 2409.4 2413.6 2194.7 2237.0 2354.5 2373.3 2405.4 2350.8 2377.9 2386.8 2389.5 2390.6 2338.9 2365.0 2371.6 2375.4 2385.6 2274.3 2358.9 2366.6 2367.3 2368.2 7880.6 8157.1 8798.8 8876.5 8901.2 4804.4 5048.8 5178.2 5413.1 5418.6 3437.8 3533.1 3582.2 3597.3 3602.1 980.5 1024.5 1028.0 1029.6 1034.5 11629.7 11732.8 11733.5 11737.9 11738.9 4742.3 4779.7 4876.0 4878.5 4886.7 10054.6 10059.7 10060.3 10068.8 10093.3 5414.8 5420.3 5423.5 5429.8 5449.8 4786.7 4879.6 4949.1 4966.8 4978.0 3321.3 3775.6 3807.2 3901.9 3908.6 1064.6 1072.8 1079.2 1081.7 1200.5 810.5 872.6 879.9 898.6 920.8 5635.6 5642.7 5739.5 5769.7 5902.4 432.0 438.4 441.5 453.3 455.2 3792.5 3849.8 3859.8 3946.8 3951.7 9066.5 10119.7 10326.4 10805.9 10819.7 10637.2 10746.7 10761.4 10769.0 10791.4 7943.4 8118.8 8243.5 8249.8 8278.8 5574.9 5655.4 5711.8 5785.2 5939.8 9417.8 9829.9 9867.7 10012.9 10015.0 9804.6 10082.9 10124.7 10180.8 10227.3 6608.4 6864.0 6930.0 7249.0 7352.5 8037.2 8202.8 8211.6 8216.3 8233.2 6623.2 7017.1 7106.1 7355.4 7430.6 7183.2 7258.8 7290.1 7427.2 7430.5 5874.5 6974.9 6999.6 7009.7 7025.4 4047.3 4674.3 4713.8 4747.8 5089.3 4815.1 4981.1 4998.7 5013.5 5177.8 17:15:25 INFO - PROCESS | 1961 | 17:15:25 INFO - PROCESS | 1961 | [#4] prototype.html Cycles:180 Average:4399.05 Median:4944.31 stddev:1345.76 (27.2%) stddev-sans-first:1314.05 17:15:25 INFO - PROCESS | 1961 | Values: 308.7 312.8 314.8 320.0 325.2 4399.6 4924.8 5280.9 5300.7 5374.7 4351.7 4973.1 5207.7 5252.3 5254.0 3053.3 3747.2 3954.9 4045.6 4073.0 3471.8 3928.1 3982.6 3994.7 4025.4 3329.7 3783.0 3932.8 3974.1 3997.4 3638.7 3768.0 3837.4 3986.9 4010.9 3462.3 3491.2 3492.5 3498.0 3499.5 4948.9 4950.8 4961.3 4966.7 4970.7 4323.7 4389.8 4444.6 4529.5 4577.3 4754.3 5150.0 5372.7 5383.4 5386.8 3687.4 3813.1 3817.3 3821.7 3855.2 4982.4 5128.6 5259.6 5653.7 5719.9 3430.9 3837.8 3879.0 3883.1 3894.6 3070.2 3929.4 3931.3 3954.3 4033.4 4490.1 4531.5 4558.5 4563.7 4580.4 5174.9 5304.6 5529.9 5557.4 5563.7 1155.7 1317.6 1323.5 1325.7 1326.8 683.1 748.9 810.8 830.1 851.1 4437.7 4518.6 4713.0 5082.2 5139.2 4755.9 4939.8 5623.0 5628.9 5635.8 4488.3 4769.0 4895.5 5094.6 5101.5 2735.0 2769.8 2933.6 2938.5 2939.3 5807.0 5836.6 5843.6 5862.9 5867.6 5125.5 5396.4 5419.3 5754.6 5782.6 5775.7 5791.8 5814.2 5872.6 5909.6 4388.0 4715.0 5014.3 5607.9 5648.3 5061.9 5476.6 5586.5 5616.8 5620.9 4598.0 5203.8 5832.7 5837.0 5839.9 3256.7 3554.7 3781.9 3802.3 3816.5 5173.8 5203.3 5240.3 5608.6 5611.3 4868.7 5015.8 5446.5 5449.7 5463.1 4750.8 5405.3 5437.5 5457.9 5472.7 5294.3 5332.2 5337.0 5350.7 5351.1 4303.6 5012.5 5121.2 5299.7 5304.6 4468.2 4574.9 4933.4 5129.0 5350.8 17:15:25 INFO - PROCESS | 1961 | 17:15:25 INFO - PROCESS | 1961 | [#5] yui.html Cycles:180 Average:17646.07 Median:30251.98 stddev:24681.92 (81.6%) stddev-sans-first:24718.01 17:15:25 INFO - PROCESS | 1961 | Values: 609.8 629.1 638.1 670.4 678.5 14984.3 15565.0 15576.6 15583.7 15597.6 14143.9 14198.1 14617.3 14644.3 14684.8 901.3 908.4 918.5 918.9 919.2 721.1 741.3 873.9 904.2 906.4 962.4 963.3 968.6 970.4 973.1 754.9 960.3 962.7 963.4 964.8 692.7 713.2 713.4 714.0 714.1 14710.8 14737.9 14909.9 14975.0 15017.4 11945.8 12156.8 12189.5 12599.1 12734.4 10296.1 10804.3 11178.3 11320.8 11337.2 3969.0 4044.2 4059.7 4060.4 4379.5 73833.3 76012.6 76101.6 84370.6 84427.0 9242.9 9349.4 9365.9 9418.8 9753.3 20916.4 21039.4 21082.0 21083.6 21089.0 7404.4 7473.5 7480.1 7565.5 7657.2 3397.6 3593.5 3960.3 4065.9 4073.9 3536.1 3633.8 3643.4 3643.9 3644.0 635.1 643.8 651.4 665.1 672.8 462.6 579.8 591.7 597.5 605.5 7685.5 7968.6 7995.6 8098.6 8121.3 541.7 566.2 587.6 593.5 605.0 3689.6 3704.2 3816.3 3818.7 3822.5 92215.6 92815.0 93593.5 93653.0 94334.6 59899.0 72886.0 76284.6 81303.6 84478.2 50795.5 59000.9 66369.0 67657.7 70170.0 7776.4 7992.6 8072.4 8143.6 8226.1 34189.3 45054.5 45400.8 45531.5 45719.0 61930.4 62069.4 63091.7 63493.7 63772.7 10629.4 10656.8 10682.9 11044.4 11130.6 11303.6 11712.2 11810.8 11858.3 12325.9 11099.0 11145.7 11212.0 11258.7 11522.8 10490.2 10606.1 10921.1 11115.3 11715.8 10823.6 10891.3 10903.9 11095.1 12130.8 9117.6 9436.3 9571.6 9609.9 9810.5 9172.9 9477.9 9503.1 9524.3 9530.6 17:15:25 INFO - PROCESS | 1961 | -------- Summary: end -------- 17:15:25 INFO - PROCESS | 1961 | 17:15:26 INFO - TEST-INFO | 1961: exit 0 17:15:26 INFO - TEST-OK | dromaeo_css | took 1115276ms 17:15:26 INFO - TEST-START | kraken 17:15:26 INFO - operating with platform_type : mac_ 17:15:26 INFO - Initialising browser for kraken test... 17:15:26 INFO - TEST-INFO | started process 1963 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/tmpzNyrZV/profile http://localhost:50569/getInfo.html) 17:15:28 INFO - PROCESS | 1963 | __metrics Screen width/height:1600/1200 17:15:28 INFO - PROCESS | 1963 | colorDepth:24 17:15:28 INFO - PROCESS | 1963 | Browser inner width/height: 1024/689 17:15:28 INFO - PROCESS | 1963 | __metrics 17:15:28 INFO - PROCESS | 1963 | 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" 17:15:28 ERROR - PROCESS | 1963 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 17:15:28 INFO - TEST-INFO | 1963: exit 0 17:15:28 INFO - Browser initialized. 17:15:28 INFO - Running cycle 1/1 for kraken test... 17:15:28 INFO - TEST-INFO | started process 1964 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/tmpzNyrZV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:15:40 INFO - PROCESS | 1964 | RSS: Main: 240996352 17:15:40 INFO - PROCESS | 1964 | 17:15:43 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:50569/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50569/tests/kraken/driver.html?testName=audio-beat-detection) 17:15:44 INFO - PROCESS | 1964 | RSS: Main: 288825344 17:15:44 INFO - PROCESS | 1964 | 17:15:47 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:50569/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50569/tests/kraken/driver.html?testName=audio-dft) 17:15:47 INFO - PROCESS | 1964 | RSS: Main: 297562112 17:15:47 INFO - PROCESS | 1964 | 17:15:51 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:50569/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50569/tests/kraken/driver.html?testName=audio-fft) 17:15:51 INFO - PROCESS | 1964 | RSS: Main: 390393856 17:15:51 INFO - PROCESS | 1964 | 17:15:53 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:50569/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50569/tests/kraken/driver.html?testName=audio-oscillator) 17:15:54 INFO - PROCESS | 1964 | RSS: Main: 286945280 17:15:54 INFO - PROCESS | 1964 | 17:15:56 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:50569/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50569/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:15:57 INFO - PROCESS | 1964 | RSS: Main: 290639872 17:15:57 INFO - PROCESS | 1964 | 17:15:59 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:50569/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50569/tests/kraken/driver.html?testName=imaging-darkroom) 17:16:00 INFO - PROCESS | 1964 | RSS: Main: 327163904 17:16:00 INFO - PROCESS | 1964 | 17:16:03 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:50569/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50569/tests/kraken/driver.html?testName=imaging-desaturate) 17:16:04 INFO - PROCESS | 1964 | RSS: Main: 329011200 17:16:04 INFO - PROCESS | 1964 | 17:16:06 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:50569/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50569/tests/kraken/driver.html?testName=json-parse-financial) 17:16:07 INFO - PROCESS | 1964 | RSS: Main: 330457088 17:16:07 INFO - PROCESS | 1964 | 17:16:09 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:50569/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50569/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:16:09 INFO - PROCESS | 1964 | RSS: Main: 315445248 17:16:09 INFO - PROCESS | 1964 | 17:16:11 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:50569/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50569/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:16:12 INFO - PROCESS | 1964 | RSS: Main: 322338816 17:16:12 INFO - PROCESS | 1964 | 17:16:14 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:50569/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50569/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:16:15 INFO - PROCESS | 1964 | RSS: Main: 326193152 17:16:15 INFO - PROCESS | 1964 | 17:16:17 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:50569/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50569/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:16:18 INFO - PROCESS | 1964 | RSS: Main: 329715712 17:16:18 INFO - PROCESS | 1964 | 17:16:21 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:50569/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50569/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:16:22 INFO - PROCESS | 1964 | RSS: Main: 327196672 17:16:22 INFO - PROCESS | 1964 | 17:16:23 INFO - PROCESS | 1964 | Cycle 1(1): loaded http://localhost:50569/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50569/tests/kraken/driver.html?testName=ai-astar) 17:16:24 INFO - PROCESS | 1964 | RSS: Main: 374206464 17:16:24 INFO - PROCESS | 1964 | 17:16:24 INFO - PROCESS | 1964 | __start_tp_report 17:16:24 INFO - PROCESS | 1964 | _x_x_mozilla_page_load 17:16:24 INFO - PROCESS | 1964 | _x_x_mozilla_page_load_details 17:16:24 INFO - PROCESS | 1964 | |i|pagename|runs| 17:16:24 INFO - PROCESS | 1964 | |0;ai-astar;111;105;111;110;110;110;110;109;110;111 17:16:24 INFO - PROCESS | 1964 | |1;audio-beat-detection;159;120;119;172;120;120;141;120;152;117 17:16:24 INFO - PROCESS | 1964 | |2;audio-dft;186;183;183;183;184;183;180;180;183;184 17:16:24 INFO - PROCESS | 1964 | |3;audio-fft;81;60;74;110;82;70;62;56;100;56 17:16:24 INFO - PROCESS | 1964 | |4;audio-oscillator;95;70;99;70;79;69;97;70;68;101 17:16:24 INFO - PROCESS | 1964 | |5;imaging-gaussian-blur;86;84;87;85;87;83;88;84;86;83 17:16:24 INFO - PROCESS | 1964 | |6;imaging-darkroom;92;94;93;94;93;94;94;94;92;93 17:16:24 INFO - PROCESS | 1964 | |7;imaging-desaturate;84;84;85;83;85;82;83;85;86;86 17:16:24 INFO - PROCESS | 1964 | |8;json-parse-financial;60;59;59;56;57;57;57;78;58;55 17:16:24 INFO - PROCESS | 1964 | |9;json-stringify-tinderbox;65;71;82;76;73;71;69;71;72;71 17:16:24 INFO - PROCESS | 1964 | |10;stanford-crypto-aes;88;86;87;89;86;86;82;86;85;88 17:16:24 INFO - PROCESS | 1964 | |11;stanford-crypto-ccm;141;138;137;139;131;136;140;144;197;142 17:16:24 INFO - PROCESS | 1964 | |12;stanford-crypto-pbkdf2;169;165;168;164;166;166;163;165;162;168 17:16:24 INFO - PROCESS | 1964 | |13;stanford-crypto-sha256-iterative;68;68;68;66;66;65;66;65;74;85 17:16:24 INFO - PROCESS | 1964 | __end_tp_report 17:16:24 INFO - PROCESS | 1964 | __start_cc_report 17:16:24 INFO - PROCESS | 1964 | _x_x_mozilla_cycle_collect,1962 17:16:24 INFO - PROCESS | 1964 | __end_cc_report 17:16:24 INFO - PROCESS | 1964 | __startTimestamp1464221784043__endTimestamp 17:16:24 INFO - PROCESS | 1964 | 17:16:24 INFO - PROCESS | 1964 | ------- Summary: start ------- 17:16:24 INFO - PROCESS | 1964 | Number of tests: 14 17:16:24 INFO - PROCESS | 1964 | 17:16:24 INFO - PROCESS | 1964 | [#0] ai-astar Cycles:10 Average:109.70 Median:110.00 stddev:1.77 (1.6%) stddev-sans-first:1.81 17:16:24 INFO - PROCESS | 1964 | Values: 111.0 105.0 111.0 110.0 110.0 110.0 110.0 109.0 110.0 111.0 17:16:24 INFO - PROCESS | 1964 | 17:16:24 INFO - PROCESS | 1964 | [#1] audio-beat-detection Cycles:10 Average:134.00 Median:130.50 stddev:20.39 (15.6%) stddev-sans-first:19.51 17:16:24 INFO - PROCESS | 1964 | Values: 159.0 120.0 119.0 172.0 120.0 120.0 141.0 120.0 152.0 117.0 17:16:24 INFO - PROCESS | 1964 | 17:16:24 INFO - PROCESS | 1964 | [#2] audio-dft Cycles:10 Average:182.90 Median:183.00 stddev:1.79 (1.0%) stddev-sans-first:1.51 17:16:24 INFO - PROCESS | 1964 | Values: 186.0 183.0 183.0 183.0 184.0 183.0 180.0 180.0 183.0 184.0 17:16:24 INFO - PROCESS | 1964 | 17:16:24 INFO - PROCESS | 1964 | [#3] audio-fft Cycles:10 Average:75.10 Median:66.00 stddev:18.49 (28.0%) stddev-sans-first:19.49 17:16:24 INFO - PROCESS | 1964 | Values: 81.0 60.0 74.0 110.0 82.0 70.0 62.0 56.0 100.0 56.0 17:16:24 INFO - PROCESS | 1964 | 17:16:24 INFO - PROCESS | 1964 | [#4] audio-oscillator Cycles:10 Average:81.80 Median:74.50 stddev:14.34 (19.2%) stddev-sans-first:14.39 17:16:24 INFO - PROCESS | 1964 | Values: 95.0 70.0 99.0 70.0 79.0 69.0 97.0 70.0 68.0 101.0 17:16:24 INFO - PROCESS | 1964 | 17:16:24 INFO - PROCESS | 1964 | [#5] imaging-gaussian-blur Cycles:10 Average:85.30 Median:86.00 stddev:1.77 (2.1%) stddev-sans-first:1.86 17:16:24 INFO - PROCESS | 1964 | Values: 86.0 84.0 87.0 85.0 87.0 83.0 88.0 84.0 86.0 83.0 17:16:24 INFO - PROCESS | 1964 | 17:16:24 INFO - PROCESS | 1964 | [#6] imaging-darkroom Cycles:10 Average:93.30 Median:94.00 stddev:0.82 (0.9%) stddev-sans-first:0.73 17:16:24 INFO - PROCESS | 1964 | Values: 92.0 94.0 93.0 94.0 93.0 94.0 94.0 94.0 92.0 93.0 17:16:24 INFO - PROCESS | 1964 | 17:16:24 INFO - PROCESS | 1964 | [#7] imaging-desaturate Cycles:10 Average:84.30 Median:85.00 stddev:1.34 (1.6%) stddev-sans-first:1.41 17:16:24 INFO - PROCESS | 1964 | Values: 84.0 84.0 85.0 83.0 85.0 82.0 83.0 85.0 86.0 86.0 17:16:24 INFO - PROCESS | 1964 | 17:16:24 INFO - PROCESS | 1964 | [#8] json-parse-financial Cycles:10 Average:59.60 Median:58.50 stddev:6.64 (11.3%) stddev-sans-first:7.04 17:16:24 INFO - PROCESS | 1964 | Values: 60.0 59.0 59.0 56.0 57.0 57.0 57.0 78.0 58.0 55.0 17:16:24 INFO - PROCESS | 1964 | 17:16:24 INFO - PROCESS | 1964 | [#9] json-stringify-tinderbox Cycles:10 Average:72.10 Median:71.50 stddev:4.46 (6.2%) stddev-sans-first:3.92 17:16:24 INFO - PROCESS | 1964 | Values: 65.0 71.0 82.0 76.0 73.0 71.0 69.0 71.0 72.0 71.0 17:16:24 INFO - PROCESS | 1964 | 17:16:24 INFO - PROCESS | 1964 | [#10] stanford-crypto-aes Cycles:10 Average:86.30 Median:86.50 stddev:1.95 (2.3%) stddev-sans-first:1.96 17:16:24 INFO - PROCESS | 1964 | Values: 88.0 86.0 87.0 89.0 86.0 86.0 82.0 86.0 85.0 88.0 17:16:24 INFO - PROCESS | 1964 | 17:16:24 INFO - PROCESS | 1964 | [#11] stanford-crypto-ccm Cycles:10 Average:144.50 Median:140.50 stddev:18.79 (13.4%) stddev-sans-first:19.89 17:16:24 INFO - PROCESS | 1964 | Values: 141.0 138.0 137.0 139.0 131.0 136.0 140.0 144.0 197.0 142.0 17:16:24 INFO - PROCESS | 1964 | 17:16:24 INFO - PROCESS | 1964 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.60 Median:166.00 stddev:2.27 (1.4%) stddev-sans-first:2.05 17:16:24 INFO - PROCESS | 1964 | Values: 169.0 165.0 168.0 164.0 166.0 166.0 163.0 165.0 162.0 168.0 17:16:24 INFO - PROCESS | 1964 | 17:16:24 INFO - PROCESS | 1964 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:68.00 stddev:6.17 (9.1%) stddev-sans-first:6.53 17:16:24 INFO - PROCESS | 1964 | Values: 68.0 68.0 68.0 66.0 66.0 65.0 66.0 65.0 74.0 85.0 17:16:24 INFO - PROCESS | 1964 | -------- Summary: end -------- 17:16:24 INFO - PROCESS | 1964 | 17:16:24 INFO - TEST-INFO | 1964: exit 0 17:16:25 INFO - TEST-OK | kraken | took 58976ms 17:16:25 INFO - SUITE-END | took 1174s 17:16:25 INFO - Completed test suite (00:19:34) 17:16:25 INFO - javascript benchmark 17:16:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3391.8304084795755, 3398.4052790761625, 3414.487826825974, 3419.5554577904877, 3462.2902305027474, 5581.888362232753, 5592.776288948442, 5620.494155526002, 5676.262085928829, 5701.657900525966, 5631.831045068644, 5667.546596272288, 5740.540756739461, 5753.252077229965, 5764.942350576493, 5091.185410334348, 5136.332276804011, 5176.637635365507, 5183.403908550508, 5303.496167864047, 5240.764165612557, 5285.445028272034, 5301.496357845998, 5308.814191503298, 5337.439568845273, 4974.975125124387, 5126.077306084884, 5176.0683077046115, 5178.016176926372, 5204.557612602934, 5013.273075404082, 5119.87200319995, 5137.357830271216, 5141.794328226866, 5142.485751424828, 4985.977874535712, 5155.175171972466, 5160.303359046518, 5186.988537235241, 5262.210668399733, 5300.522952934254, 5468.48049435303, 5564.86087847803, 5626.071698169797, 5693.829185124411, 6115.48018418435, 6120.204373431426, 6129.450483694044, 6175.66033868137, 6188.164597779332, 5519.668819870821, 6232.906506402408, 6252.874942501125, 6278.591891527036, 6280.87438251237, 5640.689762063126, 5827.446392592697, 5831.329397119301, 5835.183074369592, 5840.094785308284, 5182.714950677673, 5295.788168473295, 6229.563930524911, 6269.435750782452, 6269.749210031548, 6147.84630384244, 6156.661383623944, 6167.105769663373, 6169.136320915076, 6176.320604733476, 6137.294211165684, 6197.0, 6256.843578910563, 6281.811545653638, 6294.779682711083, 6101.389861013863, 6109.480694140959, 6123.801220575414, 6137.254412462224, 6152.600080994721, 4783.923617186106, 5771.595988280864, 6191.752329906753, 6195.132681424604, 6221.2845522764555, 4812.494688057774, 4886.120498310338, 4893.070316639827, 4904.411470623548, 4939.086269040167, 4348.739075655408, 4351.06452112791, 4351.543087975717, 4365.672574556921, 4414.646828253732, 5713.571482138773, 5726.348071551925, 5741.397153298927, 5812.59311848175, 5825.970870145622, 6067.332593414721, 6093.025115981422, 6120.908186377208, 6156.168917196211, 6171.9691401542705, 5717.113847353667, 5719.113537401709, 5738.626989245686, 5743.368229494752, 5748.137779333133, 5375.489328513785, 5378.139497680352, 5381.7578208980685, 5387.595930305243, 5400.135978243462, 6233.688315584202, 6260.561760676709, 6265.966115590876, 6278.277998030194, 6317.873642527124, 6134.877302454015, 6174.783882564088, 6186.226721659793, 6203.131561581473, 6206.193194884636, 6359.268684101203, 6395.072714456471, 6403.871922561616, 6406.487481001602, 6407.519436042222, 6048.576599637983, 6093.542984276109, 6106.0, 6115.510759139347, 6140.539459540463, 6144.556029333193, 6168.753249869955, 6169.444749972524, 6191.19514463117, 6207.131001659862, 5435.776950186335, 5890.676012819336, 6097.512199023979, 6118.051701986199, 6119.785807496716, 5866.765329386947, 5946.910796337972, 6027.457528822251, 6040.214772079602, 6078.665673388006, 5925.496332811759, 5945.078512830518, 5955.076963070731, 5986.102084687158, 5990.7004649768205, 6099.11562823397, 6109.511239100772, 6114.844865410725, 6129.912172896396, 6139.640854993005, 5754.05058165398, 5771.047777116729, 5823.272090988627, 5882.764689412375, 5992.340842507408], "value": 5647.566187707658, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [478.67928487913093, 510.12768166435393, 515.6905856486109, 530.2258702294649, 540.3893600231737, 31145.0, 42977.35533966989, 43962.90092747675, 44024.55975440245, 44035.33946990798, 44572.3314150288, 44869.77565112178, 45082.54917450825, 45155.548444515545, 45167.09665806682, 8738.694145704902, 9653.951730241339, 9895.010498950083, 9903.752406189868, 9903.752406189868, 8742.650293988232, 11642.476088575992, 11910.464029118668, 11946.283223006603, 12237.0, 10677.466126693673, 11412.029977451926, 12050.457729402155, 12051.638450846445, 12057.819132713017, 8703.738887833375, 11833.881661183406, 11916.702082448008, 11979.101567382437, 12306.200096993667, 11764.294142351486, 11891.227070240408, 11898.405079745979, 11906.761864762657, 11917.22538035025, 42368.78815605933, 42801.35797963033, 42851.35722964158, 42872.78563607193, 43060.56939430597, 3066.0955018269665, 3096.5819614352, 3205.5351973963643, 3262.3964566555264, 3733.3653278942643, 23758.64362034571, 29373.412531749247, 29651.0, 30064.398712025635, 30314.84842575795, 2676.9866150669122, 2678.8794504247358, 2707.7156898525764, 2785.7632101271415, 2803.0889960762834, 504848.0, 512436.0, 513353.0, 513672.0, 513993.9999999963, 65753.0, 66172.66913665498, 66285.00572491417, 66374.33625663682, 66378.66810666013, 35980.0, 36500.45249321209, 36514.26971460556, 37523.4371484428, 37533.436998445046, 17219.82780172182, 17336.65326693459, 17414.738778918327, 17432.82567174312, 17452.738208926876, 11891.464884080237, 12142.635720928387, 12925.741485170243, 13030.413631386611, 13163.0, 18133.45599632013, 19074.661934161468, 20138.093785779674, 20170.822275864448, 20390.79609203889, 6665.833354166185, 7829.608519573999, 7913.248241417171, 7945.854619252885, 7976.800579985431, 854.9016863060704, 963.802420503795, 966.3863446711248, 996.8953259907751, 1087.6138970665459, 24707.505849883262, 28530.85734571314, 30404.0, 30446.93435729739, 30553.0, 1055.3957983864636, 1067.108964015056, 1072.769354588767, 1166.4984056855633, 1169.6432588060723, 14526.927365363106, 15524.844751552566, 15665.68668626621, 15858.841411585967, 16091.356345746273, 574505.0, 855546.0, 872410.6379468061, 875603.0, 876010.0, 59861.40138598646, 59906.0, 60455.395446045855, 60592.15262778967, 60604.0, 1305.4712841299288, 1347.4946894914408, 1437.058726534122, 1455.2536955653259, 1472.4036765110147, 29918.401631967674, 30618.234544136576, 30683.0, 31522.68477315289, 32337.51493727549, 61235.0, 61854.38145618666, 62188.689056556235, 62234.0, 62383.0, 625352.0, 634511.0, 634995.0, 635308.8234558798, 635760.0, 26767.0, 27694.584581231702, 27736.167914962585, 27751.889924402796, 28554.85722571374, 47178.52821471879, 47970.520294798, 48123.79690507765, 49154.508454916424, 49622.0, 38357.424638631004, 39281.82154535368, 39745.80127099346, 40430.19139617132, 40742.79628601838, 38993.02517436973, 39284.80357598194, 39476.0, 40206.0, 40517.59482405256, 38146.618533814304, 38609.42085868771, 39024.148115056756, 39393.80303098466, 39440.0, 32296.0, 33858.3228335427, 34524.65475345315, 34628.30743385068, 34747.478787817694, 22310.442238944157, 24243.63634545448, 24785.38894971821, 25095.623565646896, 25658.0], "value": 20611.722623165733, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [383.8925100971728, 384.961503849615, 386.9400242962342, 403.6427761431133, 412.59359530862116, 18538.814611853893, 18875.62248755024, 21423.785762142375, 21471.57056858862, 21638.350849474522, 20214.898925505353, 20290.0, 21233.893830530866, 21237.362879113614, 21248.8937555312, 5196.610254230929, 5780.277465316846, 6041.72812223451, 6063.605865618742, 6074.453299203071, 5904.143899134644, 5920.585559010871, 5972.492338151263, 6040.184575082373, 6045.3047899491685, 5732.398098199692, 5918.40815918409, 5928.822135335947, 5932.317783454915, 5933.584649074566, 5714.657120572738, 5893.823185304448, 5910.97044514779, 5916.733746981375, 5921.73352199152, 4995.400551933792, 5159.71621560814, 5180.922286165711, 5188.481151884819, 5189.714565698885, 20387.592248155102, 25078.24765257045, 25440.81989751281, 25728.871355643103, 25840.74159258402, 1806.7922188948307, 2180.618391781432, 2347.5774360615083, 2390.8206884483734, 2583.289595361272, 6803.455723542104, 13292.069555131146, 14177.78733319001, 14640.560783176521, 14822.40710371584, 1587.3333200056004, 1590.7454807230788, 1732.5841797968396, 1789.5168304557697, 1846.2522678315304, 86658.0, 104916.0, 115342.42328788302, 118714.40642796818, 119121.0, 10539.525721342558, 10737.832339459877, 10964.451777411097, 10970.506327215295, 11181.83227251592, 13989.650258743613, 14002.929985350213, 14009.299535023209, 14034.36845341962, 14079.22564258955, 7154.35610795031, 8535.615897284637, 8680.8697869532, 8695.260902823204, 8854.911450885538, 8748.387612867165, 8778.0, 8780.0, 8868.068852770495, 8919.331050171133, 6564.67176641166, 6699.196096468358, 6723.260441351447, 6726.461883049345, 6779.457643388517, 1847.5381154711322, 1872.2230274436229, 1877.11775465531, 1883.4067268810281, 1913.0, 833.966641334352, 902.7968707040864, 947.6114792934864, 1049.1344640671477, 1093.1145771924607, 19279.903600481906, 20405.591888162155, 20543.8972805135, 20909.37271881846, 20949.476263093544, 912.8680436258991, 937.2689302344182, 949.4635530925005, 976.3458482816388, 990.321629683669, 9998.0, 11166.944165279121, 11213.9907408332, 11223.214374993835, 11263.493142808593, 493035.0, 517543.0, 523524.0, 524873.0, 598246.0, 128394.0, 132941.0, 134940.0, 135982.0, 137460.0, 930.5998420679038, 961.2838435365697, 975.6682727872416, 977.2182254196614, 1003.1423133221136, 18022.459326220236, 18193.363232286807, 18330.541736456697, 18339.541511461786, 18346.082695864887, 55065.0, 55604.88790224254, 55867.72066139643, 56716.432835672764, 57038.57403564778, 73915.63042184754, 74816.62591687006, 77724.0, 81910.0, 84461.0, 7591.033210148682, 7943.92056079455, 7994.1606131355375, 8133.8373232536205, 8306.335493160654, 11626.593069242535, 12615.49538018469, 12620.116591838483, 12654.746905062031, 12809.935950320189, 8538.914610853812, 10809.405482698527, 10811.945940270249, 11094.389808560607, 11366.976972072553, 9155.450672959643, 9231.353805233837, 11153.442327883735, 11215.271007384494, 11536.0, 11148.0, 11308.547658093585, 11487.54049837997, 11657.941710291394, 11671.708207294885, 8069.919300807152, 8162.551059691774, 8210.014798224012, 8211.753647390588, 8265.793355166168, 8324.70863519774, 10616.0, 11040.668779936615, 11388.974992250738, 11433.542658293907], "value": 9613.865349783373, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1281.589891234804, 1527.6486408126132, 1560.134125560314, 1788.2131861980727, 1955.9022048897552, 7786.338161256293, 8603.0, 8829.823403531926, 8875.334349923758, 8990.550472476367, 8606.61270242839, 8800.735977920656, 8804.691835785748, 8807.515586642732, 8836.0722124177, 2046.0, 2369.0523790483817, 2406.4224586099385, 2409.4458274596855, 2413.6379543068506, 2194.714687090676, 2237.049254067023, 2354.458474550854, 2373.3354660694945, 2405.3625789165885, 2350.753170917055, 2377.9048838046456, 2386.820988425875, 2389.5340408620327, 2390.605550084234, 2338.918137865184, 2364.9645255321097, 2371.6323969784626, 2375.3705268103968, 2385.6183010718373, 2274.341866669317, 2358.9410264743346, 2366.6331718583647, 2367.337145599235, 2368.194813763329, 7880.605969701549, 8157.102718700998, 8798.780030499225, 8876.490996530605, 8901.198892099743, 4804.447488538828, 5048.7728052237735, 5178.17149256121, 5413.052715774724, 5418.620696551243, 3437.79373237604, 3533.0990597397754, 3582.2119133790784, 3597.2985267872778, 3602.081469225357, 980.5048450532507, 1024.5440778853413, 1027.9897201027968, 1029.608748675506, 1034.4517405774875, 11629.651110466699, 11732.765344693144, 11733.53065877364, 11737.94131029348, 11738.882611173864, 4742.312364707098, 4779.656175979588, 4876.049170411807, 4878.487758785347, 4886.682365646222, 10054.64808731691, 10059.748506287255, 10060.295779295378, 10068.798624027624, 10093.343932644355, 5414.810481633124, 5420.349558053059, 5423.538999185034, 5429.809956651498, 5449.782008719607, 4786.712797232177, 4879.634027447955, 4949.059678661043, 4966.776495057731, 4977.9751101244265, 3321.2527181383994, 3775.5846856845724, 3807.200487897517, 3901.902452438655, 3908.5700572936953, 1064.6220591689994, 1072.7961687279392, 1079.1636481726725, 1081.734974931147, 1200.5257923120319, 810.5379933438002, 872.646578135856, 879.8900137482815, 898.595631965618, 920.7882187097006, 5635.633683810539, 5642.71786410686, 5739.540836733051, 5769.653820770767, 5902.409759024149, 431.98704038878884, 438.39939283182247, 441.54520843531213, 453.33133627899247, 455.22384334709244, 3792.488014118114, 3849.8117693679083, 3859.7684138951754, 3946.8223929922647, 3951.7233793634746, 9066.501342426098, 10119.7470063249, 10326.432046237378, 10805.891941080488, 10819.729506762395, 10637.202209834293, 10746.731331716614, 10761.354318740901, 10769.0, 10791.352518848737, 7943.404244681672, 8118.75643730689, 8243.464174828618, 8249.8350032999, 8278.751637450887, 5574.91637625444, 5655.406182350794, 5711.771529138954, 5785.161151632906, 5939.792107276225, 9417.764555885884, 9829.901700982899, 9867.654632087842, 10012.899871001488, 10015.0, 9804.607815687292, 10082.899171008195, 10124.69625911224, 10180.796384072426, 10227.335223210468, 6608.405243527914, 6863.965680171567, 6930.0, 7248.96375518119, 7352.522086064602, 8037.156098609562, 8202.794930126556, 8211.630476628447, 8216.301614362606, 8233.17668233172, 6623.238327592196, 7017.087778588751, 7106.076210092655, 7355.448341374526, 7430.628468576658, 7183.245759195418, 7258.782236532912, 7290.088738907637, 7427.2201418850245, 7430.517016393917, 5874.530037597067, 6974.860502790018, 6999.580025198505, 7009.738247115364, 7025.402840758593, 4047.332190188586, 4674.252119660838, 4713.764311784328, 4747.813046738315, 5089.338386009943, 4815.133276010211, 4981.053599816023, 4998.700077995332, 5013.548780609763, 5177.8187763428095], "value": 4426.7029435546365, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [308.7483700783861, 312.8498320806013, 314.8252719740543, 319.9648038715741, 325.19514202349205, 4399.604035636792, 4924.818043669516, 5280.877548777685, 5300.655457395276, 5374.7043912584795, 4351.651867850573, 4973.104841128605, 5207.7135757533415, 5252.264682944381, 5254.0332049302815, 3053.3435311408043, 3747.2318174774105, 3954.861579844706, 4045.615666511677, 4072.9796351018203, 3471.75697701161, 3928.057266256092, 3982.641562259396, 3994.720369574131, 4025.376066709665, 3329.6836800503916, 3782.9621703782886, 3932.7640341579454, 3974.1122966590783, 3997.3804060370544, 3638.6543278388776, 3767.9638099522576, 3837.443570682249, 3986.940195897064, 4010.9398359024344, 3462.272922686239, 3491.2493813830147, 3492.4761285807076, 3497.9825100874586, 3499.4575840744724, 4948.851534453972, 4950.8267210647455, 4961.305417241589, 4966.726830024347, 4970.6520543561965, 4323.654107671378, 4389.758563279019, 4444.622207112399, 4529.4791099023705, 4577.33628623851, 4754.286856971447, 5150.0, 5372.7313634318125, 5383.43473935239, 5386.78452861885, 3687.3547129252274, 3813.0848596336677, 3817.2747178036084, 3821.700621788605, 3855.248226595815, 4982.352294201767, 5128.564072053842, 5259.579233661298, 5653.717314134276, 5719.857003574903, 3430.8970730878113, 3837.846486140523, 3878.9612103879163, 3883.126296583246, 3894.552126505488, 3070.2017475456096, 3929.410588411704, 3931.331673615435, 3954.347532657137, 4033.4353190553347, 4490.146872094291, 4531.456225252925, 4558.475775285885, 4563.726176429424, 4580.404547408815, 5174.8965020700125, 5304.628675992643, 5529.921665275313, 5557.3886872444, 5563.749631266603, 1155.7168493719093, 1317.631063302277, 1323.4639970811836, 1325.6818363592668, 1326.8257592031123, 683.1255992329826, 748.8916403722521, 810.8121601853313, 830.1032141556515, 851.0638297872291, 4437.689361744647, 4518.615917646971, 4713.0, 5082.246624914399, 5139.203423469368, 4755.881102972384, 4939.777710002986, 5623.0, 5628.943710562842, 5635.774569017275, 4488.349189367524, 4769.046190761792, 4895.510448955147, 5094.643374963717, 5101.464346243665, 2734.9726502735116, 2769.7507224349906, 2933.603963464948, 2938.5298352263962, 2939.3392733105725, 5806.983777838861, 5836.643929740416, 5843.5909486336395, 5862.882742345129, 5867.647941123546, 5125.513076257749, 5396.3524377075, 5419.34967803866, 5754.5971781975695, 5782.624129431658, 5775.740091695884, 5791.797287094932, 5814.244148260784, 5872.647641141545, 5909.6158749681135, 4388.0, 4715.03341814934, 5014.323066385991, 5607.915881261702, 5648.265725455664, 5061.861081256688, 5476.561875050066, 5586.486081263529, 5616.775328986795, 5620.85947851307, 4597.954020459886, 5203.791848326025, 5832.708364581838, 5836.970815145897, 5839.912401313899, 3256.674332566819, 3554.7118915004035, 3781.8865434037025, 3802.319364847831, 3816.4656948026745, 5173.7671804769625, 5203.323567936144, 5240.318758561362, 5608.579356548194, 5611.326640802967, 4868.683535570177, 5015.824446144367, 5446.509814116748, 5449.700266485382, 5463.093887870192, 4750.762461876823, 5405.270288511001, 5437.4562543745305, 5457.871430142327, 5472.671639701631, 5294.258803767399, 5332.173513105475, 5337.0, 5350.732463376893, 5351.143816989263, 4303.569643035671, 5012.47369026247, 5121.207577347898, 5299.655522391032, 5304.628675992643, 4468.173387923114, 4574.862754117382, 4933.358663373739, 5128.999845030333, 5350.8127215547265], "value": 3969.841819963543, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [609.8444896551379, 629.1223742878688, 638.1162090504652, 670.4200866250694, 678.5080816408105, 14984.325705343259, 15565.0, 15576.610584735374, 15583.688326233498, 15597.610059748513, 14143.858561414409, 14198.077124986869, 14617.342219600117, 14644.341004654789, 14684.779728304084, 901.2699713232287, 908.3641450984304, 918.5085979001236, 918.9081091890824, 919.204887772079, 721.0950257426922, 741.314284287035, 873.9126087391242, 904.1500989070286, 906.4380084347714, 962.3600305796637, 963.2631037256505, 968.5738275158908, 970.4032020307495, 973.095021629884, 754.8754455514834, 960.3133759362055, 962.6726912849595, 963.3930623707055, 964.7588102974256, 692.7332976803916, 713.2154629907112, 713.4185638704439, 713.9750108746169, 714.0503130835975, 14710.779338309934, 14737.926310368486, 14909.925450372788, 14975.026623269561, 15017.399304027826, 11945.820812687816, 12156.756864862742, 12189.451474683661, 12599.05507086963, 12734.363281835871, 10296.124829389437, 10804.297720648132, 11178.27341222818, 11320.830187547193, 11337.206395552315, 3969.0077480629843, 4044.211378781139, 4059.6955228357697, 4060.350343944984, 4379.5182529921685, 73833.26166738318, 76012.61993690052, 76101.61949190273, 84370.57814710948, 84427.0, 9242.86135707965, 9349.39228950116, 9365.906340936503, 9418.811623767486, 9753.317267791328, 20916.37250882476, 21039.368818935458, 21082.0, 21083.578328433345, 21089.0, 7404.444666650023, 7473.51422157558, 7480.139783924918, 7565.546067235983, 7657.234276572298, 3397.592288925295, 3593.5328407306883, 3960.2871483133313, 4065.8576949806616, 4073.877783666495, 3536.1159710072484, 3633.8364773585254, 3643.3624115780162, 3643.927121457556, 3643.9635603644156, 635.139386131794, 643.8358218654307, 651.4202359899693, 665.0921492163162, 672.8452455935064, 462.6345187302061, 579.823153938053, 591.6952769323801, 597.5488506177808, 605.4974371271834, 7685.500442471333, 7968.641411136513, 7995.560244186509, 8098.635561399751, 8121.309688676411, 541.6565580547945, 566.2164182824137, 587.6415386614162, 593.456986857021, 604.9546284028715, 3689.575698794673, 3704.203596226824, 3816.255830113101, 3818.694504439638, 3822.464854920258, 92215.61676574856, 92815.0, 93593.53203234076, 93653.0, 94334.58498122534, 59899.0, 72886.0, 76284.61857690677, 81303.5934820334, 84478.15521844826, 50795.492045079074, 59000.9349672768, 66369.0, 67657.66171169112, 70170.0, 7776.377889768692, 7992.600369981361, 8072.4349295548745, 8143.5928203590765, 8226.0540037894, 34189.29053547362, 45054.54945450504, 45400.77299613481, 45531.54468455273, 45719.0, 61930.380696192464, 62069.37930620636, 63091.684541577, 63493.682531587045, 63772.7245455079, 10629.377552283133, 10656.840147397641, 10682.946585267024, 11044.447777611247, 11130.610428634958, 11303.604373846876, 11712.180147389601, 11810.822837657613, 11858.288502689866, 12325.938370308091, 11099.0, 11145.721356965816, 11212.0, 11258.66224013281, 11522.82715759281, 10490.213234007328, 10606.09848162884, 10921.071708904832, 11115.333080015225, 11715.824262635897, 10823.567057317934, 10891.346519208873, 10903.945480272549, 11095.056920161876, 12130.818037729618, 9117.635294588408, 9436.339456237998, 9571.569279382305, 9609.9039009609, 9810.548078643444, 9172.908270917473, 9477.857832132662, 9503.144716975505, 9524.283241479467, 9530.571124299562], "value": 6796.728104466361, "unit": "score"}], "name": "dromaeo_css", "value": 7150.661330928695, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 105.0, 111.0, 110.0, 110.0, 110.0, 110.0, 109.0, 110.0, 111.0], "value": 109.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 120.0, 119.0, 172.0, 120.0, 120.0, 141.0, 120.0, 152.0, 117.0], "value": 134.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 183.0, 183.0, 183.0, 184.0, 183.0, 180.0, 180.0, 183.0, 184.0], "value": 182.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 74.0, 110.0, 82.0, 70.0, 62.0, 56.0, 100.0, 56.0], "value": 75.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 70.0, 99.0, 70.0, 79.0, 69.0, 97.0, 70.0, 68.0, 101.0], "value": 81.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 87.0, 85.0, 87.0, 83.0, 88.0, 84.0, 86.0, 83.0], "value": 85.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 94.0, 93.0, 94.0, 93.0, 94.0, 94.0, 94.0, 92.0, 93.0], "value": 93.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 85.0, 83.0, 85.0, 82.0, 83.0, 85.0, 86.0, 86.0], "value": 84.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 59.0, 56.0, 57.0, 57.0, 57.0, 78.0, 58.0, 55.0], "value": 59.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 71.0, 82.0, 76.0, 73.0, 71.0, 69.0, 71.0, 72.0, 71.0], "value": 72.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 87.0, 89.0, 86.0, 86.0, 82.0, 86.0, 85.0, 88.0], "value": 86.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 138.0, 137.0, 139.0, 131.0, 136.0, 140.0, 144.0, 197.0, 142.0], "value": 144.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 165.0, 168.0, 164.0, 166.0, 166.0, 163.0, 165.0, 162.0, 168.0], "value": 165.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 66.0, 66.0, 65.0, 66.0, 65.0, 74.0, 85.0], "value": 69.1, "unit": "score"}], "name": "kraken", "value": 1443.5999999999997, "alertThreshold": 2.0}]} 17:16:25 INFO - Return code: 0 17:16:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 17:16:25 ERROR - # TBPL SUCCESS # 17:16:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:16:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:16:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:16:25 INFO - 2016-05-25 17:16:25.314 screenresolution[1968:63721] starting screenresolution argv=screenresolution get 17:16:25 INFO - 2016-05-25 17:16:25.317 screenresolution[1968:63721] Display 0: 1600x1200x32@60 17:16:25 INFO - 2016-05-25 17:16:25.324 screenresolution[1969:63724] starting screenresolution argv=screenresolution list 17:16:25 INFO - Available Modes on Display 0 17:16:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:16:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:16:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:16:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:16:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:16:25 INFO - Intel Iris: 17:16:25 INFO - Chipset Model: Intel Iris 17:16:25 INFO - Type: GPU 17:16:25 INFO - Bus: Built-In 17:16:25 INFO - VRAM (Dynamic, Max): 1536 MB 17:16:25 INFO - Vendor: Intel (0x8086) 17:16:25 INFO - Device ID: 0x0a2e 17:16:25 INFO - Revision ID: 0x0009 17:16:25 INFO - Displays: 17:16:25 INFO - Display: 17:16:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:16:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:16:25 INFO - Display Serial Number: 1600x1200 60 17:16:25 INFO - Main Display: Yes 17:16:25 INFO - Mirror: Off 17:16:25 INFO - Online: Yes 17:16:25 INFO - Rotation: Supported 17:16:25 INFO - Return code: 0 17:16:25 INFO - Running post-action listener: _resource_record_post_action 17:16:25 INFO - ##### 17:16:25 INFO - ##### Finished run-tests step (success) 17:16:25 INFO - ##### 17:16:25 INFO - Running post-run listener: _resource_record_post_run 17:16:25 INFO - Total resource usage - Wall time: 1210s; CPU: 53.0%; Read bytes: 0; Write bytes: 695109120; Read time: 0; Write time: 1893 17:16:25 INFO - TinderboxPrint: CPU usage
53.5% 17:16:25 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 17:16:25 INFO - TinderboxPrint: I/O write bytes / time
695,109,120 / 1,893 17:16:25 INFO - TinderboxPrint: CPU idle
2,251.4 (46.5%) 17:16:25 INFO - TinderboxPrint: CPU system
965.2 (19.9%) 17:16:25 INFO - TinderboxPrint: CPU user
1,625.0 (33.6%) 17:16:25 INFO - TinderboxPrint: Swap in / out
396,500,992 / 0 17:16:25 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 397869568; Write bytes: 400004608; Read time: 29909; Write time: 815 17:16:25 INFO - run-tests - Wall time: 1177s; CPU: 53.0%; Read bytes: 0; Write bytes: 294088704; Read time: 0; Write time: 1072 17:16:26 INFO - Running post-run listener: _upload_blobber_files 17:16:26 INFO - Blob upload gear active. 17:16:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:16:26 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 17:16:26 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'] 17:16:26 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 17:16:26 INFO - (blobuploader) - INFO - Open directory for files ... 17:16:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 17:16:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:16:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:16:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 17:16:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:16:27 INFO - (blobuploader) - INFO - Done attempting. 17:16:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 17:16:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:16:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:16:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 17:16:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:16:28 INFO - (blobuploader) - INFO - Done attempting. 17:16:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:16:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:16:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:16:31 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:16:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:16:31 INFO - (blobuploader) - INFO - Done attempting. 17:16:31 INFO - (blobuploader) - INFO - Iteration through files over. 17:16:31 INFO - Return code: 0 17:16:31 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:16:31 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:16:31 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/25fba840c122ae789b7b00ab1685f43eb060ffab4cad886285c6c3bc1960e00710fbc534e57aa401b27fe5201b1b6eca4f860575a90a041882b037d1f9b7d8cc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5933b8ab87cfd7ba6ab129f381930aa5482e3784396634bc1eb500c00d12474842502b8168d2e2876bbb348754181b3b2d6f4140ae119972a520e5040f18dfb0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9d4ad3437c3d3cc7a5f78bb8bc05171935a0107b085e9c12cfd484004ddd19f48e25beacfade085941ce47b9d0bb11ed70937dba4533673d267b8ab60ea199e3"} 17:16:31 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:16:31 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:16:31 INFO - Contents: 17:16:31 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/25fba840c122ae789b7b00ab1685f43eb060ffab4cad886285c6c3bc1960e00710fbc534e57aa401b27fe5201b1b6eca4f860575a90a041882b037d1f9b7d8cc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5933b8ab87cfd7ba6ab129f381930aa5482e3784396634bc1eb500c00d12474842502b8168d2e2876bbb348754181b3b2d6f4140ae119972a520e5040f18dfb0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9d4ad3437c3d3cc7a5f78bb8bc05171935a0107b085e9c12cfd484004ddd19f48e25beacfade085941ce47b9d0bb11ed70937dba4533673d267b8ab60ea199e3"} 17:16:31 INFO - Running post-run listener: copy_logs_to_upload_dir 17:16:31 INFO - Copying logs to upload dir... 17:16:31 INFO - mkdir: /builds/slave/test/build/upload/logs 17:16:31 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1268.658435 ========= master_lag: 0.10 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 8 secs) (at 2016-05-25 17:16:31.824194) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-25 17:16:31.828756) ========= 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.VI4smXMnM3/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.5NcLNPCbX6/Listeners TMPDIR=/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/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/25fba840c122ae789b7b00ab1685f43eb060ffab4cad886285c6c3bc1960e00710fbc534e57aa401b27fe5201b1b6eca4f860575a90a041882b037d1f9b7d8cc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5933b8ab87cfd7ba6ab129f381930aa5482e3784396634bc1eb500c00d12474842502b8168d2e2876bbb348754181b3b2d6f4140ae119972a520e5040f18dfb0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9d4ad3437c3d3cc7a5f78bb8bc05171935a0107b085e9c12cfd484004ddd19f48e25beacfade085941ce47b9d0bb11ed70937dba4533673d267b8ab60ea199e3"} build_url:https://queue.taskcluster.net/v1/task/P2ZJxbWwS2mfRkUzMacv4A/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011110 build_url: 'https://queue.taskcluster.net/v1/task/P2ZJxbWwS2mfRkUzMacv4A/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/25fba840c122ae789b7b00ab1685f43eb060ffab4cad886285c6c3bc1960e00710fbc534e57aa401b27fe5201b1b6eca4f860575a90a041882b037d1f9b7d8cc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5933b8ab87cfd7ba6ab129f381930aa5482e3784396634bc1eb500c00d12474842502b8168d2e2876bbb348754181b3b2d6f4140ae119972a520e5040f18dfb0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9d4ad3437c3d3cc7a5f78bb8bc05171935a0107b085e9c12cfd484004ddd19f48e25beacfade085941ce47b9d0bb11ed70937dba4533673d267b8ab60ea199e3"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-25 17:16:31.865839) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-05-25 17:16:31.866140) ========= 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.VI4smXMnM3/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.5NcLNPCbX6/Listeners TMPDIR=/var/folders/rl/6cxjq2sd5dg7sqq1wv3b47pm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.005225 ========= master_lag: 2.47 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 2 secs) (at 2016-05-25 17:16:34.337018) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-25 17:16:34.337355) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-25 17:16:34.340324) ========= ========= Total master_lag: 2.67 =========