builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0366 starttime: 1464032832.71 results: success (0) buildid: 20160523104644 builduid: e391aba6d6ce4534b2a2ef0515f5e336 revision: f8689bbe3b5f819302c43ecf24ea5ff15c57b8c5 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-23 12:47:12.707747) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-23 12:47:12.708159) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-23 12:47:12.708427) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-23 12:47:12.773734) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 12:47:12.774039) ========= 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.0FByRzYdkn/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.pkNtMGmDKa/Listeners TMPDIR=/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/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-23 12:47:12-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 41.1M=0s 2016-05-23 12:47:13 (41.1 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.566068 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 12:47:13.359042) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 12:47:13.359380) ========= 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.0FByRzYdkn/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.pkNtMGmDKa/Listeners TMPDIR=/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/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.025773 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 12:47:13.407491) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-23 12:47:13.407823) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev f8689bbe3b5f819302c43ecf24ea5ff15c57b8c5 --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 f8689bbe3b5f819302c43ecf24ea5ff15c57b8c5 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0FByRzYdkn/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.pkNtMGmDKa/Listeners TMPDIR=/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/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-23 12:47:13,490 truncating revision to first 12 chars 2016-05-23 12:47:13,490 Setting DEBUG logging. 2016-05-23 12:47:13,490 attempt 1/10 2016-05-23 12:47:13,490 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/f8689bbe3b5f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-23 12:47:14,642 unpacking tar archive at: fx-team-f8689bbe3b5f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.432643 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-23 12:47:14.858683) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-23 12:47:14.858984) ========= script_repo_revision: f8689bbe3b5f819302c43ecf24ea5ff15c57b8c5 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-23 12:47:14.859373) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-23 12:47:14.859642) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-23 12:47:14.874518) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2016-05-23 12:47:14.874908) ========= /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.0FByRzYdkn/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.pkNtMGmDKa/Listeners TMPDIR=/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/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 12:47:15 INFO - MultiFileLogger online at 20160523 12:47:15 in /builds/slave/test 12:47:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 12:47:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:47:15 INFO - {'append_to_log': False, 12:47:15 INFO - 'base_work_dir': '/builds/slave/test', 12:47:15 INFO - 'blob_upload_branch': 'Fx-Team', 12:47:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:47:15 INFO - 'branch': 'Fx-Team', 12:47:15 INFO - 'buildbot_json_path': 'buildprops.json', 12:47:15 INFO - 'config_files': ('talos/mac_config.py',), 12:47:15 INFO - 'default_actions': ('clobber', 12:47:15 INFO - 'read-buildbot-config', 12:47:15 INFO - 'download-and-extract', 12:47:15 INFO - 'populate-webroot', 12:47:15 INFO - 'create-virtualenv', 12:47:15 INFO - 'install', 12:47:15 INFO - 'run-tests'), 12:47:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:47:15 INFO - 'download_minidump_stackwalk': True, 12:47:15 INFO - 'download_symbols': 'ondemand', 12:47:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:47:15 INFO - 'tooltool.py': '/tools/tooltool.py', 12:47:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:47:15 INFO - '/tools/misc-python/virtualenv.py')}, 12:47:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:47:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:47:15 INFO - 'installer_path': 'installer.exe', 12:47:15 INFO - 'log_level': 'info', 12:47:15 INFO - 'log_name': 'talos', 12:47:15 INFO - 'log_to_console': True, 12:47:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:47:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:47:15 INFO - 'opt_config_files': (), 12:47:15 INFO - 'pip_index': False, 12:47:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:47:15 INFO - 'cmd': ('bash', 12:47:15 INFO - '-c', 12:47:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:47:15 INFO - 'enabled': True, 12:47:15 INFO - 'halt_on_failure': False, 12:47:15 INFO - 'name': 'check_screen_resolution'},), 12:47:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:47:15 INFO - 'cmd': ('bash', 12:47:15 INFO - '-c', 12:47:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:47:15 INFO - 'enabled': True, 12:47:15 INFO - 'halt_on_failure': False, 12:47:15 INFO - 'name': 'check_screen_resolution'},), 12:47:15 INFO - 'run_cmd_checks_enabled': True, 12:47:15 INFO - 'sps_profile': False, 12:47:15 INFO - 'sps_profile_interval': 0, 12:47:15 INFO - 'suite': 'dromaeojs', 12:47:15 INFO - 'system_bits': '32', 12:47:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:47:15 INFO - 'title': 't-yosemite-r7-0366', 12:47:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:47:15 INFO - 'use_talos_json': True, 12:47:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:47:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:47:15 INFO - 'work_dir': 'build'} 12:47:15 INFO - ##### 12:47:15 INFO - ##### Running clobber step. 12:47:15 INFO - ##### 12:47:15 INFO - Running pre-action listener: _resource_record_pre_action 12:47:15 INFO - Running main action method: clobber 12:47:15 INFO - rmtree: /builds/slave/test/build 12:47:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:47:16 INFO - Running post-action listener: _resource_record_post_action 12:47:16 INFO - ##### 12:47:16 INFO - ##### Finished clobber step (success) 12:47:16 INFO - ##### 12:47:16 INFO - ##### 12:47:16 INFO - ##### Running read-buildbot-config step. 12:47:16 INFO - ##### 12:47:16 INFO - Running pre-action listener: _resource_record_pre_action 12:47:16 INFO - Running main action method: read_buildbot_config 12:47:16 INFO - Using buildbot properties: 12:47:16 INFO - { 12:47:16 INFO - "project": "", 12:47:16 INFO - "product": "firefox", 12:47:16 INFO - "script_repo_revision": "production", 12:47:16 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 12:47:16 INFO - "repository": "", 12:47:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 12:47:16 INFO - "buildid": "20160523104644", 12:47:16 INFO - "pgo_build": "False", 12:47:16 INFO - "basedir": "/builds/slave/test", 12:47:16 INFO - "buildnumber": 35, 12:47:16 INFO - "slavename": "t-yosemite-r7-0366", 12:47:16 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 12:47:16 INFO - "platform": "yosemite_r7", 12:47:16 INFO - "scheduler": "tests-fx-team-macosx64-talos", 12:47:16 INFO - "branch": "fx-team", 12:47:16 INFO - "repo_path": "integration/fx-team", 12:47:16 INFO - "revision": "f8689bbe3b5f819302c43ecf24ea5ff15c57b8c5", 12:47:16 INFO - "stage_platform": "macosx64", 12:47:16 INFO - "builduid": "e391aba6d6ce4534b2a2ef0515f5e336", 12:47:16 INFO - "slavebuilddir": "test" 12:47:16 INFO - } 12:47:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/GC9SgidCQm-qxbCSWPyNww/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 12:47:16 INFO - Running post-action listener: _resource_record_post_action 12:47:16 INFO - ##### 12:47:16 INFO - ##### Finished read-buildbot-config step (success) 12:47:16 INFO - ##### 12:47:16 INFO - ##### 12:47:16 INFO - ##### Running download-and-extract step. 12:47:16 INFO - ##### 12:47:16 INFO - Running pre-action listener: _resource_record_pre_action 12:47:16 INFO - Running main action method: download_and_extract 12:47:16 INFO - mkdir: /builds/slave/test/build/tests 12:47:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:47:16 INFO - https://queue.taskcluster.net/v1/task/GC9SgidCQm-qxbCSWPyNww/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 12:47:16 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GC9SgidCQm-qxbCSWPyNww/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 12:47:16 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GC9SgidCQm-qxbCSWPyNww/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 12:47:16 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GC9SgidCQm-qxbCSWPyNww/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 12:47:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GC9SgidCQm-qxbCSWPyNww/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 12:47:25 INFO - Downloaded 1199 bytes. 12:47:25 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 12:47:25 INFO - Using the following test package requirements: 12:47:25 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 12:47:25 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:47:25 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 12:47:25 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:47:25 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 12:47:25 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:47:25 INFO - u'jsshell-mac.zip'], 12:47:25 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:47:25 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 12:47:25 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 12:47:25 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:47:25 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 12:47:25 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:47:25 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 12:47:25 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:47:25 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 12:47:25 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 12:47:25 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 12:47:25 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:47: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')]} 12:47:25 INFO - https://queue.taskcluster.net/v1/task/GC9SgidCQm-qxbCSWPyNww/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:47:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GC9SgidCQm-qxbCSWPyNww/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 12:47:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GC9SgidCQm-qxbCSWPyNww/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 12:47:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GC9SgidCQm-qxbCSWPyNww/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 12:47:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GC9SgidCQm-qxbCSWPyNww/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 12:47:31 INFO - Downloaded 18630554 bytes. 12:47:31 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'] 12:47:31 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 12:47:34 INFO - Return code: 0 12:47:34 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 12:47:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:47:34 INFO - https://queue.taskcluster.net/v1/task/GC9SgidCQm-qxbCSWPyNww/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 12:47:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GC9SgidCQm-qxbCSWPyNww/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 12:47:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GC9SgidCQm-qxbCSWPyNww/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 12:47:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GC9SgidCQm-qxbCSWPyNww/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 12:47:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GC9SgidCQm-qxbCSWPyNww/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 12:47:37 INFO - Downloaded 18630554 bytes. 12:47:37 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 12:47:37 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 12:47:40 INFO - Return code: 0 12:47:40 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')]} 12:47:40 INFO - https://queue.taskcluster.net/v1/task/GC9SgidCQm-qxbCSWPyNww/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 12:47:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GC9SgidCQm-qxbCSWPyNww/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 12:47:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GC9SgidCQm-qxbCSWPyNww/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 12:47:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GC9SgidCQm-qxbCSWPyNww/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 12:47:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GC9SgidCQm-qxbCSWPyNww/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 12:47:43 INFO - Downloaded 10840359 bytes. 12:47:43 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'] 12:47:43 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 12:47:43 INFO - Return code: 0 12:47:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:47:43 INFO - https://queue.taskcluster.net/v1/task/GC9SgidCQm-qxbCSWPyNww/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 12:47:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GC9SgidCQm-qxbCSWPyNww/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 12:47:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GC9SgidCQm-qxbCSWPyNww/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 12:47:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GC9SgidCQm-qxbCSWPyNww/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 12:47:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GC9SgidCQm-qxbCSWPyNww/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 12:47:46 INFO - Downloaded 110158258 bytes. 12:47:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GC9SgidCQm-qxbCSWPyNww/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 12:47:46 INFO - mkdir: /builds/slave/test/properties 12:47:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:47:46 INFO - Writing to file /builds/slave/test/properties/build_url 12:47:46 INFO - Contents: 12:47:46 INFO - build_url:https://queue.taskcluster.net/v1/task/GC9SgidCQm-qxbCSWPyNww/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 12:47:47 INFO - Running post-action listener: _resource_record_post_action 12:47:47 INFO - Running post-action listener: set_extra_try_arguments 12:47:47 INFO - ##### 12:47:47 INFO - ##### Finished download-and-extract step (success) 12:47:47 INFO - ##### 12:47:47 INFO - ##### 12:47:47 INFO - ##### Running populate-webroot step. 12:47:47 INFO - ##### 12:47:47 INFO - Running pre-action listener: _resource_record_pre_action 12:47:47 INFO - Running main action method: populate_webroot 12:47:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:47:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:47:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:47:47 INFO - '--tppagecycles', 12:47:47 INFO - '7'], 12:47:47 INFO - 'tests': ['tsvgm']}}, 12:47:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:47:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:47:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:47:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:47:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:47:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:47:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:47:47 INFO - 'talos_options': ['--disable-e10s'], 12:47:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:47:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:47:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:47:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:47:47 INFO - 'talos_options': ['--disable-e10s'], 12:47:47 INFO - 'tests': ['damp', 'tps']}, 12:47:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:47:47 INFO - 'tests': ['damp', 'tps']}, 12:47:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:47:47 INFO - 'tests': ['dromaeo_dom']}, 12:47:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:47:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:47:47 INFO - 'tests': ['a11yr', 12:47:47 INFO - 'ts_paint', 12:47:47 INFO - 'tpaint', 12:47:47 INFO - 'sessionrestore', 12:47:47 INFO - 'sessionrestore_no_auto_restore', 12:47:47 INFO - 'tabpaint']}, 12:47:47 INFO - 'other-e10s': {'tests': ['a11yr', 12:47:47 INFO - 'ts_paint', 12:47:47 INFO - 'tpaint', 12:47:47 INFO - 'sessionrestore', 12:47:47 INFO - 'sessionrestore_no_auto_restore', 12:47:47 INFO - 'tabpaint']}, 12:47:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:47:47 INFO - 'ts_paint', 12:47:47 INFO - 'tpaint', 12:47:47 INFO - 'sessionrestore', 12:47:47 INFO - 'sessionrestore_no_auto_restore', 12:47:47 INFO - 'tabpaint']}, 12:47:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:47:47 INFO - 'ts_paint', 12:47:47 INFO - 'tpaint', 12:47:47 INFO - 'sessionrestore', 12:47:47 INFO - 'sessionrestore_no_auto_restore', 12:47:47 INFO - 'tabpaint']}, 12:47:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:47:47 INFO - 'tests': ['a11yr', 12:47:47 INFO - 'ts_paint', 12:47:47 INFO - 'tpaint', 12:47:47 INFO - 'sessionrestore', 12:47:47 INFO - 'sessionrestore_no_auto_restore', 12:47:47 INFO - 'tabpaint']}, 12:47:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:47:47 INFO - 'tests': ['a11yr', 12:47:47 INFO - 'ts_paint', 12:47:47 INFO - 'tpaint', 12:47:47 INFO - 'sessionrestore', 12:47:47 INFO - 'sessionrestore_no_auto_restore', 12:47:47 INFO - 'tabpaint']}, 12:47:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:47:47 INFO - 'tests': ['tsvgx', 12:47:47 INFO - 'tsvgr_opacity', 12:47:47 INFO - 'tart', 12:47:47 INFO - 'tscrollx', 12:47:47 INFO - 'cart']}, 12:47:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:47:47 INFO - 'tsvgr_opacity', 12:47:47 INFO - 'tart', 12:47:47 INFO - 'tscrollx', 12:47:47 INFO - 'cart']}, 12:47:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:47:47 INFO - 'talos_options': ['--disable-e10s'], 12:47:47 INFO - 'tests': ['tp5o']}, 12:47:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:47:47 INFO - 'tests': ['tp5o']}, 12:47:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:47:47 INFO - 'talos_options': ['--disable-e10s', 12:47:47 INFO - '--xperf_path', 12:47:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:47:47 INFO - 'tests': ['tp5n']}, 12:47:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:47:47 INFO - 'talos_options': ['--xperf_path', 12:47:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:47:47 INFO - 'tests': ['tp5n']}}, 12:47:47 INFO - 'talos.zip': {'path': '', 12:47:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:47:47 INFO - Running post-action listener: _resource_record_post_action 12:47:47 INFO - ##### 12:47:47 INFO - ##### Finished populate-webroot step (success) 12:47:47 INFO - ##### 12:47:47 INFO - ##### 12:47:47 INFO - ##### Running create-virtualenv step. 12:47:47 INFO - ##### 12:47:47 INFO - Running pre-action listener: _resource_record_pre_action 12:47:47 INFO - Running main action method: create_virtualenv 12:47:47 INFO - Creating virtualenv /builds/slave/test/build/venv 12:47:47 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:47:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:47:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:47:47 INFO - Using real prefix '/tools/python27' 12:47:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:47:48 INFO - Installing distribute.............................................................................................................................................................................................done. 12:47:51 INFO - Installing pip.................done. 12:47:51 INFO - Return code: 0 12:47:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:47:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:47:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:47:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:47:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:47:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:47:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:47:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1018c4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018ca570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98a95bd3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101768d30>, '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 0x10158cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10175fb20>, '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.0FByRzYdkn/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.pkNtMGmDKa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/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 12:47:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:47:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:47:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0FByRzYdkn/Render', 12:47:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:47:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:47:51 INFO - 'HOME': '/Users/cltbld', 12:47:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:47:51 INFO - 'LOGNAME': 'cltbld', 12:47:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:47:51 INFO - 'MOZ_NO_REMOTE': '1', 12:47:51 INFO - 'NO_EM_RESTART': '1', 12:47:51 INFO - 'PAGER': '/bin/cat', 12:47:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:47:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:47:51 INFO - 'PWD': '/builds/slave/test', 12:47:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:47:51 INFO - 'SHELL': '/bin/bash', 12:47:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pkNtMGmDKa/Listeners', 12:47:51 INFO - 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', 12:47:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:47:51 INFO - 'USER': 'cltbld', 12:47:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:47:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:47:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:47:51 INFO - 'XPC_FLAGS': '0x0', 12:47:51 INFO - 'XPC_SERVICE_NAME': '0', 12:47:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:47:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:47:51 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 12:47:51 INFO - Cleaning up... 12:47:51 INFO - Return code: 0 12:47:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:47:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:47:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:47:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:47:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:47:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:47:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:47:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1018c4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018ca570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98a95bd3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101768d30>, '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 0x10158cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10175fb20>, '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.0FByRzYdkn/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.pkNtMGmDKa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/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 12:47:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:47:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:47:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0FByRzYdkn/Render', 12:47:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:47:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:47:51 INFO - 'HOME': '/Users/cltbld', 12:47:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:47:51 INFO - 'LOGNAME': 'cltbld', 12:47:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:47:51 INFO - 'MOZ_NO_REMOTE': '1', 12:47:51 INFO - 'NO_EM_RESTART': '1', 12:47:51 INFO - 'PAGER': '/bin/cat', 12:47:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:47:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:47:51 INFO - 'PWD': '/builds/slave/test', 12:47:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:47:51 INFO - 'SHELL': '/bin/bash', 12:47:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pkNtMGmDKa/Listeners', 12:47:51 INFO - 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', 12:47:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:47:51 INFO - 'USER': 'cltbld', 12:47:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:47:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:47:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:47:51 INFO - 'XPC_FLAGS': '0x0', 12:47:51 INFO - 'XPC_SERVICE_NAME': '0', 12:47:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:47:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:47:51 INFO - Downloading/unpacking psutil>=3.1.1 12:47:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:47:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:47:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:47:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:47:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:47: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 12:47:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:47:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:47:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:47:55 INFO - Installing collected packages: psutil 12:47:55 INFO - Running setup.py install for psutil 12:47:55 INFO - building 'psutil._psutil_osx' extension 12:47:55 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 12:47:56 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 12:47:56 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 12:47:56 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 12:47:56 INFO - building 'psutil._psutil_posix' extension 12:47:56 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 12:47:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:47:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:47:56 INFO - ^ 12:47:56 INFO - 1 warning generated. 12:47:56 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 12:47:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:47:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:47:56 INFO - Successfully installed psutil 12:47:56 INFO - Cleaning up... 12:47:56 INFO - Return code: 0 12:47:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:47:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:47:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:47:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:47:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:47:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:47:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:47:56 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 0x1018c4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018ca570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98a95bd3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101768d30>, '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 0x10158cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10175fb20>, '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.0FByRzYdkn/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.pkNtMGmDKa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/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 12:47:56 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 12:47:56 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 12:47:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0FByRzYdkn/Render', 12:47:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:47:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:47:56 INFO - 'HOME': '/Users/cltbld', 12:47:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:47:56 INFO - 'LOGNAME': 'cltbld', 12:47:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:47:56 INFO - 'MOZ_NO_REMOTE': '1', 12:47:56 INFO - 'NO_EM_RESTART': '1', 12:47:56 INFO - 'PAGER': '/bin/cat', 12:47:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:47:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:47:56 INFO - 'PWD': '/builds/slave/test', 12:47:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:47:56 INFO - 'SHELL': '/bin/bash', 12:47:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pkNtMGmDKa/Listeners', 12:47:56 INFO - 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', 12:47:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:47:56 INFO - 'USER': 'cltbld', 12:47:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:47:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:47:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:47:56 INFO - 'XPC_FLAGS': '0x0', 12:47:56 INFO - 'XPC_SERVICE_NAME': '0', 12:47:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:47:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:47:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:47:56 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 12:47:56 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 12:47:56 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 12:47:56 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 12:47:56 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 12:47:56 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 12:47:59 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:47:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:47:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:47:59 INFO - Installing collected packages: mozsystemmonitor 12:47:59 INFO - Running setup.py install for mozsystemmonitor 12:47:59 INFO - Successfully installed mozsystemmonitor 12:47:59 INFO - Cleaning up... 12:47:59 INFO - Return code: 0 12:47:59 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:47:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:47:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:47:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:47:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:47:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:47:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:47:59 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 0x1018c4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018ca570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98a95bd3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101768d30>, '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 0x10158cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10175fb20>, '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.0FByRzYdkn/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.pkNtMGmDKa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/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 12:47:59 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 12:47:59 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 12:47:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0FByRzYdkn/Render', 12:47:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:47:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:47:59 INFO - 'HOME': '/Users/cltbld', 12:47:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:47:59 INFO - 'LOGNAME': 'cltbld', 12:47:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:47:59 INFO - 'MOZ_NO_REMOTE': '1', 12:47:59 INFO - 'NO_EM_RESTART': '1', 12:47:59 INFO - 'PAGER': '/bin/cat', 12:47:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:47:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:47:59 INFO - 'PWD': '/builds/slave/test', 12:47:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:47:59 INFO - 'SHELL': '/bin/bash', 12:47:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pkNtMGmDKa/Listeners', 12:47:59 INFO - 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', 12:47:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:47:59 INFO - 'USER': 'cltbld', 12:47:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:47:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:47:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:47:59 INFO - 'XPC_FLAGS': '0x0', 12:47:59 INFO - 'XPC_SERVICE_NAME': '0', 12:47:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:47:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:47:59 INFO - Downloading/unpacking blobuploader==1.2.4 12:47:59 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 12:47:59 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 12:47:59 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 12:47:59 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 12:47:59 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 12:47:59 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 12:48:02 INFO - Downloading blobuploader-1.2.4.tar.gz 12:48:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:48:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:48:02 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 12:48:02 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 12:48:02 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 12:48:02 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 12:48:02 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 12:48:02 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 12:48:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:48:02 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:48:02 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 12:48:02 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 12:48:02 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 12:48:02 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 12:48:02 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 12:48:02 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 12:48:03 INFO - Downloading docopt-0.6.1.tar.gz 12:48:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:48:03 INFO - Installing collected packages: blobuploader, requests, docopt 12:48:03 INFO - Running setup.py install for blobuploader 12:48:03 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:48:03 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:48:03 INFO - Running setup.py install for requests 12:48:04 INFO - Running setup.py install for docopt 12:48:04 INFO - Successfully installed blobuploader requests docopt 12:48:04 INFO - Cleaning up... 12:48:04 INFO - Return code: 0 12:48:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:48:04 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')]} 12:48:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:48:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:48:04 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')]} 12:48:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:48:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:48:04 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 0x1018c4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018ca570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98a95bd3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101768d30>, '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 0x10158cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10175fb20>, '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.0FByRzYdkn/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.pkNtMGmDKa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/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 12:48:04 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 12:48:04 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 12:48:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0FByRzYdkn/Render', 12:48:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:48:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:48:04 INFO - 'HOME': '/Users/cltbld', 12:48:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:48:04 INFO - 'LOGNAME': 'cltbld', 12:48:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:48:04 INFO - 'MOZ_NO_REMOTE': '1', 12:48:04 INFO - 'NO_EM_RESTART': '1', 12:48:04 INFO - 'PAGER': '/bin/cat', 12:48:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:48:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:48:04 INFO - 'PWD': '/builds/slave/test', 12:48:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:48:04 INFO - 'SHELL': '/bin/bash', 12:48:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pkNtMGmDKa/Listeners', 12:48:04 INFO - 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', 12:48:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:48:04 INFO - 'USER': 'cltbld', 12:48:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:48:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:48:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:48:04 INFO - 'XPC_FLAGS': '0x0', 12:48:04 INFO - 'XPC_SERVICE_NAME': '0', 12:48:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:48:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:48:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:48:04 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-ufEjgX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:48:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:48:04 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-86rhyu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:48:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:48:04 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-G_WQek-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:48:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:48:04 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-zpBGC2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:48:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:48:05 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-J6jw1y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:48:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:48:05 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-iSNyHd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:48:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:48:05 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-YeQwbV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:48:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:48:05 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-h3xll6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:48:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:48:05 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-F_SnkM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:48:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:48:05 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-dBnHVO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:48:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:48:05 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-In7bod-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:48:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:48:05 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-_ZAD0w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:48:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:48:05 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-CqMdjV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:48:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:48:05 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-DX08f2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:48:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:48:06 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-z6xSBI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:48:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:48:06 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-ZuFkCe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:48:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:48:06 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-75YTGH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:48:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:48:06 INFO - Running setup.py install for manifestparser 12:48:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:48:06 INFO - Running setup.py install for mozcrash 12:48:06 INFO - Running setup.py install for mozdebug 12:48:06 INFO - Running setup.py install for mozdevice 12:48:06 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:48:06 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:48:06 INFO - Running setup.py install for mozfile 12:48:07 INFO - Running setup.py install for mozhttpd 12:48:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:48:07 INFO - Running setup.py install for mozinfo 12:48:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:48:07 INFO - Running setup.py install for mozInstall 12:48:07 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:48:07 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:48:07 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:48:07 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:48:07 INFO - Running setup.py install for mozleak 12:48:07 INFO - Running setup.py install for mozlog 12:48:07 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:48:07 INFO - Running setup.py install for moznetwork 12:48:07 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:48:07 INFO - Running setup.py install for mozprocess 12:48:08 INFO - Running setup.py install for mozprofile 12:48:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:48:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:48:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:48:08 INFO - Running setup.py install for mozrunner 12:48:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:48:08 INFO - Running setup.py install for mozscreenshot 12:48:08 INFO - Running setup.py install for moztest 12:48:08 INFO - Running setup.py install for mozversion 12:48:08 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:48:08 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:48:08 INFO - Cleaning up... 12:48:08 INFO - Return code: 0 12:48:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:48:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:48:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:48:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:48:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:48:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:48:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:48:08 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 0x1018c4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018ca570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98a95bd3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101768d30>, '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 0x10158cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10175fb20>, '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.0FByRzYdkn/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.pkNtMGmDKa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/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 12:48:08 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 12:48:08 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 12:48:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0FByRzYdkn/Render', 12:48:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:48:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:48:08 INFO - 'HOME': '/Users/cltbld', 12:48:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:48:08 INFO - 'LOGNAME': 'cltbld', 12:48:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:48:08 INFO - 'MOZ_NO_REMOTE': '1', 12:48:08 INFO - 'NO_EM_RESTART': '1', 12:48:08 INFO - 'PAGER': '/bin/cat', 12:48:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:48:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:48:08 INFO - 'PWD': '/builds/slave/test', 12:48:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:48:08 INFO - 'SHELL': '/bin/bash', 12:48:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pkNtMGmDKa/Listeners', 12:48:08 INFO - 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', 12:48:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:48:08 INFO - 'USER': 'cltbld', 12:48:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:48:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:48:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:48:08 INFO - 'XPC_FLAGS': '0x0', 12:48:08 INFO - 'XPC_SERVICE_NAME': '0', 12:48:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:48:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:48:09 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-cy5SaM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:48:09 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)) 12:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:48:09 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-kyb8i8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:48:09 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)) 12:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:48:09 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-6ieGEL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:48:09 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)) 12:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:48:09 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-6Rjy9d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:48:09 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)) 12:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:48:09 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-VRlnGg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:48:09 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)) 12:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:48:09 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-lx66Yy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:48:09 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)) 12:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:48:09 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-IUucZm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:48:09 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)) 12:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:48:09 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-EDW_mp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:48:10 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)) 12:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:48:10 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-Z4WBgF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:48:10 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)) 12:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:48:10 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-heEpZ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:48:10 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)) 12:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:48:10 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-ceV269-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:48:10 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)) 12:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:48:10 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-ArhNmY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:48:10 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)) 12:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:48:10 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-U3KZmF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:48:10 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)) 12:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:48:10 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-qUMIj3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:48:10 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)) 12:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:48:10 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-cUuZRq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:48:10 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)) 12:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:48:10 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-jkzqMe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:48:10 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)) 12:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:48:10 INFO - Running setup.py (path:/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/pip-GimPy2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:48:11 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)) 12:48:11 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)) 12:48:11 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)) 12:48:11 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)) 12:48:11 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)) 12:48:11 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)) 12:48:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:48:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:48:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:48:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:48:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:48:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:48:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:48:13 INFO - Downloading blessings-1.6.tar.gz 12:48:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:48:13 INFO - Installing collected packages: blessings 12:48:13 INFO - Running setup.py install for blessings 12:48:13 INFO - Successfully installed blessings 12:48:13 INFO - Cleaning up... 12:48:13 INFO - Return code: 0 12:48:13 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:48:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:48:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:48:14 INFO - Reading from file tmpfile_stdout 12:48:14 INFO - Current package versions: 12:48:14 INFO - blessings == 1.6 12:48:14 INFO - blobuploader == 1.2.4 12:48:14 INFO - docopt == 0.6.1 12:48:14 INFO - manifestparser == 1.1 12:48:14 INFO - mozInstall == 1.12 12:48:14 INFO - mozcrash == 0.17 12:48:14 INFO - mozdebug == 0.1 12:48:14 INFO - mozdevice == 0.48 12:48:14 INFO - mozfile == 1.2 12:48:14 INFO - mozhttpd == 0.7 12:48:14 INFO - mozinfo == 0.9 12:48:14 INFO - mozleak == 0.1 12:48:14 INFO - mozlog == 3.2 12:48:14 INFO - moznetwork == 0.27 12:48:14 INFO - mozprocess == 0.22 12:48:14 INFO - mozprofile == 0.28 12:48:14 INFO - mozrunner == 6.11 12:48:14 INFO - mozscreenshot == 0.1 12:48:14 INFO - mozsystemmonitor == 0.3 12:48:14 INFO - moztest == 0.7 12:48:14 INFO - mozversion == 1.4 12:48:14 INFO - psutil == 3.1.1 12:48:14 INFO - requests == 1.2.3 12:48:14 INFO - wsgiref == 0.1.2 12:48:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:48:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:48:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:48:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:48:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:48:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:48:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:48:14 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 0x1018c4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018ca570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98a95bd3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101768d30>, '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 0x10158cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10175fb20>, '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.0FByRzYdkn/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.pkNtMGmDKa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/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 12:48:14 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 12:48:14 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 12:48:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0FByRzYdkn/Render', 12:48:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:48:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:48:14 INFO - 'HOME': '/Users/cltbld', 12:48:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:48:14 INFO - 'LOGNAME': 'cltbld', 12:48:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:48:14 INFO - 'MOZ_NO_REMOTE': '1', 12:48:14 INFO - 'NO_EM_RESTART': '1', 12:48:14 INFO - 'PAGER': '/bin/cat', 12:48:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:48:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:48:14 INFO - 'PWD': '/builds/slave/test', 12:48:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:48:14 INFO - 'SHELL': '/bin/bash', 12:48:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pkNtMGmDKa/Listeners', 12:48:14 INFO - 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', 12:48:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:48:14 INFO - 'USER': 'cltbld', 12:48:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:48:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:48:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:48:14 INFO - 'XPC_FLAGS': '0x0', 12:48:14 INFO - 'XPC_SERVICE_NAME': '0', 12:48:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:48:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:48:14 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)) 12:48:14 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)) 12:48:14 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)) 12:48:14 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)) 12:48:14 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)) 12:48:14 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)) 12:48:14 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)) 12:48:14 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)) 12:48:14 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)) 12:48:14 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)) 12:48:14 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)) 12:48:14 INFO - Cleaning up... 12:48:14 INFO - Return code: 0 12:48:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:48:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:48:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:48:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:48:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 12:48:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:48:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:48:14 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 0x1018c4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018ca570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98a95bd3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101768d30>, '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 0x10158cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10175fb20>, '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.0FByRzYdkn/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.pkNtMGmDKa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/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 12:48:14 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 12:48:14 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 12:48:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0FByRzYdkn/Render', 12:48:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:48:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:48:14 INFO - 'HOME': '/Users/cltbld', 12:48:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:48:14 INFO - 'LOGNAME': 'cltbld', 12:48:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:48:14 INFO - 'MOZ_NO_REMOTE': '1', 12:48:14 INFO - 'NO_EM_RESTART': '1', 12:48:14 INFO - 'PAGER': '/bin/cat', 12:48:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:48:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:48:14 INFO - 'PWD': '/builds/slave/test', 12:48:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:48:14 INFO - 'SHELL': '/bin/bash', 12:48:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pkNtMGmDKa/Listeners', 12:48:14 INFO - 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', 12:48:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:48:14 INFO - 'USER': 'cltbld', 12:48:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:48:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:48:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:48:14 INFO - 'XPC_FLAGS': '0x0', 12:48:14 INFO - 'XPC_SERVICE_NAME': '0', 12:48:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:48:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:48:14 INFO - Downloading/unpacking jsonschema 12:48: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 12:48: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 12:48: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 12:48: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 12:48: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 12:48: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 12:48:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:48:17 INFO - Downloading/unpacking functools32 (from jsonschema) 12:48:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:48:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:48:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:48:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:48:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:48:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:48:17 INFO - Downloading functools32-3.2.3-2.tar.gz 12:48:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:48:17 INFO - warning: no files found matching '*.txt' 12:48:17 INFO - no previously-included directories found matching 'build' 12:48:17 INFO - no previously-included directories found matching 'dist' 12:48:17 INFO - no previously-included directories found matching '.git*' 12:48:17 INFO - Installing collected packages: jsonschema, functools32 12:48:17 INFO - Running setup.py install for functools32 12:48:17 INFO - warning: no files found matching '*.txt' 12:48:17 INFO - no previously-included directories found matching 'build' 12:48:17 INFO - no previously-included directories found matching 'dist' 12:48:17 INFO - no previously-included directories found matching '.git*' 12:48:17 INFO - Successfully installed jsonschema functools32 12:48:17 INFO - Cleaning up... 12:48:18 INFO - Return code: 0 12:48:18 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 12:48:18 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')]} 12:48:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:48:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:48:18 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')]} 12:48:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:48:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:48:18 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 0x1018c4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1018ca570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f98a95bd3b0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101768d30>, '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 0x10158cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10175fb20>, '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.0FByRzYdkn/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.pkNtMGmDKa/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/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 12:48:18 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 12:48:18 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 12:48:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0FByRzYdkn/Render', 12:48:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:48:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:48:18 INFO - 'HOME': '/Users/cltbld', 12:48:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:48:18 INFO - 'LOGNAME': 'cltbld', 12:48:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:48:18 INFO - 'MOZ_NO_REMOTE': '1', 12:48:18 INFO - 'NO_EM_RESTART': '1', 12:48:18 INFO - 'PAGER': '/bin/cat', 12:48:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:48:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:48:18 INFO - 'PWD': '/builds/slave/test', 12:48:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:48:18 INFO - 'SHELL': '/bin/bash', 12:48:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pkNtMGmDKa/Listeners', 12:48:18 INFO - 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', 12:48:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:48:18 INFO - 'USER': 'cltbld', 12:48:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:48:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:48:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:48:18 INFO - 'XPC_FLAGS': '0x0', 12:48:18 INFO - 'XPC_SERVICE_NAME': '0', 12:48:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:48:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:48:18 INFO - Downloading/unpacking flake8 12:48:18 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 12:48:18 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 12:48:18 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 12:48:18 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 12:48:18 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 12:48:18 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 12:48:21 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 12:48:21 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 12:48:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:48:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:48:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:48:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:48:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:48:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:48:21 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 12:48:21 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 12:48:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:48:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:48:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:48:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:48:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:48:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:48:21 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 12:48:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:48:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:48:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:48:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:48:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:48:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:48:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 12:48:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:48:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:48:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:48:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:48:22 INFO - no previously-included directories found matching 'docs/_build' 12:48:22 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 12:48:22 INFO - Running setup.py install for pep8 12:48:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 12:48:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 12:48:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 12:48:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 12:48:22 INFO - no previously-included directories found matching 'docs/_build' 12:48:22 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 12:48:22 INFO - Successfully installed flake8 mccabe pyflakes pep8 12:48:22 INFO - Cleaning up... 12:48:22 INFO - Return code: 0 12:48:22 INFO - Running post-action listener: _resource_record_post_action 12:48:22 INFO - Running post-action listener: _start_resource_monitoring 12:48:22 INFO - Starting resource monitoring. 12:48:22 INFO - ##### 12:48:22 INFO - ##### Finished create-virtualenv step (success) 12:48:22 INFO - ##### 12:48:22 INFO - ##### 12:48:22 INFO - ##### Running install step. 12:48:22 INFO - ##### 12:48:22 INFO - Running pre-action listener: _resource_record_pre_action 12:48:22 INFO - Running main action method: install 12:48:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:48:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:48:23 INFO - Reading from file tmpfile_stdout 12:48:23 INFO - Detecting whether we're running mozinstall >=1.0... 12:48:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:48:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:48:23 INFO - Reading from file tmpfile_stdout 12:48:23 INFO - Output received: 12:48:23 INFO - Usage: mozinstall [options] installer 12:48:23 INFO - Options: 12:48:23 INFO - -h, --help show this help message and exit 12:48:23 INFO - -d DEST, --destination=DEST 12:48:23 INFO - Directory to install application into. [default: 12:48:23 INFO - "/builds/slave/test"] 12:48:23 INFO - --app=APP Application being installed. [default: firefox] 12:48:23 INFO - mkdir: /builds/slave/test/build/application 12:48:23 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'] 12:48:23 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 12:48:57 INFO - Reading from file tmpfile_stdout 12:48:57 INFO - Output received: 12:48:57 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:48:57 INFO - Running post-action listener: _resource_record_post_action 12:48:57 INFO - ##### 12:48:57 INFO - ##### Finished install step (success) 12:48:57 INFO - ##### 12:48:57 INFO - ##### 12:48:57 INFO - ##### Running run-tests step. 12:48:57 INFO - ##### 12:48:57 INFO - Running pre-action listener: _resource_record_pre_action 12:48:57 INFO - Running main action method: run_tests 12:48:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:48:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:48:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:48:57 INFO - 2016-05-23 12:48:57.047 screenresolution[1645:8081] starting screenresolution argv=screenresolution get 12:48:57 INFO - 2016-05-23 12:48:57.049 screenresolution[1645:8081] Display 0: 1600x1200x32@60 12:48:57 INFO - 2016-05-23 12:48:57.056 screenresolution[1646:8083] starting screenresolution argv=screenresolution list 12:48:57 INFO - Available Modes on Display 0 12:48:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:48:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:48:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:48:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:48:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:48:57 INFO - Intel Iris: 12:48:57 INFO - Chipset Model: Intel Iris 12:48:57 INFO - Type: GPU 12:48:57 INFO - Bus: Built-In 12:48:57 INFO - VRAM (Dynamic, Max): 1536 MB 12:48:57 INFO - Vendor: Intel (0x8086) 12:48:57 INFO - Device ID: 0x0a2e 12:48:57 INFO - Revision ID: 0x0009 12:48:57 INFO - Displays: 12:48:57 INFO - Display: 12:48:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:48:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:48:57 INFO - Display Serial Number: 1600x1200 60 12:48:57 INFO - Main Display: Yes 12:48:57 INFO - Mirror: Off 12:48:57 INFO - Online: Yes 12:48:57 INFO - Rotation: Supported 12:48:57 INFO - Return code: 0 12:48:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:48:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:48:57 INFO - Python 2.7.3 12:48:57 INFO - Return code: 0 12:48:57 INFO - grabbing minidump binary from tooltool 12:48: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')]} 12:48:57 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 0x101768d30>, '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 0x10158cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10175fb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:48:57 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 12:48:57 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 12:48:57 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 12:48:57 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:48:57 INFO - Return code: 0 12:48:57 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:48:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:48:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:48:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:48:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:48:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 12:48:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 12:48:58 INFO - Return code: 0 12:48:58 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/GC9SgidCQm-qxbCSWPyNww/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0366', '--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 12:48:58 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/GC9SgidCQm-qxbCSWPyNww/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0366 --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 12:48:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0FByRzYdkn/Render', 12:48:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:48:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:48:58 INFO - 'HOME': '/Users/cltbld', 12:48:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:48:58 INFO - 'LOGNAME': 'cltbld', 12:48:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:48:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:48:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:48:58 INFO - 'MOZ_NO_REMOTE': '1', 12:48:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:48:58 INFO - 'NO_EM_RESTART': '1', 12:48:58 INFO - 'PAGER': '/bin/cat', 12:48:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:48:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:48:58 INFO - 'PWD': '/builds/slave/test', 12:48:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:48:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:48:58 INFO - 'SHELL': '/bin/bash', 12:48:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.pkNtMGmDKa/Listeners', 12:48:58 INFO - 'TMPDIR': '/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/', 12:48:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:48:58 INFO - 'USER': 'cltbld', 12:48:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:48:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:48:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:48:58 INFO - 'XPC_FLAGS': '0x0', 12:48:58 INFO - 'XPC_SERVICE_NAME': '0', 12:48:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:48:58 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/GC9SgidCQm-qxbCSWPyNww/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0366', '--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 12:48:58 INFO - mozversion application_buildid: 20160523104644 12:48:58 INFO - mozversion application_changeset: f8689bbe3b5f819302c43ecf24ea5ff15c57b8c5 12:48:58 INFO - mozversion application_display_name: Nightly 12:48:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:48:58 INFO - mozversion application_name: Firefox 12:48:58 INFO - mozversion application_remotingname: firefox 12:48:58 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 12:48:58 INFO - mozversion application_vendor: Mozilla 12:48:58 INFO - mozversion application_version: 49.0a1 12:48:58 INFO - mozversion platform_buildid: 20160523104644 12:48:58 INFO - mozversion platform_changeset: f8689bbe3b5f819302c43ecf24ea5ff15c57b8c5 12:48:58 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 12:48:58 INFO - mozversion platform_version: 49.0a1 12:48:58 INFO - using testdate: 1464032938 12:48:58 INFO - actual date: 1464032938 12:48:58 INFO - starting webserver on 'localhost:49275' 12:48:58 INFO - SUITE-START | Running 2 tests 12:48:58 INFO - TEST-START | dromaeo_css 12:48:58 INFO - operating with platform_type : mac_ 12:48:58 INFO - Initialising browser for dromaeo_css test... 12:48:58 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/tmpQyX6oX/profile http://localhost:49275/getInfo.html) 12:49:00 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 12:49:00 INFO - PROCESS | 1664 | colorDepth:24 12:49:00 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 12:49:00 INFO - PROCESS | 1664 | __metrics 12:49:01 INFO - PROCESS | 1664 | 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" 12:49:01 ERROR - PROCESS | 1664 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 12:49:01 INFO - TEST-INFO | 1664: exit 0 12:49:01 INFO - Browser initialized. 12:49:01 INFO - Running cycle 1/1 for dromaeo_css test... 12:49:01 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/tmpQyX6oX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:49:13 INFO - PROCESS | 1667 | RSS: Main: 238788608 12:49:13 INFO - PROCESS | 1667 | 12:50:05 INFO - PROCESS | 1667 | 1464033005134 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] 12:52:15 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 12:52:16 INFO - PROCESS | 1667 | RSS: Main: 349917184 12:52:16 INFO - PROCESS | 1667 | 12:55:19 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 12:55:20 INFO - PROCESS | 1667 | RSS: Main: 377487360 12:55:20 INFO - PROCESS | 1667 | 12:58:22 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 12:58:22 INFO - PROCESS | 1667 | RSS: Main: 371458048 12:58:22 INFO - PROCESS | 1667 | 13:01:25 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 13:01:25 INFO - PROCESS | 1667 | RSS: Main: 372330496 13:01:25 INFO - PROCESS | 1667 | 13:04:28 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 13:04:29 INFO - PROCESS | 1667 | RSS: Main: 345993216 13:04:29 INFO - PROCESS | 1667 | 13:07:32 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 13:07:32 INFO - PROCESS | 1667 | RSS: Main: 399540224 13:07:32 INFO - PROCESS | 1667 | 13:07:32 INFO - PROCESS | 1667 | __start_tp_report 13:07:32 INFO - PROCESS | 1667 | _x_x_mozilla_page_load 13:07:32 INFO - PROCESS | 1667 | _x_x_mozilla_page_load_details 13:07:32 INFO - PROCESS | 1667 | |i|pagename|runs| 13:07:32 INFO - PROCESS | 1667 | |0;dojo.html;3407.1141503209165;3431.914202144946;3464.618891921888;3478.5825700915893;3516.4725291206314;5612.298462692163;5624.746886390112;5658.349289831671;5665.433456654332;5730.312362516499;5391.81128660496;5633.070543360341;5736.569757268211;5743.9712801436035;5816.592838501296;5149.871253218662;5180.507851754077;5253.6322457428;5268.420473747904;5357.035733567956;5309.973450132744;5349;5349.117395629716;5372.892542149155;5398.406175320712;5187.818426355059;5223.6343455958095;5237.240600112962;5257.158854583267;5291.444398338158;4313.568643135693;5182.948170518284;5207.609429292818;5217.58259339252;5225.843224703265;4050.939235911434;5157.381114266312;5207.453217412155;5243.161094224905;5281.524662780368;4609.861704148881;5521.503064724194;5581.49766521015;5630.183623374588;5680.914786278209;6173.382661733835;6233.033879748601;6280.403361680633;6298.905516417258;6300.653464059475;6279.937200627981;6342.556021078526;6351.174347334863;6365.1725275714325;6368.172137622079;5644.181593668936;5758.165066065397;5829.162761948452;5838.211841401441;5885.941140588582;6003.519718422515;6318.368163183737;6324.9051264231075;6344.587601805868;6350.015747559136;6194.0708893665405;6196.194494715658;6196.318404975449;6200.937990620127;6215.409536094064;6141.025769127022;6175.752969881154;6177.536684748572;6220.7511699532415;6319.8104056878365;6146.354632763585;6213.720382582795;6220.315765265817;6220.968895155585;6226.346233645402;6194;6225.688715564294;6253.155823963798;6256.906146407717;6282.434580887742;4896.706197628154;4897.7795999180125;4899.632527560376;4975.726335051534;4986.626003049786;4413.558644135561;4480.1711683338035;4482.977585112054;4541.2733962565835;4561.064981678748;5742.511886489611;5754.913676294859;5756.251687280626;5758.452946970031;5820.82537523875;6253.906191407133;6280.842978925563;6288.371162883766;6301.212348456443;6301.842453938597;5491.341039075257;5610.803621873215;5653.575981801381;5666.46168613981;5714.114312281603;5409.107497262986;5413.323334583177;5415.945840541623;5418.160185171305;5421.918671219935;6325.936740632626;6347.52393570484;6350.96824515893;6359.427651511386;6368.681565921685;6070.271567411939;6164;6197.25632924052;6263.27972283175;6274.403931626488;6281.528885333527;6332.30344662096;6340.334264902303;6340.968295158495;6347.68261586928;6213.502919766498;6232.501399888089;6233.376662333731;6247.062940558771;6247.781327653511;6083.847903802441;6172.938270617236;6237.906431403623;6245.781397651061;6323.620582765049;5918.023526118142;6123.173371594778;6133.785317513865;6135.6932153393045;6141.938580614136;5916.497097746567;6097.924322177014;6129.178702026901;6151.231096112986;6162.661053642093;5890.4698577128265;5937.34689184198;5956.612820166849;6054.54590905675;6059;6112.28080654231;6139.416755408229;6145.262568491809;6151.200343955257;6153.815385538441;5806.709664516673;5885.9405307045145;6024.277086749443;6027.336992930862;6056.394360563909 13:07:32 INFO - PROCESS | 1667 | |1;ext.html;498.69828753604094;502.97895272596617;514.7040451740249;527.7836087204245;539.9082156033476;30956.690433095664;45173;47183.292250616265;47249.527504724945;47266;46303.84240393992;46874.76562617191;46907.765461172734;47117.293240601415;47117.52882471183;9570.186534144588;9672.177864881458;9740.318177727562;9766.560504777266;9794.90205097947;9340.766480837965;9422.387544809566;9457.19613832825;9465;9475.62097516102;9433.858492122623;9456.8581471278;9471.526423678823;9484.478353690543;9493.240540756757;9460.101290377437;9463.337566370392;9464;9475.384100033476;9487;9469.479178645171;9570.808583828353;9609.375390599658;9676.467794271312;9676.612935482643;42227;42429.575704242874;42997.57002429967;43023.56976430227;43200.783996079816;3296.8021918684954;3503.1417302760733;3645.8723944662074;3754.924901501982;3756.6806821420214;29585.408291834257;29958.850205748833;30113.096607101823;30655.233619159477;30692.38615227683;2563.871806409672;2654.9203523894316;2662.760351568368;2667.66654168229;2736.5621500559814;484475.57762211317;492352.5382373065;492532;492650.536747314;493597;34218.31563368718;65498.017529737095;65736.05583664996;65875;66137.00794488087;34023.31953360919;35194.29611407809;36006.63993360033;36024.639753602125;36052.09869753277;14818;15036.17301048431;15341.079535227922;15356.385744570316;15376.308066136828;12522.87477125217;12894.35528223585;12973.54592589255;13091.476340946256;13092.672683182996;7707.267809558083;7835.84328313442;7847.803804904923;7860.0567931848545;7905.486143400661;3232.434323993292;3286.704196622268;3307.7849939753837;3310.337932413527;3340.4154273002127;925.2782829393084;956.9090936361034;978.7797745507346;999.2655398282257;1066.7813098314828;29668.703312966594;29958.25104372408;29981.850090749405;30064.849675751917;30157.396852062837;1101.5869049106584;1115.983260251097;1119.5801574409597;1123.4831977290376;1150.9942450287695;16085.758713619305;16269.837301626832;16360.836391636169;16761.329546818233;17113.401030963858;630859;631561;638541;638884;639423;64158.037629435596;64311.678441607495;64466.677666611366;64829.02756458657;64836.35163648303;1309.7642424363517;1358.877701006914;1415.3347926474746;1438.8489208633152;1453.9709205816037;30974.53538196884;31042;31089.533656995616;31745.682543174273;31772.364552709278;54955.90088198294;55025.72487137539;55738.72130639483;55952.44047559635;56011;470194;518132;525150.7484925254;564691;565749;22358.441038974157;23130.306090817303;25820.09629663028;26433.33916652099;26789.86605066962;46571;46815.29777053279;47351;47475.525244747114;48018;36811.63188368189;37947.81026094852;37962.0509487265;38581.614183857804;39067.6093239064;37688.43467348047;37991;38476;38957.80521097376;38997;31138.377232455678;36750.26499470049;37596.812015939744;38597.42103868388;38611.806940965114;31271.687283126877;32046.519302210952;33782.662173377954;33989.32021359608;33995;22794.544109117393;24211.878940605184;24261.63607545853;24835.74699839089;25107.121250756136 13:07:32 INFO - PROCESS | 1667 | |2;jquery.html;382.7569493371709;384.13569468695437;385.06621443000716;399.10401149419556;403.61656426394916;19449;20255.885926274048;20517.384478465632;20584.38246852593;20604.381868543947;19408.514787130327;19903.40289791305;20327.59344813103;20510.282140125062;21111.68332475014;4983.302337672729;5142.434332223453;5304.283921670583;6186.876262474771;6198.287196972361;6130.478909292693;6143.232095988002;6156.630602163862;6184.350643182446;6229.470495007907;6011.57918945674;6120.326764055951;6174.043023331391;6217.316095229521;6228.906566401508;5953.523718102563;6030.849228769272;6097.268327800648;6128.877422451548;6198.752049917998;5816.592838501307;5928.762849486058;5971.28344598651;5996.340402555716;5999.580029397944;12804.48782048717;13410.195388276637;13421.798673019912;13443.596692099252;13452.192868427926;1985.4143027806688;2048.7541495020546;2053.4558342039372;2104.410764985791;2316.548273086749;9957.70126896187;10257.025582569719;12052.337121458317;14222.270740191181;14880.553583392408;1720.6128621060286;1796.622709231063;1804.224183601051;1816.128258435958;1888.9055547222583;116951.41524292323;122237;123180.9999999991;123707;124929;9028.909710902939;11625.825612615818;11664.842004229246;11680.474378652982;11847.763044739057;14709.338079786436;14800.555983320517;14825.406983720746;14865.851341486663;14894.329755161043;8642.524661143572;8665.2634526066;8725.171108744658;8741;8845.955770221108;8307.293880020266;8530.360222983301;8560.272376847914;8657.783555411164;8738.21356077956;3744.9514136041953;4403.669724770591;4507.6168525675685;4545.136424079415;4616.538346165356;1869.560653246486;1929.9710504342447;1938.893360865166;1943.9902800485906;1965.9973413558903;859.1537335724306;866.787637028932;918.3663272342062;926.6293482607011;950.9667161649309;19427.222911083398;20058;20483.38549843507;21258.893705531682;21388.251411200534;855.9001682837524;914.2274777812737;916.5783739479914;938.3103418987039;1008.8738907636546;8180.959095204605;10396.584136634601;10927.726806829893;11038.337699738042;11313.321200727984;528986;599248;616758;623022;623651;127598;129936.3503182478;130602;131229;133163;843.7047033538336;999.205631522941;1017.5573625472942;1025.1696126137851;1027.111548510533;18347.54131146732;18727.71908421402;18932.337368192046;18983.715244271072;19068.618627627093;50795.74602126966;50969.49030509647;52846;53434;54357;71655;78308.43383132419;79150.20849791428;79709.60145199236;80338.59830701043;6773;7280.490365674564;8021.598920054091;8033.35733141359;8476.194761497647;11216.214864959375;12252.387380630755;12663.240205587694;12681.492740290654;12694.365281736062;9559.522023898639;10140.340877842917;10379.221558383;10398.68803935883;10747.516361763583;9848.458334791656;10160.136388407069;10315.437310817199;10560.947195263974;10740.194485413469;10566.524506397383;10810.972957569018;10871.456427178768;10973.451327433757;11093.5562577496;6471.899777038009;7093.503454758117;7267.382272506896;7366.96316518414;7400.148982867039;10101.848472272775;10863.511141998788;11069.620031693225;11081.667549973514;11136.276142051065 13:07:32 INFO - PROCESS | 1667 | |3;mootools.html;1421.8720315171645;1601.6876709041735;1628.080134723881;1871.2140900821653;1983.2959299448696;7761.0298712660915;8550.187732165443;8760.561971901412;8771.342149338801;8774.605142768574;6921.480888933325;8526.95736521318;8592.668136438846;8816.250618697402;8830.116988301166;2105.5999360121527;2182.5416662500893;2318.5594736999915;2360.752121027293;2404.879756012201;2088.830255057173;2349.025154560864;2371.762823717632;2409.9518009639796;2417.6494408310787;2279.680844681746;2298.8965496552696;2378.1914149189274;2379.1316169598144;2380.059876348841;2072.253988564116;2299.586074506588;2328.4062564046144;2363.61000434928;2365.775251351119;2352.4589344450874;2369.8459600126107;2390.8087353011715;2392.222527678494;2397.436602398435;8761.342899282516;8791.56042197894;8834.86747698785;8846.380753347268;8853.911460885372;5201.869953251199;5218.7912483500995;5343.412224655285;5377.784888604451;5381.408045115034;2885.9322050841115;3412.2322477442626;3443.1736383267835;3484.181217413906;3488.4069708149677;851.880736696863;997.6458357283133;1002.5989604158322;1002.7593377589399;1012.296453964495;11732.354720490284;11820.822687659693;11831.230969986924;11836.408179591072;11857.466414011391;4726.719654597234;4773.11697335994;4775.5463230993;4829.7585120744525;4886.902261954741;10087;10096.040876116756;10133.391996480235;10134.087932086;10141.594336226615;5302.655327403706;5303.575713942875;5378.489043540935;5406.999705054577;5411.350637923396;4412.933805992912;4566.041131362451;4760.33355330254;4895.118878601886;4900.656954013256;3914.64768170866;3976.165005348851;4019.21625283067;4069.694772892045;4078.9796051019557;1276.272524660953;1276.310792172235;1288.9742205155844;1289.664687181321;1295.740851829638;820.3642177312511;837.4849467577396;857.1385757313859;893.9955300223587;934.6495064350869;4829.227323628203;5616.6068375213345;5649.604527683023;5671.688057156814;5953.732082056231;419.6789456066112;429.4009856250513;442.369623286815;442.5264966485829;442.811804982881;3499.230169362736;3724.7578907370935;3813.027677942107;3846.653801157909;3902.1610353774076;10295.227857910686;10734.355938643706;10836.837447438294;10837.512311945982;10839.295445795999;10380.273380863418;10661.520231589597;10718.303310284806;10825.83761243582;10828.566857325777;8160.714374996846;8205.466644668197;8205.835883282301;8294.460860044077;8305.875411868827;5578.609497335147;5585.441455854382;5607.018771715015;5705.743241554057;5785.334686511105;8087.110417854149;10010.799784004133;10082.29423940317;10098.091171794575;10099.848502272618;9840.60637574489;10080.747981300527;10195.490225488844;10335.94832025835;10383.376997380181;6827.624480653413;7034.261402552658;7298.343149116605;7407.888881666887;7551.358134558624;8207;8303.709370172015;8358.45670031446;8452.830943380975;8455.74632761018;7304.123505179413;7413.888791668021;7511.254404439753;7515.098188217449;7576.6969321228735;7219.566825990458;7257.709691612276;7299.781006569811;7413.369863561442;7433.070866141732;6949.722011119498;6976.267491913276;7018.473614479037;7028.367446929597;7029.824254393681;4706.034873922322;4853.466118727008;4854.68444551103;4912.091263116335;5030.421501527371;4752.691075080109;4803.759812009455;4817.325574419514;4867.902641947212;4981.75091245429 13:07:32 INFO - PROCESS | 1667 | |4;prototype.html;256.38084027074615;282.96180015697877;309.3658001097749;311.74522546749336;315.6385938100873;5338.145896656536;5349.144136938091;5352.678839269642;5366.70483123428;5382.4886635769635;4366.995591014069;5021.824236151735;5234.50272224139;5259.921101183485;5317.9202311965355;3858.189780146173;3859.7105217108688;3964.751103402419;4033.253848038122;4046.5751096134923;3374.9156271093298;3941.8620348287814;4020.477337946063;4037.7173597848164;4041.4746083009168;3693.316736403774;3694.07648087978;3999.98000010001;4004.799760011988;4005.0588111793704;2980.940381192375;3501.9474707879403;3772.320982223199;4046.9595304046875;4053.6351728344293;3119.313750974782;3223.903282901517;3327.234736010707;3414.641462646411;3511.631278715749;4800.975995120037;4837.64545927141;4895.265710143507;5022.447530771612;5035.647504674675;4045.5145382553987;4354.499232588261;4601.562851529099;4609.464794363121;4651.860444186646;4463.910721785546;5081.119985605313;5459.044667183227;5548.55611551075;5568.248286481355;3309.5603412515634;3407.9659203407896;3870.7096967727534;3873.1479074603544;3887.222555488885;5629.437056294379;5739;5871.823845284648;5894.17481552583;5947.9702601486715;3779.637726420987;3836.9808150959066;3929.6266854649334;3932.46911666927;3936.0356712605594;3598.8740394086603;4009.1557883373393;4013.7591744495426;4050.8987275317763;4055.7566546007333;3743.9625603743616;4478.820847166076;4629.305604159403;4662.298261134729;4737.441781437985;4756.833510827105;4791.976040119846;5266.605004624592;5660.2075709400715;5686.77252909879;1118.6364431559775;1318.7955866840655;1344.3748656874404;1353.627752368087;1353.6480515066153;789.6920201121567;812.7805492517106;820.7168526858225;821.6877586517086;833.7873842170208;4681.368015317957;4955.207166853286;5045.445001049881;5058.190689489664;5203.060270946903;5566.55467562594;5685.687287199161;5688.573356998241;5737.569682273846;5765.3369862465515;4892.951070489321;4923.704577725276;5172.948270517247;5181.616456430009;5204.73593986617;2695.4339588686203;2832.4901517727008;2835.9858200708863;2991.9551206731917;3000.744936680363;4838.371011768448;5116.897662046738;5982.218268505166;5985.25184351956;6011.669358185254;5210.609204309692;5672.404397538282;5801.0118043844695;5891.145783861316;5897.67562784051;4945.950540494549;5513.42109078549;5818.010938140442;5909.9315239665775;5937.851553711192;5040.344755181803;5309.415964244007;5703.572232082527;5754.942450575608;5769.53843692512;4599.93100103492;5312.814051508179;5695.544356451391;5736.512396446349;5752.424757524214;5304.549113325411;5544.002079625545;5943.58394912352;6044.942135126247;6048.939510604838;3467.3758723430024;3673.8611030580605;3683.8158092095823;3809.047738065484;3809.847606095836;5168.276441298146;5497.36780270274;5736.827895163152;5753.223314852441;5759.8560035999435;4695.366125573004;5578.246936663499;5590.385057643737;5601.403599973988;5603.747831347518;4489.775511224491;4533.387992620954;4944.3572335596145;5496.340439147328;5580.525655319343;4958.57852082577;5283.603729720209;5423.213634022965;5450.945490545202;5451.618386712892;4845.830395936126;5428.859939412768;5477.972610136924;5541.058020136506;5619.634723742944;4533.501314855429;5296;5490.270564772244;5523.364813046551;5627.774889004394 13:07:32 INFO - PROCESS | 1667 | |5;yui.html;673.4948788408693;685.5989246290919;703.8627467643809;705.9117610298713;729.4711334282642;14828.925855370722;15213.315400806963;15221;15257.23713814309;15296.005759625617;13946.860531394683;13955.651108722286;13987.930060349683;14007.859921400783;14018.369173387196;705.8023753349045;870.7779516223354;889.8042430665244;891.8260939116875;892.8660700894885;880.9603567839431;892.7634176943116;894.8657701344786;895.6596493332524;895.7805337692274;749.3817600479599;938.052566907425;946.8769060022222;946.9337146399755;952.7760976170595;910.0671811393308;914.2868562521244;930.3534043839534;931.9207867331285;933.6965486216939;692.4702602509085;696.5402834129501;696.644711197288;698.4901022253732;698.7414656577048;14487.565373038826;14497.637559061002;14513.984021118526;14524.201168935704;14572.271386430635;9993;11988.64034078979;12012.099092568093;12102.39488025604;12243.510259589606;10640.255182137253;10822.67531974042;10860.3483790972;11201.71995700114;11252.324860508314;3880.611938806125;3913.9021524462114;3929.3713005919203;3930.331843586597;4107.486564179478;81495;81742;81927.77108343379;82026.58986705088;86437.13562864353;8706.34210697274;9091.681791137278;9242.21441177494;9245.399049061789;9362.859557106649;19982.30061947825;20610.484737881372;20706.618738844925;20831.583368332547;20861.791382085983;7130.03744494497;7192.244814294529;7284.526505777108;7311.305425984523;7390.482666213314;3882.437046628223;3939.5075615548058;3951.703622228287;3951.940720889189;3998.660113890352;3293.555370025016;3355.027042157747;3379.2903490266826;3380.543626610376;3383.154211447138;500.3145690404097;607.5564837668438;617.6541136963315;621.5835390288518;666.3136968922018;505.3632423146788;528.3026405145248;542.7666103575422;604.6840748383096;611.1321922869447;7684.500507466997;8172.42793004484;8215.712450064219;8311.71427857332;8398.664053437795;472.70219761550004;518.3209994906684;571.4571157400403;584.392232078642;599.3497055694532;3872.3997780344093;3881;3886.8639597614515;3976.324024915771;4017.2969730297664;90784;94706;95607;95776.98564665153;101496.49251753694;87526.56236718904;88534.55732721295;89069.66395504073;89090.55454722684;89526;75412.49175016469;75505;75515.24484755192;75662.62168689231;78945.99999999885;7594.924050759421;7681.577513236825;7744.2642948919765;7879.684812607661;8023.839523209386;38839;44944;46322;48102.27846582374;48252.5174748248;64062.35937640564;66069;66784;67186.66406667935;67285.66357168183;9953.909678967499;10262.230332724926;10402.687919362128;10547.789044219226;10588.576456941637;10992.395418251754;11066.446677666245;11190.776184476428;11221;11251.549938002388;10303.996187015082;10541.894581054397;10558.524866380878;10619.681409557727;10664.680059597915;10237;10464.372137671764;10754.24720269605;10870.728231794268;11294.774104518028;10305.175585953255;10350.792984140124;10626.149908007186;10739.46302684847;11256.21206515536;7316.780496585111;8792.040554133624;8909.1536304051;8942;8986.550672466481;8682.826343473222;8812.691555795516;9216.907830921606;9523.904760952302;9655 13:07:32 INFO - PROCESS | 1667 | __end_tp_report 13:07:32 INFO - PROCESS | 1667 | __start_cc_report 13:07:32 INFO - PROCESS | 1667 | _x_x_mozilla_cycle_collect,1185 13:07:32 INFO - PROCESS | 1667 | __end_cc_report 13:07:32 INFO - PROCESS | 1667 | __startTimestamp1464034052560__endTimestamp 13:07:32 INFO - PROCESS | 1667 | 13:07:32 INFO - PROCESS | 1667 | ------- Summary: start ------- 13:07:32 INFO - PROCESS | 1667 | Number of tests: 6 13:07:32 INFO - PROCESS | 1667 | 13:07:32 INFO - PROCESS | 1667 | [#0] dojo.html Cycles:165 Average:5747.28 Median:6003.52 stddev:653.41 (10.9%) stddev-sans-first:629.09 13:07:32 INFO - PROCESS | 1667 | Values: 3407.1 3431.9 3464.6 3478.6 3516.5 5612.3 5624.7 5658.3 5665.4 5730.3 5391.8 5633.1 5736.6 5744.0 5816.6 5149.9 5180.5 5253.6 5268.4 5357.0 5310.0 5349.0 5349.1 5372.9 5398.4 5187.8 5223.6 5237.2 5257.2 5291.4 4313.6 5182.9 5207.6 5217.6 5225.8 4050.9 5157.4 5207.5 5243.2 5281.5 4609.9 5521.5 5581.5 5630.2 5680.9 6173.4 6233.0 6280.4 6298.9 6300.7 6279.9 6342.6 6351.2 6365.2 6368.2 5644.2 5758.2 5829.2 5838.2 5885.9 6003.5 6318.4 6324.9 6344.6 6350.0 6194.1 6196.2 6196.3 6200.9 6215.4 6141.0 6175.8 6177.5 6220.8 6319.8 6146.4 6213.7 6220.3 6221.0 6226.3 6194.0 6225.7 6253.2 6256.9 6282.4 4896.7 4897.8 4899.6 4975.7 4986.6 4413.6 4480.2 4483.0 4541.3 4561.1 5742.5 5754.9 5756.3 5758.5 5820.8 6253.9 6280.8 6288.4 6301.2 6301.8 5491.3 5610.8 5653.6 5666.5 5714.1 5409.1 5413.3 5415.9 5418.2 5421.9 6325.9 6347.5 6351.0 6359.4 6368.7 6070.3 6164.0 6197.3 6263.3 6274.4 6281.5 6332.3 6340.3 6341.0 6347.7 6213.5 6232.5 6233.4 6247.1 6247.8 6083.8 6172.9 6237.9 6245.8 6323.6 5918.0 6123.2 6133.8 6135.7 6141.9 5916.5 6097.9 6129.2 6151.2 6162.7 5890.5 5937.3 5956.6 6054.5 6059.0 6112.3 6139.4 6145.3 6151.2 6153.8 5806.7 5885.9 6024.3 6027.3 6056.4 13:07:32 INFO - PROCESS | 1667 | 13:07:32 INFO - PROCESS | 1667 | [#1] ext.html Cycles:180 Average:68100.19 Median:38977.40 stddev:148589.65 (381.2%) stddev-sans-first:148919.79 13:07:32 INFO - PROCESS | 1667 | Values: 498.7 503.0 514.7 527.8 539.9 30956.7 45173.0 47183.3 47249.5 47266.0 46303.8 46874.8 46907.8 47117.3 47117.5 9570.2 9672.2 9740.3 9766.6 9794.9 9340.8 9422.4 9457.2 9465.0 9475.6 9433.9 9456.9 9471.5 9484.5 9493.2 9460.1 9463.3 9464.0 9475.4 9487.0 9469.5 9570.8 9609.4 9676.5 9676.6 42227.0 42429.6 42997.6 43023.6 43200.8 3296.8 3503.1 3645.9 3754.9 3756.7 29585.4 29958.9 30113.1 30655.2 30692.4 2563.9 2654.9 2662.8 2667.7 2736.6 484475.6 492352.5 492532.0 492650.5 493597.0 34218.3 65498.0 65736.1 65875.0 66137.0 34023.3 35194.3 36006.6 36024.6 36052.1 14818.0 15036.2 15341.1 15356.4 15376.3 12522.9 12894.4 12973.5 13091.5 13092.7 7707.3 7835.8 7847.8 7860.1 7905.5 3232.4 3286.7 3307.8 3310.3 3340.4 925.3 956.9 978.8 999.3 1066.8 29668.7 29958.3 29981.9 30064.8 30157.4 1101.6 1116.0 1119.6 1123.5 1151.0 16085.8 16269.8 16360.8 16761.3 17113.4 630859.0 631561.0 638541.0 638884.0 639423.0 64158.0 64311.7 64466.7 64829.0 64836.4 1309.8 1358.9 1415.3 1438.8 1454.0 30974.5 31042.0 31089.5 31745.7 31772.4 54955.9 55025.7 55738.7 55952.4 56011.0 470194.0 518132.0 525150.7 564691.0 565749.0 22358.4 23130.3 25820.1 26433.3 26789.9 46571.0 46815.3 47351.0 47475.5 48018.0 36811.6 37947.8 37962.1 38581.6 39067.6 37688.4 37991.0 38476.0 38957.8 38997.0 31138.4 36750.3 37596.8 38597.4 38611.8 31271.7 32046.5 33782.7 33989.3 33995.0 22794.5 24211.9 24261.6 24835.7 25107.1 13:07:32 INFO - PROCESS | 1667 | 13:07:32 INFO - PROCESS | 1667 | [#2] jquery.html Cycles:180 Average:34810.54 Median:21185.29 stddev:100267.03 (473.3%) stddev-sans-first:100514.98 13:07:32 INFO - PROCESS | 1667 | Values: 382.8 384.1 385.1 399.1 403.6 19449.0 20255.9 20517.4 20584.4 20604.4 19408.5 19903.4 20327.6 20510.3 21111.7 4983.3 5142.4 5304.3 6186.9 6198.3 6130.5 6143.2 6156.6 6184.4 6229.5 6011.6 6120.3 6174.0 6217.3 6228.9 5953.5 6030.8 6097.3 6128.9 6198.8 5816.6 5928.8 5971.3 5996.3 5999.6 12804.5 13410.2 13421.8 13443.6 13452.2 1985.4 2048.8 2053.5 2104.4 2316.5 9957.7 10257.0 12052.3 14222.3 14880.6 1720.6 1796.6 1804.2 1816.1 1888.9 116951.4 122237.0 123181.0 123707.0 124929.0 9028.9 11625.8 11664.8 11680.5 11847.8 14709.3 14800.6 14825.4 14865.9 14894.3 8642.5 8665.3 8725.2 8741.0 8846.0 8307.3 8530.4 8560.3 8657.8 8738.2 3745.0 4403.7 4507.6 4545.1 4616.5 1869.6 1930.0 1938.9 1944.0 1966.0 859.2 866.8 918.4 926.6 951.0 19427.2 20058.0 20483.4 21258.9 21388.3 855.9 914.2 916.6 938.3 1008.9 8181.0 10396.6 10927.7 11038.3 11313.3 528986.0 599248.0 616758.0 623022.0 623651.0 127598.0 129936.4 130602.0 131229.0 133163.0 843.7 999.2 1017.6 1025.2 1027.1 18347.5 18727.7 18932.3 18983.7 19068.6 50795.7 50969.5 52846.0 53434.0 54357.0 71655.0 78308.4 79150.2 79709.6 80338.6 6773.0 7280.5 8021.6 8033.4 8476.2 11216.2 12252.4 12663.2 12681.5 12694.4 9559.5 10140.3 10379.2 10398.7 10747.5 9848.5 10160.1 10315.4 10560.9 10740.2 10566.5 10811.0 10871.5 10973.5 11093.6 6471.9 7093.5 7267.4 7367.0 7400.1 10101.8 10863.5 11069.6 11081.7 11136.3 13:07:32 INFO - PROCESS | 1667 | 13:07:32 INFO - PROCESS | 1667 | [#3] mootools.html Cycles:180 Average:5653.37 Median:4756.51 stddev:3231.85 (67.9%) stddev-sans-first:3225.27 13:07:32 INFO - PROCESS | 1667 | Values: 1421.9 1601.7 1628.1 1871.2 1983.3 7761.0 8550.2 8760.6 8771.3 8774.6 6921.5 8527.0 8592.7 8816.3 8830.1 2105.6 2182.5 2318.6 2360.8 2404.9 2088.8 2349.0 2371.8 2410.0 2417.6 2279.7 2298.9 2378.2 2379.1 2380.1 2072.3 2299.6 2328.4 2363.6 2365.8 2352.5 2369.8 2390.8 2392.2 2397.4 8761.3 8791.6 8834.9 8846.4 8853.9 5201.9 5218.8 5343.4 5377.8 5381.4 2885.9 3412.2 3443.2 3484.2 3488.4 851.9 997.6 1002.6 1002.8 1012.3 11732.4 11820.8 11831.2 11836.4 11857.5 4726.7 4773.1 4775.5 4829.8 4886.9 10087.0 10096.0 10133.4 10134.1 10141.6 5302.7 5303.6 5378.5 5407.0 5411.4 4412.9 4566.0 4760.3 4895.1 4900.7 3914.6 3976.2 4019.2 4069.7 4079.0 1276.3 1276.3 1289.0 1289.7 1295.7 820.4 837.5 857.1 894.0 934.6 4829.2 5616.6 5649.6 5671.7 5953.7 419.7 429.4 442.4 442.5 442.8 3499.2 3724.8 3813.0 3846.7 3902.2 10295.2 10734.4 10836.8 10837.5 10839.3 10380.3 10661.5 10718.3 10825.8 10828.6 8160.7 8205.5 8205.8 8294.5 8305.9 5578.6 5585.4 5607.0 5705.7 5785.3 8087.1 10010.8 10082.3 10098.1 10099.8 9840.6 10080.7 10195.5 10335.9 10383.4 6827.6 7034.3 7298.3 7407.9 7551.4 8207.0 8303.7 8358.5 8452.8 8455.7 7304.1 7413.9 7511.3 7515.1 7576.7 7219.6 7257.7 7299.8 7413.4 7433.1 6949.7 6976.3 7018.5 7028.4 7029.8 4706.0 4853.5 4854.7 4912.1 5030.4 4752.7 4803.8 4817.3 4867.9 4981.8 13:07:32 INFO - PROCESS | 1667 | 13:07:32 INFO - PROCESS | 1667 | [#4] prototype.html Cycles:180 Average:4473.14 Median:4990.20 stddev:1394.78 (28.0%) stddev-sans-first:1362.31 13:07:32 INFO - PROCESS | 1667 | Values: 256.4 283.0 309.4 311.7 315.6 5338.1 5349.1 5352.7 5366.7 5382.5 4367.0 5021.8 5234.5 5259.9 5317.9 3858.2 3859.7 3964.8 4033.3 4046.6 3374.9 3941.9 4020.5 4037.7 4041.5 3693.3 3694.1 4000.0 4004.8 4005.1 2980.9 3501.9 3772.3 4047.0 4053.6 3119.3 3223.9 3327.2 3414.6 3511.6 4801.0 4837.6 4895.3 5022.4 5035.6 4045.5 4354.5 4601.6 4609.5 4651.9 4463.9 5081.1 5459.0 5548.6 5568.2 3309.6 3408.0 3870.7 3873.1 3887.2 5629.4 5739.0 5871.8 5894.2 5948.0 3779.6 3837.0 3929.6 3932.5 3936.0 3598.9 4009.2 4013.8 4050.9 4055.8 3744.0 4478.8 4629.3 4662.3 4737.4 4756.8 4792.0 5266.6 5660.2 5686.8 1118.6 1318.8 1344.4 1353.6 1353.6 789.7 812.8 820.7 821.7 833.8 4681.4 4955.2 5045.4 5058.2 5203.1 5566.6 5685.7 5688.6 5737.6 5765.3 4893.0 4923.7 5172.9 5181.6 5204.7 2695.4 2832.5 2836.0 2992.0 3000.7 4838.4 5116.9 5982.2 5985.3 6011.7 5210.6 5672.4 5801.0 5891.1 5897.7 4946.0 5513.4 5818.0 5909.9 5937.9 5040.3 5309.4 5703.6 5754.9 5769.5 4599.9 5312.8 5695.5 5736.5 5752.4 5304.5 5544.0 5943.6 6044.9 6048.9 3467.4 3673.9 3683.8 3809.0 3809.8 5168.3 5497.4 5736.8 5753.2 5759.9 4695.4 5578.2 5590.4 5601.4 5603.7 4489.8 4533.4 4944.4 5496.3 5580.5 4958.6 5283.6 5423.2 5450.9 5451.6 4845.8 5428.9 5478.0 5541.1 5619.6 4533.5 5296.0 5490.3 5523.4 5627.8 13:07:32 INFO - PROCESS | 1667 | 13:07:32 INFO - PROCESS | 1667 | [#5] yui.html Cycles:180 Average:18533.06 Median:614.39 stddev:27034.54 (4400.2%) stddev-sans-first:27077.12 13:07:32 INFO - PROCESS | 1667 | Values: 673.5 685.6 703.9 705.9 729.5 14828.9 15213.3 15221.0 15257.2 15296.0 13946.9 13955.7 13987.9 14007.9 14018.4 705.8 870.8 889.8 891.8 892.9 881.0 892.8 894.9 895.7 895.8 749.4 938.1 946.9 946.9 952.8 910.1 914.3 930.4 931.9 933.7 692.5 696.5 696.6 698.5 698.7 14487.6 14497.6 14514.0 14524.2 14572.3 9993.0 11988.6 12012.1 12102.4 12243.5 10640.3 10822.7 10860.3 11201.7 11252.3 3880.6 3913.9 3929.4 3930.3 4107.5 81495.0 81742.0 81927.8 82026.6 86437.1 8706.3 9091.7 9242.2 9245.4 9362.9 19982.3 20610.5 20706.6 20831.6 20861.8 7130.0 7192.2 7284.5 7311.3 7390.5 3882.4 3939.5 3951.7 3951.9 3998.7 3293.6 3355.0 3379.3 3380.5 3383.2 500.3 607.6 617.7 621.6 666.3 505.4 528.3 542.8 604.7 611.1 7684.5 8172.4 8215.7 8311.7 8398.7 472.7 518.3 571.5 584.4 599.3 3872.4 3881.0 3886.9 3976.3 4017.3 90784.0 94706.0 95607.0 95777.0 101496.5 87526.6 88534.6 89069.7 89090.6 89526.0 75412.5 75505.0 75515.2 75662.6 78946.0 7594.9 7681.6 7744.3 7879.7 8023.8 38839.0 44944.0 46322.0 48102.3 48252.5 64062.4 66069.0 66784.0 67186.7 67285.7 9953.9 10262.2 10402.7 10547.8 10588.6 10992.4 11066.4 11190.8 11221.0 11251.5 10304.0 10541.9 10558.5 10619.7 10664.7 10237.0 10464.4 10754.2 10870.7 11294.8 10305.2 10350.8 10626.1 10739.5 11256.2 7316.8 8792.0 8909.2 8942.0 8986.6 8682.8 8812.7 9216.9 9523.9 9655.0 13:07:32 INFO - PROCESS | 1667 | -------- Summary: end -------- 13:07:32 INFO - PROCESS | 1667 | 13:07:33 INFO - TEST-INFO | 1667: exit 0 13:07:33 INFO - TEST-OK | dromaeo_css | took 1114407ms 13:07:33 INFO - TEST-START | kraken 13:07:33 INFO - operating with platform_type : mac_ 13:07:33 INFO - Initialising browser for kraken test... 13:07:33 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/tmpa0W5bo/profile http://localhost:49275/getInfo.html) 13:07:35 INFO - PROCESS | 1673 | __metrics Screen width/height:1600/1200 13:07:35 INFO - PROCESS | 1673 | colorDepth:24 13:07:35 INFO - PROCESS | 1673 | Browser inner width/height: 1024/689 13:07:35 INFO - PROCESS | 1673 | __metrics 13:07:35 INFO - PROCESS | 1673 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:07:35 ERROR - PROCESS | 1673 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 13:07:36 INFO - TEST-INFO | 1673: exit 0 13:07:36 INFO - Browser initialized. 13:07:36 INFO - Running cycle 1/1 for kraken test... 13:07:36 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/T/tmpa0W5bo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:07:48 INFO - PROCESS | 1675 | RSS: Main: 238784512 13:07:48 INFO - PROCESS | 1675 | 13:07:50 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 13:07:51 INFO - PROCESS | 1675 | RSS: Main: 286556160 13:07:51 INFO - PROCESS | 1675 | 13:07:54 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 13:07:55 INFO - PROCESS | 1675 | RSS: Main: 297558016 13:07:55 INFO - PROCESS | 1675 | 13:07:58 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 13:07:59 INFO - PROCESS | 1675 | RSS: Main: 387899392 13:07:59 INFO - PROCESS | 1675 | 13:08:01 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 13:08:01 INFO - PROCESS | 1675 | RSS: Main: 286597120 13:08:01 INFO - PROCESS | 1675 | 13:08:04 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:08:04 INFO - PROCESS | 1675 | RSS: Main: 290865152 13:08:04 INFO - PROCESS | 1675 | 13:08:07 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 13:08:08 INFO - PROCESS | 1675 | RSS: Main: 326733824 13:08:08 INFO - PROCESS | 1675 | 13:08:11 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 13:08:12 INFO - PROCESS | 1675 | RSS: Main: 330514432 13:08:12 INFO - PROCESS | 1675 | 13:08:15 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 13:08:15 INFO - PROCESS | 1675 | RSS: Main: 328634368 13:08:15 INFO - PROCESS | 1675 | 13:08:17 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:08:18 INFO - PROCESS | 1675 | RSS: Main: 316190720 13:08:18 INFO - PROCESS | 1675 | 13:08:20 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:08:20 INFO - PROCESS | 1675 | RSS: Main: 322772992 13:08:20 INFO - PROCESS | 1675 | 13:08:23 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:08:23 INFO - PROCESS | 1675 | RSS: Main: 326148096 13:08:23 INFO - PROCESS | 1675 | 13:08:26 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:08:27 INFO - PROCESS | 1675 | RSS: Main: 331415552 13:08:27 INFO - PROCESS | 1675 | 13:08:30 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:08:31 INFO - PROCESS | 1675 | RSS: Main: 328097792 13:08:31 INFO - PROCESS | 1675 | 13:08:33 INFO - PROCESS | 1675 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 13:08:33 INFO - PROCESS | 1675 | RSS: Main: 374976512 13:08:33 INFO - PROCESS | 1675 | 13:08:33 INFO - PROCESS | 1675 | __start_tp_report 13:08:33 INFO - PROCESS | 1675 | _x_x_mozilla_page_load 13:08:33 INFO - PROCESS | 1675 | _x_x_mozilla_page_load_details 13:08:33 INFO - PROCESS | 1675 | |i|pagename|runs| 13:08:33 INFO - PROCESS | 1675 | |0;ai-astar;113;105;113;111;111;112;112;112;112;112 13:08:33 INFO - PROCESS | 1675 | |1;audio-beat-detection;153;111;122;203;121;113;143;124;194;103 13:08:33 INFO - PROCESS | 1675 | |2;audio-dft;186;184;184;183;183;183;184;186;183;180 13:08:33 INFO - PROCESS | 1675 | |3;audio-fft;80;57;60;119;57;56;65;57;93;56 13:08:33 INFO - PROCESS | 1675 | |4;audio-oscillator;93;95;131;80;84;76;103;79;76;110 13:08:33 INFO - PROCESS | 1675 | |5;imaging-gaussian-blur;89;87;89;86;89;85;89;87;91;119 13:08:33 INFO - PROCESS | 1675 | |6;imaging-darkroom;94;93;95;95;110;150;145;106;146;95 13:08:33 INFO - PROCESS | 1675 | |7;imaging-desaturate;85;84;84;83;89;83;83;87;87;85 13:08:33 INFO - PROCESS | 1675 | |8;json-parse-financial;53;59;54;55;56;58;56;121;56;54 13:08:33 INFO - PROCESS | 1675 | |9;json-stringify-tinderbox;65;72;70;69;75;72;70;70;68;70 13:08:33 INFO - PROCESS | 1675 | |10;stanford-crypto-aes;84;85;86;83;85;83;84;84;83;85 13:08:33 INFO - PROCESS | 1675 | |11;stanford-crypto-ccm;137;153;147;132;137;135;124;143;258;153 13:08:33 INFO - PROCESS | 1675 | |12;stanford-crypto-pbkdf2;167;166;186;221;196;168;218;220;164;164 13:08:33 INFO - PROCESS | 1675 | |13;stanford-crypto-sha256-iterative;66;66;66;67;67;63;64;65;66;64 13:08:33 INFO - PROCESS | 1675 | __end_tp_report 13:08:33 INFO - PROCESS | 1675 | __start_cc_report 13:08:33 INFO - PROCESS | 1675 | _x_x_mozilla_cycle_collect,2290 13:08:33 INFO - PROCESS | 1675 | __end_cc_report 13:08:33 INFO - PROCESS | 1675 | __startTimestamp1464034113274__endTimestamp 13:08:33 INFO - PROCESS | 1675 | 13:08:33 INFO - PROCESS | 1675 | ------- Summary: start ------- 13:08:33 INFO - PROCESS | 1675 | Number of tests: 14 13:08:33 INFO - PROCESS | 1675 | 13:08:33 INFO - PROCESS | 1675 | [#0] ai-astar Cycles:10 Average:111.30 Median:112.00 stddev:2.31 (2.1%) stddev-sans-first:2.37 13:08:33 INFO - PROCESS | 1675 | Values: 113.0 105.0 113.0 111.0 111.0 112.0 112.0 112.0 112.0 112.0 13:08:33 INFO - PROCESS | 1675 | 13:08:33 INFO - PROCESS | 1675 | [#1] audio-beat-detection Cycles:10 Average:138.70 Median:133.50 stddev:34.84 (26.1%) stddev-sans-first:36.57 13:08:33 INFO - PROCESS | 1675 | Values: 153.0 111.0 122.0 203.0 121.0 113.0 143.0 124.0 194.0 103.0 13:08:33 INFO - PROCESS | 1675 | 13:08:33 INFO - PROCESS | 1675 | [#2] audio-dft Cycles:10 Average:183.60 Median:184.00 stddev:1.71 (0.9%) stddev-sans-first:1.58 13:08:33 INFO - PROCESS | 1675 | Values: 186.0 184.0 184.0 183.0 183.0 183.0 184.0 186.0 183.0 180.0 13:08:33 INFO - PROCESS | 1675 | 13:08:33 INFO - PROCESS | 1675 | [#3] audio-fft Cycles:10 Average:70.00 Median:58.50 stddev:21.22 (36.3%) stddev-sans-first:22.20 13:08:33 INFO - PROCESS | 1675 | Values: 80.0 57.0 60.0 119.0 57.0 56.0 65.0 57.0 93.0 56.0 13:08:33 INFO - PROCESS | 1675 | 13:08:33 INFO - PROCESS | 1675 | [#4] audio-oscillator Cycles:10 Average:92.70 Median:79.50 stddev:17.83 (22.4%) stddev-sans-first:18.91 13:08:33 INFO - PROCESS | 1675 | Values: 93.0 95.0 131.0 80.0 84.0 76.0 103.0 79.0 76.0 110.0 13:08:33 INFO - PROCESS | 1675 | 13:08:33 INFO - PROCESS | 1675 | [#5] imaging-gaussian-blur Cycles:10 Average:91.10 Median:89.00 stddev:9.96 (11.2%) stddev-sans-first:10.54 13:08:33 INFO - PROCESS | 1675 | Values: 89.0 87.0 89.0 86.0 89.0 85.0 89.0 87.0 91.0 119.0 13:08:33 INFO - PROCESS | 1675 | 13:08:33 INFO - PROCESS | 1675 | [#6] imaging-darkroom Cycles:10 Average:112.90 Median:93.50 stddev:24.20 (25.9%) stddev-sans-first:24.69 13:08:33 INFO - PROCESS | 1675 | Values: 94.0 93.0 95.0 95.0 110.0 150.0 145.0 106.0 146.0 95.0 13:08:33 INFO - PROCESS | 1675 | 13:08:33 INFO - PROCESS | 1675 | [#7] imaging-desaturate Cycles:10 Average:85.00 Median:85.00 stddev:2.05 (2.4%) stddev-sans-first:2.18 13:08:33 INFO - PROCESS | 1675 | Values: 85.0 84.0 84.0 83.0 89.0 83.0 83.0 87.0 87.0 85.0 13:08:33 INFO - PROCESS | 1675 | 13:08:33 INFO - PROCESS | 1675 | [#8] json-parse-financial Cycles:10 Average:62.20 Median:56.00 stddev:20.74 (37.0%) stddev-sans-first:21.73 13:08:33 INFO - PROCESS | 1675 | Values: 53.0 59.0 54.0 55.0 56.0 58.0 56.0 121.0 56.0 54.0 13:08:33 INFO - PROCESS | 1675 | 13:08:33 INFO - PROCESS | 1675 | [#9] json-stringify-tinderbox Cycles:10 Average:70.10 Median:70.00 stddev:2.64 (3.8%) stddev-sans-first:2.06 13:08:33 INFO - PROCESS | 1675 | Values: 65.0 72.0 70.0 69.0 75.0 72.0 70.0 70.0 68.0 70.0 13:08:33 INFO - PROCESS | 1675 | 13:08:33 INFO - PROCESS | 1675 | [#10] stanford-crypto-aes Cycles:10 Average:84.20 Median:84.50 stddev:1.03 (1.2%) stddev-sans-first:1.09 13:08:33 INFO - PROCESS | 1675 | Values: 84.0 85.0 86.0 83.0 85.0 83.0 84.0 84.0 83.0 85.0 13:08:33 INFO - PROCESS | 1675 | 13:08:33 INFO - PROCESS | 1675 | [#11] stanford-crypto-ccm Cycles:10 Average:151.90 Median:145.00 stddev:38.39 (26.5%) stddev-sans-first:40.34 13:08:33 INFO - PROCESS | 1675 | Values: 137.0 153.0 147.0 132.0 137.0 135.0 124.0 143.0 258.0 153.0 13:08:33 INFO - PROCESS | 1675 | 13:08:33 INFO - PROCESS | 1675 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.00 Median:191.00 stddev:24.83 (13.0%) stddev-sans-first:25.26 13:08:33 INFO - PROCESS | 1675 | Values: 167.0 166.0 186.0 221.0 196.0 168.0 218.0 220.0 164.0 164.0 13:08:33 INFO - PROCESS | 1675 | 13:08:33 INFO - PROCESS | 1675 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.40 Median:66.00 stddev:1.35 (2.0%) stddev-sans-first:1.41 13:08:33 INFO - PROCESS | 1675 | Values: 66.0 66.0 66.0 67.0 67.0 63.0 64.0 65.0 66.0 64.0 13:08:33 INFO - PROCESS | 1675 | -------- Summary: end -------- 13:08:33 INFO - PROCESS | 1675 | 13:08:34 INFO - TEST-INFO | 1675: exit 0 13:08:34 INFO - TEST-OK | kraken | took 61021ms 13:08:34 INFO - SUITE-END | took 1175s 13:08:34 INFO - Completed test suite (00:19:35) 13:08:34 INFO - javascript benchmark 13:08:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3407.1141503209165, 3431.914202144946, 3464.618891921888, 3478.5825700915893, 3516.4725291206314, 5612.298462692163, 5624.746886390112, 5658.349289831671, 5665.433456654332, 5730.312362516499, 5391.81128660496, 5633.070543360341, 5736.569757268211, 5743.9712801436035, 5816.592838501296, 5149.871253218662, 5180.507851754077, 5253.6322457428, 5268.420473747904, 5357.035733567956, 5309.973450132744, 5349.0, 5349.117395629716, 5372.892542149155, 5398.406175320712, 5187.818426355059, 5223.6343455958095, 5237.240600112962, 5257.158854583267, 5291.444398338158, 4313.568643135693, 5182.948170518284, 5207.609429292818, 5217.58259339252, 5225.843224703265, 4050.939235911434, 5157.381114266312, 5207.453217412155, 5243.161094224905, 5281.524662780368, 4609.861704148881, 5521.503064724194, 5581.49766521015, 5630.183623374588, 5680.914786278209, 6173.382661733835, 6233.033879748601, 6280.403361680633, 6298.905516417258, 6300.653464059475, 6279.937200627981, 6342.556021078526, 6351.174347334863, 6365.1725275714325, 6368.172137622079, 5644.181593668936, 5758.165066065397, 5829.162761948452, 5838.211841401441, 5885.941140588582, 6003.519718422515, 6318.368163183737, 6324.9051264231075, 6344.587601805868, 6350.015747559136, 6194.0708893665405, 6196.194494715658, 6196.318404975449, 6200.937990620127, 6215.409536094064, 6141.025769127022, 6175.752969881154, 6177.536684748572, 6220.7511699532415, 6319.8104056878365, 6146.354632763585, 6213.720382582795, 6220.315765265817, 6220.968895155585, 6226.346233645402, 6194.0, 6225.688715564294, 6253.155823963798, 6256.906146407717, 6282.434580887742, 4896.706197628154, 4897.7795999180125, 4899.632527560376, 4975.726335051534, 4986.626003049786, 4413.558644135561, 4480.1711683338035, 4482.977585112054, 4541.2733962565835, 4561.064981678748, 5742.511886489611, 5754.913676294859, 5756.251687280626, 5758.452946970031, 5820.82537523875, 6253.906191407133, 6280.842978925563, 6288.371162883766, 6301.212348456443, 6301.842453938597, 5491.341039075257, 5610.803621873215, 5653.575981801381, 5666.46168613981, 5714.114312281603, 5409.107497262986, 5413.323334583177, 5415.945840541623, 5418.160185171305, 5421.918671219935, 6325.936740632626, 6347.52393570484, 6350.96824515893, 6359.427651511386, 6368.681565921685, 6070.271567411939, 6164.0, 6197.25632924052, 6263.27972283175, 6274.403931626488, 6281.528885333527, 6332.30344662096, 6340.334264902303, 6340.968295158495, 6347.68261586928, 6213.502919766498, 6232.501399888089, 6233.376662333731, 6247.062940558771, 6247.781327653511, 6083.847903802441, 6172.938270617236, 6237.906431403623, 6245.781397651061, 6323.620582765049, 5918.023526118142, 6123.173371594778, 6133.785317513865, 6135.6932153393045, 6141.938580614136, 5916.497097746567, 6097.924322177014, 6129.178702026901, 6151.231096112986, 6162.661053642093, 5890.4698577128265, 5937.34689184198, 5956.612820166849, 6054.54590905675, 6059.0, 6112.28080654231, 6139.416755408229, 6145.262568491809, 6151.200343955257, 6153.815385538441, 5806.709664516673, 5885.9405307045145, 6024.277086749443, 6027.336992930862, 6056.394360563909], "value": 5706.149699815532, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [498.69828753604094, 502.97895272596617, 514.7040451740249, 527.7836087204245, 539.9082156033476, 30956.690433095664, 45173.0, 47183.292250616265, 47249.527504724945, 47266.0, 46303.84240393992, 46874.76562617191, 46907.765461172734, 47117.293240601415, 47117.52882471183, 9570.186534144588, 9672.177864881458, 9740.318177727562, 9766.560504777266, 9794.90205097947, 9340.766480837965, 9422.387544809566, 9457.19613832825, 9465.0, 9475.62097516102, 9433.858492122623, 9456.8581471278, 9471.526423678823, 9484.478353690543, 9493.240540756757, 9460.101290377437, 9463.337566370392, 9464.0, 9475.384100033476, 9487.0, 9469.479178645171, 9570.808583828353, 9609.375390599658, 9676.467794271312, 9676.612935482643, 42227.0, 42429.575704242874, 42997.57002429967, 43023.56976430227, 43200.783996079816, 3296.8021918684954, 3503.1417302760733, 3645.8723944662074, 3754.924901501982, 3756.6806821420214, 29585.408291834257, 29958.850205748833, 30113.096607101823, 30655.233619159477, 30692.38615227683, 2563.871806409672, 2654.9203523894316, 2662.760351568368, 2667.66654168229, 2736.5621500559814, 484475.57762211317, 492352.5382373065, 492532.0, 492650.536747314, 493597.0, 34218.31563368718, 65498.017529737095, 65736.05583664996, 65875.0, 66137.00794488087, 34023.31953360919, 35194.29611407809, 36006.63993360033, 36024.639753602125, 36052.09869753277, 14818.0, 15036.17301048431, 15341.079535227922, 15356.385744570316, 15376.308066136828, 12522.87477125217, 12894.35528223585, 12973.54592589255, 13091.476340946256, 13092.672683182996, 7707.267809558083, 7835.84328313442, 7847.803804904923, 7860.0567931848545, 7905.486143400661, 3232.434323993292, 3286.704196622268, 3307.7849939753837, 3310.337932413527, 3340.4154273002127, 925.2782829393084, 956.9090936361034, 978.7797745507346, 999.2655398282257, 1066.7813098314828, 29668.703312966594, 29958.25104372408, 29981.850090749405, 30064.849675751917, 30157.396852062837, 1101.5869049106584, 1115.983260251097, 1119.5801574409597, 1123.4831977290376, 1150.9942450287695, 16085.758713619305, 16269.837301626832, 16360.836391636169, 16761.329546818233, 17113.401030963858, 630859.0, 631561.0, 638541.0, 638884.0, 639423.0, 64158.037629435596, 64311.678441607495, 64466.677666611366, 64829.02756458657, 64836.35163648303, 1309.7642424363517, 1358.877701006914, 1415.3347926474746, 1438.8489208633152, 1453.9709205816037, 30974.53538196884, 31042.0, 31089.533656995616, 31745.682543174273, 31772.364552709278, 54955.90088198294, 55025.72487137539, 55738.72130639483, 55952.44047559635, 56011.0, 470194.0, 518132.0, 525150.7484925254, 564691.0, 565749.0, 22358.441038974157, 23130.306090817303, 25820.09629663028, 26433.33916652099, 26789.86605066962, 46571.0, 46815.29777053279, 47351.0, 47475.525244747114, 48018.0, 36811.63188368189, 37947.81026094852, 37962.0509487265, 38581.614183857804, 39067.6093239064, 37688.43467348047, 37991.0, 38476.0, 38957.80521097376, 38997.0, 31138.377232455678, 36750.26499470049, 37596.812015939744, 38597.42103868388, 38611.806940965114, 31271.687283126877, 32046.519302210952, 33782.662173377954, 33989.32021359608, 33995.0, 22794.544109117393, 24211.878940605184, 24261.63607545853, 24835.74699839089, 25107.121250756136], "value": 18829.863081015927, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.7569493371709, 384.13569468695437, 385.06621443000716, 399.10401149419556, 403.61656426394916, 19449.0, 20255.885926274048, 20517.384478465632, 20584.38246852593, 20604.381868543947, 19408.514787130327, 19903.40289791305, 20327.59344813103, 20510.282140125062, 21111.68332475014, 4983.302337672729, 5142.434332223453, 5304.283921670583, 6186.876262474771, 6198.287196972361, 6130.478909292693, 6143.232095988002, 6156.630602163862, 6184.350643182446, 6229.470495007907, 6011.57918945674, 6120.326764055951, 6174.043023331391, 6217.316095229521, 6228.906566401508, 5953.523718102563, 6030.849228769272, 6097.268327800648, 6128.877422451548, 6198.752049917998, 5816.592838501307, 5928.762849486058, 5971.28344598651, 5996.340402555716, 5999.580029397944, 12804.48782048717, 13410.195388276637, 13421.798673019912, 13443.596692099252, 13452.192868427926, 1985.4143027806688, 2048.7541495020546, 2053.4558342039372, 2104.410764985791, 2316.548273086749, 9957.70126896187, 10257.025582569719, 12052.337121458317, 14222.270740191181, 14880.553583392408, 1720.6128621060286, 1796.622709231063, 1804.224183601051, 1816.128258435958, 1888.9055547222583, 116951.41524292323, 122237.0, 123180.9999999991, 123707.0, 124929.0, 9028.909710902939, 11625.825612615818, 11664.842004229246, 11680.474378652982, 11847.763044739057, 14709.338079786436, 14800.555983320517, 14825.406983720746, 14865.851341486663, 14894.329755161043, 8642.524661143572, 8665.2634526066, 8725.171108744658, 8741.0, 8845.955770221108, 8307.293880020266, 8530.360222983301, 8560.272376847914, 8657.783555411164, 8738.21356077956, 3744.9514136041953, 4403.669724770591, 4507.6168525675685, 4545.136424079415, 4616.538346165356, 1869.560653246486, 1929.9710504342447, 1938.893360865166, 1943.9902800485906, 1965.9973413558903, 859.1537335724306, 866.787637028932, 918.3663272342062, 926.6293482607011, 950.9667161649309, 19427.222911083398, 20058.0, 20483.38549843507, 21258.893705531682, 21388.251411200534, 855.9001682837524, 914.2274777812737, 916.5783739479914, 938.3103418987039, 1008.8738907636546, 8180.959095204605, 10396.584136634601, 10927.726806829893, 11038.337699738042, 11313.321200727984, 528986.0, 599248.0, 616758.0, 623022.0, 623651.0, 127598.0, 129936.3503182478, 130602.0, 131229.0, 133163.0, 843.7047033538336, 999.205631522941, 1017.5573625472942, 1025.1696126137851, 1027.111548510533, 18347.54131146732, 18727.71908421402, 18932.337368192046, 18983.715244271072, 19068.618627627093, 50795.74602126966, 50969.49030509647, 52846.0, 53434.0, 54357.0, 71655.0, 78308.43383132419, 79150.20849791428, 79709.60145199236, 80338.59830701043, 6773.0, 7280.490365674564, 8021.598920054091, 8033.35733141359, 8476.194761497647, 11216.214864959375, 12252.387380630755, 12663.240205587694, 12681.492740290654, 12694.365281736062, 9559.522023898639, 10140.340877842917, 10379.221558383, 10398.68803935883, 10747.516361763583, 9848.458334791656, 10160.136388407069, 10315.437310817199, 10560.947195263974, 10740.194485413469, 10566.524506397383, 10810.972957569018, 10871.456427178768, 10973.451327433757, 11093.5562577496, 6471.899777038009, 7093.503454758117, 7267.382272506896, 7366.96316518414, 7400.148982867039, 10101.848472272775, 10863.511141998788, 11069.620031693225, 11081.667549973514, 11136.276142051065], "value": 9341.48512956832, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1421.8720315171645, 1601.6876709041735, 1628.080134723881, 1871.2140900821653, 1983.2959299448696, 7761.0298712660915, 8550.187732165443, 8760.561971901412, 8771.342149338801, 8774.605142768574, 6921.480888933325, 8526.95736521318, 8592.668136438846, 8816.250618697402, 8830.116988301166, 2105.5999360121527, 2182.5416662500893, 2318.5594736999915, 2360.752121027293, 2404.879756012201, 2088.830255057173, 2349.025154560864, 2371.762823717632, 2409.9518009639796, 2417.6494408310787, 2279.680844681746, 2298.8965496552696, 2378.1914149189274, 2379.1316169598144, 2380.059876348841, 2072.253988564116, 2299.586074506588, 2328.4062564046144, 2363.61000434928, 2365.775251351119, 2352.4589344450874, 2369.8459600126107, 2390.8087353011715, 2392.222527678494, 2397.436602398435, 8761.342899282516, 8791.56042197894, 8834.86747698785, 8846.380753347268, 8853.911460885372, 5201.869953251199, 5218.7912483500995, 5343.412224655285, 5377.784888604451, 5381.408045115034, 2885.9322050841115, 3412.2322477442626, 3443.1736383267835, 3484.181217413906, 3488.4069708149677, 851.880736696863, 997.6458357283133, 1002.5989604158322, 1002.7593377589399, 1012.296453964495, 11732.354720490284, 11820.822687659693, 11831.230969986924, 11836.408179591072, 11857.466414011391, 4726.719654597234, 4773.11697335994, 4775.5463230993, 4829.7585120744525, 4886.902261954741, 10087.0, 10096.040876116756, 10133.391996480235, 10134.087932086, 10141.594336226615, 5302.655327403706, 5303.575713942875, 5378.489043540935, 5406.999705054577, 5411.350637923396, 4412.933805992912, 4566.041131362451, 4760.33355330254, 4895.118878601886, 4900.656954013256, 3914.64768170866, 3976.165005348851, 4019.21625283067, 4069.694772892045, 4078.9796051019557, 1276.272524660953, 1276.310792172235, 1288.9742205155844, 1289.664687181321, 1295.740851829638, 820.3642177312511, 837.4849467577396, 857.1385757313859, 893.9955300223587, 934.6495064350869, 4829.227323628203, 5616.6068375213345, 5649.604527683023, 5671.688057156814, 5953.732082056231, 419.6789456066112, 429.4009856250513, 442.369623286815, 442.5264966485829, 442.811804982881, 3499.230169362736, 3724.7578907370935, 3813.027677942107, 3846.653801157909, 3902.1610353774076, 10295.227857910686, 10734.355938643706, 10836.837447438294, 10837.512311945982, 10839.295445795999, 10380.273380863418, 10661.520231589597, 10718.303310284806, 10825.83761243582, 10828.566857325777, 8160.714374996846, 8205.466644668197, 8205.835883282301, 8294.460860044077, 8305.875411868827, 5578.609497335147, 5585.441455854382, 5607.018771715015, 5705.743241554057, 5785.334686511105, 8087.110417854149, 10010.799784004133, 10082.29423940317, 10098.091171794575, 10099.848502272618, 9840.60637574489, 10080.747981300527, 10195.490225488844, 10335.94832025835, 10383.376997380181, 6827.624480653413, 7034.261402552658, 7298.343149116605, 7407.888881666887, 7551.358134558624, 8207.0, 8303.709370172015, 8358.45670031446, 8452.830943380975, 8455.74632761018, 7304.123505179413, 7413.888791668021, 7511.254404439753, 7515.098188217449, 7576.6969321228735, 7219.566825990458, 7257.709691612276, 7299.781006569811, 7413.369863561442, 7433.070866141732, 6949.722011119498, 6976.267491913276, 7018.473614479037, 7028.367446929597, 7029.824254393681, 4706.034873922322, 4853.466118727008, 4854.68444551103, 4912.091263116335, 5030.421501527371, 4752.691075080109, 4803.759812009455, 4817.325574419514, 4867.902641947212, 4981.75091245429], "value": 4444.335418858595, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [256.38084027074615, 282.96180015697877, 309.3658001097749, 311.74522546749336, 315.6385938100873, 5338.145896656536, 5349.144136938091, 5352.678839269642, 5366.70483123428, 5382.4886635769635, 4366.995591014069, 5021.824236151735, 5234.50272224139, 5259.921101183485, 5317.9202311965355, 3858.189780146173, 3859.7105217108688, 3964.751103402419, 4033.253848038122, 4046.5751096134923, 3374.9156271093298, 3941.8620348287814, 4020.477337946063, 4037.7173597848164, 4041.4746083009168, 3693.316736403774, 3694.07648087978, 3999.98000010001, 4004.799760011988, 4005.0588111793704, 2980.940381192375, 3501.9474707879403, 3772.320982223199, 4046.9595304046875, 4053.6351728344293, 3119.313750974782, 3223.903282901517, 3327.234736010707, 3414.641462646411, 3511.631278715749, 4800.975995120037, 4837.64545927141, 4895.265710143507, 5022.447530771612, 5035.647504674675, 4045.5145382553987, 4354.499232588261, 4601.562851529099, 4609.464794363121, 4651.860444186646, 4463.910721785546, 5081.119985605313, 5459.044667183227, 5548.55611551075, 5568.248286481355, 3309.5603412515634, 3407.9659203407896, 3870.7096967727534, 3873.1479074603544, 3887.222555488885, 5629.437056294379, 5739.0, 5871.823845284648, 5894.17481552583, 5947.9702601486715, 3779.637726420987, 3836.9808150959066, 3929.6266854649334, 3932.46911666927, 3936.0356712605594, 3598.8740394086603, 4009.1557883373393, 4013.7591744495426, 4050.8987275317763, 4055.7566546007333, 3743.9625603743616, 4478.820847166076, 4629.305604159403, 4662.298261134729, 4737.441781437985, 4756.833510827105, 4791.976040119846, 5266.605004624592, 5660.2075709400715, 5686.77252909879, 1118.6364431559775, 1318.7955866840655, 1344.3748656874404, 1353.627752368087, 1353.6480515066153, 789.6920201121567, 812.7805492517106, 820.7168526858225, 821.6877586517086, 833.7873842170208, 4681.368015317957, 4955.207166853286, 5045.445001049881, 5058.190689489664, 5203.060270946903, 5566.55467562594, 5685.687287199161, 5688.573356998241, 5737.569682273846, 5765.3369862465515, 4892.951070489321, 4923.704577725276, 5172.948270517247, 5181.616456430009, 5204.73593986617, 2695.4339588686203, 2832.4901517727008, 2835.9858200708863, 2991.9551206731917, 3000.744936680363, 4838.371011768448, 5116.897662046738, 5982.218268505166, 5985.25184351956, 6011.669358185254, 5210.609204309692, 5672.404397538282, 5801.0118043844695, 5891.145783861316, 5897.67562784051, 4945.950540494549, 5513.42109078549, 5818.010938140442, 5909.9315239665775, 5937.851553711192, 5040.344755181803, 5309.415964244007, 5703.572232082527, 5754.942450575608, 5769.53843692512, 4599.93100103492, 5312.814051508179, 5695.544356451391, 5736.512396446349, 5752.424757524214, 5304.549113325411, 5544.002079625545, 5943.58394912352, 6044.942135126247, 6048.939510604838, 3467.3758723430024, 3673.8611030580605, 3683.8158092095823, 3809.047738065484, 3809.847606095836, 5168.276441298146, 5497.36780270274, 5736.827895163152, 5753.223314852441, 5759.8560035999435, 4695.366125573004, 5578.246936663499, 5590.385057643737, 5601.403599973988, 5603.747831347518, 4489.775511224491, 4533.387992620954, 4944.3572335596145, 5496.340439147328, 5580.525655319343, 4958.57852082577, 5283.603729720209, 5423.213634022965, 5450.945490545202, 5451.618386712892, 4845.830395936126, 5428.859939412768, 5477.972610136924, 5541.058020136506, 5619.634723742944, 4533.501314855429, 5296.0, 5490.270564772244, 5523.364813046551, 5627.774889004394], "value": 4022.653135057173, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [673.4948788408693, 685.5989246290919, 703.8627467643809, 705.9117610298713, 729.4711334282642, 14828.925855370722, 15213.315400806963, 15221.0, 15257.23713814309, 15296.005759625617, 13946.860531394683, 13955.651108722286, 13987.930060349683, 14007.859921400783, 14018.369173387196, 705.8023753349045, 870.7779516223354, 889.8042430665244, 891.8260939116875, 892.8660700894885, 880.9603567839431, 892.7634176943116, 894.8657701344786, 895.6596493332524, 895.7805337692274, 749.3817600479599, 938.052566907425, 946.8769060022222, 946.9337146399755, 952.7760976170595, 910.0671811393308, 914.2868562521244, 930.3534043839534, 931.9207867331285, 933.6965486216939, 692.4702602509085, 696.5402834129501, 696.644711197288, 698.4901022253732, 698.7414656577048, 14487.565373038826, 14497.637559061002, 14513.984021118526, 14524.201168935704, 14572.271386430635, 9993.0, 11988.64034078979, 12012.099092568093, 12102.39488025604, 12243.510259589606, 10640.255182137253, 10822.67531974042, 10860.3483790972, 11201.71995700114, 11252.324860508314, 3880.611938806125, 3913.9021524462114, 3929.3713005919203, 3930.331843586597, 4107.486564179478, 81495.0, 81742.0, 81927.77108343379, 82026.58986705088, 86437.13562864353, 8706.34210697274, 9091.681791137278, 9242.21441177494, 9245.399049061789, 9362.859557106649, 19982.30061947825, 20610.484737881372, 20706.618738844925, 20831.583368332547, 20861.791382085983, 7130.03744494497, 7192.244814294529, 7284.526505777108, 7311.305425984523, 7390.482666213314, 3882.437046628223, 3939.5075615548058, 3951.703622228287, 3951.940720889189, 3998.660113890352, 3293.555370025016, 3355.027042157747, 3379.2903490266826, 3380.543626610376, 3383.154211447138, 500.3145690404097, 607.5564837668438, 617.6541136963315, 621.5835390288518, 666.3136968922018, 505.3632423146788, 528.3026405145248, 542.7666103575422, 604.6840748383096, 611.1321922869447, 7684.500507466997, 8172.42793004484, 8215.712450064219, 8311.71427857332, 8398.664053437795, 472.70219761550004, 518.3209994906684, 571.4571157400403, 584.392232078642, 599.3497055694532, 3872.3997780344093, 3881.0, 3886.8639597614515, 3976.324024915771, 4017.2969730297664, 90784.0, 94706.0, 95607.0, 95776.98564665153, 101496.49251753694, 87526.56236718904, 88534.55732721295, 89069.66395504073, 89090.55454722684, 89526.0, 75412.49175016469, 75505.0, 75515.24484755192, 75662.62168689231, 78945.99999999885, 7594.924050759421, 7681.577513236825, 7744.2642948919765, 7879.684812607661, 8023.839523209386, 38839.0, 44944.0, 46322.0, 48102.27846582374, 48252.5174748248, 64062.35937640564, 66069.0, 66784.0, 67186.66406667935, 67285.66357168183, 9953.909678967499, 10262.230332724926, 10402.687919362128, 10547.789044219226, 10588.576456941637, 10992.395418251754, 11066.446677666245, 11190.776184476428, 11221.0, 11251.549938002388, 10303.996187015082, 10541.894581054397, 10558.524866380878, 10619.681409557727, 10664.680059597915, 10237.0, 10464.372137671764, 10754.24720269605, 10870.728231794268, 11294.774104518028, 10305.175585953255, 10350.792984140124, 10626.149908007186, 10739.46302684847, 11256.21206515536, 7316.780496585111, 8792.040554133624, 8909.1536304051, 8942.0, 8986.550672466481, 8682.826343473222, 8812.691555795516, 9216.907830921606, 9523.904760952302, 9655.0], "value": 6749.475394405149, "unit": "score"}], "name": "dromaeo_css", "value": 7034.072148540223, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 105.0, 113.0, 111.0, 111.0, 112.0, 112.0, 112.0, 112.0, 112.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 111.0, 122.0, 203.0, 121.0, 113.0, 143.0, 124.0, 194.0, 103.0], "value": 138.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 184.0, 184.0, 183.0, 183.0, 183.0, 184.0, 186.0, 183.0, 180.0], "value": 183.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 57.0, 60.0, 119.0, 57.0, 56.0, 65.0, 57.0, 93.0, 56.0], "value": 70.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 95.0, 131.0, 80.0, 84.0, 76.0, 103.0, 79.0, 76.0, 110.0], "value": 92.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 89.0, 86.0, 89.0, 85.0, 89.0, 87.0, 91.0, 119.0], "value": 91.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 95.0, 95.0, 110.0, 150.0, 145.0, 106.0, 146.0, 95.0], "value": 112.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 84.0, 83.0, 89.0, 83.0, 83.0, 87.0, 87.0, 85.0], "value": 85.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 59.0, 54.0, 55.0, 56.0, 58.0, 56.0, 121.0, 56.0, 54.0], "value": 62.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 72.0, 70.0, 69.0, 75.0, 72.0, 70.0, 70.0, 68.0, 70.0], "value": 70.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 86.0, 83.0, 85.0, 83.0, 84.0, 84.0, 83.0, 85.0], "value": 84.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 153.0, 147.0, 132.0, 137.0, 135.0, 124.0, 143.0, 258.0, 153.0], "value": 151.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 166.0, 186.0, 221.0, 196.0, 168.0, 218.0, 220.0, 164.0, 164.0], "value": 187.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 67.0, 67.0, 63.0, 64.0, 65.0, 66.0, 64.0], "value": 65.4, "unit": "score"}], "name": "kraken", "value": 1506.1000000000004, "alertThreshold": 2.0}]} 13:08:34 INFO - Return code: 0 13:08:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 13:08:34 ERROR - # TBPL SUCCESS # 13:08:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:08:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:08:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:08:34 INFO - 2016-05-23 13:08:34.613 screenresolution[1679:10479] starting screenresolution argv=screenresolution get 13:08:34 INFO - 2016-05-23 13:08:34.616 screenresolution[1679:10479] Display 0: 1600x1200x32@60 13:08:34 INFO - 2016-05-23 13:08:34.621 screenresolution[1680:10482] starting screenresolution argv=screenresolution list 13:08:34 INFO - Available Modes on Display 0 13:08:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:08:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:08:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:08:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:08:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:08:34 INFO - Intel Iris: 13:08:34 INFO - Chipset Model: Intel Iris 13:08:34 INFO - Type: GPU 13:08:34 INFO - Bus: Built-In 13:08:34 INFO - VRAM (Dynamic, Max): 1536 MB 13:08:34 INFO - Vendor: Intel (0x8086) 13:08:34 INFO - Device ID: 0x0a2e 13:08:34 INFO - Revision ID: 0x0009 13:08:34 INFO - Displays: 13:08:34 INFO - Display: 13:08:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:08:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:08:34 INFO - Display Serial Number: 1600x1200 60 13:08:34 INFO - Main Display: Yes 13:08:34 INFO - Mirror: Off 13:08:34 INFO - Online: Yes 13:08:34 INFO - Rotation: Supported 13:08:34 INFO - Return code: 0 13:08:34 INFO - Running post-action listener: _resource_record_post_action 13:08:34 INFO - ##### 13:08:34 INFO - ##### Finished run-tests step (success) 13:08:34 INFO - ##### 13:08:34 INFO - Running post-run listener: _resource_record_post_run 13:08:34 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 31505920; Write bytes: 696971776; Read time: 197; Write time: 1900 13:08:34 INFO - TinderboxPrint: CPU usage
53.7% 13:08:34 INFO - TinderboxPrint: I/O read bytes / time
31,505,920 / 197 13:08:34 INFO - TinderboxPrint: I/O write bytes / time
696,971,776 / 1,900 13:08:34 INFO - TinderboxPrint: CPU idle
2,242.2 (46.3%) 13:08:34 INFO - TinderboxPrint: CPU system
972.4 (20.1%) 13:08:34 INFO - TinderboxPrint: CPU user
1,631.1 (33.7%) 13:08:34 INFO - TinderboxPrint: Swap in / out
423,866,368 / 0 13:08:34 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 394437120; Write bytes: 388621824; Read time: 29836; Write time: 793 13:08:34 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 29724160; Write bytes: 297233408; Read time: 182; Write time: 1071 13:08:35 INFO - Running post-run listener: _upload_blobber_files 13:08:35 INFO - Blob upload gear active. 13:08:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:08:35 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 13:08:35 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'] 13:08:35 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 13:08:35 INFO - (blobuploader) - INFO - Open directory for files ... 13:08:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:08:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:08:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:08:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:08:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:08:36 INFO - (blobuploader) - INFO - Done attempting. 13:08:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:08:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:08:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:08:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:08:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:08:37 INFO - (blobuploader) - INFO - Done attempting. 13:08:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:08:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:08:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:08:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:08:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:08:43 INFO - (blobuploader) - INFO - Done attempting. 13:08:43 INFO - (blobuploader) - INFO - Iteration through files over. 13:08:43 INFO - Return code: 0 13:08:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:08:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:08:43 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7b4924cae3f5208a0bcb39d6f9a14fbb9e3950af3461a3e0e28b30c12a07fca3944d31520ec7129d0ad79f80eb9c92d76f5fe8fada3b8d809373b0476a7751a6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/079829fef5da2f902c30f4b3bfa7956ab1050a63448d5f40b798d710ed8e082f49951f7ff1c7a5f08bf7c4f3c713d87faf1c5a99896209f095675c23ef78fd41", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ed3455658bfd1ba22cf032728643a4700cf823d91a1c974ac54196f59a1415f2f899d417e2ff4caa79e2aeb1a2c5c66c3460f5b9c2718042b8765c1a475c342e"} 13:08:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:08:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:08:43 INFO - Contents: 13:08:43 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7b4924cae3f5208a0bcb39d6f9a14fbb9e3950af3461a3e0e28b30c12a07fca3944d31520ec7129d0ad79f80eb9c92d76f5fe8fada3b8d809373b0476a7751a6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/079829fef5da2f902c30f4b3bfa7956ab1050a63448d5f40b798d710ed8e082f49951f7ff1c7a5f08bf7c4f3c713d87faf1c5a99896209f095675c23ef78fd41", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ed3455658bfd1ba22cf032728643a4700cf823d91a1c974ac54196f59a1415f2f899d417e2ff4caa79e2aeb1a2c5c66c3460f5b9c2718042b8765c1a475c342e"} 13:08:43 INFO - Running post-run listener: copy_logs_to_upload_dir 13:08:43 INFO - Copying logs to upload dir... 13:08:43 INFO - mkdir: /builds/slave/test/build/upload/logs 13:08:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1288.900185 ========= master_lag: 0.60 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 29 secs) (at 2016-05-23 13:08:44.379973) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-23 13:08:44.387544) ========= 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.0FByRzYdkn/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.pkNtMGmDKa/Listeners TMPDIR=/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/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/7b4924cae3f5208a0bcb39d6f9a14fbb9e3950af3461a3e0e28b30c12a07fca3944d31520ec7129d0ad79f80eb9c92d76f5fe8fada3b8d809373b0476a7751a6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/079829fef5da2f902c30f4b3bfa7956ab1050a63448d5f40b798d710ed8e082f49951f7ff1c7a5f08bf7c4f3c713d87faf1c5a99896209f095675c23ef78fd41", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ed3455658bfd1ba22cf032728643a4700cf823d91a1c974ac54196f59a1415f2f899d417e2ff4caa79e2aeb1a2c5c66c3460f5b9c2718042b8765c1a475c342e"} build_url:https://queue.taskcluster.net/v1/task/GC9SgidCQm-qxbCSWPyNww/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010916 build_url: 'https://queue.taskcluster.net/v1/task/GC9SgidCQm-qxbCSWPyNww/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/7b4924cae3f5208a0bcb39d6f9a14fbb9e3950af3461a3e0e28b30c12a07fca3944d31520ec7129d0ad79f80eb9c92d76f5fe8fada3b8d809373b0476a7751a6", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/079829fef5da2f902c30f4b3bfa7956ab1050a63448d5f40b798d710ed8e082f49951f7ff1c7a5f08bf7c4f3c713d87faf1c5a99896209f095675c23ef78fd41", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ed3455658bfd1ba22cf032728643a4700cf823d91a1c974ac54196f59a1415f2f899d417e2ff4caa79e2aeb1a2c5c66c3460f5b9c2718042b8765c1a475c342e"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-23 13:08:44.423617) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 13:08:44.423935) ========= 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.0FByRzYdkn/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.pkNtMGmDKa/Listeners TMPDIR=/var/folders/fg/pfrf4nhd1hz969r_0mf1rb2400000w/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.005077 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-23 13:08:44.473059) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-23 13:08:44.473375) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-23 13:08:44.473812) ========= ========= Total master_lag: 0.73 =========