builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0015 starttime: 1464875230.98 results: success (0) buildid: 20160602044143 builduid: 60d0460c3332412785e8edc42695dac2 revision: a92a2888973b566c4d4630df3a22869f80ff5587 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 06:47:10.979491) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-02 06:47:10.979978) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 06:47:10.980252) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-02 06:47:11.159344) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 06:47:11.159996) ========= 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.bUdM42UdzW/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.YGGrC0ORzB/Listeners TMPDIR=/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-06-02 06:47:11-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 29.5M=0s 2016-06-02 06:47:11 (29.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.580747 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 06:47:11.764190) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 06:47:11.764492) ========= 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.bUdM42UdzW/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.YGGrC0ORzB/Listeners TMPDIR=/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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.028434 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 06:47:11.813538) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-02 06:47:11.813997) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev a92a2888973b566c4d4630df3a22869f80ff5587 --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 a92a2888973b566c4d4630df3a22869f80ff5587 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bUdM42UdzW/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.YGGrC0ORzB/Listeners TMPDIR=/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-06-02 06:47:11,890 truncating revision to first 12 chars 2016-06-02 06:47:11,890 Setting DEBUG logging. 2016-06-02 06:47:11,890 attempt 1/10 2016-06-02 06:47:11,890 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/a92a2888973b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-02 06:47:12,882 unpacking tar archive at: fx-team-a92a2888973b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.272571 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-02 06:47:13.106495) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 06:47:13.106796) ========= script_repo_revision: a92a2888973b566c4d4630df3a22869f80ff5587 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-02 06:47:13.107196) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 06:47:13.107487) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-02 06:47:13.123628) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 30 secs) (at 2016-06-02 06:47:13.123909) ========= /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.bUdM42UdzW/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.YGGrC0ORzB/Listeners TMPDIR=/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 06:47:13 INFO - MultiFileLogger online at 20160602 06:47:13 in /builds/slave/test 06:47:13 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 06:47:13 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:47:13 INFO - {'append_to_log': False, 06:47:13 INFO - 'base_work_dir': '/builds/slave/test', 06:47:13 INFO - 'blob_upload_branch': 'Fx-Team', 06:47:13 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:47:13 INFO - 'branch': 'Fx-Team', 06:47:13 INFO - 'buildbot_json_path': 'buildprops.json', 06:47:13 INFO - 'config_files': ('talos/mac_config.py',), 06:47:13 INFO - 'default_actions': ('clobber', 06:47:13 INFO - 'read-buildbot-config', 06:47:13 INFO - 'download-and-extract', 06:47:13 INFO - 'populate-webroot', 06:47:13 INFO - 'create-virtualenv', 06:47:13 INFO - 'install', 06:47:13 INFO - 'run-tests'), 06:47:13 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:47:13 INFO - 'download_minidump_stackwalk': True, 06:47:13 INFO - 'download_symbols': 'ondemand', 06:47:13 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:47:13 INFO - 'tooltool.py': '/tools/tooltool.py', 06:47:13 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:47:13 INFO - '/tools/misc-python/virtualenv.py')}, 06:47:13 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:47:13 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:47:13 INFO - 'installer_path': 'installer.exe', 06:47:13 INFO - 'log_level': 'info', 06:47:13 INFO - 'log_name': 'talos', 06:47:13 INFO - 'log_to_console': True, 06:47:13 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:47:13 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:47:13 INFO - 'opt_config_files': (), 06:47:13 INFO - 'pip_index': False, 06:47:13 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:47:13 INFO - 'cmd': ('bash', 06:47:13 INFO - '-c', 06:47:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:47:13 INFO - 'enabled': True, 06:47:13 INFO - 'halt_on_failure': False, 06:47:13 INFO - 'name': 'check_screen_resolution'},), 06:47:13 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:47:13 INFO - 'cmd': ('bash', 06:47:13 INFO - '-c', 06:47:13 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:47:13 INFO - 'enabled': True, 06:47:13 INFO - 'halt_on_failure': False, 06:47:13 INFO - 'name': 'check_screen_resolution'},), 06:47:13 INFO - 'run_cmd_checks_enabled': True, 06:47:13 INFO - 'sps_profile': False, 06:47:13 INFO - 'sps_profile_interval': 0, 06:47:13 INFO - 'suite': 'dromaeojs', 06:47:13 INFO - 'system_bits': '32', 06:47:13 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:47:13 INFO - 'title': 't-yosemite-r7-0015', 06:47:13 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:47:13 INFO - 'use_talos_json': True, 06:47:13 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:47:13 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:47:13 INFO - 'work_dir': 'build'} 06:47:13 INFO - ##### 06:47:13 INFO - ##### Running clobber step. 06:47:13 INFO - ##### 06:47:13 INFO - Running pre-action listener: _resource_record_pre_action 06:47:13 INFO - Running main action method: clobber 06:47:13 INFO - rmtree: /builds/slave/test/build 06:47:13 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:47:15 INFO - Running post-action listener: _resource_record_post_action 06:47:15 INFO - ##### 06:47:15 INFO - ##### Finished clobber step (success) 06:47:15 INFO - ##### 06:47:15 INFO - ##### 06:47:15 INFO - ##### Running read-buildbot-config step. 06:47:15 INFO - ##### 06:47:15 INFO - Running pre-action listener: _resource_record_pre_action 06:47:15 INFO - Running main action method: read_buildbot_config 06:47:15 INFO - Using buildbot properties: 06:47:15 INFO - { 06:47:15 INFO - "project": "", 06:47:15 INFO - "product": "firefox", 06:47:15 INFO - "script_repo_revision": "production", 06:47:15 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 06:47:15 INFO - "repository": "", 06:47:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 06:47:15 INFO - "buildid": "20160602044143", 06:47:15 INFO - "pgo_build": "False", 06:47:15 INFO - "basedir": "/builds/slave/test", 06:47:15 INFO - "buildnumber": 32, 06:47:15 INFO - "slavename": "t-yosemite-r7-0015", 06:47:15 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 06:47:15 INFO - "platform": "yosemite_r7", 06:47:15 INFO - "scheduler": "tests-fx-team-macosx64-talos", 06:47:15 INFO - "branch": "fx-team", 06:47:15 INFO - "repo_path": "integration/fx-team", 06:47:15 INFO - "revision": "a92a2888973b566c4d4630df3a22869f80ff5587", 06:47:15 INFO - "stage_platform": "macosx64", 06:47:15 INFO - "builduid": "60d0460c3332412785e8edc42695dac2", 06:47:15 INFO - "slavebuilddir": "test" 06:47:15 INFO - } 06:47:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/Vl-5PFJ2Q7-bcarXMTvNDQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 06:47:15 INFO - Running post-action listener: _resource_record_post_action 06:47:15 INFO - ##### 06:47:15 INFO - ##### Finished read-buildbot-config step (success) 06:47:15 INFO - ##### 06:47:15 INFO - ##### 06:47:15 INFO - ##### Running download-and-extract step. 06:47:15 INFO - ##### 06:47:15 INFO - Running pre-action listener: _resource_record_pre_action 06:47:15 INFO - Running main action method: download_and_extract 06:47:15 INFO - mkdir: /builds/slave/test/build/tests 06:47:15 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')]} 06:47:15 INFO - https://queue.taskcluster.net/v1/task/Vl-5PFJ2Q7-bcarXMTvNDQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 06:47:15 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vl-5PFJ2Q7-bcarXMTvNDQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 06:47:15 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vl-5PFJ2Q7-bcarXMTvNDQ/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 06:47:15 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vl-5PFJ2Q7-bcarXMTvNDQ/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 06:47:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vl-5PFJ2Q7-bcarXMTvNDQ/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 06:47:30 INFO - Downloaded 1199 bytes. 06:47:30 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 06:47:30 INFO - Using the following test package requirements: 06:47:30 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 06:47:30 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:47:30 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 06:47:30 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:47:30 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 06:47:30 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:47:30 INFO - u'jsshell-mac.zip'], 06:47:30 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:47:30 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 06:47:30 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 06:47:30 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:47:30 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 06:47:30 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:47:30 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 06:47:30 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:47:30 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 06:47:30 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 06:47:30 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 06:47:30 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:47:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:47:30 INFO - https://queue.taskcluster.net/v1/task/Vl-5PFJ2Q7-bcarXMTvNDQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 06:47:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vl-5PFJ2Q7-bcarXMTvNDQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 06:47:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vl-5PFJ2Q7-bcarXMTvNDQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 06:47:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vl-5PFJ2Q7-bcarXMTvNDQ/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 06:47:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vl-5PFJ2Q7-bcarXMTvNDQ/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 06:47:37 INFO - Downloaded 18713681 bytes. 06: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'] 06: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 06:47:40 INFO - Return code: 0 06:47:40 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 06: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')]} 06:47:40 INFO - https://queue.taskcluster.net/v1/task/Vl-5PFJ2Q7-bcarXMTvNDQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 06:47:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vl-5PFJ2Q7-bcarXMTvNDQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 06:47:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vl-5PFJ2Q7-bcarXMTvNDQ/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 06:47:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vl-5PFJ2Q7-bcarXMTvNDQ/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 06:47:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vl-5PFJ2Q7-bcarXMTvNDQ/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 06:47:42 INFO - Downloaded 18713681 bytes. 06:47:42 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'] 06:47:42 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 06:47:45 INFO - Return code: 0 06:47:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:47:45 INFO - https://queue.taskcluster.net/v1/task/Vl-5PFJ2Q7-bcarXMTvNDQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 06:47:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vl-5PFJ2Q7-bcarXMTvNDQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 06:47:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vl-5PFJ2Q7-bcarXMTvNDQ/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 06:47:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vl-5PFJ2Q7-bcarXMTvNDQ/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 06:47:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vl-5PFJ2Q7-bcarXMTvNDQ/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 06:47:46 INFO - Downloaded 10834055 bytes. 06:47:46 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'] 06:47:46 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 06:47:47 INFO - Return code: 0 06:47:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:47:47 INFO - https://queue.taskcluster.net/v1/task/Vl-5PFJ2Q7-bcarXMTvNDQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 06:47:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vl-5PFJ2Q7-bcarXMTvNDQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 06:47:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vl-5PFJ2Q7-bcarXMTvNDQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 06:47:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vl-5PFJ2Q7-bcarXMTvNDQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 06:47:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/Vl-5PFJ2Q7-bcarXMTvNDQ/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 06:47:51 INFO - Downloaded 111074043 bytes. 06:47:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Vl-5PFJ2Q7-bcarXMTvNDQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 06:47:51 INFO - mkdir: /builds/slave/test/properties 06:47:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:47:51 INFO - Writing to file /builds/slave/test/properties/build_url 06:47:51 INFO - Contents: 06:47:51 INFO - build_url:https://queue.taskcluster.net/v1/task/Vl-5PFJ2Q7-bcarXMTvNDQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 06:47:52 INFO - Running post-action listener: _resource_record_post_action 06:47:52 INFO - Running post-action listener: set_extra_try_arguments 06:47:52 INFO - ##### 06:47:52 INFO - ##### Finished download-and-extract step (success) 06:47:52 INFO - ##### 06:47:52 INFO - ##### 06:47:52 INFO - ##### Running populate-webroot step. 06:47:52 INFO - ##### 06:47:52 INFO - Running pre-action listener: _resource_record_pre_action 06:47:52 INFO - Running main action method: populate_webroot 06:47:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:47:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:47:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:47:52 INFO - '--tppagecycles', 06:47:52 INFO - '7'], 06:47:52 INFO - 'tests': ['tsvgm']}}, 06:47:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:47:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:47:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:47:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:47:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:47:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:47:52 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:47:52 INFO - 'talos_options': ['--disable-e10s'], 06:47:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:47:52 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:47:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:47:52 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:47:52 INFO - 'talos_options': ['--disable-e10s'], 06:47:52 INFO - 'tests': ['damp', 'tps']}, 06:47:52 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:47:52 INFO - 'tests': ['damp', 'tps']}, 06:47:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:47:52 INFO - 'tests': ['dromaeo_dom']}, 06:47:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:47:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:47:52 INFO - 'tests': ['a11yr', 06:47:52 INFO - 'ts_paint', 06:47:52 INFO - 'tpaint', 06:47:52 INFO - 'sessionrestore', 06:47:52 INFO - 'sessionrestore_no_auto_restore', 06:47:52 INFO - 'tabpaint']}, 06:47:52 INFO - 'other-e10s': {'tests': ['a11yr', 06:47:52 INFO - 'ts_paint', 06:47:52 INFO - 'tpaint', 06:47:52 INFO - 'sessionrestore', 06:47:52 INFO - 'sessionrestore_no_auto_restore', 06:47:52 INFO - 'tabpaint']}, 06:47:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:47:52 INFO - 'ts_paint', 06:47:52 INFO - 'tpaint', 06:47:52 INFO - 'sessionrestore', 06:47:52 INFO - 'sessionrestore_no_auto_restore', 06:47:52 INFO - 'tabpaint']}, 06:47:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:47:52 INFO - 'ts_paint', 06:47:52 INFO - 'tpaint', 06:47:52 INFO - 'sessionrestore', 06:47:52 INFO - 'sessionrestore_no_auto_restore', 06:47:52 INFO - 'tabpaint']}, 06:47:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:47:52 INFO - 'tests': ['a11yr', 06:47:52 INFO - 'ts_paint', 06:47:52 INFO - 'tpaint', 06:47:52 INFO - 'sessionrestore', 06:47:52 INFO - 'sessionrestore_no_auto_restore', 06:47:52 INFO - 'tabpaint']}, 06:47:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:47:52 INFO - 'tests': ['a11yr', 06:47:52 INFO - 'ts_paint', 06:47:52 INFO - 'tpaint', 06:47:52 INFO - 'sessionrestore', 06:47:52 INFO - 'sessionrestore_no_auto_restore', 06:47:52 INFO - 'tabpaint']}, 06:47:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:47:52 INFO - 'tests': ['tsvgx', 06:47:52 INFO - 'tsvgr_opacity', 06:47:52 INFO - 'tart', 06:47:52 INFO - 'tscrollx', 06:47:52 INFO - 'cart']}, 06:47:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:47:52 INFO - 'tsvgr_opacity', 06:47:52 INFO - 'tart', 06:47:52 INFO - 'tscrollx', 06:47:52 INFO - 'cart']}, 06:47:52 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:47:52 INFO - 'talos_options': ['--disable-e10s'], 06:47:52 INFO - 'tests': ['tp5o']}, 06:47:52 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:47:52 INFO - 'tests': ['tp5o']}, 06:47:52 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:47:52 INFO - 'talos_options': ['--disable-e10s', 06:47:52 INFO - '--xperf_path', 06:47:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:47:52 INFO - 'tests': ['tp5n']}, 06:47:52 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:47:52 INFO - 'talos_options': ['--xperf_path', 06:47:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:47:52 INFO - 'tests': ['tp5n']}}, 06:47:52 INFO - 'talos.zip': {'path': '', 06:47:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:47:52 INFO - Running post-action listener: _resource_record_post_action 06:47:52 INFO - ##### 06:47:52 INFO - ##### Finished populate-webroot step (success) 06:47:52 INFO - ##### 06:47:52 INFO - ##### 06:47:52 INFO - ##### Running create-virtualenv step. 06:47:52 INFO - ##### 06:47:52 INFO - Running pre-action listener: _resource_record_pre_action 06:47:52 INFO - Running main action method: create_virtualenv 06:47:52 INFO - Creating virtualenv /builds/slave/test/build/venv 06:47:52 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 06:47:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:47:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:47:52 INFO - Using real prefix '/tools/python27' 06:47:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:47:53 INFO - Installing distribute.............................................................................................................................................................................................done. 06:47:56 INFO - Installing pip.................done. 06:47:56 INFO - Return code: 0 06:47:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06: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')]} 06:47:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:47:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06: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')]} 06:47:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:47:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ce47a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce4d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca82570e60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccebd30>, '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 0x10cb0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cce2b20>, '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.bUdM42UdzW/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.YGGrC0ORzB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 06: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', 'pip>=1.5'] in /builds/slave/test/build 06: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 pip>=1.5 06:47:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bUdM42UdzW/Render', 06:47:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:47:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:47:56 INFO - 'HOME': '/Users/cltbld', 06:47:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:47:56 INFO - 'LOGNAME': 'cltbld', 06:47:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:47:56 INFO - 'MOZ_NO_REMOTE': '1', 06:47:56 INFO - 'NO_EM_RESTART': '1', 06:47:56 INFO - 'PAGER': '/bin/cat', 06:47:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:47:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:47:56 INFO - 'PWD': '/builds/slave/test', 06:47:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:47:56 INFO - 'SHELL': '/bin/bash', 06:47:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YGGrC0ORzB/Listeners', 06:47:56 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 06:47:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:47:56 INFO - 'USER': 'cltbld', 06:47:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:47:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:47:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:47:56 INFO - 'XPC_FLAGS': '0x0', 06:47:56 INFO - 'XPC_SERVICE_NAME': '0', 06:47:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:47:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:47:56 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 06:47:56 INFO - Cleaning up... 06:47:56 INFO - Return code: 0 06:47:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06: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')]} 06:47:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:47:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06: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')]} 06:47:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:47:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06: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', '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 0x10ce47a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce4d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca82570e60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccebd30>, '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 0x10cb0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cce2b20>, '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.bUdM42UdzW/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.YGGrC0ORzB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 06: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', 'psutil>=3.1.1'] in /builds/slave/test/build 06: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 psutil>=3.1.1 06:47:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bUdM42UdzW/Render', 06:47:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:47:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:47:56 INFO - 'HOME': '/Users/cltbld', 06:47:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:47:56 INFO - 'LOGNAME': 'cltbld', 06:47:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:47:56 INFO - 'MOZ_NO_REMOTE': '1', 06:47:56 INFO - 'NO_EM_RESTART': '1', 06:47:56 INFO - 'PAGER': '/bin/cat', 06:47:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:47:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:47:56 INFO - 'PWD': '/builds/slave/test', 06:47:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:47:56 INFO - 'SHELL': '/bin/bash', 06:47:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YGGrC0ORzB/Listeners', 06:47:56 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 06:47:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:47:56 INFO - 'USER': 'cltbld', 06:47:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:47:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:47:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:47:56 INFO - 'XPC_FLAGS': '0x0', 06:47:56 INFO - 'XPC_SERVICE_NAME': '0', 06:47:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:47:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:47:56 INFO - Downloading/unpacking psutil>=3.1.1 06: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 06: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 06: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 06: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 06: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 06: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 06:47:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:48:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:48:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:48:00 INFO - Installing collected packages: psutil 06:48:00 INFO - Running setup.py install for psutil 06:48:00 INFO - building 'psutil._psutil_osx' extension 06:48:00 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 06:48:00 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 06:48:00 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 06:48:00 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 06:48:01 INFO - building 'psutil._psutil_posix' extension 06:48:01 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 06:48:01 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:48:01 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:48:01 INFO - ^ 06:48:01 INFO - 1 warning generated. 06:48:01 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 06:48:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:48:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:48:01 INFO - Successfully installed psutil 06:48:01 INFO - Cleaning up... 06:48:01 INFO - Return code: 0 06:48:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:48:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:48:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:48:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:01 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 0x10ce47a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce4d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca82570e60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccebd30>, '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 0x10cb0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cce2b20>, '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.bUdM42UdzW/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.YGGrC0ORzB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 06:48:01 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 06:48:01 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 06:48:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bUdM42UdzW/Render', 06:48:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:48:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:48:01 INFO - 'HOME': '/Users/cltbld', 06:48:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:48:01 INFO - 'LOGNAME': 'cltbld', 06:48:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:01 INFO - 'MOZ_NO_REMOTE': '1', 06:48:01 INFO - 'NO_EM_RESTART': '1', 06:48:01 INFO - 'PAGER': '/bin/cat', 06:48:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:48:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:01 INFO - 'PWD': '/builds/slave/test', 06:48:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:48:01 INFO - 'SHELL': '/bin/bash', 06:48:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YGGrC0ORzB/Listeners', 06:48:01 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 06:48:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:48:01 INFO - 'USER': 'cltbld', 06:48:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:48:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:48:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:01 INFO - 'XPC_FLAGS': '0x0', 06:48:01 INFO - 'XPC_SERVICE_NAME': '0', 06:48:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:48:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:01 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:48:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:03 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:48:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:48:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:48:04 INFO - Installing collected packages: mozsystemmonitor 06:48:04 INFO - Running setup.py install for mozsystemmonitor 06:48:04 INFO - Successfully installed mozsystemmonitor 06:48:04 INFO - Cleaning up... 06:48:04 INFO - Return code: 0 06:48:04 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06: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')]} 06:48:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06: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')]} 06:48:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:04 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 0x10ce47a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce4d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca82570e60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccebd30>, '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 0x10cb0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cce2b20>, '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.bUdM42UdzW/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.YGGrC0ORzB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 06:48:04 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 06:48:04 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 06:48:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bUdM42UdzW/Render', 06:48:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:48:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:48:04 INFO - 'HOME': '/Users/cltbld', 06:48:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:48:04 INFO - 'LOGNAME': 'cltbld', 06:48:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:04 INFO - 'MOZ_NO_REMOTE': '1', 06:48:04 INFO - 'NO_EM_RESTART': '1', 06:48:04 INFO - 'PAGER': '/bin/cat', 06:48:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:48:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:04 INFO - 'PWD': '/builds/slave/test', 06:48:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:48:04 INFO - 'SHELL': '/bin/bash', 06:48:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YGGrC0ORzB/Listeners', 06:48:04 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 06:48:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:48:04 INFO - 'USER': 'cltbld', 06:48:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:48:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:48:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:04 INFO - 'XPC_FLAGS': '0x0', 06:48:04 INFO - 'XPC_SERVICE_NAME': '0', 06:48:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:48:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:04 INFO - Downloading/unpacking blobuploader==1.2.4 06:48:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:06 INFO - Downloading blobuploader-1.2.4.tar.gz 06:48:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:48:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:48:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:48:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:48:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:08 INFO - Downloading docopt-0.6.1.tar.gz 06:48:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:48:08 INFO - Installing collected packages: blobuploader, requests, docopt 06:48:08 INFO - Running setup.py install for blobuploader 06:48:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:48:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:48:08 INFO - Running setup.py install for requests 06:48:08 INFO - Running setup.py install for docopt 06:48:09 INFO - Successfully installed blobuploader requests docopt 06:48:09 INFO - Cleaning up... 06:48:09 INFO - Return code: 0 06:48:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:48:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:48:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:48:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:09 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 0x10ce47a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce4d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca82570e60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccebd30>, '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 0x10cb0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cce2b20>, '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.bUdM42UdzW/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.YGGrC0ORzB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 06:48:09 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 06:48:09 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 06:48:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bUdM42UdzW/Render', 06:48:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:48:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:48:09 INFO - 'HOME': '/Users/cltbld', 06:48:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:48:09 INFO - 'LOGNAME': 'cltbld', 06:48:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:09 INFO - 'MOZ_NO_REMOTE': '1', 06:48:09 INFO - 'NO_EM_RESTART': '1', 06:48:09 INFO - 'PAGER': '/bin/cat', 06:48:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:48:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:09 INFO - 'PWD': '/builds/slave/test', 06:48:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:48:09 INFO - 'SHELL': '/bin/bash', 06:48:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YGGrC0ORzB/Listeners', 06:48:09 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 06:48:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:48:09 INFO - 'USER': 'cltbld', 06:48:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:48:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:48:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:09 INFO - 'XPC_FLAGS': '0x0', 06:48:09 INFO - 'XPC_SERVICE_NAME': '0', 06:48:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:48:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:48:09 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-a8p99l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:48:09 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-6XthlL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:48:09 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-qAAGzt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:48:09 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-KJFtCN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:48:09 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-D3HUbz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:48:09 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-V6Wvz9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:48:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:48:10 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-slDMmb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:48:10 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-m9nDTh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:48:10 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-ly6CLN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:48:10 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-O_QspV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:48:10 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-bGufov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:48:10 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-OVjI5p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:48:10 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-2rYgTV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:48:10 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-RDrpEI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:48:10 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-d0vdFW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:48:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:48:10 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-4XwzaN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:48:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:48:11 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-ASwEBL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:48:11 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:48:11 INFO - Running setup.py install for manifestparser 06:48:11 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:48:11 INFO - Running setup.py install for mozcrash 06:48:11 INFO - Running setup.py install for mozdebug 06:48:11 INFO - Running setup.py install for mozdevice 06:48:11 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:48:11 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:48:11 INFO - Running setup.py install for mozfile 06:48:11 INFO - Running setup.py install for mozhttpd 06:48:11 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:48:12 INFO - Running setup.py install for mozinfo 06:48:12 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:48:12 INFO - Running setup.py install for mozInstall 06:48:12 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:48:12 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:48:12 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:48:12 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:48:12 INFO - Running setup.py install for mozleak 06:48:12 INFO - Running setup.py install for mozlog 06:48:12 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:48:12 INFO - Running setup.py install for moznetwork 06:48:12 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:48:12 INFO - Running setup.py install for mozprocess 06:48:12 INFO - Running setup.py install for mozprofile 06:48:13 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:48:13 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:48:13 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:48:13 INFO - Running setup.py install for mozrunner 06:48:13 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:48:13 INFO - Running setup.py install for mozscreenshot 06:48:13 INFO - Running setup.py install for moztest 06:48:13 INFO - Running setup.py install for mozversion 06:48:13 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:48:13 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:48:13 INFO - Cleaning up... 06:48:13 INFO - Return code: 0 06:48:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:48:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:48:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:48:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:13 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 0x10ce47a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce4d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca82570e60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccebd30>, '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 0x10cb0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cce2b20>, '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.bUdM42UdzW/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.YGGrC0ORzB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 06:48:13 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 06:48:13 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 06:48:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bUdM42UdzW/Render', 06:48:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:48:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:48:13 INFO - 'HOME': '/Users/cltbld', 06:48:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:48:13 INFO - 'LOGNAME': 'cltbld', 06:48:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:13 INFO - 'MOZ_NO_REMOTE': '1', 06:48:13 INFO - 'NO_EM_RESTART': '1', 06:48:13 INFO - 'PAGER': '/bin/cat', 06:48:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:48:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:13 INFO - 'PWD': '/builds/slave/test', 06:48:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:48:13 INFO - 'SHELL': '/bin/bash', 06:48:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YGGrC0ORzB/Listeners', 06:48:13 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 06:48:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:48:13 INFO - 'USER': 'cltbld', 06:48:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:48:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:48:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:13 INFO - 'XPC_FLAGS': '0x0', 06:48:13 INFO - 'XPC_SERVICE_NAME': '0', 06:48:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:48:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:48:13 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-x1FxsE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:48:14 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)) 06:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:48:14 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-j5_K4z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:48:14 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)) 06:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:48:14 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-V50kTs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:48:14 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)) 06:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:48:14 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-Oc4FHs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:48:14 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)) 06:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:48:14 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-I254zC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:48:14 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)) 06:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:48:14 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-cneuz1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:48:14 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)) 06:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:48:14 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-2rTakC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:48:14 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)) 06:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:48:14 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-5JFMbH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:48:14 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)) 06:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:48:14 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-qtJOBL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:48:14 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)) 06:48:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:48:14 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-DAXXnq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:48:15 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)) 06:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:48:15 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-k1TvBG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:48:15 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)) 06:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:48:15 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-mKCgWY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:48:15 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)) 06:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:48:15 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-JbY3Y9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:48:15 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)) 06:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:48:15 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-AFaaCu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:48:15 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)) 06:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:48:15 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-taXMQh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:48:15 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)) 06:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:48:15 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-rdL25w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:48:15 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)) 06:48:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:48:15 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-c_OPso-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:48:15 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)) 06:48:15 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)) 06:48:15 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)) 06:48:15 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)) 06:48:15 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)) 06:48:15 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)) 06:48:15 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:48:15 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 06:48:15 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 06:48:15 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 06:48:15 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 06:48:15 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 06:48:15 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 06:48:18 INFO - Downloading blessings-1.6.tar.gz 06:48:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:48:18 INFO - Installing collected packages: blessings 06:48:18 INFO - Running setup.py install for blessings 06:48:18 INFO - Successfully installed blessings 06:48:18 INFO - Cleaning up... 06:48:18 INFO - Return code: 0 06:48:18 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:48:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:48:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:48:18 INFO - Reading from file tmpfile_stdout 06:48:18 INFO - Current package versions: 06:48:18 INFO - blessings == 1.6 06:48:18 INFO - blobuploader == 1.2.4 06:48:18 INFO - docopt == 0.6.1 06:48:18 INFO - manifestparser == 1.1 06:48:18 INFO - mozInstall == 1.12 06:48:18 INFO - mozcrash == 0.17 06:48:18 INFO - mozdebug == 0.1 06:48:18 INFO - mozdevice == 0.48 06:48:18 INFO - mozfile == 1.2 06:48:18 INFO - mozhttpd == 0.7 06:48:18 INFO - mozinfo == 0.9 06:48:18 INFO - mozleak == 0.1 06:48:18 INFO - mozlog == 3.2 06:48:18 INFO - moznetwork == 0.27 06:48:18 INFO - mozprocess == 0.22 06:48:18 INFO - mozprofile == 0.28 06:48:18 INFO - mozrunner == 6.11 06:48:18 INFO - mozscreenshot == 0.1 06:48:18 INFO - mozsystemmonitor == 0.3 06:48:18 INFO - moztest == 0.7 06:48:18 INFO - mozversion == 1.4 06:48:18 INFO - psutil == 3.1.1 06:48:18 INFO - requests == 1.2.3 06:48:18 INFO - wsgiref == 0.1.2 06:48:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06: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')]} 06:48:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06: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')]} 06:48:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:18 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 0x10ce47a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce4d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca82570e60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccebd30>, '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 0x10cb0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cce2b20>, '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.bUdM42UdzW/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.YGGrC0ORzB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 06:48:18 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 06:48:18 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 06:48:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bUdM42UdzW/Render', 06:48:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:48:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:48:18 INFO - 'HOME': '/Users/cltbld', 06:48:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:48:18 INFO - 'LOGNAME': 'cltbld', 06:48:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:18 INFO - 'MOZ_NO_REMOTE': '1', 06:48:18 INFO - 'NO_EM_RESTART': '1', 06:48:18 INFO - 'PAGER': '/bin/cat', 06:48:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:48:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:18 INFO - 'PWD': '/builds/slave/test', 06:48:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:48:18 INFO - 'SHELL': '/bin/bash', 06:48:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YGGrC0ORzB/Listeners', 06:48:18 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 06:48:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:48:18 INFO - 'USER': 'cltbld', 06:48:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:48:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:48:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:18 INFO - 'XPC_FLAGS': '0x0', 06:48:18 INFO - 'XPC_SERVICE_NAME': '0', 06:48:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:48:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:19 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)) 06:48:19 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)) 06:48:19 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)) 06:48:19 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)) 06:48:19 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)) 06:48:19 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)) 06:48:19 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)) 06:48:19 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)) 06:48:19 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)) 06:48:19 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)) 06:48:19 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)) 06:48:19 INFO - Cleaning up... 06:48:19 INFO - Return code: 0 06:48:19 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:48:19 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')]} 06:48:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:19 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')]} 06:48:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:19 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 0x10ce47a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce4d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca82570e60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccebd30>, '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 0x10cb0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cce2b20>, '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.bUdM42UdzW/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.YGGrC0ORzB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 06:48:19 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 06:48:19 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 06:48:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bUdM42UdzW/Render', 06:48:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:48:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:48:19 INFO - 'HOME': '/Users/cltbld', 06:48:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:48:19 INFO - 'LOGNAME': 'cltbld', 06:48:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:19 INFO - 'MOZ_NO_REMOTE': '1', 06:48:19 INFO - 'NO_EM_RESTART': '1', 06:48:19 INFO - 'PAGER': '/bin/cat', 06:48:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:48:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:19 INFO - 'PWD': '/builds/slave/test', 06:48:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:48:19 INFO - 'SHELL': '/bin/bash', 06:48:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YGGrC0ORzB/Listeners', 06:48:19 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 06:48:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:48:19 INFO - 'USER': 'cltbld', 06:48:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:48:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:48:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:19 INFO - 'XPC_FLAGS': '0x0', 06:48:19 INFO - 'XPC_SERVICE_NAME': '0', 06:48:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:48:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:19 INFO - Downloading/unpacking jsonschema 06:48:19 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 06:48:19 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 06:48:19 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 06:48:19 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 06:48:19 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 06:48:19 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 06:48:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:48:21 INFO - Downloading/unpacking functools32 (from jsonschema) 06: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 06: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 06: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 06: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 06: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 06: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 06:48:22 INFO - Downloading functools32-3.2.3-2.tar.gz 06:48:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:48:22 INFO - warning: no files found matching '*.txt' 06:48:22 INFO - no previously-included directories found matching 'build' 06:48:22 INFO - no previously-included directories found matching 'dist' 06:48:22 INFO - no previously-included directories found matching '.git*' 06:48:22 INFO - Installing collected packages: jsonschema, functools32 06:48:22 INFO - Running setup.py install for functools32 06:48:22 INFO - warning: no files found matching '*.txt' 06:48:22 INFO - no previously-included directories found matching 'build' 06:48:22 INFO - no previously-included directories found matching 'dist' 06:48:22 INFO - no previously-included directories found matching '.git*' 06:48:22 INFO - Successfully installed jsonschema functools32 06:48:22 INFO - Cleaning up... 06:48:22 INFO - Return code: 0 06:48:22 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 06:48:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:48:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:48:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:48:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:48:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:48:22 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 0x10ce47a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce4d570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fca82570e60>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ccebd30>, '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 0x10cb0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cce2b20>, '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.bUdM42UdzW/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.YGGrC0ORzB/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 06:48:22 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 06:48:22 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 06:48:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bUdM42UdzW/Render', 06:48:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:48:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:48:22 INFO - 'HOME': '/Users/cltbld', 06:48:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:48:22 INFO - 'LOGNAME': 'cltbld', 06:48:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:48:22 INFO - 'MOZ_NO_REMOTE': '1', 06:48:22 INFO - 'NO_EM_RESTART': '1', 06:48:22 INFO - 'PAGER': '/bin/cat', 06:48:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:48:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:48:22 INFO - 'PWD': '/builds/slave/test', 06:48:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:48:22 INFO - 'SHELL': '/bin/bash', 06:48:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YGGrC0ORzB/Listeners', 06:48:22 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 06:48:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:48:22 INFO - 'USER': 'cltbld', 06:48:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:48:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:48:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:48:22 INFO - 'XPC_FLAGS': '0x0', 06:48:22 INFO - 'XPC_SERVICE_NAME': '0', 06:48:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:48:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:48:22 INFO - Downloading/unpacking flake8 06:48:22 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 06:48:22 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 06:48:22 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 06:48:22 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 06:48:22 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 06:48:22 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 06:48:25 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 06:48:25 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 06:48:25 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 06:48:25 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 06:48:25 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 06:48:25 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 06:48:25 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 06:48:25 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 06:48:25 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 06:48:25 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 06:48:25 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 06:48:25 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 06:48:25 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 06:48:25 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 06:48:25 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 06:48:25 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 06:48:26 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 06:48:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:48:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:48:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:48:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 06:48:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:48:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:48:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:48:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:48:26 INFO - no previously-included directories found matching 'docs/_build' 06:48:26 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 06:48:26 INFO - Running setup.py install for pep8 06:48:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 06:48:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 06:48:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 06:48:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 06:48:26 INFO - no previously-included directories found matching 'docs/_build' 06:48:26 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 06:48:26 INFO - Successfully installed flake8 mccabe pyflakes pep8 06:48:26 INFO - Cleaning up... 06:48:26 INFO - Return code: 0 06:48:26 INFO - Running post-action listener: _resource_record_post_action 06:48:26 INFO - Running post-action listener: _start_resource_monitoring 06:48:26 INFO - Starting resource monitoring. 06:48:26 INFO - ##### 06:48:26 INFO - ##### Finished create-virtualenv step (success) 06:48:26 INFO - ##### 06:48:26 INFO - ##### 06:48:26 INFO - ##### Running install step. 06:48:26 INFO - ##### 06:48:26 INFO - Running pre-action listener: _resource_record_pre_action 06:48:26 INFO - Running main action method: install 06:48:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:48:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:48:27 INFO - Reading from file tmpfile_stdout 06:48:27 INFO - Detecting whether we're running mozinstall >=1.0... 06:48:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:48:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:48:27 INFO - Reading from file tmpfile_stdout 06:48:27 INFO - Output received: 06:48:27 INFO - Usage: mozinstall [options] installer 06:48:27 INFO - Options: 06:48:27 INFO - -h, --help show this help message and exit 06:48:27 INFO - -d DEST, --destination=DEST 06:48:27 INFO - Directory to install application into. [default: 06:48:27 INFO - "/builds/slave/test"] 06:48:27 INFO - --app=APP Application being installed. [default: firefox] 06:48:27 INFO - mkdir: /builds/slave/test/build/application 06:48:27 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'] 06:48:27 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 06:49:01 INFO - Reading from file tmpfile_stdout 06:49:01 INFO - Output received: 06:49:01 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 06:49:01 INFO - Running post-action listener: _resource_record_post_action 06:49:01 INFO - ##### 06:49:01 INFO - ##### Finished install step (success) 06:49:01 INFO - ##### 06:49:01 INFO - ##### 06:49:01 INFO - ##### Running run-tests step. 06:49:01 INFO - ##### 06:49:01 INFO - Running pre-action listener: _resource_record_pre_action 06:49:01 INFO - Running main action method: run_tests 06:49:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:49:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:49:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:49:01 INFO - 2016-06-02 06:49:01.037 screenresolution[1652:8533] starting screenresolution argv=screenresolution get 06:49:01 INFO - 2016-06-02 06:49:01.039 screenresolution[1652:8533] Display 0: 1600x1200x32@60 06:49:01 INFO - 2016-06-02 06:49:01.045 screenresolution[1653:8535] starting screenresolution argv=screenresolution list 06:49:01 INFO - Available Modes on Display 0 06:49:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:49:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:49:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:49:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:49:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:49:01 INFO - Intel Iris: 06:49:01 INFO - Chipset Model: Intel Iris 06:49:01 INFO - Type: GPU 06:49:01 INFO - Bus: Built-In 06:49:01 INFO - VRAM (Dynamic, Max): 1536 MB 06:49:01 INFO - Vendor: Intel (0x8086) 06:49:01 INFO - Device ID: 0x0a2e 06:49:01 INFO - Revision ID: 0x0009 06:49:01 INFO - Displays: 06:49:01 INFO - Display: 06:49:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:49:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:49:01 INFO - Display Serial Number: 1600x1200 60 06:49:01 INFO - Main Display: Yes 06:49:01 INFO - Mirror: Off 06:49:01 INFO - Online: Yes 06:49:01 INFO - Rotation: Supported 06:49:01 INFO - Return code: 0 06:49:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:49:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:49:01 INFO - Python 2.7.3 06:49:01 INFO - Return code: 0 06:49:01 INFO - grabbing minidump binary from tooltool 06:49:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 06:49:01 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 0x10ccebd30>, '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 0x10cb0fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cce2b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:49:01 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 06:49:01 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 06:49:01 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 06:49:01 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:49:01 INFO - Return code: 0 06:49:01 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:49:01 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:49:01 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:49:01 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:49:01 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:49:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 06:49:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 06:49:02 INFO - Return code: 0 06:49:02 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/Vl-5PFJ2Q7-bcarXMTvNDQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0015', '--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 06:49:02 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/Vl-5PFJ2Q7-bcarXMTvNDQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0015 --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 06:49:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bUdM42UdzW/Render', 06:49:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:49:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:49:02 INFO - 'HOME': '/Users/cltbld', 06:49:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:49:02 INFO - 'LOGNAME': 'cltbld', 06:49:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:49:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:49:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:49:02 INFO - 'MOZ_NO_REMOTE': '1', 06:49:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:49:02 INFO - 'NO_EM_RESTART': '1', 06:49:02 INFO - 'PAGER': '/bin/cat', 06:49:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:49:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:49:02 INFO - 'PWD': '/builds/slave/test', 06:49:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:49:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:49:02 INFO - 'SHELL': '/bin/bash', 06:49:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YGGrC0ORzB/Listeners', 06:49:02 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 06:49:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:49:02 INFO - 'USER': 'cltbld', 06:49:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:49:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:49:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:49:02 INFO - 'XPC_FLAGS': '0x0', 06:49:02 INFO - 'XPC_SERVICE_NAME': '0', 06:49:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:49:02 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/Vl-5PFJ2Q7-bcarXMTvNDQ/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0015', '--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 06:49:02 INFO - mozversion application_buildid: 20160602044143 06:49:02 INFO - mozversion application_changeset: a92a2888973b566c4d4630df3a22869f80ff5587 06:49:02 INFO - mozversion application_display_name: Nightly 06:49:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:49:02 INFO - mozversion application_name: Firefox 06:49:02 INFO - mozversion application_remotingname: firefox 06:49:02 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 06:49:02 INFO - mozversion application_vendor: Mozilla 06:49:02 INFO - mozversion application_version: 49.0a1 06:49:02 INFO - mozversion platform_buildid: 20160602044143 06:49:02 INFO - mozversion platform_changeset: a92a2888973b566c4d4630df3a22869f80ff5587 06:49:02 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 06:49:02 INFO - mozversion platform_version: 49.0a1 06:49:02 INFO - using testdate: 1464875342 06:49:02 INFO - actual date: 1464875342 06:49:02 INFO - starting webserver on 'localhost:49281' 06:49:02 INFO - SUITE-START | Running 2 tests 06:49:02 INFO - TEST-START | dromaeo_css 06:49:02 INFO - operating with platform_type : mac_ 06:49:02 INFO - Initialising browser for dromaeo_css test... 06:49:02 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/tmpoH9ZrA/profile http://localhost:49281/getInfo.html) 06:49:05 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 06:49:05 INFO - PROCESS | 1671 | colorDepth:24 06:49:05 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 06:49:05 INFO - PROCESS | 1671 | __metrics 06:49:05 INFO - PROCESS | 1671 | 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" 06:49:05 ERROR - PROCESS | 1671 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 06:49:05 INFO - TEST-INFO | 1671: exit 0 06:49:05 INFO - Browser initialized. 06:49:05 INFO - Running cycle 1/1 for dromaeo_css test... 06:49:05 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/tmpoH9ZrA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:49:17 INFO - PROCESS | 1674 | RSS: Main: 242393088 06:49:17 INFO - PROCESS | 1674 | 06:50:09 INFO - PROCESS | 1674 | 1464875409305 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] 06:52:19 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49281/tests/dromaeo/cssquery-ext.html) 06:52:21 INFO - PROCESS | 1674 | RSS: Main: 364277760 06:52:21 INFO - PROCESS | 1674 | 06:55:24 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-ext.html (next: http://localhost:49281/tests/dromaeo/cssquery-jquery.html) 06:55:25 INFO - PROCESS | 1674 | RSS: Main: 380702720 06:55:25 INFO - PROCESS | 1674 | 06:58:27 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49281/tests/dromaeo/cssquery-mootools.html) 06:58:27 INFO - PROCESS | 1674 | RSS: Main: 373678080 06:58:27 INFO - PROCESS | 1674 | 07:01:29 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49281/tests/dromaeo/cssquery-prototype.html) 07:01:30 INFO - PROCESS | 1674 | RSS: Main: 372965376 07:01:30 INFO - PROCESS | 1674 | 07:04:33 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49281/tests/dromaeo/cssquery-yui.html) 07:04:34 INFO - PROCESS | 1674 | RSS: Main: 338546688 07:04:34 INFO - PROCESS | 1674 | 07:07:37 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49281/tests/dromaeo/cssquery-yui.html (next: http://localhost:49281/tests/dromaeo/cssquery-dojo.html) 07:07:37 INFO - PROCESS | 1674 | RSS: Main: 381538304 07:07:37 INFO - PROCESS | 1674 | 07:07:37 INFO - PROCESS | 1674 | __start_tp_report 07:07:37 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 07:07:37 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 07:07:37 INFO - PROCESS | 1674 | |i|pagename|runs| 07:07:37 INFO - PROCESS | 1674 | |0;dojo.html;2951.158919707884;3435.519027336173;3444.397230484666;3469.098034511027;3471.4445688689784;5515.669059856412;5540.390557038722;5639.774409023645;5659.405762394951;5688.2889638795095;5600.831975040745;5677.347105082907;5713.571482138836;5718.285214348207;5748.367679555246;5078.974605126969;5141.0489059523925;5162.92255616166;5164.431912489623;5263.44733802949;5224.4775522447835;5257.027449921758;5259.710715910624;5287.41838397776;5292.285541451913;5066.062778385992;5116.872078198075;5123.4108077571;5125.359330083739;5167.22491626259;5110.948890511085;5126.102931986888;5134.897302053938;5137.229415587654;5169.431362550116;5104.081265372231;5115.488451154892;5179.378474583036;5198.844034678966;5215.348081489814;5505.944940550583;5522.779088836442;5537.916931246034;5554.583406244507;5605.327360716699;6154.692265386758;6159.38406159385;6169.629822210682;6176.567640265183;6197.411245931675;5191.714455704935;6208.286047104596;6224.782132625336;6253.937460625381;6264.749410023594;4683.882902927386;5613.158026296088;5654.236678048493;5697.173909783059;5754.769809207669;6349.142865713162;6361.236651601837;6363.204599425072;6368.235811702625;6368.5860419072615;5965.254343207099;5992.730327135289;6045.455908968214;6047.971844786397;6052.455279024909;6123.435141503484;6137.508999280047;6140.4473597376455;6167.814965551041;6189.226346706661;6124.38756124384;6165.599236049642;6172.969135154295;6177.351378105324;6188.907166392508;6192.380761923861;6206.162168107338;6213.161223234805;6217.347178546278;6231.781887633911;4760.809567617326;4811.615070794328;4813.614910807127;4828.155072862235;4869.732164730974;3969.761814291152;4262.296721041055;4332.090261045216;4450.198964186413;4456.5766252205985;5626.831195064155;5645.548356131581;5646.520045796071;5654.8303550892715;5659.830205093854;6098.390160983866;6171.537134714914;6212.751489940351;6215.06773983897;6291.6854157291955;5679.034564124109;5687.43125687428;5689.687067211343;5692.772289108472;5716.171155182558;5205.895882082337;5324.414314425488;5350;5353.330833645794;5364.865878353073;6315.273743519462;6365.108884756138;6374.458171055327;6383.553151279366;6398.552101352953;6120.629988696738;6195.19462469876;6203.534734894811;6265.436110749872;6284.120223168668;6330.987042073246;6357.396047375492;6358.77744278948;6361.840953976188;6366.013267943476;5937.2578427696535;5990.400959903975;6129.877402452015;6130.877382452415;6153.230846144232;6097.756089756537;6125.693326622233;6137.079438084323;6160.476359509492;6191.566590338633;5020.949790502048;5917.467427931507;5965.105234214902;5979.5814292999075;6014.548908831943;5788.102844059177;5854.765809367748;5931.110333449845;5952.434518720715;6007.429294217042;5813.9534883721335;5855.531557475307;5857.26784151981;5890.823275301748;5908.645481271137;6039.607425517327;6083.6045657032155;6085;6087.421694938974;6090.543209259235;5701.429857014431;5753.280839895013;5862.384449632727;5870.471657550841;5880.735366908585 07:07:37 INFO - PROCESS | 1674 | |1;ext.html;499.97006167295376;504.43755212937583;527.4909712127796;530.9867253318666;577.0334689395263;29407;42133.578664213346;43366.783166084206;43381.132377352515;45725.856853578676;44746;45253.54746452534;45266.547334526724;45312.773436132855;45368.3194752079;8704.129587041309;9603.759906002353;9865.556049977768;9882.011798820096;9882.604695812159;8865.113488651148;11762.823557646641;11993.16047876649;12042.277463352228;12299.69250768729;12069.456874440672;12169.756604867898;12183;12192.81710774339;12203.93898030513;8728.258098061673;8924.598393072327;8940;10092.495375231245;10205.183585313194;8869.600867960957;11564.942175289154;11879.40602969848;11897.524099036029;12208.267503949792;42075;42578.78710606427;42618;42649;42720.14559708788;3567.965290065878;3647.927041459156;3767.7739335639676;3768.811559422018;3839.9040023999623;32219.51670724941;32512;32625.184370390692;33029.834850825835;33248.6675133248;2786.595943588168;3283.1792051987004;3309.1396236978317;3391.5590973173576;3421.8973430797114;507128;508817;510412;511044;512080.8791912108;63702.68148659227;63717.681411592646;63848.36151638517;63925;64224;34008.31983360319;35564.644353556134;65729.34270657328;66643.00035499471;66689.99860008319;17231.655366892843;17351.39270125565;17443.56391090233;17454;17458.34145756151;11939.283642981449;12034.157608967289;12995.155314904501;13104.80342794859;13206.669833254246;20247.595048098956;20365.2872149477;20431.284905028253;20527.794722052888;20663.58672826535;6837.692303846339;8110.675572977015;8122.472039317426;8137;8144.429889907649;972.781124247053;1038.99480502597;1060.1412855587;1101.7067016351803;1133.2434726174397;30756.231094222825;30777.076687699406;31203.687963120534;31292.53049309377;31572.684273156974;944.475815922156;1095.611058074372;1126.5437497813398;1145.4330106597122;1188.95838645647;14203.64490887736;15083.698326033636;16386.75419868703;16740.748888766677;16936.661266774594;684967;691568;693366;693687;694491;62627.6868615654;63101.73796524044;63198.68400657967;63354.68322658357;63460.36539634545;1318.4989703912452;1363.8158848555518;1423.2422957647505;1450.0936914428482;1483.1101339196612;30034.405788561595;31175.53236701406;31266.687333127287;31631.36737265196;31883.68116318807;51880;51969.74015130052;52795.94408111739;52807.47192528025;52821.20768188557;505920;510687;512829;514707;515352;23813.928373223593;26184.345391364604;26225.73774262233;26807.463850722484;26864.194074177805;48489;48536.5146348532;48941.755291223315;49468.50531494639;49769.502304976486;32637.347253055283;38379;39934.60065399309;40317.589819615445;40608.390874137505;38662;39394;39457.605423946545;39858.60141398549;40031.79904602867;38037;38463.230735385696;38546.421803673526;38839.6116038836;39045.60954390533;33870;34015.65984340224;34890.12774680653;35184.296314073064;35279;22922.54154916926;24071.686307435477;25485.617715733908;25894.61158082668;25968.480630387665 07:07:37 INFO - PROCESS | 1674 | |2;jquery.html;386.54773914520007;388.09380097472393;389.27674929400683;391.2175648702593;414.56056580025177;13415;20253.898730506364;20512.589748205028;21013.84423856687;21018.369448916517;16071.919640401782;17015.404460843874;19693.803061969375;20246.392608221777;20729.792702072973;5739.3399759027825;5866.677332746697;5874;5914.290285165786;5956.255468066492;5725.885482290351;5781.103928891008;5816.738246778884;5822.650640961556;5836.824895253149;5664.291963504562;5758.798442054529;5781.277340332458;5792.942070579283;5827.213326200951;5710.714464276791;5774.5091667208335;5777.104548794943;5819.912701309462;5839.532837373;4946.777395017233;5091.949080509222;5118.539331460186;5125.923111153298;5129.333186685744;25269.494610107697;25502.87248563764;26207.73792262072;27133.321666958287;27145.321366965785;2250.3698964290024;2262.6266666000092;2359.6696462495265;2384.892679829412;2443.303658457349;14965.625859353495;15263.771043434357;15419.537413877602;15981.698983937114;16314.673706525802;1824.6806808808406;1847.1503108569939;1876.7184922261633;1885.4626431466966;1908.3416221403731;94068.52965735196;119263;119311;121263;123236.76763232342;10635.308704934308;10667;11149.498272577754;11173.497192626352;11309.982101610894;14113.153210807384;14221.64445888861;14243.216623085622;14259.786103208253;14283.500077497238;7792.142864284924;7805.921940780633;8624.52565108925;8799.29605631548;8912.777180570409;8829.64681412749;8869.822603547893;8894.377393582516;8981.101889811098;9000.279977601776;5525.917111243334;6785.117934668461;6793.320667933266;6796.898046529207;6800.591964482147;1861.15317530526;1877.483691984715;1885.9151338189815;1913.6555420024529;1927.681932481153;931.7950050988771;944.2964991081689;1037.9792404151874;1050.9344957305714;1140.122105978392;21192.682109768364;22074.779252207594;22139.667904981437;22172.891355432163;22184.33446996593;971.8095333216753;1017.3234798858676;1055.4036969112426;1079.4818487126067;1100.1418893263267;10599.099076578423;12004.939975300067;12058.577949771716;12111.273323600612;12139;562252;583378;597175;598744;599369;121168;122327.38836305939;125996;126296;127960.63945924076;985.8274801909637;1042.5464922758777;1057.2440704896037;1060.8408738689104;1061.2253055269696;18938;19228.134733936728;19449.124789384794;19453;19469.318573849847;51609;53046.93906121779;54038.45961540334;54806.177907330624;55663.16505252505;82240.58879705564;82687.5865620668;82862.3427531458;82993.58503207445;85047;8217.479766243265;8303.833923321621;8374.036985746474;8399.70601028961;8573.056963734109;11012.119030477703;12975.156614820007;13067.215967042008;13187.736245275233;13240;10998.450077495932;11018.779624407627;11150.776984460428;11303.943480282875;11391.145664075062;10386.792264154523;11109.50007249692;11141.554337826396;11198.496067677137;11261.324320540794;11670.241434306743;11707.707307317385;11812.468438920097;11879.643610691694;12008.519659213534;8269.95865020695;8389.286910612665;8434.451760635538;8489.660413583388;8525.573721313785;8468.195521425454;8722.738317850475;11111.666650000512;11334.546618135511;11618.825717614412 07:07:37 INFO - PROCESS | 1674 | |3;mootools.html;1366.6036849313687;1540.7303721848677;1559.4308077551693;1830.3593742190226;1993.5813479169374;7838.686452541899;8330.0420451648;8563.015253245878;8588.61351239195;8595.613197406115;8187;8491.19333663301;8499.405041647087;8605.870911936327;8628.611712472939;2331.8717470539073;2384.403899025244;2385.057902128658;2388.8924998375114;2388.916387926423;2314.0628045641542;2339.7543257957923;2358.740538540759;2360.9965764550084;2364.408897775556;2327.045911176418;2347.6361164019518;2360.1385494294545;2362.7164740231196;2366.2309749331534;2326.8254880883915;2330.822934418125;2362.3621622162004;2363.468219650582;2366.5621859955963;2286.474110954473;2365.775251351136;2390.9163179288817;2391.3423808452812;2394.736578976311;7633;7883.487573307717;8561.914380856237;8637.568121593895;8713.390062695615;5019.171836646949;5257.342832145981;5257.947420525784;5281.339832520935;5299.947000529984;3508.614052454228;3566.1619519412916;3618.9276214475562;3629.94555081674;3635.654612811779;1020.1787561013381;1028.0541901450683;1783.9286428542844;1846.1415441819618;1870.9990155267003;11110.555577776879;11353.545858165664;11366.37484938328;11450.969412752893;11556.94221528887;4292.720973136736;4611.423572053493;4773.37946067016;4844.15227335215;4882.9755851221225;9976;10071.748206294755;10074.042965918226;10086.24353173515;10109.140723038478;5391.676499410048;5404.0272750905215;5406.107992181324;5412.91880621791;5412.91880621791;4002.239574480839;4832.202686556749;4857.6599638025;4907.043126590281;4948.109340318777;3650.671439570451;3664.7434679572175;3682.8710995115043;3739.738218324691;3779.962200378016;1065.8507808906759;1240.1752834365227;1265.9303738294298;1275.2093701904878;1277.150694787974;945.6642891773322;958.5925981457992;960.3229723045237;960.673371053845;998.2530571499875;5778.479936805707;5830.970845145832;5873.823785286449;5893.528517718572;6090.934086534839;440.6430791059252;449.51676947281805;458.84857996860956;458.86922227165763;460.2520903531761;3473.3747925373677;3852.036990752312;3921.9803900980883;4024.3158663027357;4030.8387664493093;10276.691699248884;10321.225908056926;10631.362118272928;10640.414777187329;10708.357498550113;9384.202342800801;10176.982301769913;10666.466676666136;10698.197635177237;11103.444827758742;6905.723771049303;7853.921460785434;7986.920130798734;8088.555129467818;8188.50868947865;5785.652860828363;5826.329972053099;5844.47399734026;5873.73568189424;5891.116332550152;9431.905680943377;9980.301378903407;9987.400755954666;9989.300748947504;10020;9700.951495242478;10099.94950025245;10174.186065114625;10188.184945204217;10200.0819926207;7187.820304492429;7293.708251669874;7329.890051649123;7334.596597187206;7348.559086454829;8069.354451643737;8090.757277281691;8180.427370084275;8193.959030204811;8225.629846657035;7054.753083642048;7341.70633174667;7371.857362108882;7375.2256013119995;7480.887786683313;7141.9642901785155;7241.601711905896;7256.89114663291;7311.963440182978;7413.555186688816;5946.940530594638;6963.895541566779;6998.265182156004;7004.789856304319;7008.334208250208;4735.431748190239;4919.311296418576;4933.210686290176;4955.776990035529;5004.124278251219;4998.1503144464805;5063.13926632466;5104.489551044866;5110.872228194325;5223.085959957069 07:07:37 INFO - PROCESS | 1674 | |4;prototype.html;300.29730430791955;304.2651995431034;313.2310178511754;316.53943512189767;320.21866645385256;4441.000774825663;4864.464908860032;5058.519440653141;5319.414864364917;5453.536449401806;4943.307936888838;5043.747812609364;5112.594036639925;5160.612954028454;5323.787048518065;3533.452314891196;3961.623645753649;4009.298372784751;4015.1254039851538;4050.554439011721;3659.3047321009058;3778.1688028633657;4021.3967904814367;4042.9797851010703;4068.4914385701786;3066.402051599939;3809.942850857239;3971.582983786704;4002.259017327472;4028.858989935353;3074.5849310343265;3955.268275369066;3964.345882929313;3981.442598036277;4031.3348297530874;3471.993121994644;3484.8083355415442;3492.877749278763;3510.701590364822;3537.363274610569;4795.352627395292;5020.573251273646;5044.873878153076;5050.772715227824;5088.898222035538;3726.273376691546;4429;4525.547445255481;4588.0365123324145;4594.563516465931;4670.486246512881;5463.945360546383;5482.1624862596345;5483.561315094783;5549.361823390322;3611.2235869287965;3734.2075803614416;3761.4357846322996;3786.772793632391;3935.5670876203594;5125.718085505296;5160.432352441228;5722.799702010451;5728.1407788831175;5854.68269639332;3583.7670551414076;3722.6277372262557;3899.323290984904;3903.590123037097;3909.6285852843935;3736.3274610570356;3962.4244241242845;3992.500937382827;3993.6206060424215;4051.885731423882;3477.4003958179014;4082.6529744971417;4367.034834324567;4523.471692080183;4618.884527886763;5023.447420783711;5567.582431317586;5570.41510641385;5603.80386686464;5615.052097648767;1234.3149551998727;1321.9272939988393;1354.8441929178073;1374.4776984745733;1375.5460697969645;914.9542522873829;940.9623615055458;942.1661829280998;944.126682818404;947.8862536495665;4976.676516026447;5143;5144.9163088949035;5227.686338819683;5297.5232229099565;4828.444728856156;5286.286351342519;5396.600141910571;5410.269613602192;5648.051422259314;4601.98756273626;5151.433342332415;5199.7140157291715;5204.76578553966;5222.503862133091;2970.203027168532;3014.9246268843453;3022.851316499716;3025.3949210158057;3042.209025653302;4854.587360074363;5893.5579831512805;5894.528437724972;5895;6003.699815009232;4950.4406112074585;5221;5595.776168953196;5691.402402747734;5894.734736936848;4530.388397566352;5239.240310155006;5851.444112809277;5877.177195192762;5901.055831067095;5114.64197506171;5352.625316227826;5520.53075488588;5766.5112606872235;5818.450745045603;4726.574608285271;5682.77268909239;5688.042218046676;5699.544036477155;5714.742756593543;5120.206368012964;5393.705510677488;5575.888482230252;6017.518598512196;6225.346338634378;3505.4069578682056;3820.522434695663;3848.807559621952;3851.383778595411;3860.247251785987;4884.7069175849565;5729.341125770589;5730.283714535683;5769.711514424346;5797.449242321973;4687.648426367968;4877.7339833008255;5593.216949626974;5615.915761263666;5652.236948012131;4896.902061958812;5304.681719096867;5442.564594832483;5572.526335261386;5582.162675598693;4856.1987272099705;5359.839204823861;5408.080131553242;5588.888222235451;5616.971915140398;4953.777080031479;5064.518870707277;5158.09733296679;5538.6399884007415;5649.022842005265;4586.931196032129;5043.646944713835;5347.491988261109;5489.204065410573;5497.092979658471 07:07:37 INFO - PROCESS | 1674 | |5;yui.html;713.8679344321301;728.402709777982;742.6398196874514;823.8311146215027;850.5407080176705;13207.6698082548;14783.260836958149;14886.478973235939;15572.377104915804;15581.766273505878;12775.872241277584;13899.861001389983;14024.158550486978;14063.156210627369;14072.64818379541;900.8153328567661;900.9594568244445;906.3293163059354;907.1291560102277;909.6543313540845;900.2842740021698;904.421170450912;905.2441211588332;905.384338649718;906.7642412972608;957.301170145794;958.5446912716474;958.5494817435829;959.49626446116;960.7790208252106;952.5618215620823;953.8616900549416;955.0640372435053;955.1117460761488;955.5461155950937;708.8298808286025;708.8334241453255;708.8688592610383;709.574255446733;712.3766704133883;12801.61595152147;12945;12946.805797913039;12957.805632915513;13831.446742130403;10684.252102352839;12063.336516491589;12094.213876098025;12204.694882627917;12357.814632780515;11238.325700458;12152.21010634306;12396.194247373893;12441.855842245426;12628.116031877773;4501.437320334958;4508.842190523316;4659.836905708284;4665.230237010889;4667.183242932473;67406;73299.63350183268;94570.16289511394;97324.51337743337;98227;11039.503222355013;11219.1585631078;11237.269577477438;11237.775244495228;11261.943690281496;21639.026243818753;22051.66922496164;22056.88971555164;22081.668774968388;22099.779002210093;8027.959860200662;8072.717454889051;8145.144759800254;8217.424780265444;8254.711085111992;4489.236829738952;4510.661700372485;4539.387182730356;4605.263157894721;4689.155951928686;3611.223586928823;3722.7766334020043;3725.2177042821313;3725.720570957189;3734.3278209922473;666.9533132680763;668.5754694121805;670.4842883181767;671.2280876991499;679.0900193740313;622.8723111762168;666.2970566052861;685.9828504287433;694.4513834071028;703.6024646074951;9102.86345704815;9529.380590261611;9654.343908698773;9707;9764.121229089416;649.3084864619166;691.5945427625954;701.193627328566;705.5061456980031;706.3819158236543;4204.684648651363;4517.570830771071;4597.919488920101;4617.976910115428;4668.767828289902;90934;106926.93073069325;133073;148544;151912.24043879932;97809.5109524462;98082;100461;101915.98084019213;112629.43685281686;108506.8298634023;110414.3437848433;111061.33407998887;111553.44223278832;113152.43423782829;8592.957035214784;8621.913780862362;8639.654413823377;8672.913270867211;8749.47503149813;57908.71045644745;60428;62151;62755;63865.38738926909;86525;86694.56652716696;89705;94059;94754;11349.48927298257;11366.943165284121;11521.711957201138;11631.59289424866;11789;12773.361331933553;12999.545015924397;13157.60527184186;13244.967161444214;13305.201687898758;11821.763564728486;11913.463894124958;11930.164888457724;12013.219140755822;12566.623001309976;11915.391789193774;12137.393130343271;12143.271403715806;12155;12227;11772.234804737665;11918.761624767629;11974.101942354533;12094.274343539415;12107.152499324962;10115.342502737298;10191.694249172537;10281.948590257001;10299.691009269734;10663.98692124247;8285.83428331418;9920.553575089283;9937.701868943943;10240.48797560134;10245 07:07:37 INFO - PROCESS | 1674 | __end_tp_report 07:07:37 INFO - PROCESS | 1674 | __start_cc_report 07:07:37 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,1148 07:07:37 INFO - PROCESS | 1674 | __end_cc_report 07:07:37 INFO - PROCESS | 1674 | __startTimestamp1464876457444__endTimestamp 07:07:37 INFO - PROCESS | 1674 | 07:07:37 INFO - PROCESS | 1674 | ------- Summary: start ------- 07:07:37 INFO - PROCESS | 1674 | Number of tests: 6 07:07:37 INFO - PROCESS | 1674 | 07:07:37 INFO - PROCESS | 1674 | [#0] dojo.html Cycles:165 Average:5672.60 Median:5855.53 stddev:653.23 (11.2%) stddev-sans-first:619.36 07:07:37 INFO - PROCESS | 1674 | Values: 2951.2 3435.5 3444.4 3469.1 3471.4 5515.7 5540.4 5639.8 5659.4 5688.3 5600.8 5677.3 5713.6 5718.3 5748.4 5079.0 5141.0 5162.9 5164.4 5263.4 5224.5 5257.0 5259.7 5287.4 5292.3 5066.1 5116.9 5123.4 5125.4 5167.2 5110.9 5126.1 5134.9 5137.2 5169.4 5104.1 5115.5 5179.4 5198.8 5215.3 5505.9 5522.8 5537.9 5554.6 5605.3 6154.7 6159.4 6169.6 6176.6 6197.4 5191.7 6208.3 6224.8 6253.9 6264.7 4683.9 5613.2 5654.2 5697.2 5754.8 6349.1 6361.2 6363.2 6368.2 6368.6 5965.3 5992.7 6045.5 6048.0 6052.5 6123.4 6137.5 6140.4 6167.8 6189.2 6124.4 6165.6 6173.0 6177.4 6188.9 6192.4 6206.2 6213.2 6217.3 6231.8 4760.8 4811.6 4813.6 4828.2 4869.7 3969.8 4262.3 4332.1 4450.2 4456.6 5626.8 5645.5 5646.5 5654.8 5659.8 6098.4 6171.5 6212.8 6215.1 6291.7 5679.0 5687.4 5689.7 5692.8 5716.2 5205.9 5324.4 5350.0 5353.3 5364.9 6315.3 6365.1 6374.5 6383.6 6398.6 6120.6 6195.2 6203.5 6265.4 6284.1 6331.0 6357.4 6358.8 6361.8 6366.0 5937.3 5990.4 6129.9 6130.9 6153.2 6097.8 6125.7 6137.1 6160.5 6191.6 5020.9 5917.5 5965.1 5979.6 6014.5 5788.1 5854.8 5931.1 5952.4 6007.4 5814.0 5855.5 5857.3 5890.8 5908.6 6039.6 6083.6 6085.0 6087.4 6090.5 5701.4 5753.3 5862.4 5870.5 5880.7 07:07:37 INFO - PROCESS | 1674 | 07:07:37 INFO - PROCESS | 1674 | [#1] ext.html Cycles:180 Average:71118.41 Median:20902.91 stddev:154274.96 (738.1%) stddev-sans-first:154616.63 07:07:37 INFO - PROCESS | 1674 | Values: 500.0 504.4 527.5 531.0 577.0 29407.0 42133.6 43366.8 43381.1 45725.9 44746.0 45253.5 45266.5 45312.8 45368.3 8704.1 9603.8 9865.6 9882.0 9882.6 8865.1 11762.8 11993.2 12042.3 12299.7 12069.5 12169.8 12183.0 12192.8 12203.9 8728.3 8924.6 8940.0 10092.5 10205.2 8869.6 11564.9 11879.4 11897.5 12208.3 42075.0 42578.8 42618.0 42649.0 42720.1 3568.0 3647.9 3767.8 3768.8 3839.9 32219.5 32512.0 32625.2 33029.8 33248.7 2786.6 3283.2 3309.1 3391.6 3421.9 507128.0 508817.0 510412.0 511044.0 512080.9 63702.7 63717.7 63848.4 63925.0 64224.0 34008.3 35564.6 65729.3 66643.0 66690.0 17231.7 17351.4 17443.6 17454.0 17458.3 11939.3 12034.2 12995.2 13104.8 13206.7 20247.6 20365.3 20431.3 20527.8 20663.6 6837.7 8110.7 8122.5 8137.0 8144.4 972.8 1039.0 1060.1 1101.7 1133.2 30756.2 30777.1 31203.7 31292.5 31572.7 944.5 1095.6 1126.5 1145.4 1189.0 14203.6 15083.7 16386.8 16740.7 16936.7 684967.0 691568.0 693366.0 693687.0 694491.0 62627.7 63101.7 63198.7 63354.7 63460.4 1318.5 1363.8 1423.2 1450.1 1483.1 30034.4 31175.5 31266.7 31631.4 31883.7 51880.0 51969.7 52795.9 52807.5 52821.2 505920.0 510687.0 512829.0 514707.0 515352.0 23813.9 26184.3 26225.7 26807.5 26864.2 48489.0 48536.5 48941.8 49468.5 49769.5 32637.3 38379.0 39934.6 40317.6 40608.4 38662.0 39394.0 39457.6 39858.6 40031.8 38037.0 38463.2 38546.4 38839.6 39045.6 33870.0 34015.7 34890.1 35184.3 35279.0 22922.5 24071.7 25485.6 25894.6 25968.5 07:07:37 INFO - PROCESS | 1674 | 07:07:37 INFO - PROCESS | 1674 | [#2] jquery.html Cycles:180 Average:34970.69 Median:22156.28 stddev:98183.59 (443.1%) stddev-sans-first:98424.68 07:07:37 INFO - PROCESS | 1674 | Values: 386.5 388.1 389.3 391.2 414.6 13415.0 20253.9 20512.6 21013.8 21018.4 16071.9 17015.4 19693.8 20246.4 20729.8 5739.3 5866.7 5874.0 5914.3 5956.3 5725.9 5781.1 5816.7 5822.7 5836.8 5664.3 5758.8 5781.3 5792.9 5827.2 5710.7 5774.5 5777.1 5819.9 5839.5 4946.8 5091.9 5118.5 5125.9 5129.3 25269.5 25502.9 26207.7 27133.3 27145.3 2250.4 2262.6 2359.7 2384.9 2443.3 14965.6 15263.8 15419.5 15981.7 16314.7 1824.7 1847.2 1876.7 1885.5 1908.3 94068.5 119263.0 119311.0 121263.0 123236.8 10635.3 10667.0 11149.5 11173.5 11310.0 14113.2 14221.6 14243.2 14259.8 14283.5 7792.1 7805.9 8624.5 8799.3 8912.8 8829.6 8869.8 8894.4 8981.1 9000.3 5525.9 6785.1 6793.3 6796.9 6800.6 1861.2 1877.5 1885.9 1913.7 1927.7 931.8 944.3 1038.0 1050.9 1140.1 21192.7 22074.8 22139.7 22172.9 22184.3 971.8 1017.3 1055.4 1079.5 1100.1 10599.1 12004.9 12058.6 12111.3 12139.0 562252.0 583378.0 597175.0 598744.0 599369.0 121168.0 122327.4 125996.0 126296.0 127960.6 985.8 1042.5 1057.2 1060.8 1061.2 18938.0 19228.1 19449.1 19453.0 19469.3 51609.0 53046.9 54038.5 54806.2 55663.2 82240.6 82687.6 82862.3 82993.6 85047.0 8217.5 8303.8 8374.0 8399.7 8573.1 11012.1 12975.2 13067.2 13187.7 13240.0 10998.5 11018.8 11150.8 11303.9 11391.1 10386.8 11109.5 11141.6 11198.5 11261.3 11670.2 11707.7 11812.5 11879.6 12008.5 8270.0 8389.3 8434.5 8489.7 8525.6 8468.2 8722.7 11111.7 11334.5 11618.8 07:07:37 INFO - PROCESS | 1674 | 07:07:37 INFO - PROCESS | 1674 | [#3] mootools.html Cycles:180 Average:5628.40 Median:4870.32 stddev:3128.63 (64.2%) stddev-sans-first:3121.01 07:07:37 INFO - PROCESS | 1674 | Values: 1366.6 1540.7 1559.4 1830.4 1993.6 7838.7 8330.0 8563.0 8588.6 8595.6 8187.0 8491.2 8499.4 8605.9 8628.6 2331.9 2384.4 2385.1 2388.9 2388.9 2314.1 2339.8 2358.7 2361.0 2364.4 2327.0 2347.6 2360.1 2362.7 2366.2 2326.8 2330.8 2362.4 2363.5 2366.6 2286.5 2365.8 2390.9 2391.3 2394.7 7633.0 7883.5 8561.9 8637.6 8713.4 5019.2 5257.3 5257.9 5281.3 5299.9 3508.6 3566.2 3618.9 3629.9 3635.7 1020.2 1028.1 1783.9 1846.1 1871.0 11110.6 11353.5 11366.4 11451.0 11556.9 4292.7 4611.4 4773.4 4844.2 4883.0 9976.0 10071.7 10074.0 10086.2 10109.1 5391.7 5404.0 5406.1 5412.9 5412.9 4002.2 4832.2 4857.7 4907.0 4948.1 3650.7 3664.7 3682.9 3739.7 3780.0 1065.9 1240.2 1265.9 1275.2 1277.2 945.7 958.6 960.3 960.7 998.3 5778.5 5831.0 5873.8 5893.5 6090.9 440.6 449.5 458.8 458.9 460.3 3473.4 3852.0 3922.0 4024.3 4030.8 10276.7 10321.2 10631.4 10640.4 10708.4 9384.2 10177.0 10666.5 10698.2 11103.4 6905.7 7853.9 7986.9 8088.6 8188.5 5785.7 5826.3 5844.5 5873.7 5891.1 9431.9 9980.3 9987.4 9989.3 10020.0 9701.0 10099.9 10174.2 10188.2 10200.1 7187.8 7293.7 7329.9 7334.6 7348.6 8069.4 8090.8 8180.4 8194.0 8225.6 7054.8 7341.7 7371.9 7375.2 7480.9 7142.0 7241.6 7256.9 7312.0 7413.6 5946.9 6963.9 6998.3 7004.8 7008.3 4735.4 4919.3 4933.2 4955.8 5004.1 4998.2 5063.1 5104.5 5110.9 5223.1 07:07:37 INFO - PROCESS | 1674 | 07:07:37 INFO - PROCESS | 1674 | [#4] prototype.html Cycles:180 Average:4466.66 Median:5033.55 stddev:1358.06 (27.0%) stddev-sans-first:1325.38 07:07:37 INFO - PROCESS | 1674 | Values: 300.3 304.3 313.2 316.5 320.2 4441.0 4864.5 5058.5 5319.4 5453.5 4943.3 5043.7 5112.6 5160.6 5323.8 3533.5 3961.6 4009.3 4015.1 4050.6 3659.3 3778.2 4021.4 4043.0 4068.5 3066.4 3809.9 3971.6 4002.3 4028.9 3074.6 3955.3 3964.3 3981.4 4031.3 3472.0 3484.8 3492.9 3510.7 3537.4 4795.4 5020.6 5044.9 5050.8 5088.9 3726.3 4429.0 4525.5 4588.0 4594.6 4670.5 5463.9 5482.2 5483.6 5549.4 3611.2 3734.2 3761.4 3786.8 3935.6 5125.7 5160.4 5722.8 5728.1 5854.7 3583.8 3722.6 3899.3 3903.6 3909.6 3736.3 3962.4 3992.5 3993.6 4051.9 3477.4 4082.7 4367.0 4523.5 4618.9 5023.4 5567.6 5570.4 5603.8 5615.1 1234.3 1321.9 1354.8 1374.5 1375.5 915.0 941.0 942.2 944.1 947.9 4976.7 5143.0 5144.9 5227.7 5297.5 4828.4 5286.3 5396.6 5410.3 5648.1 4602.0 5151.4 5199.7 5204.8 5222.5 2970.2 3014.9 3022.9 3025.4 3042.2 4854.6 5893.6 5894.5 5895.0 6003.7 4950.4 5221.0 5595.8 5691.4 5894.7 4530.4 5239.2 5851.4 5877.2 5901.1 5114.6 5352.6 5520.5 5766.5 5818.5 4726.6 5682.8 5688.0 5699.5 5714.7 5120.2 5393.7 5575.9 6017.5 6225.3 3505.4 3820.5 3848.8 3851.4 3860.2 4884.7 5729.3 5730.3 5769.7 5797.4 4687.6 4877.7 5593.2 5615.9 5652.2 4896.9 5304.7 5442.6 5572.5 5582.2 4856.2 5359.8 5408.1 5588.9 5617.0 4953.8 5064.5 5158.1 5538.6 5649.0 4586.9 5043.6 5347.5 5489.2 5497.1 07:07:37 INFO - PROCESS | 1674 | 07:07:37 INFO - PROCESS | 1674 | [#5] yui.html Cycles:180 Average:22445.09 Median:4568.65 stddev:34833.45 (762.4%) stddev-sans-first:34892.95 07:07:37 INFO - PROCESS | 1674 | Values: 713.9 728.4 742.6 823.8 850.5 13207.7 14783.3 14886.5 15572.4 15581.8 12775.9 13899.9 14024.2 14063.2 14072.6 900.8 901.0 906.3 907.1 909.7 900.3 904.4 905.2 905.4 906.8 957.3 958.5 958.5 959.5 960.8 952.6 953.9 955.1 955.1 955.5 708.8 708.8 708.9 709.6 712.4 12801.6 12945.0 12946.8 12957.8 13831.4 10684.3 12063.3 12094.2 12204.7 12357.8 11238.3 12152.2 12396.2 12441.9 12628.1 4501.4 4508.8 4659.8 4665.2 4667.2 67406.0 73299.6 94570.2 97324.5 98227.0 11039.5 11219.2 11237.3 11237.8 11261.9 21639.0 22051.7 22056.9 22081.7 22099.8 8028.0 8072.7 8145.1 8217.4 8254.7 4489.2 4510.7 4539.4 4605.3 4689.2 3611.2 3722.8 3725.2 3725.7 3734.3 667.0 668.6 670.5 671.2 679.1 622.9 666.3 686.0 694.5 703.6 9102.9 9529.4 9654.3 9707.0 9764.1 649.3 691.6 701.2 705.5 706.4 4204.7 4517.6 4597.9 4618.0 4668.8 90934.0 106926.9 133073.0 148544.0 151912.2 97809.5 98082.0 100461.0 101916.0 112629.4 108506.8 110414.3 111061.3 111553.4 113152.4 8593.0 8621.9 8639.7 8672.9 8749.5 57908.7 60428.0 62151.0 62755.0 63865.4 86525.0 86694.6 89705.0 94059.0 94754.0 11349.5 11366.9 11521.7 11631.6 11789.0 12773.4 12999.5 13157.6 13245.0 13305.2 11821.8 11913.5 11930.2 12013.2 12566.6 11915.4 12137.4 12143.3 12155.0 12227.0 11772.2 11918.8 11974.1 12094.3 12107.2 10115.3 10191.7 10281.9 10299.7 10664.0 8285.8 9920.6 9937.7 10240.5 10245.0 07:07:37 INFO - PROCESS | 1674 | -------- Summary: end -------- 07:07:37 INFO - PROCESS | 1674 | 07:07:38 INFO - TEST-INFO | 1674: exit 0 07:07:38 INFO - TEST-OK | dromaeo_css | took 1115295ms 07:07:38 INFO - TEST-START | kraken 07:07:38 INFO - operating with platform_type : mac_ 07:07:38 INFO - Initialising browser for kraken test... 07:07:38 INFO - TEST-INFO | started process 1681 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/tmpiiT38S/profile http://localhost:49281/getInfo.html) 07:07:40 INFO - PROCESS | 1681 | __metrics Screen width/height:1600/1200 07:07:40 INFO - PROCESS | 1681 | colorDepth:24 07:07:40 INFO - PROCESS | 1681 | Browser inner width/height: 1024/689 07:07:40 INFO - PROCESS | 1681 | __metrics 07:07:40 INFO - PROCESS | 1681 | 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" 07:07:40 ERROR - PROCESS | 1681 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 07:07:41 INFO - TEST-INFO | 1681: exit 0 07:07:41 INFO - Browser initialized. 07:07:41 INFO - Running cycle 1/1 for kraken test... 07:07:41 INFO - TEST-INFO | started process 1682 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/tmpiiT38S/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:07:53 INFO - PROCESS | 1682 | RSS: Main: 242315264 07:07:53 INFO - PROCESS | 1682 | 07:07:55 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-beat-detection) 07:07:56 INFO - PROCESS | 1682 | RSS: Main: 291954688 07:07:56 INFO - PROCESS | 1682 | 07:07:59 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-dft) 07:08:00 INFO - PROCESS | 1682 | RSS: Main: 301359104 07:08:00 INFO - PROCESS | 1682 | 07:08:03 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-fft) 07:08:04 INFO - PROCESS | 1682 | RSS: Main: 387649536 07:08:04 INFO - PROCESS | 1682 | 07:08:06 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49281/tests/kraken/driver.html?testName=audio-oscillator) 07:08:06 INFO - PROCESS | 1682 | RSS: Main: 288370688 07:08:06 INFO - PROCESS | 1682 | 07:08:08 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49281/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:08:09 INFO - PROCESS | 1682 | RSS: Main: 293023744 07:08:09 INFO - PROCESS | 1682 | 07:08:12 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49281/tests/kraken/driver.html?testName=imaging-darkroom) 07:08:12 INFO - PROCESS | 1682 | RSS: Main: 331276288 07:08:12 INFO - PROCESS | 1682 | 07:08:15 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49281/tests/kraken/driver.html?testName=imaging-desaturate) 07:08:16 INFO - PROCESS | 1682 | RSS: Main: 334192640 07:08:16 INFO - PROCESS | 1682 | 07:08:19 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49281/tests/kraken/driver.html?testName=json-parse-financial) 07:08:19 INFO - PROCESS | 1682 | RSS: Main: 331108352 07:08:19 INFO - PROCESS | 1682 | 07:08:21 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49281/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:08:22 INFO - PROCESS | 1682 | RSS: Main: 311422976 07:08:22 INFO - PROCESS | 1682 | 07:08:23 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:08:24 INFO - PROCESS | 1682 | RSS: Main: 318005248 07:08:24 INFO - PROCESS | 1682 | 07:08:26 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:08:27 INFO - PROCESS | 1682 | RSS: Main: 322969600 07:08:27 INFO - PROCESS | 1682 | 07:08:30 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:08:30 INFO - PROCESS | 1682 | RSS: Main: 324648960 07:08:30 INFO - PROCESS | 1682 | 07:08:33 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:08:34 INFO - PROCESS | 1682 | RSS: Main: 325152768 07:08:34 INFO - PROCESS | 1682 | 07:08:36 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49281/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49281/tests/kraken/driver.html?testName=ai-astar) 07:08:36 INFO - PROCESS | 1682 | RSS: Main: 369250304 07:08:36 INFO - PROCESS | 1682 | 07:08:36 INFO - PROCESS | 1682 | __start_tp_report 07:08:36 INFO - PROCESS | 1682 | _x_x_mozilla_page_load 07:08:36 INFO - PROCESS | 1682 | _x_x_mozilla_page_load_details 07:08:36 INFO - PROCESS | 1682 | |i|pagename|runs| 07:08:36 INFO - PROCESS | 1682 | |0;ai-astar;112;107;112;112;112;113;113;112;112;112 07:08:36 INFO - PROCESS | 1682 | |1;audio-beat-detection;156;122;124;170;118;133;137;116;151;119 07:08:36 INFO - PROCESS | 1682 | |2;audio-dft;183;187;182;184;184;184;182;185;183;183 07:08:36 INFO - PROCESS | 1682 | |3;audio-fft;76;73;58;108;57;56;63;66;81;56 07:08:36 INFO - PROCESS | 1682 | |4;audio-oscillator;82;93;91;71;76;68;95;70;67;100 07:08:36 INFO - PROCESS | 1682 | |5;imaging-gaussian-blur;88;85;88;85;88;85;93;130;115;85 07:08:36 INFO - PROCESS | 1682 | |6;imaging-darkroom;93;94;95;95;93;94;95;94;94;94 07:08:36 INFO - PROCESS | 1682 | |7;imaging-desaturate;87;89;87;87;90;86;86;88;86;88 07:08:36 INFO - PROCESS | 1682 | |8;json-parse-financial;53;55;57;57;57;55;55;54;55;54 07:08:36 INFO - PROCESS | 1682 | |9;json-stringify-tinderbox;65;66;78;77;73;74;71;71;71;76 07:08:36 INFO - PROCESS | 1682 | |10;stanford-crypto-aes;85;86;86;86;86;85;86;88;122;87 07:08:36 INFO - PROCESS | 1682 | |11;stanford-crypto-ccm;155;137;137;138;138;136;145;143;148;142 07:08:36 INFO - PROCESS | 1682 | |12;stanford-crypto-pbkdf2;166;165;165;164;166;165;163;225;212;165 07:08:36 INFO - PROCESS | 1682 | |13;stanford-crypto-sha256-iterative;65;67;64;65;65;64;66;66;63;73 07:08:36 INFO - PROCESS | 1682 | __end_tp_report 07:08:36 INFO - PROCESS | 1682 | __start_cc_report 07:08:36 INFO - PROCESS | 1682 | _x_x_mozilla_cycle_collect,1985 07:08:36 INFO - PROCESS | 1682 | __end_cc_report 07:08:36 INFO - PROCESS | 1682 | __startTimestamp1464876516424__endTimestamp 07:08:36 INFO - PROCESS | 1682 | 07:08:36 INFO - PROCESS | 1682 | ------- Summary: start ------- 07:08:36 INFO - PROCESS | 1682 | Number of tests: 14 07:08:36 INFO - PROCESS | 1682 | 07:08:36 INFO - PROCESS | 1682 | [#0] ai-astar Cycles:10 Average:111.70 Median:112.00 stddev:1.70 (1.5%) stddev-sans-first:1.80 07:08:36 INFO - PROCESS | 1682 | Values: 112.0 107.0 112.0 112.0 112.0 113.0 113.0 112.0 112.0 112.0 07:08:36 INFO - PROCESS | 1682 | 07:08:36 INFO - PROCESS | 1682 | [#1] audio-beat-detection Cycles:10 Average:134.60 Median:135.00 stddev:18.63 (13.8%) stddev-sans-first:18.08 07:08:36 INFO - PROCESS | 1682 | Values: 156.0 122.0 124.0 170.0 118.0 133.0 137.0 116.0 151.0 119.0 07:08:36 INFO - PROCESS | 1682 | 07:08:36 INFO - PROCESS | 1682 | [#2] audio-dft Cycles:10 Average:183.70 Median:184.00 stddev:1.49 (0.8%) stddev-sans-first:1.56 07:08:36 INFO - PROCESS | 1682 | Values: 183.0 187.0 182.0 184.0 184.0 184.0 182.0 185.0 183.0 183.0 07:08:36 INFO - PROCESS | 1682 | 07:08:36 INFO - PROCESS | 1682 | [#3] audio-fft Cycles:10 Average:69.40 Median:64.50 stddev:16.25 (25.2%) stddev-sans-first:17.06 07:08:36 INFO - PROCESS | 1682 | Values: 76.0 73.0 58.0 108.0 57.0 56.0 63.0 66.0 81.0 56.0 07:08:36 INFO - PROCESS | 1682 | 07:08:36 INFO - PROCESS | 1682 | [#4] audio-oscillator Cycles:10 Average:81.30 Median:79.00 stddev:12.53 (15.9%) stddev-sans-first:13.28 07:08:36 INFO - PROCESS | 1682 | Values: 82.0 93.0 91.0 71.0 76.0 68.0 95.0 70.0 67.0 100.0 07:08:36 INFO - PROCESS | 1682 | 07:08:36 INFO - PROCESS | 1682 | [#5] imaging-gaussian-blur Cycles:10 Average:94.20 Median:86.50 stddev:15.53 (17.9%) stddev-sans-first:16.31 07:08:36 INFO - PROCESS | 1682 | Values: 88.0 85.0 88.0 85.0 88.0 85.0 93.0 130.0 115.0 85.0 07:08:36 INFO - PROCESS | 1682 | 07:08:36 INFO - PROCESS | 1682 | [#6] imaging-darkroom Cycles:10 Average:94.10 Median:94.00 stddev:0.74 (0.8%) stddev-sans-first:0.67 07:08:36 INFO - PROCESS | 1682 | Values: 93.0 94.0 95.0 95.0 93.0 94.0 95.0 94.0 94.0 94.0 07:08:36 INFO - PROCESS | 1682 | 07:08:36 INFO - PROCESS | 1682 | [#7] imaging-desaturate Cycles:10 Average:87.40 Median:87.50 stddev:1.35 (1.5%) stddev-sans-first:1.42 07:08:36 INFO - PROCESS | 1682 | Values: 87.0 89.0 87.0 87.0 90.0 86.0 86.0 88.0 86.0 88.0 07:08:36 INFO - PROCESS | 1682 | 07:08:36 INFO - PROCESS | 1682 | [#8] json-parse-financial Cycles:10 Average:55.20 Median:55.00 stddev:1.40 (2.5%) stddev-sans-first:1.24 07:08:36 INFO - PROCESS | 1682 | Values: 53.0 55.0 57.0 57.0 57.0 55.0 55.0 54.0 55.0 54.0 07:08:36 INFO - PROCESS | 1682 | 07:08:36 INFO - PROCESS | 1682 | [#9] json-stringify-tinderbox Cycles:10 Average:72.20 Median:73.50 stddev:4.34 (5.9%) stddev-sans-first:3.74 07:08:36 INFO - PROCESS | 1682 | Values: 65.0 66.0 78.0 77.0 73.0 74.0 71.0 71.0 71.0 76.0 07:08:36 INFO - PROCESS | 1682 | 07:08:36 INFO - PROCESS | 1682 | [#10] stanford-crypto-aes Cycles:10 Average:89.70 Median:86.00 stddev:11.38 (13.2%) stddev-sans-first:11.95 07:08:36 INFO - PROCESS | 1682 | Values: 85.0 86.0 86.0 86.0 86.0 85.0 86.0 88.0 122.0 87.0 07:08:36 INFO - PROCESS | 1682 | 07:08:36 INFO - PROCESS | 1682 | [#11] stanford-crypto-ccm Cycles:10 Average:141.90 Median:142.50 stddev:6.08 (4.3%) stddev-sans-first:4.22 07:08:36 INFO - PROCESS | 1682 | Values: 155.0 137.0 137.0 138.0 138.0 136.0 145.0 143.0 148.0 142.0 07:08:36 INFO - PROCESS | 1682 | 07:08:36 INFO - PROCESS | 1682 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.60 Median:165.50 stddev:22.83 (13.8%) stddev-sans-first:23.95 07:08:36 INFO - PROCESS | 1682 | Values: 166.0 165.0 165.0 164.0 166.0 165.0 163.0 225.0 212.0 165.0 07:08:36 INFO - PROCESS | 1682 | 07:08:36 INFO - PROCESS | 1682 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.80 Median:65.50 stddev:2.78 (4.2%) stddev-sans-first:2.93 07:08:36 INFO - PROCESS | 1682 | Values: 65.0 67.0 64.0 65.0 65.0 64.0 66.0 66.0 63.0 73.0 07:08:36 INFO - PROCESS | 1682 | -------- Summary: end -------- 07:08:36 INFO - PROCESS | 1682 | 07:08:37 INFO - TEST-INFO | 1682: exit 0 07:08:37 INFO - TEST-OK | kraken | took 59192ms 07:08:37 INFO - SUITE-END | took 1174s 07:08:37 INFO - Completed test suite (00:19:34) 07:08:37 INFO - javascript benchmark 07:08:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2951.158919707884, 3435.519027336173, 3444.397230484666, 3469.098034511027, 3471.4445688689784, 5515.669059856412, 5540.390557038722, 5639.774409023645, 5659.405762394951, 5688.2889638795095, 5600.831975040745, 5677.347105082907, 5713.571482138836, 5718.285214348207, 5748.367679555246, 5078.974605126969, 5141.0489059523925, 5162.92255616166, 5164.431912489623, 5263.44733802949, 5224.4775522447835, 5257.027449921758, 5259.710715910624, 5287.41838397776, 5292.285541451913, 5066.062778385992, 5116.872078198075, 5123.4108077571, 5125.359330083739, 5167.22491626259, 5110.948890511085, 5126.102931986888, 5134.897302053938, 5137.229415587654, 5169.431362550116, 5104.081265372231, 5115.488451154892, 5179.378474583036, 5198.844034678966, 5215.348081489814, 5505.944940550583, 5522.779088836442, 5537.916931246034, 5554.583406244507, 5605.327360716699, 6154.692265386758, 6159.38406159385, 6169.629822210682, 6176.567640265183, 6197.411245931675, 5191.714455704935, 6208.286047104596, 6224.782132625336, 6253.937460625381, 6264.749410023594, 4683.882902927386, 5613.158026296088, 5654.236678048493, 5697.173909783059, 5754.769809207669, 6349.142865713162, 6361.236651601837, 6363.204599425072, 6368.235811702625, 6368.5860419072615, 5965.254343207099, 5992.730327135289, 6045.455908968214, 6047.971844786397, 6052.455279024909, 6123.435141503484, 6137.508999280047, 6140.4473597376455, 6167.814965551041, 6189.226346706661, 6124.38756124384, 6165.599236049642, 6172.969135154295, 6177.351378105324, 6188.907166392508, 6192.380761923861, 6206.162168107338, 6213.161223234805, 6217.347178546278, 6231.781887633911, 4760.809567617326, 4811.615070794328, 4813.614910807127, 4828.155072862235, 4869.732164730974, 3969.761814291152, 4262.296721041055, 4332.090261045216, 4450.198964186413, 4456.5766252205985, 5626.831195064155, 5645.548356131581, 5646.520045796071, 5654.8303550892715, 5659.830205093854, 6098.390160983866, 6171.537134714914, 6212.751489940351, 6215.06773983897, 6291.6854157291955, 5679.034564124109, 5687.43125687428, 5689.687067211343, 5692.772289108472, 5716.171155182558, 5205.895882082337, 5324.414314425488, 5350.0, 5353.330833645794, 5364.865878353073, 6315.273743519462, 6365.108884756138, 6374.458171055327, 6383.553151279366, 6398.552101352953, 6120.629988696738, 6195.19462469876, 6203.534734894811, 6265.436110749872, 6284.120223168668, 6330.987042073246, 6357.396047375492, 6358.77744278948, 6361.840953976188, 6366.013267943476, 5937.2578427696535, 5990.400959903975, 6129.877402452015, 6130.877382452415, 6153.230846144232, 6097.756089756537, 6125.693326622233, 6137.079438084323, 6160.476359509492, 6191.566590338633, 5020.949790502048, 5917.467427931507, 5965.105234214902, 5979.5814292999075, 6014.548908831943, 5788.102844059177, 5854.765809367748, 5931.110333449845, 5952.434518720715, 6007.429294217042, 5813.9534883721335, 5855.531557475307, 5857.26784151981, 5890.823275301748, 5908.645481271137, 6039.607425517327, 6083.6045657032155, 6085.0, 6087.421694938974, 6090.543209259235, 5701.429857014431, 5753.280839895013, 5862.384449632727, 5870.471657550841, 5880.735366908585], "value": 5630.446517752278, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [499.97006167295376, 504.43755212937583, 527.4909712127796, 530.9867253318666, 577.0334689395263, 29407.0, 42133.578664213346, 43366.783166084206, 43381.132377352515, 45725.856853578676, 44746.0, 45253.54746452534, 45266.547334526724, 45312.773436132855, 45368.3194752079, 8704.129587041309, 9603.759906002353, 9865.556049977768, 9882.011798820096, 9882.604695812159, 8865.113488651148, 11762.823557646641, 11993.16047876649, 12042.277463352228, 12299.69250768729, 12069.456874440672, 12169.756604867898, 12183.0, 12192.81710774339, 12203.93898030513, 8728.258098061673, 8924.598393072327, 8940.0, 10092.495375231245, 10205.183585313194, 8869.600867960957, 11564.942175289154, 11879.40602969848, 11897.524099036029, 12208.267503949792, 42075.0, 42578.78710606427, 42618.0, 42649.0, 42720.14559708788, 3567.965290065878, 3647.927041459156, 3767.7739335639676, 3768.811559422018, 3839.9040023999623, 32219.51670724941, 32512.0, 32625.184370390692, 33029.834850825835, 33248.6675133248, 2786.595943588168, 3283.1792051987004, 3309.1396236978317, 3391.5590973173576, 3421.8973430797114, 507128.0, 508817.0, 510412.0, 511044.0, 512080.8791912108, 63702.68148659227, 63717.681411592646, 63848.36151638517, 63925.0, 64224.0, 34008.31983360319, 35564.644353556134, 65729.34270657328, 66643.00035499471, 66689.99860008319, 17231.655366892843, 17351.39270125565, 17443.56391090233, 17454.0, 17458.34145756151, 11939.283642981449, 12034.157608967289, 12995.155314904501, 13104.80342794859, 13206.669833254246, 20247.595048098956, 20365.2872149477, 20431.284905028253, 20527.794722052888, 20663.58672826535, 6837.692303846339, 8110.675572977015, 8122.472039317426, 8137.0, 8144.429889907649, 972.781124247053, 1038.99480502597, 1060.1412855587, 1101.7067016351803, 1133.2434726174397, 30756.231094222825, 30777.076687699406, 31203.687963120534, 31292.53049309377, 31572.684273156974, 944.475815922156, 1095.611058074372, 1126.5437497813398, 1145.4330106597122, 1188.95838645647, 14203.64490887736, 15083.698326033636, 16386.75419868703, 16740.748888766677, 16936.661266774594, 684967.0, 691568.0, 693366.0, 693687.0, 694491.0, 62627.6868615654, 63101.73796524044, 63198.68400657967, 63354.68322658357, 63460.36539634545, 1318.4989703912452, 1363.8158848555518, 1423.2422957647505, 1450.0936914428482, 1483.1101339196612, 30034.405788561595, 31175.53236701406, 31266.687333127287, 31631.36737265196, 31883.68116318807, 51880.0, 51969.74015130052, 52795.94408111739, 52807.47192528025, 52821.20768188557, 505920.0, 510687.0, 512829.0, 514707.0, 515352.0, 23813.928373223593, 26184.345391364604, 26225.73774262233, 26807.463850722484, 26864.194074177805, 48489.0, 48536.5146348532, 48941.755291223315, 49468.50531494639, 49769.502304976486, 32637.347253055283, 38379.0, 39934.60065399309, 40317.589819615445, 40608.390874137505, 38662.0, 39394.0, 39457.605423946545, 39858.60141398549, 40031.79904602867, 38037.0, 38463.230735385696, 38546.421803673526, 38839.6116038836, 39045.60954390533, 33870.0, 34015.65984340224, 34890.12774680653, 35184.296314073064, 35279.0, 22922.54154916926, 24071.686307435477, 25485.617715733908, 25894.61158082668, 25968.480630387665], "value": 20779.760181654685, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.54773914520007, 388.09380097472393, 389.27674929400683, 391.2175648702593, 414.56056580025177, 13415.0, 20253.898730506364, 20512.589748205028, 21013.84423856687, 21018.369448916517, 16071.919640401782, 17015.404460843874, 19693.803061969375, 20246.392608221777, 20729.792702072973, 5739.3399759027825, 5866.677332746697, 5874.0, 5914.290285165786, 5956.255468066492, 5725.885482290351, 5781.103928891008, 5816.738246778884, 5822.650640961556, 5836.824895253149, 5664.291963504562, 5758.798442054529, 5781.277340332458, 5792.942070579283, 5827.213326200951, 5710.714464276791, 5774.5091667208335, 5777.104548794943, 5819.912701309462, 5839.532837373, 4946.777395017233, 5091.949080509222, 5118.539331460186, 5125.923111153298, 5129.333186685744, 25269.494610107697, 25502.87248563764, 26207.73792262072, 27133.321666958287, 27145.321366965785, 2250.3698964290024, 2262.6266666000092, 2359.6696462495265, 2384.892679829412, 2443.303658457349, 14965.625859353495, 15263.771043434357, 15419.537413877602, 15981.698983937114, 16314.673706525802, 1824.6806808808406, 1847.1503108569939, 1876.7184922261633, 1885.4626431466966, 1908.3416221403731, 94068.52965735196, 119263.0, 119311.0, 121263.0, 123236.76763232342, 10635.308704934308, 10667.0, 11149.498272577754, 11173.497192626352, 11309.982101610894, 14113.153210807384, 14221.64445888861, 14243.216623085622, 14259.786103208253, 14283.500077497238, 7792.142864284924, 7805.921940780633, 8624.52565108925, 8799.29605631548, 8912.777180570409, 8829.64681412749, 8869.822603547893, 8894.377393582516, 8981.101889811098, 9000.279977601776, 5525.917111243334, 6785.117934668461, 6793.320667933266, 6796.898046529207, 6800.591964482147, 1861.15317530526, 1877.483691984715, 1885.9151338189815, 1913.6555420024529, 1927.681932481153, 931.7950050988771, 944.2964991081689, 1037.9792404151874, 1050.9344957305714, 1140.122105978392, 21192.682109768364, 22074.779252207594, 22139.667904981437, 22172.891355432163, 22184.33446996593, 971.8095333216753, 1017.3234798858676, 1055.4036969112426, 1079.4818487126067, 1100.1418893263267, 10599.099076578423, 12004.939975300067, 12058.577949771716, 12111.273323600612, 12139.0, 562252.0, 583378.0, 597175.0, 598744.0, 599369.0, 121168.0, 122327.38836305939, 125996.0, 126296.0, 127960.63945924076, 985.8274801909637, 1042.5464922758777, 1057.2440704896037, 1060.8408738689104, 1061.2253055269696, 18938.0, 19228.134733936728, 19449.124789384794, 19453.0, 19469.318573849847, 51609.0, 53046.93906121779, 54038.45961540334, 54806.177907330624, 55663.16505252505, 82240.58879705564, 82687.5865620668, 82862.3427531458, 82993.58503207445, 85047.0, 8217.479766243265, 8303.833923321621, 8374.036985746474, 8399.70601028961, 8573.056963734109, 11012.119030477703, 12975.156614820007, 13067.215967042008, 13187.736245275233, 13240.0, 10998.450077495932, 11018.779624407627, 11150.776984460428, 11303.943480282875, 11391.145664075062, 10386.792264154523, 11109.50007249692, 11141.554337826396, 11198.496067677137, 11261.324320540794, 11670.241434306743, 11707.707307317385, 11812.468438920097, 11879.643610691694, 12008.519659213534, 8269.95865020695, 8389.286910612665, 8434.451760635538, 8489.660413583388, 8525.573721313785, 8468.195521425454, 8722.738317850475, 11111.666650000512, 11334.546618135511, 11618.825717614412], "value": 9858.995970724609, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1366.6036849313687, 1540.7303721848677, 1559.4308077551693, 1830.3593742190226, 1993.5813479169374, 7838.686452541899, 8330.0420451648, 8563.015253245878, 8588.61351239195, 8595.613197406115, 8187.0, 8491.19333663301, 8499.405041647087, 8605.870911936327, 8628.611712472939, 2331.8717470539073, 2384.403899025244, 2385.057902128658, 2388.8924998375114, 2388.916387926423, 2314.0628045641542, 2339.7543257957923, 2358.740538540759, 2360.9965764550084, 2364.408897775556, 2327.045911176418, 2347.6361164019518, 2360.1385494294545, 2362.7164740231196, 2366.2309749331534, 2326.8254880883915, 2330.822934418125, 2362.3621622162004, 2363.468219650582, 2366.5621859955963, 2286.474110954473, 2365.775251351136, 2390.9163179288817, 2391.3423808452812, 2394.736578976311, 7633.0, 7883.487573307717, 8561.914380856237, 8637.568121593895, 8713.390062695615, 5019.171836646949, 5257.342832145981, 5257.947420525784, 5281.339832520935, 5299.947000529984, 3508.614052454228, 3566.1619519412916, 3618.9276214475562, 3629.94555081674, 3635.654612811779, 1020.1787561013381, 1028.0541901450683, 1783.9286428542844, 1846.1415441819618, 1870.9990155267003, 11110.555577776879, 11353.545858165664, 11366.37484938328, 11450.969412752893, 11556.94221528887, 4292.720973136736, 4611.423572053493, 4773.37946067016, 4844.15227335215, 4882.9755851221225, 9976.0, 10071.748206294755, 10074.042965918226, 10086.24353173515, 10109.140723038478, 5391.676499410048, 5404.0272750905215, 5406.107992181324, 5412.91880621791, 5412.91880621791, 4002.239574480839, 4832.202686556749, 4857.6599638025, 4907.043126590281, 4948.109340318777, 3650.671439570451, 3664.7434679572175, 3682.8710995115043, 3739.738218324691, 3779.962200378016, 1065.8507808906759, 1240.1752834365227, 1265.9303738294298, 1275.2093701904878, 1277.150694787974, 945.6642891773322, 958.5925981457992, 960.3229723045237, 960.673371053845, 998.2530571499875, 5778.479936805707, 5830.970845145832, 5873.823785286449, 5893.528517718572, 6090.934086534839, 440.6430791059252, 449.51676947281805, 458.84857996860956, 458.86922227165763, 460.2520903531761, 3473.3747925373677, 3852.036990752312, 3921.9803900980883, 4024.3158663027357, 4030.8387664493093, 10276.691699248884, 10321.225908056926, 10631.362118272928, 10640.414777187329, 10708.357498550113, 9384.202342800801, 10176.982301769913, 10666.466676666136, 10698.197635177237, 11103.444827758742, 6905.723771049303, 7853.921460785434, 7986.920130798734, 8088.555129467818, 8188.50868947865, 5785.652860828363, 5826.329972053099, 5844.47399734026, 5873.73568189424, 5891.116332550152, 9431.905680943377, 9980.301378903407, 9987.400755954666, 9989.300748947504, 10020.0, 9700.951495242478, 10099.94950025245, 10174.186065114625, 10188.184945204217, 10200.0819926207, 7187.820304492429, 7293.708251669874, 7329.890051649123, 7334.596597187206, 7348.559086454829, 8069.354451643737, 8090.757277281691, 8180.427370084275, 8193.959030204811, 8225.629846657035, 7054.753083642048, 7341.70633174667, 7371.857362108882, 7375.2256013119995, 7480.887786683313, 7141.9642901785155, 7241.601711905896, 7256.89114663291, 7311.963440182978, 7413.555186688816, 5946.940530594638, 6963.895541566779, 6998.265182156004, 7004.789856304319, 7008.334208250208, 4735.431748190239, 4919.311296418576, 4933.210686290176, 4955.776990035529, 5004.124278251219, 4998.1503144464805, 5063.13926632466, 5104.489551044866, 5110.872228194325, 5223.085959957069], "value": 4502.23707383079, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.29730430791955, 304.2651995431034, 313.2310178511754, 316.53943512189767, 320.21866645385256, 4441.000774825663, 4864.464908860032, 5058.519440653141, 5319.414864364917, 5453.536449401806, 4943.307936888838, 5043.747812609364, 5112.594036639925, 5160.612954028454, 5323.787048518065, 3533.452314891196, 3961.623645753649, 4009.298372784751, 4015.1254039851538, 4050.554439011721, 3659.3047321009058, 3778.1688028633657, 4021.3967904814367, 4042.9797851010703, 4068.4914385701786, 3066.402051599939, 3809.942850857239, 3971.582983786704, 4002.259017327472, 4028.858989935353, 3074.5849310343265, 3955.268275369066, 3964.345882929313, 3981.442598036277, 4031.3348297530874, 3471.993121994644, 3484.8083355415442, 3492.877749278763, 3510.701590364822, 3537.363274610569, 4795.352627395292, 5020.573251273646, 5044.873878153076, 5050.772715227824, 5088.898222035538, 3726.273376691546, 4429.0, 4525.547445255481, 4588.0365123324145, 4594.563516465931, 4670.486246512881, 5463.945360546383, 5482.1624862596345, 5483.561315094783, 5549.361823390322, 3611.2235869287965, 3734.2075803614416, 3761.4357846322996, 3786.772793632391, 3935.5670876203594, 5125.718085505296, 5160.432352441228, 5722.799702010451, 5728.1407788831175, 5854.68269639332, 3583.7670551414076, 3722.6277372262557, 3899.323290984904, 3903.590123037097, 3909.6285852843935, 3736.3274610570356, 3962.4244241242845, 3992.500937382827, 3993.6206060424215, 4051.885731423882, 3477.4003958179014, 4082.6529744971417, 4367.034834324567, 4523.471692080183, 4618.884527886763, 5023.447420783711, 5567.582431317586, 5570.41510641385, 5603.80386686464, 5615.052097648767, 1234.3149551998727, 1321.9272939988393, 1354.8441929178073, 1374.4776984745733, 1375.5460697969645, 914.9542522873829, 940.9623615055458, 942.1661829280998, 944.126682818404, 947.8862536495665, 4976.676516026447, 5143.0, 5144.9163088949035, 5227.686338819683, 5297.5232229099565, 4828.444728856156, 5286.286351342519, 5396.600141910571, 5410.269613602192, 5648.051422259314, 4601.98756273626, 5151.433342332415, 5199.7140157291715, 5204.76578553966, 5222.503862133091, 2970.203027168532, 3014.9246268843453, 3022.851316499716, 3025.3949210158057, 3042.209025653302, 4854.587360074363, 5893.5579831512805, 5894.528437724972, 5895.0, 6003.699815009232, 4950.4406112074585, 5221.0, 5595.776168953196, 5691.402402747734, 5894.734736936848, 4530.388397566352, 5239.240310155006, 5851.444112809277, 5877.177195192762, 5901.055831067095, 5114.64197506171, 5352.625316227826, 5520.53075488588, 5766.5112606872235, 5818.450745045603, 4726.574608285271, 5682.77268909239, 5688.042218046676, 5699.544036477155, 5714.742756593543, 5120.206368012964, 5393.705510677488, 5575.888482230252, 6017.518598512196, 6225.346338634378, 3505.4069578682056, 3820.522434695663, 3848.807559621952, 3851.383778595411, 3860.247251785987, 4884.7069175849565, 5729.341125770589, 5730.283714535683, 5769.711514424346, 5797.449242321973, 4687.648426367968, 4877.7339833008255, 5593.216949626974, 5615.915761263666, 5652.236948012131, 4896.902061958812, 5304.681719096867, 5442.564594832483, 5572.526335261386, 5582.162675598693, 4856.1987272099705, 5359.839204823861, 5408.080131553242, 5588.888222235451, 5616.971915140398, 4953.777080031479, 5064.518870707277, 5158.09733296679, 5538.6399884007415, 5649.022842005265, 4586.931196032129, 5043.646944713835, 5347.491988261109, 5489.204065410573, 5497.092979658471], "value": 4044.180392527974, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [713.8679344321301, 728.402709777982, 742.6398196874514, 823.8311146215027, 850.5407080176705, 13207.6698082548, 14783.260836958149, 14886.478973235939, 15572.377104915804, 15581.766273505878, 12775.872241277584, 13899.861001389983, 14024.158550486978, 14063.156210627369, 14072.64818379541, 900.8153328567661, 900.9594568244445, 906.3293163059354, 907.1291560102277, 909.6543313540845, 900.2842740021698, 904.421170450912, 905.2441211588332, 905.384338649718, 906.7642412972608, 957.301170145794, 958.5446912716474, 958.5494817435829, 959.49626446116, 960.7790208252106, 952.5618215620823, 953.8616900549416, 955.0640372435053, 955.1117460761488, 955.5461155950937, 708.8298808286025, 708.8334241453255, 708.8688592610383, 709.574255446733, 712.3766704133883, 12801.61595152147, 12945.0, 12946.805797913039, 12957.805632915513, 13831.446742130403, 10684.252102352839, 12063.336516491589, 12094.213876098025, 12204.694882627917, 12357.814632780515, 11238.325700458, 12152.21010634306, 12396.194247373893, 12441.855842245426, 12628.116031877773, 4501.437320334958, 4508.842190523316, 4659.836905708284, 4665.230237010889, 4667.183242932473, 67406.0, 73299.63350183268, 94570.16289511394, 97324.51337743337, 98227.0, 11039.503222355013, 11219.1585631078, 11237.269577477438, 11237.775244495228, 11261.943690281496, 21639.026243818753, 22051.66922496164, 22056.88971555164, 22081.668774968388, 22099.779002210093, 8027.959860200662, 8072.717454889051, 8145.144759800254, 8217.424780265444, 8254.711085111992, 4489.236829738952, 4510.661700372485, 4539.387182730356, 4605.263157894721, 4689.155951928686, 3611.223586928823, 3722.7766334020043, 3725.2177042821313, 3725.720570957189, 3734.3278209922473, 666.9533132680763, 668.5754694121805, 670.4842883181767, 671.2280876991499, 679.0900193740313, 622.8723111762168, 666.2970566052861, 685.9828504287433, 694.4513834071028, 703.6024646074951, 9102.86345704815, 9529.380590261611, 9654.343908698773, 9707.0, 9764.121229089416, 649.3084864619166, 691.5945427625954, 701.193627328566, 705.5061456980031, 706.3819158236543, 4204.684648651363, 4517.570830771071, 4597.919488920101, 4617.976910115428, 4668.767828289902, 90934.0, 106926.93073069325, 133073.0, 148544.0, 151912.24043879932, 97809.5109524462, 98082.0, 100461.0, 101915.98084019213, 112629.43685281686, 108506.8298634023, 110414.3437848433, 111061.33407998887, 111553.44223278832, 113152.43423782829, 8592.957035214784, 8621.913780862362, 8639.654413823377, 8672.913270867211, 8749.47503149813, 57908.71045644745, 60428.0, 62151.0, 62755.0, 63865.38738926909, 86525.0, 86694.56652716696, 89705.0, 94059.0, 94754.0, 11349.48927298257, 11366.943165284121, 11521.711957201138, 11631.59289424866, 11789.0, 12773.361331933553, 12999.545015924397, 13157.60527184186, 13244.967161444214, 13305.201687898758, 11821.763564728486, 11913.463894124958, 11930.164888457724, 12013.219140755822, 12566.623001309976, 11915.391789193774, 12137.393130343271, 12143.271403715806, 12155.0, 12227.0, 11772.234804737665, 11918.761624767629, 11974.101942354533, 12094.274343539415, 12107.152499324962, 10115.342502737298, 10191.694249172537, 10281.948590257001, 10299.691009269734, 10663.98692124247, 8285.83428331418, 9920.553575089283, 9937.701868943943, 10240.48797560134, 10245.0], "value": 7606.676707830954, "unit": "score"}], "name": "dromaeo_css", "value": 7366.364337962304, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 107.0, 112.0, 112.0, 112.0, 113.0, 113.0, 112.0, 112.0, 112.0], "value": 111.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 122.0, 124.0, 170.0, 118.0, 133.0, 137.0, 116.0, 151.0, 119.0], "value": 134.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 187.0, 182.0, 184.0, 184.0, 184.0, 182.0, 185.0, 183.0, 183.0], "value": 183.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 73.0, 58.0, 108.0, 57.0, 56.0, 63.0, 66.0, 81.0, 56.0], "value": 69.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 93.0, 91.0, 71.0, 76.0, 68.0, 95.0, 70.0, 67.0, 100.0], "value": 81.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 85.0, 88.0, 85.0, 93.0, 130.0, 115.0, 85.0], "value": 94.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 94.0, 95.0, 95.0, 93.0, 94.0, 95.0, 94.0, 94.0, 94.0], "value": 94.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 89.0, 87.0, 87.0, 90.0, 86.0, 86.0, 88.0, 86.0, 88.0], "value": 87.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 55.0, 57.0, 57.0, 57.0, 55.0, 55.0, 54.0, 55.0, 54.0], "value": 55.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 78.0, 77.0, 73.0, 74.0, 71.0, 71.0, 71.0, 76.0], "value": 72.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 86.0, 86.0, 86.0, 85.0, 86.0, 88.0, 122.0, 87.0], "value": 89.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 137.0, 137.0, 138.0, 138.0, 136.0, 145.0, 143.0, 148.0, 142.0], "value": 141.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 165.0, 165.0, 164.0, 166.0, 165.0, 163.0, 225.0, 212.0, 165.0], "value": 175.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 64.0, 65.0, 65.0, 64.0, 66.0, 66.0, 63.0, 73.0], "value": 65.8, "unit": "score"}], "name": "kraken", "value": 1456.8, "alertThreshold": 2.0}]} 07:08:37 INFO - Return code: 0 07:08:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 07:08:37 ERROR - # TBPL SUCCESS # 07:08:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:08:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:08:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:08:37 INFO - 2016-06-02 07:08:37.706 screenresolution[1686:10748] starting screenresolution argv=screenresolution get 07:08:37 INFO - 2016-06-02 07:08:37.708 screenresolution[1686:10748] Display 0: 1600x1200x32@60 07:08:37 INFO - 2016-06-02 07:08:37.714 screenresolution[1687:10751] starting screenresolution argv=screenresolution list 07:08:37 INFO - Available Modes on Display 0 07:08:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:08:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:08:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:08:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:08:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:08:37 INFO - Intel Iris: 07:08:37 INFO - Chipset Model: Intel Iris 07:08:37 INFO - Type: GPU 07:08:37 INFO - Bus: Built-In 07:08:37 INFO - VRAM (Dynamic, Max): 1536 MB 07:08:37 INFO - Vendor: Intel (0x8086) 07:08:37 INFO - Device ID: 0x0a2e 07:08:37 INFO - Revision ID: 0x0009 07:08:37 INFO - Displays: 07:08:37 INFO - Display: 07:08:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:08:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:08:37 INFO - Display Serial Number: 1600x1200 60 07:08:37 INFO - Main Display: Yes 07:08:37 INFO - Mirror: Off 07:08:37 INFO - Online: Yes 07:08:37 INFO - Rotation: Supported 07:08:37 INFO - Return code: 0 07:08:37 INFO - Running post-action listener: _resource_record_post_action 07:08:37 INFO - ##### 07:08:37 INFO - ##### Finished run-tests step (success) 07:08:37 INFO - ##### 07:08:37 INFO - Running post-run listener: _resource_record_post_run 07:08:38 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 32914944; Write bytes: 700512768; Read time: 328; Write time: 1869 07:08:38 INFO - TinderboxPrint: CPU usage
53.6% 07:08:38 INFO - TinderboxPrint: I/O read bytes / time
32,914,944 / 328 07:08:38 INFO - TinderboxPrint: I/O write bytes / time
700,512,768 / 1,869 07:08:38 INFO - TinderboxPrint: CPU idle
2,244.6 (46.4%) 07:08:38 INFO - TinderboxPrint: CPU system
961.3 (19.9%) 07:08:38 INFO - TinderboxPrint: CPU user
1,635.8 (33.8%) 07:08:38 INFO - TinderboxPrint: Swap in / out
428,879,872 / 0 07:08:38 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 400939520; Write bytes: 403429376; Read time: 29999; Write time: 806 07:08:38 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 30998016; Write bytes: 296186368; Read time: 308; Write time: 1061 07:08:38 INFO - Running post-run listener: _upload_blobber_files 07:08:38 INFO - Blob upload gear active. 07:08:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:08:38 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 07:08:38 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'] 07:08:38 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 07:08:38 INFO - (blobuploader) - INFO - Open directory for files ... 07:08:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:08:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:08:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:08:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:08:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:08:40 INFO - (blobuploader) - INFO - Done attempting. 07:08:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:08:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:08:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:08:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:08:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:08:41 INFO - (blobuploader) - INFO - Done attempting. 07:08:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:08:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:08:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:08:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:08:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:08:43 INFO - (blobuploader) - INFO - Done attempting. 07:08:43 INFO - (blobuploader) - INFO - Iteration through files over. 07:08:43 INFO - Return code: 0 07:08:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:08:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:08:43 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bc95e6b4cfde70739825059fdae94ea0dd07f47ed910d4cda72f7cf88b6b85c57c63d6860acff4f9012e23ed4112aa125bfe143c967f68976bba3058c17e6b90", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/906d91ef385e47e26fda845a243c6f8266e65e02c7606b49f4326baeae158e00c7ac493b780cc3d63f74f4e9f36f3d5b9be34ac84d25c2829a1fb93f0413ffce", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2af14471cf39945e3390802e0b58ab0e845e3ffafe45e4c7e3abc898056aa5abc28bf7be2ee101bb3bf205e0bfccff1c5833ee2d56b7d75b5a942aadfe60f8be"} 07:08:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:08:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:08:43 INFO - Contents: 07:08:43 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/bc95e6b4cfde70739825059fdae94ea0dd07f47ed910d4cda72f7cf88b6b85c57c63d6860acff4f9012e23ed4112aa125bfe143c967f68976bba3058c17e6b90", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/906d91ef385e47e26fda845a243c6f8266e65e02c7606b49f4326baeae158e00c7ac493b780cc3d63f74f4e9f36f3d5b9be34ac84d25c2829a1fb93f0413ffce", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2af14471cf39945e3390802e0b58ab0e845e3ffafe45e4c7e3abc898056aa5abc28bf7be2ee101bb3bf205e0bfccff1c5833ee2d56b7d75b5a942aadfe60f8be"} 07:08:43 INFO - Running post-run listener: copy_logs_to_upload_dir 07:08:43 INFO - Copying logs to upload dir... 07:08:43 INFO - mkdir: /builds/slave/test/build/upload/logs 07:08:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1290.547167 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 30 secs) (at 2016-06-02 07:08:43.709437) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 07:08:43.712942) ========= 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.bUdM42UdzW/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.YGGrC0ORzB/Listeners TMPDIR=/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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/bc95e6b4cfde70739825059fdae94ea0dd07f47ed910d4cda72f7cf88b6b85c57c63d6860acff4f9012e23ed4112aa125bfe143c967f68976bba3058c17e6b90", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/906d91ef385e47e26fda845a243c6f8266e65e02c7606b49f4326baeae158e00c7ac493b780cc3d63f74f4e9f36f3d5b9be34ac84d25c2829a1fb93f0413ffce", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2af14471cf39945e3390802e0b58ab0e845e3ffafe45e4c7e3abc898056aa5abc28bf7be2ee101bb3bf205e0bfccff1c5833ee2d56b7d75b5a942aadfe60f8be"} build_url:https://queue.taskcluster.net/v1/task/Vl-5PFJ2Q7-bcarXMTvNDQ/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010579 build_url: 'https://queue.taskcluster.net/v1/task/Vl-5PFJ2Q7-bcarXMTvNDQ/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/bc95e6b4cfde70739825059fdae94ea0dd07f47ed910d4cda72f7cf88b6b85c57c63d6860acff4f9012e23ed4112aa125bfe143c967f68976bba3058c17e6b90", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/906d91ef385e47e26fda845a243c6f8266e65e02c7606b49f4326baeae158e00c7ac493b780cc3d63f74f4e9f36f3d5b9be34ac84d25c2829a1fb93f0413ffce", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/2af14471cf39945e3390802e0b58ab0e845e3ffafe45e4c7e3abc898056aa5abc28bf7be2ee101bb3bf205e0bfccff1c5833ee2d56b7d75b5a942aadfe60f8be"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-02 07:08:43.743894) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 07:08:43.744263) ========= 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.bUdM42UdzW/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.YGGrC0ORzB/Listeners TMPDIR=/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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.005094 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-02 07:08:43.793247) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 07:08:43.793598) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-02 07:08:43.793972) ========= ========= Total master_lag: 0.17 =========